2019-10-03T14:10:21.328Z,1570111821.328 [Supervisor](DEBUG): Initializing supervisor.
2019-10-03T14:10:21.330Z,1570111821.330 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-03T14:10:21.331Z,1570111821.331 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-10-03T14:10:21.331Z,1570111821.331 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-03T14:10:21.332Z,1570111821.332 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-03T14:10:21.333Z,1570111821.333 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-10-03T14:10:21.335Z,1570111821.335 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-03T14:10:21.347Z,1570111821.347 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-03T14:10:21.348Z,1570111821.348 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-03T14:10:21.348Z,1570111821.348 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-10-03T14:10:21.349Z,1570111821.349 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-03T14:10:21.350Z,1570111821.350 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-03T14:10:21.350Z,1570111821.350 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-10-03T14:10:21.352Z,1570111821.352 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-03T14:10:21.353Z,1570111821.353 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-03T14:10:21.357Z,1570111821.357 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-03T14:10:21.452Z,1570111821.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-03T14:10:21.454Z,1570111821.454 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-03T14:10:22.021Z,1570111822.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-03T14:10:22.022Z,1570111822.022 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-03T14:10:22.121Z,1570111822.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-03T14:10:22.123Z,1570111822.123 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-03T14:10:22.224Z,1570111822.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-03T14:10:22.226Z,1570111822.226 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-03T14:10:22.307Z,1570111822.307 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-03T14:10:22.443Z,1570111822.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-03T14:10:22.444Z,1570111822.444 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-03T14:10:22.739Z,1570111822.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-03T14:10:22.741Z,1570111822.741 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-03T14:10:23.208Z,1570111823.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-03T14:10:23.210Z,1570111823.210 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-03T14:10:23.353Z,1570111823.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-03T14:10:23.354Z,1570111823.354 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-03T14:10:23.543Z,1570111823.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-03T14:10:23.544Z,1570111823.544 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-03T14:10:23.997Z,1570111823.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-03T14:10:23.999Z,1570111823.999 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-03T14:10:24.216Z,1570111824.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-03T14:10:24.217Z,1570111824.217 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-03T14:10:24.465Z,1570111824.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-03T14:10:24.466Z,1570111824.466 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-03T14:10:25.014Z,1570111825.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-03T14:10:25.015Z,1570111825.015 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-03T14:10:25.784Z,1570111825.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-03T14:10:25.786Z,1570111825.786 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-10-03T14:10:25.788Z,1570111825.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-10-03T14:10:25.874Z,1570111825.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-10-03T14:10:26.028Z,1570111826.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-10-03T14:10:26.136Z,1570111826.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-10-03T14:10:26.220Z,1570111826.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-10-03T14:10:26.314Z,1570111826.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-10-03T14:10:26.594Z,1570111826.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-10-03T14:10:26.822Z,1570111826.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-03T14:10:26.822Z,1570111826.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-10-03T14:10:26.916Z,1570111826.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-10-03T14:10:27.015Z,1570111827.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-10-03T14:10:27.155Z,1570111827.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-10-03T14:10:27.255Z,1570111827.255 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-03T14:10:27.273Z,1570111827.273 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-03T14:10:27.366Z,1570111827.366 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-03T14:10:27.478Z,1570111827.478 [VerticalControl] Loaded
2019-10-03T14:10:27.478Z,1570111827.478 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-03T14:10:27.479Z,1570111827.479 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-03T14:10:27.548Z,1570111827.548 [HorizontalControl] Loaded
2019-10-03T14:10:27.548Z,1570111827.548 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-03T14:10:27.549Z,1570111827.549 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-03T14:10:27.554Z,1570111827.554 [SpeedControl] Loaded
2019-10-03T14:10:27.555Z,1570111827.555 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-03T14:10:27.555Z,1570111827.555 [LoopControl](DEBUG): Construct LoopControl.
2019-10-03T14:10:27.556Z,1570111827.556 [LoopControl] Loaded
2019-10-03T14:10:27.556Z,1570111827.556 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-03T14:10:27.557Z,1570111827.557 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-03T14:10:27.559Z,1570111827.559 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-03T14:10:27.613Z,1570111827.613 [DepthRateCalculator] Loaded
2019-10-03T14:10:27.614Z,1570111827.614 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-03T14:10:27.619Z,1570111827.619 [PitchRateCalculator] Loaded
2019-10-03T14:10:27.619Z,1570111827.619 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-03T14:10:27.634Z,1570111827.634 [SpeedCalculator] Loaded
2019-10-03T14:10:27.635Z,1570111827.635 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-03T14:10:27.656Z,1570111827.656 [TempGradientCalculator] Loaded
2019-10-03T14:10:27.656Z,1570111827.656 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-03T14:10:27.661Z,1570111827.661 [YawRateCalculator] Loaded
2019-10-03T14:10:27.662Z,1570111827.662 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-03T14:10:27.691Z,1570111827.691 [ElevatorOffsetCalculator] Loaded
2019-10-03T14:10:27.692Z,1570111827.692 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-03T14:10:27.692Z,1570111827.692 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-03T14:10:27.694Z,1570111827.694 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-03T14:10:27.743Z,1570111827.743 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-03T14:10:27.745Z,1570111827.745 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-03T14:10:27.855Z,1570111827.855 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-03T14:10:27.856Z,1570111827.856 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-03T14:10:28.191Z,1570111828.191 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-03T14:10:28.191Z,1570111828.191 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-03T14:10:28.316Z,1570111828.316 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-03T14:10:28.317Z,1570111828.317 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-03T14:10:28.906Z,1570111828.906 [DataOverHttps] Loaded
2019-10-03T14:10:28.906Z,1570111828.906 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-03T14:10:28.907Z,1570111828.907 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2019-10-03T14:10:28.908Z,1570111828.908 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 891
2019-10-03T14:10:28.944Z,1570111828.944 [Depth_Keller] Loaded
2019-10-03T14:10:28.944Z,1570111828.944 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-03T14:10:29.041Z,1570111829.041 [NAL9602] Loaded
2019-10-03T14:10:29.041Z,1570111829.041 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-03T14:10:29.057Z,1570111829.057 [Onboard] Loaded
2019-10-03T14:10:29.057Z,1570111829.057 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-03T14:10:29.060Z,1570111829.060 [Radio_Surface] Loaded
2019-10-03T14:10:29.061Z,1570111829.061 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-03T14:10:29.062Z,1570111829.062 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-10-03T14:10:29.062Z,1570111829.062 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 892
2019-10-03T14:10:29.187Z,1570111829.187 [DAT] Loaded
2019-10-03T14:10:29.188Z,1570111829.188 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-03T14:10:30.676Z,1570111830.676 [BPC1] Loaded
2019-10-03T14:10:30.677Z,1570111830.677 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-03T14:10:30.677Z,1570111830.677 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-03T14:10:30.678Z,1570111830.678 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-03T14:10:30.882Z,1570111830.882 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-03T14:10:30.883Z,1570111830.883 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-03T14:10:30.933Z,1570111830.933 [NavChart] Loaded
2019-10-03T14:10:30.933Z,1570111830.933 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-03T14:10:30.948Z,1570111830.948 [UniversalFixResidualReporter] Loaded
2019-10-03T14:10:30.949Z,1570111830.949 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-03T14:10:30.949Z,1570111830.949 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-03T14:10:30.950Z,1570111830.950 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-03T14:10:31.663Z,1570111831.663 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-03T14:10:31.674Z,1570111831.674 [SBIT] Loaded
2019-10-03T14:10:31.674Z,1570111831.674 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-03T14:10:31.675Z,1570111831.675 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-03T14:10:31.687Z,1570111831.687 [IBIT] Loaded
2019-10-03T14:10:31.687Z,1570111831.687 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-03T14:10:31.690Z,1570111831.690 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-03T14:10:31.832Z,1570111831.832 [CBIT] Loaded
2019-10-03T14:10:31.833Z,1570111831.833 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-03T14:10:31.833Z,1570111831.833 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-03T14:10:31.834Z,1570111831.834 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-03T14:10:31.959Z,1570111831.959 [BuoyancyServo] Loaded
2019-10-03T14:10:31.959Z,1570111831.959 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-03T14:10:31.971Z,1570111831.971 [ElevatorServo] Loaded
2019-10-03T14:10:31.971Z,1570111831.971 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-03T14:10:31.982Z,1570111831.982 [MassServo] Loaded
2019-10-03T14:10:31.982Z,1570111831.982 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-03T14:10:31.993Z,1570111831.993 [RudderServo] Loaded
2019-10-03T14:10:31.994Z,1570111831.994 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-03T14:10:32.004Z,1570111832.004 [ThrusterServo] Loaded
2019-10-03T14:10:32.005Z,1570111832.005 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-03T14:10:32.005Z,1570111832.005 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-03T14:10:32.006Z,1570111832.006 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-03T14:10:32.030Z,1570111832.030 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-03T14:10:32.030Z,1570111832.030 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-03T14:10:32.318Z,1570111832.318 [BackSeatDriver] Loaded
2019-10-03T14:10:32.318Z,1570111832.318 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2019-10-03T14:10:32.319Z,1570111832.319 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0
2019-10-03T14:10:32.320Z,1570111832.320 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 893
2019-10-03T14:10:32.329Z,1570111832.329 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-03T14:10:32.335Z,1570111832.335 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-03T14:10:32.335Z,1570111832.335 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-03T14:10:32.340Z,1570111832.340 [CTD_Seabird](INFO): created writer for : depth
2019-10-03T14:10:32.340Z,1570111832.340 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-03T14:10:32.345Z,1570111832.345 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-03T14:10:32.346Z,1570111832.346 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-03T14:10:32.351Z,1570111832.351 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-03T14:10:32.352Z,1570111832.352 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-03T14:10:32.357Z,1570111832.357 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-03T14:10:32.357Z,1570111832.357 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-03T14:10:32.363Z,1570111832.363 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-03T14:10:32.363Z,1570111832.363 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-03T14:10:32.368Z,1570111832.368 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-03T14:10:32.395Z,1570111832.395 [CTD_Seabird] Loaded
2019-10-03T14:10:32.395Z,1570111832.395 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-03T14:10:32.396Z,1570111832.396 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0
2019-10-03T14:10:32.397Z,1570111832.397 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894
2019-10-03T14:10:32.411Z,1570111832.411 [PAR_Licor] Loaded
2019-10-03T14:10:32.412Z,1570111832.412 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-03T14:10:32.418Z,1570111832.418 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2019-10-03T14:10:32.418Z,1570111832.418 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2019-10-03T14:10:32.423Z,1570111832.423 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2019-10-03T14:10:32.423Z,1570111832.423 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2019-10-03T14:10:32.427Z,1570111832.427 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2019-10-03T14:10:32.427Z,1570111832.427 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2019-10-03T14:10:32.432Z,1570111832.432 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2019-10-03T14:10:32.432Z,1570111832.432 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2019-10-03T14:10:32.436Z,1570111832.436 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2019-10-03T14:10:32.436Z,1570111832.436 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2019-10-03T14:10:32.441Z,1570111832.441 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2019-10-03T14:10:32.441Z,1570111832.441 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2019-10-03T14:10:32.445Z,1570111832.445 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2019-10-03T14:10:32.445Z,1570111832.445 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2019-10-03T14:10:32.449Z,1570111832.449 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.454Z,1570111832.454 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.454Z,1570111832.454 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.455Z,1570111832.455 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.459Z,1570111832.459 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.459Z,1570111832.459 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.463Z,1570111832.463 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.463Z,1570111832.463 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-03T14:10:32.468Z,1570111832.468 [WetLabsBB2FL] Loaded
2019-10-03T14:10:32.468Z,1570111832.468 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-03T14:10:32.469Z,1570111832.469 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0
2019-10-03T14:10:32.469Z,1570111832.469 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895
2019-10-03T14:10:32.705Z,1570111832.705 [WetLabsUBAT..SerialDriver](INFO): Created logger
2019-10-03T14:10:32.706Z,1570111832.706 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2019-10-03T14:10:32.706Z,1570111832.706 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2019-10-03T14:10:32.711Z,1570111832.711 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2019-10-03T14:10:32.711Z,1570111832.711 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2019-10-03T14:10:32.712Z,1570111832.712 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2019-10-03T14:10:32.716Z,1570111832.716 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2019-10-03T14:10:32.716Z,1570111832.716 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2019-10-03T14:10:32.721Z,1570111832.721 [WetLabsUBAT] Loaded
2019-10-03T14:10:32.721Z,1570111832.721 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2019-10-03T14:10:32.722Z,1570111832.722 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-10-03T14:10:32.722Z,1570111832.722 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 896
2019-10-03T14:10:32.723Z,1570111832.723 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-03T14:10:32.726Z,1570111832.726 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-03T14:10:32.727Z,1570111832.727 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-03T14:10:32.734Z,1570111832.734 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-03T14:10:32.735Z,1570111832.735 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B254E0
2019-10-03T14:10:32.735Z,1570111832.735 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 897
2019-10-03T14:10:32.740Z,1570111832.740 [Supervisor](INFO): Main Thread ID is 803
2019-10-03T14:10:32.740Z,1570111832.740 [Supervisor](DEBUG): Running supervisor.
2019-10-03T14:10:32.741Z,1570111832.741 [CommandLine ThreadHandler](INFO): Handler Thread ID is 898
2019-10-03T14:10:32.744Z,1570111832.744 [controlThread ThreadHandler](INFO): Handler Thread ID is 899
2019-10-03T14:10:32.744Z,1570111832.744 [controlThread](DEBUG): Initializing ControlThread
2019-10-03T14:10:32.746Z,1570111832.746 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-03T14:10:32.747Z,1570111832.747 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-03T14:10:32.748Z,1570111832.748 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-03T14:10:32.748Z,1570111832.748 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-03T14:10:32.749Z,1570111832.749 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-03T14:10:32.749Z,1570111832.749 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-03T14:10:32.750Z,1570111832.750 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-03T14:10:32.750Z,1570111832.750 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-03T14:10:32.750Z,1570111832.750 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-03T14:10:32.751Z,1570111832.751 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-03T14:10:32.755Z,1570111832.755 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-03T14:10:32.755Z,1570111832.755 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-03T14:10:32.755Z,1570111832.755 [SBIT](INFO): Initialize SBIT Component.
2019-10-03T14:10:32.756Z,1570111832.756 [SBIT](IMPORTANT): git: 2019-10-03_bsd
2019-10-03T14:10:32.756Z,1570111832.756 [SBIT](INFO): git hash: c0737ec0000e1c9d4bf2eea0c11ce458bb27ad8a
2019-10-03T14:10:32.756Z,1570111832.756 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-03T14:10:32.757Z,1570111832.757 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-03T14:10:32.758Z,1570111832.758 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-10-03T14:10:32.758Z,1570111832.758 [IBIT](INFO): Initialize IBIT Component.
2019-10-03T14:10:32.759Z,1570111832.759 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-03T14:10:32.760Z,1570111832.760 [logger ThreadHandler](INFO): Handler Thread ID is 900
2019-10-03T14:10:32.773Z,1570111832.773 [CBIT](DEBUG): Initialized mux pins.
2019-10-03T14:10:32.773Z,1570111832.773 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-03T14:10:32.777Z,1570111832.777 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 901
2019-10-03T14:10:32.778Z,1570111832.778 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-03T14:10:32.785Z,1570111832.785 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 902
2019-10-03T14:10:32.793Z,1570111832.793 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 903
2019-10-03T14:10:32.797Z,1570111832.797 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-03T14:10:32.797Z,1570111832.797 [CBIT](DEBUG): Initializing heartbeat.
2019-10-03T14:10:32.805Z,1570111832.805 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 904
2019-10-03T14:10:32.806Z,1570111832.806 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-03T14:10:32.809Z,1570111832.809 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-03T14:10:32.811Z,1570111832.811 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 906
2019-10-03T14:10:32.812Z,1570111832.812 [WetLabsBB2FL](INFO): Powering down
2019-10-03T14:10:32.845Z,1570111832.845 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 907
2019-10-03T14:10:32.857Z,1570111832.857 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 908
2019-10-03T14:10:32.867Z,1570111832.867 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-03T14:10:32.867Z,1570111832.867 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-03T14:10:32.867Z,1570111832.867 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-03T14:10:32.868Z,1570111832.868 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-03T14:10:32.868Z,1570111832.868 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-03T14:10:32.868Z,1570111832.868 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-03T14:10:32.868Z,1570111832.868 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-03T14:10:32.868Z,1570111832.868 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-03T14:10:32.869Z,1570111832.869 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-03T14:10:32.869Z,1570111832.869 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-03T14:10:32.869Z,1570111832.869 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-03T14:10:32.869Z,1570111832.869 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-03T14:10:32.869Z,1570111832.869 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-03T14:10:32.870Z,1570111832.870 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-03T14:10:32.870Z,1570111832.870 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-03T14:10:32.870Z,1570111832.870 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-03T14:10:32.870Z,1570111832.870 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-03T14:10:32.870Z,1570111832.870 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-03T14:10:32.905Z,1570111832.905 [CBIT](DEBUG): Backplane powered.
2019-10-03T14:10:32.910Z,1570111832.910 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-03T14:10:32.946Z,1570111832.946 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-03T14:10:32.963Z,1570111832.963 [MissionManager](DEBUG):
2019-10-03T14:10:32.964Z,1570111832.964 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-03T14:10:33.034Z,1570111833.034 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-03T14:10:33.035Z,1570111833.035 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-03T14:10:33.049Z,1570111833.049 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-03T14:10:33.073Z,1570111833.073 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-03T14:10:33.075Z,1570111833.075 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-03T14:10:33.103Z,1570111833.103 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-03T14:10:33.122Z,1570111833.122 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-10-03T14:10:33.126Z,1570111833.126 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-03T14:10:33.189Z,1570111833.189 [Radio_Surface](INFO): Powering up
2019-10-03T14:10:33.365Z,1570111833.365 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-03T14:10:33.365Z,1570111833.365 [DAT](INFO): Powering up
2019-10-03T14:10:33.366Z,1570111833.366 [DAT](DEBUG): Initializing DAT.
2019-10-03T14:10:33.445Z,1570111833.445 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-03T14:10:33.708Z,1570111833.708 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:10:33.713Z,1570111833.713 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-03T14:10:33.714Z,1570111833.714 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:10:33.721Z,1570111833.721 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-03T14:10:33.722Z,1570111833.722 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:10:33.729Z,1570111833.729 [MassServo](DEBUG): Initializing MassServo.
2019-10-03T14:10:33.730Z,1570111833.730 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:10:33.737Z,1570111833.737 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-03T14:10:33.738Z,1570111833.738 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-03T14:10:33.745Z,1570111833.745 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-03T14:10:39.838Z,1570111839.838 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-10-03T14:10:47.514Z,1570111847.514 [DAT](INFO): setting local address to 4
2019-10-03T14:10:47.917Z,1570111847.917 [DAT](INFO): set local address to 4
2019-10-03T14:10:59.214Z,1570111859.214 [NAL9602](INFO): Powering up NAL9602
2019-10-03T14:11:10.123Z,1570111870.123 [NAL9602](INFO): NAL9602 initialized
2019-10-03T14:11:10.937Z,1570111870.937 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:11:32.375Z,1570111892.375 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-03T14:11:32.378Z,1570111892.378 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-03T14:11:43.453Z,1570111903.453 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.002101
CHAN A1 (24V): 0.032304
CHAN A2 (12V): -0.008106
CHAN A3 (5V): -0.001639
CHAN B0 (3.3V): 0.000558
CHAN B1 (3.15aV): -0.000129
CHAN B2 (3.15bV): -0.000182
CHAN B3 (GND): 0.001956
OPEN: -0.004032
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-03T14:12:25.639Z,1570111945.639 [SBIT](IMPORTANT): SBIT PASSED
2019-10-03T14:12:25.693Z,1570111945.693 [CommandLine](IMPORTANT): got command configSet list
2019-10-03T14:12:25.693Z,1570111945.693 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-03T14:12:25.694Z,1570111945.694 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;
2019-10-03T14:12:25.694Z,1570111945.694 [CommandLine](IMPORTANT): BackSeatDriver.verbosity=0 count;
2019-10-03T14:12:25.694Z,1570111945.694 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere;
2019-10-03T14:12:25.694Z,1570111945.694 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): Express none WetLabsUBAT.biolum_potential;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): Express none WetLabsUBAT.flow_rate;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;
2019-10-03T14:12:25.695Z,1570111945.695 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter;
2019-10-03T14:12:25.696Z,1570111945.696 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;
2019-10-03T14:12:26.035Z,1570111946.035 [MissionManager](IMPORTANT): Started mission Startup
2019-10-03T14:12:26.035Z,1570111946.035 [Startup] Running Loop=1
2019-10-03T14:12:26.035Z,1570111946.035 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-03T14:12:26.035Z,1570111946.035 [Startup:A.GoToSurface] Running Loop=1
2019-10-03T14:12:26.035Z,1570111946.035 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-03T14:12:26.036Z,1570111946.036 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-03T14:12:26.036Z,1570111946.036 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-03T14:12:26.036Z,1570111946.036 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-03T14:12:26.038Z,1570111946.038 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-03T14:12:26.039Z,1570111946.039 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-03T14:12:26.040Z,1570111946.040 [Startup:StartupSatComms] Running Loop=1
2019-10-03T14:12:26.040Z,1570111946.040 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-03T14:12:26.040Z,1570111946.040 [Startup:StartupSatComms:A] Running Loop=1
2019-10-03T14:12:26.459Z,1570111946.459 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-03T14:12:32.920Z,1570111952.920 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:12:32.920Z,1570111952.920 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-03T14:12:32.920Z,1570111952.920 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:12:32.931Z,1570111952.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:12:33.314Z,1570111953.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:12:33.314Z,1570111953.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-03T14:13:18.607Z,1570111998.607 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005083
2019-10-03T14:13:26.241Z,1570112006.241 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-03T14:12:26.0Z
2019-10-03T14:13:26.241Z,1570112006.241 [Startup:StartupSatComms:A] Stopped
2019-10-03T14:13:26.241Z,1570112006.241 [Startup:StartupSatComms:B] Running Loop=1
2019-10-03T14:13:26.635Z,1570112006.635 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-03T14:13:33.929Z,1570112013.929 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191003T080344/Courier0019.lzma
2019-10-03T14:13:35.934Z,1570112015.934 [DataOverHttps](INFO): Moved sent file to Logs/20191003T080344/Courier0019.lzma.bak
2019-10-03T14:13:35.935Z,1570112015.935 [DataOverHttps](INFO): SBD MOMSN=11844564
2019-10-03T14:13:52.696Z,1570112032.696 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191003T080344/Express0020.lzma
2019-10-03T14:13:54.702Z,1570112034.702 [DataOverHttps](INFO): Moved sent file to Logs/20191003T080344/Express0020.lzma.bak
2019-10-03T14:13:54.703Z,1570112034.703 [DataOverHttps](INFO): SBD MOMSN=11844566
2019-10-03T14:14:11.279Z,1570112051.279 [DataOverHttps](INFO): Sending 1055 bytes from file Logs/20191003T141021/Express0001.lzma
2019-10-03T14:14:13.282Z,1570112053.282 [DataOverHttps](INFO): Moved sent file to Logs/20191003T141021/Express0001.lzma.bak
2019-10-03T14:14:13.282Z,1570112053.282 [DataOverHttps](INFO): SBD MOMSN=11844568
2019-10-03T14:14:15.108Z,1570112055.108 [Startup:StartupSatComms:B] Stopped
2019-10-03T14:14:15.108Z,1570112055.108 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-03T14:14:15.108Z,1570112055.108 [Startup:StartupSatComms] Stopped
2019-10-03T14:14:15.108Z,1570112055.108 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-03T14:14:15.109Z,1570112055.109 [Startup](INFO): Completed Startup
2019-10-03T14:14:15.109Z,1570112055.109 [MissionManager](INFO): Startup is completed.
2019-10-03T14:14:15.109Z,1570112055.109 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-03T14:14:15.109Z,1570112055.109 [Startup] Stopped
2019-10-03T14:14:15.109Z,1570112055.109 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-03T14:14:15.110Z,1570112055.110 [Startup:A.GoToSurface] Stopped
2019-10-03T14:14:15.110Z,1570112055.110 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-03T14:14:15.518Z,1570112055.518 [MissionManager](IMPORTANT): Started mission Default
2019-10-03T14:14:15.518Z,1570112055.518 [Default] Running Loop=1
2019-10-03T14:14:15.518Z,1570112055.518 [Default](DEBUG): Aggregate::initialize Default
2019-10-03T14:14:15.518Z,1570112055.518 [Default:B.GoToSurface] Running Loop=1
2019-10-03T14:14:15.518Z,1570112055.518 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-03T14:14:15.519Z,1570112055.519 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-03T14:14:15.519Z,1570112055.519 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-03T14:14:15.519Z,1570112055.519 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-03T14:14:15.519Z,1570112055.519 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-03T14:14:15.520Z,1570112055.520 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-03T14:14:15.520Z,1570112055.520 [Default:A.Wait] Running Loop=1
2019-10-03T14:14:15.520Z,1570112055.520 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-03T14:14:28.851Z,1570112068.851 [Default:A.Wait](INFO): Done Waiting.
2019-10-03T14:14:28.851Z,1570112068.851 [Default:A.Wait] Stopped
2019-10-03T14:14:28.851Z,1570112068.851 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T14:14:29.262Z,1570112069.262 [Default:CheckIn] Running Loop=1
2019-10-03T14:14:29.263Z,1570112069.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T14:14:29.263Z,1570112069.263 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T14:14:29.658Z,1570112069.658 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-03T14:14:33.716Z,1570112073.716 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:14:33.716Z,1570112073.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-03T14:14:33.716Z,1570112073.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:14:33.726Z,1570112073.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:14:34.110Z,1570112074.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:14:34.110Z,1570112074.110 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-03T14:16:13.462Z,1570112173.462 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-03T14:16:14.285Z,1570112174.285 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:16.705Z,1570112176.705 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:19.933Z,1570112179.933 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:22.761Z,1570112182.761 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:26.001Z,1570112186.001 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:28.821Z,1570112188.821 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:32.053Z,1570112192.053 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:34.885Z,1570112194.885 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:34.909Z,1570112194.909 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:16:34.909Z,1570112194.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-03T14:16:34.909Z,1570112194.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:16:34.919Z,1570112194.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:16:35.330Z,1570112195.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:16:35.330Z,1570112195.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-03T14:16:37.713Z,1570112197.713 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:39.733Z,1570112199.733 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:42.965Z,1570112202.965 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:45.793Z,1570112205.793 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:49.029Z,1570112209.029 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:51.849Z,1570112211.849 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:55.081Z,1570112215.081 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:16:57.913Z,1570112217.913 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:00.745Z,1570112220.745 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:03.973Z,1570112223.973 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:06.797Z,1570112226.797 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:10.037Z,1570112230.037 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:12.861Z,1570112232.861 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:16.089Z,1570112236.089 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:18.921Z,1570112238.921 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:21.745Z,1570112241.745 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:24.981Z,1570112244.981 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:27.809Z,1570112247.809 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:31.041Z,1570112251.041 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:33.869Z,1570112253.869 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:36.701Z,1570112256.701 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:39.933Z,1570112259.933 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:42.757Z,1570112262.757 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:45.989Z,1570112265.989 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:48.820Z,1570112268.820 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:52.045Z,1570112272.045 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:54.877Z,1570112274.877 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:17:57.705Z,1570112277.705 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:00.937Z,1570112280.937 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:03.765Z,1570112283.765 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:06.997Z,1570112286.997 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:09.825Z,1570112289.825 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:13.057Z,1570112293.057 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:15.881Z,1570112295.881 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:18.713Z,1570112298.713 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:21.945Z,1570112301.945 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:24.773Z,1570112304.773 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:28.005Z,1570112308.005 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:30.829Z,1570112310.829 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:34.069Z,1570112314.069 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:36.101Z,1570112316.101 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:18:36.101Z,1570112316.101 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-10-03T14:18:36.101Z,1570112316.101 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:18:36.111Z,1570112316.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:18:36.521Z,1570112316.521 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:18:36.521Z,1570112316.521 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-10-03T14:18:36.889Z,1570112316.889 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:39.721Z,1570112319.721 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:42.949Z,1570112322.949 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:45.781Z,1570112325.781 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:49.009Z,1570112329.009 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:51.845Z,1570112331.845 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:55.073Z,1570112335.073 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:18:57.901Z,1570112337.901 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:00.725Z,1570112340.725 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:03.961Z,1570112343.961 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:04.809Z,1570112344.809 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-03T14:19:04.812Z,1570112344.812 [BPC1](INFO): Received data from all battery sticks.
2019-10-03T14:19:06.793Z,1570112346.793 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:10.021Z,1570112350.021 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:12.853Z,1570112352.853 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:16.081Z,1570112356.081 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:18.909Z,1570112358.909 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:21.737Z,1570112361.737 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:24.969Z,1570112364.969 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:27.797Z,1570112367.797 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:29.422Z,1570112369.422 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-03T14:14:29.3Z
2019-10-03T14:19:29.423Z,1570112369.423 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T14:19:29.423Z,1570112369.423 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-03T14:19:29.835Z,1570112369.835 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-03T14:19:31.033Z,1570112371.033 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:33.857Z,1570112373.857 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:35.977Z,1570112375.977 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20191003T141021/Courier0004.lzma
2019-10-03T14:19:36.685Z,1570112376.685 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:37.982Z,1570112377.982 [DataOverHttps](INFO): Moved sent file to Logs/20191003T141021/Courier0004.lzma.bak
2019-10-03T14:19:37.982Z,1570112377.982 [DataOverHttps](INFO): SBD MOMSN=11844604
2019-10-03T14:19:39.921Z,1570112379.921 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:42.748Z,1570112382.748 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:45.979Z,1570112385.979 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:48.809Z,1570112388.809 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:52.037Z,1570112392.037 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:54.298Z,1570112394.298 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20191003T141021/Express0005.lzma
2019-10-03T14:19:54.869Z,1570112394.869 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:56.302Z,1570112396.302 [DataOverHttps](INFO): Moved sent file to Logs/20191003T141021/Express0005.lzma.bak
2019-10-03T14:19:56.303Z,1570112396.303 [DataOverHttps](INFO): SBD MOMSN=11844606
2019-10-03T14:19:57.701Z,1570112397.701 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:19:58.130Z,1570112398.130 [Default:CheckIn:Read_Iridium] Stopped
2019-10-03T14:19:58.130Z,1570112398.130 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-03T14:19:58.130Z,1570112398.130 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-03T14:20:00.929Z,1570112400.929 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:03.757Z,1570112403.757 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:06.985Z,1570112406.985 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:09.813Z,1570112409.813 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:13.053Z,1570112413.053 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:15.877Z,1570112415.877 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:18.701Z,1570112418.701 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:21.937Z,1570112421.937 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:24.765Z,1570112424.765 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:27.997Z,1570112427.997 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:30.825Z,1570112430.825 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:34.057Z,1570112434.057 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:36.885Z,1570112436.885 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:36.916Z,1570112436.916 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:20:36.916Z,1570112436.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-10-03T14:20:36.916Z,1570112436.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:20:36.966Z,1570112436.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:20:37.363Z,1570112437.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:20:37.364Z,1570112437.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-10-03T14:20:39.713Z,1570112439.713 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:42.945Z,1570112442.945 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:45.774Z,1570112445.774 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:49.001Z,1570112449.001 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:51.833Z,1570112451.833 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:55.065Z,1570112455.065 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:20:57.895Z,1570112457.895 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:21:00.725Z,1570112460.725 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:21:03.953Z,1570112463.953 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:21:06.777Z,1570112466.777 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:21:10.017Z,1570112470.017 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:21:12.832Z,1570112472.832 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-03T14:21:12.832Z,1570112472.832 [NAL9602] Data Fault, FailCount= 1
2019-10-03T14:21:12.832Z,1570112472.832 [NAL9602](ERROR): Data Fault
2019-10-03T14:21:12.878Z,1570112472.878 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-03T14:21:13.230Z,1570112473.230 [NAL9602](INFO): Powering down
2019-10-03T14:21:14.110Z,1570112474.110 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-03T14:21:14.110Z,1570112474.110 [NAL9602] No Fault, FailCount= 1
2019-10-03T14:21:43.534Z,1570112503.534 [NAL9602](INFO): Powering up NAL9602
2019-10-03T14:21:54.440Z,1570112514.440 [NAL9602](INFO): NAL9602 initialized
2019-10-03T14:21:55.261Z,1570112515.261 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:21:58.093Z,1570112518.093 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:01.325Z,1570112521.325 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:04.149Z,1570112524.149 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:06.977Z,1570112526.977 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:08.993Z,1570112528.993 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:12.229Z,1570112532.229 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:15.057Z,1570112535.057 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:18.289Z,1570112538.289 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:21.117Z,1570112541.117 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:24.349Z,1570112544.349 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:27.173Z,1570112547.173 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:30.005Z,1570112550.005 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:33.237Z,1570112553.237 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:36.065Z,1570112556.065 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:38.098Z,1570112558.098 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:22:38.098Z,1570112558.098 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-10-03T14:22:38.098Z,1570112558.098 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:22:38.109Z,1570112558.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:22:38.523Z,1570112558.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:22:38.523Z,1570112558.523 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-10-03T14:22:39.293Z,1570112559.293 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:42.121Z,1570112562.121 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:45.353Z,1570112565.353 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:48.185Z,1570112568.185 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:51.017Z,1570112571.017 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:54.249Z,1570112574.249 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:22:57.073Z,1570112577.073 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:00.301Z,1570112580.301 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:03.137Z,1570112583.137 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:05.957Z,1570112585.957 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:07.981Z,1570112587.981 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:10.001Z,1570112590.001 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:13.233Z,1570112593.233 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:16.061Z,1570112596.061 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:19.289Z,1570112599.289 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:22.117Z,1570112602.117 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:25.357Z,1570112605.357 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:28.589Z,1570112608.589 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:31.413Z,1570112611.413 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:34.645Z,1570112614.645 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:37.469Z,1570112617.469 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:40.305Z,1570112620.305 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:42.325Z,1570112622.325 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:44.349Z,1570112624.349 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:47.581Z,1570112627.581 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:50.405Z,1570112630.405 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:53.633Z,1570112633.633 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:56.469Z,1570112636.469 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:23:59.701Z,1570112639.701 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:02.529Z,1570112642.529 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:05.757Z,1570112645.757 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:08.585Z,1570112648.585 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:11.817Z,1570112651.817 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:14.645Z,1570112654.645 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:17.469Z,1570112657.469 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:20.301Z,1570112660.301 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:22.321Z,1570112662.321 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:25.553Z,1570112665.553 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:28.381Z,1570112668.381 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:31.617Z,1570112671.617 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:34.441Z,1570112674.441 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:37.269Z,1570112677.269 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:39.289Z,1570112679.289 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:39.309Z,1570112679.309 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-03T14:24:39.309Z,1570112679.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-10-03T14:24:39.310Z,1570112679.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-03T14:24:39.340Z,1570112679.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-03T14:24:39.724Z,1570112679.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-03T14:24:39.724Z,1570112679.724 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-10-03T14:24:41.713Z,1570112681.713 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:44.541Z,1570112684.541 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:47.773Z,1570112687.773 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:50.601Z,1570112690.601 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:53.433Z,1570112693.433 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:56.257Z,1570112696.257 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:58.277Z,1570112698.277 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:24:58.710Z,1570112698.710 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-03T14:24:58.710Z,1570112698.710 [Default:CheckIn:C.Wait] Stopped
2019-10-03T14:24:58.710Z,1570112698.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-03T14:24:58.711Z,1570112698.711 [Default:CheckIn:D] Running Loop=1
2019-10-03T14:24:59.110Z,1570112699.110 [Default:CheckIn:D] Stopped
2019-10-03T14:24:59.110Z,1570112699.110 [Default:CheckIn:E] Running Loop=1
2019-10-03T14:24:59.506Z,1570112699.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.726539 min
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn:E] Stopped
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn] Stopped
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn](INFO): Running loop #2
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn] Running Loop=2
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-03T14:24:59.507Z,1570112699.507 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-03T14:25:00.297Z,1570112700.297 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:03.529Z,1570112703.529 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:06.357Z,1570112706.357 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:09.585Z,1570112709.585 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:12.413Z,1570112712.413 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:15.649Z,1570112715.649 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:18.473Z,1570112718.473 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:20.411Z,1570112720.411 [CommandLine](IMPORTANT): got command restart system
2019-10-03T14:25:21.313Z,1570112721.313 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:22.473Z,1570112722.473 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-10-03T14:25:22.473Z,1570112722.473 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:22.474Z,1570112722.474 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:22.517Z,1570112722.517 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-10-03T14:25:22.518Z,1570112722.518 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:22.518Z,1570112722.518 [CommandLine](INFO): Join timeout helper Thread ID is 951
2019-10-03T14:25:22.519Z,1570112722.519 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-03T14:25:22.519Z,1570112722.519 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:22.520Z,1570112722.520 [NavChartDb](INFO): Join timeout helper Thread ID is 952
2019-10-03T14:25:22.669Z,1570112722.669 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:22.669Z,1570112722.669 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:22.677Z,1570112722.677 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2019-10-03T14:25:22.677Z,1570112722.677 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:22.677Z,1570112722.677 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 953
2019-10-03T14:25:23.049Z,1570112723.049 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:23.049Z,1570112723.049 [WetLabsUBAT](INFO): Powering down
2019-10-03T14:25:23.067Z,1570112723.067 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.081Z,1570112723.081 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-03T14:25:23.081Z,1570112723.081 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.081Z,1570112723.081 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 954
2019-10-03T14:25:23.189Z,1570112723.189 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:23.189Z,1570112723.189 [WetLabsBB2FL](INFO): Powering down
2019-10-03T14:25:23.190Z,1570112723.190 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.193Z,1570112723.193 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-03T14:25:23.193Z,1570112723.193 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.193Z,1570112723.193 [CTD_Seabird](INFO): Join timeout helper Thread ID is 955
2019-10-03T14:25:23.501Z,1570112723.501 [CTD_Seabird](INFO): Powering down
2019-10-03T14:25:23.517Z,1570112723.517 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:23.517Z,1570112723.517 [CTD_Seabird](INFO): Powering down
2019-10-03T14:25:23.529Z,1570112723.529 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.538Z,1570112723.538 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2019-10-03T14:25:23.538Z,1570112723.538 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.538Z,1570112723.538 [BackSeatDriver](INFO): Join timeout helper Thread ID is 956
2019-10-03T14:25:23.613Z,1570112723.613 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:23.613Z,1570112723.613 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.618Z,1570112723.618 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-03T14:25:23.618Z,1570112723.618 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.618Z,1570112723.618 [Radio_Surface](INFO): Join timeout helper Thread ID is 957
2019-10-03T14:25:23.637Z,1570112723.637 [Radio_Surface](INFO): Powering down
2019-10-03T14:25:23.638Z,1570112723.638 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:23.638Z,1570112723.638 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.646Z,1570112723.646 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-03T14:25:23.646Z,1570112723.646 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:23.646Z,1570112723.646 [DataOverHttps](INFO): Join timeout helper Thread ID is 958
2019-10-03T14:25:23.725Z,1570112723.725 [NAL9602](DEBUG): Fix Requested
2019-10-03T14:25:24.093Z,1570112724.093 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:24.099Z,1570112724.099 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.110Z,1570112724.110 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-03T14:25:24.110Z,1570112724.110 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.110Z,1570112724.110 [logger](INFO): Join timeout helper Thread ID is 959
2019-10-03T14:25:24.137Z,1570112724.137 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:24.138Z,1570112724.138 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.150Z,1570112724.150 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-03T14:25:24.150Z,1570112724.150 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.150Z,1570112724.150 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-03T14:25:24.150Z,1570112724.150 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.150Z,1570112724.150 [controlThread](INFO): Join timeout helper Thread ID is 960
2019-10-03T14:25:24.167Z,1570112724.167 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-03T14:25:24.167Z,1570112724.167 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-03T14:25:24.168Z,1570112724.168 [NAL9602](INFO): Powering down
2019-10-03T14:25:24.169Z,1570112724.169 [DAT](INFO): Powering down
2019-10-03T14:25:24.290Z,1570112724.290 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-03T14:25:24.291Z,1570112724.291 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-03T14:25:24.291Z,1570112724.291 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-03T14:25:24.292Z,1570112724.292 [MissionManager](INFO): Uninitializing Mission Default
2019-10-03T14:25:24.292Z,1570112724.292 [Default] Stopped
2019-10-03T14:25:24.292Z,1570112724.292 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-03T14:25:24.292Z,1570112724.292 [Default:B.GoToSurface] Stopped
2019-10-03T14:25:24.292Z,1570112724.292 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-03T14:25:24.292Z,1570112724.292 [Default:CheckIn] Stopped
2019-10-03T14:25:24.292Z,1570112724.292 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-03T14:25:24.292Z,1570112724.292 [Default:CheckIn:Read_GPS] Stopped
2019-10-03T14:25:24.295Z,1570112724.295 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-03T14:25:24.295Z,1570112724.295 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-03T14:25:24.295Z,1570112724.295 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-03T14:25:24.296Z,1570112724.296 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-03T14:25:24.296Z,1570112724.296 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-03T14:25:24.296Z,1570112724.296 [BuoyancyServo](INFO): Powering down
2019-10-03T14:25:24.309Z,1570112724.309 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-03T14:25:24.309Z,1570112724.309 [ElevatorServo](INFO): Powering down
2019-10-03T14:25:24.310Z,1570112724.310 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-03T14:25:24.310Z,1570112724.310 [MassServo](INFO): Powering down
2019-10-03T14:25:24.311Z,1570112724.311 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-03T14:25:24.311Z,1570112724.311 [RudderServo](INFO): Powering down
2019-10-03T14:25:24.312Z,1570112724.312 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-03T14:25:24.312Z,1570112724.312 [ThrusterServo](INFO): Powering down
2019-10-03T14:25:24.313Z,1570112724.313 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-03T14:25:24.313Z,1570112724.313 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-03T14:25:24.313Z,1570112724.313 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-03T14:25:24.313Z,1570112724.313 [CBIT](DEBUG): Powering off loads.
2019-10-03T14:25:24.325Z,1570112724.325 [CBIT](DEBUG): Disabling WDT.
2019-10-03T14:25:24.337Z,1570112724.337 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-03T14:25:24.337Z,1570112724.337 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.432Z,1570112724.432 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.438Z,1570112724.438 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.488Z,1570112724.488 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.490Z,1570112724.490 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2019-10-03T14:25:24.490Z,1570112724.490 [WetLabsUBAT](INFO): WetLabs destructor: done
2019-10-03T14:25:24.497Z,1570112724.497 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.501Z,1570112724.501 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.504Z,1570112724.504 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.512Z,1570112724.512 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-03T14:25:24.573Z,1570112724.573 [logger ThreadHandler](INFO): Thread cancelled.