2020-11-06T22:01:44.744Z,1604700104.744 [Supervisor](DEBUG): Initializing supervisor.
2020-11-06T22:01:44.747Z,1604700104.747 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-11-06T22:01:44.747Z,1604700104.747 [SyncHandler](INFO): Protected caller Thread ID is 936
2020-11-06T22:01:44.748Z,1604700104.748 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-11-06T22:01:44.749Z,1604700104.749 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-11-06T22:01:44.749Z,1604700104.749 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 937
2020-11-06T22:01:44.752Z,1604700104.752 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-11-06T22:01:44.769Z,1604700104.769 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-11-06T22:01:44.770Z,1604700104.770 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-11-06T22:01:44.770Z,1604700104.770 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 938
2020-11-06T22:01:44.771Z,1604700104.771 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-11-06T22:01:44.772Z,1604700104.772 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-11-06T22:01:44.772Z,1604700104.772 [logger ThreadHandler](INFO): Protected caller Thread ID is 939
2020-11-06T22:01:44.774Z,1604700104.774 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-11-06T22:01:44.774Z,1604700104.774 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-11-06T22:01:44.776Z,1604700104.776 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-11-06T22:01:44.873Z,1604700104.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-11-06T22:01:44.874Z,1604700104.874 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-11-06T22:01:45.487Z,1604700105.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-11-06T22:01:45.488Z,1604700105.488 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-11-06T22:01:45.697Z,1604700105.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-11-06T22:01:45.698Z,1604700105.698 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-11-06T22:01:45.801Z,1604700105.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-11-06T22:01:45.801Z,1604700105.801 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-11-06T22:01:45.911Z,1604700105.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-11-06T22:01:45.912Z,1604700105.912 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-11-06T22:01:45.997Z,1604700105.997 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-11-06T22:01:46.139Z,1604700106.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-11-06T22:01:46.140Z,1604700106.140 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-11-06T22:01:46.442Z,1604700106.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-11-06T22:01:46.443Z,1604700106.443 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-11-06T22:01:47.040Z,1604700107.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-11-06T22:01:47.041Z,1604700107.041 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-11-06T22:01:47.189Z,1604700107.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-11-06T22:01:47.189Z,1604700107.189 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-11-06T22:01:47.389Z,1604700107.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-11-06T22:01:47.390Z,1604700107.390 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-11-06T22:01:47.892Z,1604700107.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-11-06T22:01:47.892Z,1604700107.892 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-11-06T22:01:48.113Z,1604700108.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-11-06T22:01:48.114Z,1604700108.114 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-11-06T22:01:48.318Z,1604700108.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-11-06T22:01:48.319Z,1604700108.319 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-11-06T22:01:48.736Z,1604700108.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-11-06T22:01:48.737Z,1604700108.737 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-11-06T22:01:49.071Z,1604700109.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-11-06T22:01:49.073Z,1604700109.073 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-11-06T22:01:49.074Z,1604700109.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-11-06T22:01:49.159Z,1604700109.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-11-06T22:01:49.319Z,1604700109.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-11-06T22:01:49.430Z,1604700109.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-11-06T22:01:49.517Z,1604700109.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-11-06T22:01:49.614Z,1604700109.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-11-06T22:01:49.829Z,1604700109.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-11-06T22:01:50.459Z,1604700110.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-11-06T22:01:50.460Z,1604700110.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-11-06T22:01:50.749Z,1604700110.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-11-06T22:01:50.850Z,1604700110.850 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-11-06T22:01:50.984Z,1604700110.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-11-06T22:01:51.087Z,1604700111.087 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-11-06T22:01:51.087Z,1604700111.087 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-11-06T22:01:51.101Z,1604700111.101 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-11-06T22:01:51.179Z,1604700111.179 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-11-06T22:01:51.180Z,1604700111.180 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-11-06T22:01:51.226Z,1604700111.226 [VerticalControl](DEBUG): Construct VerticalControl.
2020-11-06T22:01:51.284Z,1604700111.284 [VerticalControl] Loaded
2020-11-06T22:01:51.285Z,1604700111.285 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-11-06T22:01:51.285Z,1604700111.285 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-11-06T22:01:51.322Z,1604700111.322 [HorizontalControl] Loaded
2020-11-06T22:01:51.322Z,1604700111.322 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-11-06T22:01:51.322Z,1604700111.322 [SpeedControl](DEBUG): Construct SpeedControl.
2020-11-06T22:01:51.325Z,1604700111.325 [SpeedControl] Loaded
2020-11-06T22:01:51.326Z,1604700111.326 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-11-06T22:01:51.326Z,1604700111.326 [LoopControl](DEBUG): Construct LoopControl.
2020-11-06T22:01:51.327Z,1604700111.327 [LoopControl] Loaded
2020-11-06T22:01:51.327Z,1604700111.327 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-11-06T22:01:51.328Z,1604700111.328 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-11-06T22:01:51.328Z,1604700111.328 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-11-06T22:01:51.366Z,1604700111.366 [DepthRateCalculator] Loaded
2020-11-06T22:01:51.367Z,1604700111.367 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-11-06T22:01:51.369Z,1604700111.369 [PitchRateCalculator] Loaded
2020-11-06T22:01:51.370Z,1604700111.370 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-11-06T22:01:51.378Z,1604700111.378 [SpeedCalculator] Loaded
2020-11-06T22:01:51.378Z,1604700111.378 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-11-06T22:01:51.389Z,1604700111.389 [TempGradientCalculator] Loaded
2020-11-06T22:01:51.389Z,1604700111.389 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-11-06T22:01:51.391Z,1604700111.391 [YawRateCalculator] Loaded
2020-11-06T22:01:51.392Z,1604700111.392 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-11-06T22:01:51.406Z,1604700111.406 [ElevatorOffsetCalculator] Loaded
2020-11-06T22:01:51.406Z,1604700111.406 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-11-06T22:01:51.407Z,1604700111.407 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-11-06T22:01:51.407Z,1604700111.407 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-11-06T22:01:51.433Z,1604700111.433 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-11-06T22:01:51.433Z,1604700111.433 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-11-06T22:01:51.515Z,1604700111.515 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-11-06T22:01:51.516Z,1604700111.516 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-11-06T22:01:51.799Z,1604700111.799 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-11-06T22:01:51.799Z,1604700111.799 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-11-06T22:01:51.894Z,1604700111.894 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-11-06T22:01:51.895Z,1604700111.895 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-11-06T22:01:52.193Z,1604700112.193 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-11-06T22:01:52.196Z,1604700112.196 [AHRS_M2](INFO): created writer for : platform_orientation
2020-11-06T22:01:52.197Z,1604700112.197 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-11-06T22:01:52.200Z,1604700112.200 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-11-06T22:01:52.201Z,1604700112.201 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-11-06T22:01:52.203Z,1604700112.203 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-11-06T22:01:52.204Z,1604700112.204 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-11-06T22:01:52.207Z,1604700112.207 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-11-06T22:01:52.243Z,1604700112.243 [AHRS_M2] Loaded
2020-11-06T22:01:52.244Z,1604700112.244 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-11-06T22:01:52.317Z,1604700112.317 [DataOverHttps] Loaded
2020-11-06T22:01:52.317Z,1604700112.317 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-11-06T22:01:52.318Z,1604700112.318 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2020-11-06T22:01:52.318Z,1604700112.318 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1022
2020-11-06T22:01:52.335Z,1604700112.335 [Depth_Keller] Loaded
2020-11-06T22:01:52.335Z,1604700112.335 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-11-06T22:01:52.338Z,1604700112.338 [DropWeight] Loaded
2020-11-06T22:01:52.338Z,1604700112.338 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-11-06T22:01:52.392Z,1604700112.392 [NAL9602] Loaded
2020-11-06T22:01:52.393Z,1604700112.393 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-11-06T22:01:52.418Z,1604700112.418 [Onboard] Loaded
2020-11-06T22:01:52.419Z,1604700112.419 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-11-06T22:01:52.420Z,1604700112.420 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2020-11-06T22:01:52.420Z,1604700112.420 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1023
2020-11-06T22:01:52.430Z,1604700112.430 [Radio_Surface] Loaded
2020-11-06T22:01:52.431Z,1604700112.431 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-11-06T22:01:52.432Z,1604700112.432 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2020-11-06T22:01:52.432Z,1604700112.432 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1024
2020-11-06T22:01:52.459Z,1604700112.459 [RDI_Pathfinder] Loaded
2020-11-06T22:01:52.459Z,1604700112.459 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-11-06T22:01:52.531Z,1604700112.531 [DAT] Loaded
2020-11-06T22:01:52.532Z,1604700112.532 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-11-06T22:01:53.304Z,1604700113.304 [BPC1] Loaded
2020-11-06T22:01:53.305Z,1604700113.305 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-11-06T22:01:53.305Z,1604700113.305 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-11-06T22:01:53.306Z,1604700113.306 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-11-06T22:01:53.382Z,1604700113.382 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-11-06T22:01:53.383Z,1604700113.383 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-11-06T22:01:53.393Z,1604700113.393 [NavChart] Loaded
2020-11-06T22:01:53.394Z,1604700113.394 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-11-06T22:01:53.398Z,1604700113.398 [UniversalFixResidualReporter] Loaded
2020-11-06T22:01:53.398Z,1604700113.398 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-11-06T22:01:53.398Z,1604700113.398 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-11-06T22:01:53.399Z,1604700113.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-11-06T22:01:53.530Z,1604700113.530 [SBIT](DEBUG): Construct Startup Built In Test.
2020-11-06T22:01:53.539Z,1604700113.539 [SBIT] Loaded
2020-11-06T22:01:53.539Z,1604700113.539 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-11-06T22:01:53.540Z,1604700113.540 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-11-06T22:01:53.552Z,1604700113.552 [IBIT] Loaded
2020-11-06T22:01:53.552Z,1604700113.552 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-11-06T22:01:53.555Z,1604700113.555 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-11-06T22:01:53.650Z,1604700113.650 [CBIT] Loaded
2020-11-06T22:01:53.651Z,1604700113.651 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-11-06T22:01:53.651Z,1604700113.651 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-11-06T22:01:53.652Z,1604700113.652 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-11-06T22:01:53.760Z,1604700113.760 [BuoyancyServo] Loaded
2020-11-06T22:01:53.760Z,1604700113.760 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-11-06T22:01:53.777Z,1604700113.777 [ElevatorServo] Loaded
2020-11-06T22:01:53.777Z,1604700113.777 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-11-06T22:01:53.793Z,1604700113.793 [MassServo] Loaded
2020-11-06T22:01:53.794Z,1604700113.794 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-11-06T22:01:53.809Z,1604700113.809 [RudderServo] Loaded
2020-11-06T22:01:53.809Z,1604700113.809 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-11-06T22:01:53.824Z,1604700113.824 [ThrusterServo] Loaded
2020-11-06T22:01:53.825Z,1604700113.825 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-11-06T22:01:53.825Z,1604700113.825 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-11-06T22:01:53.826Z,1604700113.826 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-11-06T22:01:53.843Z,1604700113.843 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-11-06T22:01:53.844Z,1604700113.844 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-11-06T22:01:54.049Z,1604700114.049 [BackSeatDriver] Loaded
2020-11-06T22:01:54.050Z,1604700114.050 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-11-06T22:01:54.051Z,1604700114.051 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B064E0
2020-11-06T22:01:54.051Z,1604700114.051 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 1025
2020-11-06T22:01:54.071Z,1604700114.071 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-11-06T22:01:54.074Z,1604700114.074 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-11-06T22:01:54.074Z,1604700114.074 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-11-06T22:01:54.077Z,1604700114.077 [CTD_Seabird](INFO): created writer for : depth
2020-11-06T22:01:54.077Z,1604700114.077 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-11-06T22:01:54.080Z,1604700114.080 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-11-06T22:01:54.081Z,1604700114.081 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-11-06T22:01:54.084Z,1604700114.084 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-11-06T22:01:54.084Z,1604700114.084 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-11-06T22:01:54.087Z,1604700114.087 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-11-06T22:01:54.088Z,1604700114.088 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-11-06T22:01:54.091Z,1604700114.091 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-11-06T22:01:54.091Z,1604700114.091 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-11-06T22:01:54.094Z,1604700114.094 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-11-06T22:01:54.107Z,1604700114.107 [CTD_Seabird] Loaded
2020-11-06T22:01:54.107Z,1604700114.107 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-11-06T22:01:54.108Z,1604700114.108 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B364E0
2020-11-06T22:01:54.109Z,1604700114.109 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1026
2020-11-06T22:01:54.126Z,1604700114.126 [PAR_Licor] Loaded
2020-11-06T22:01:54.126Z,1604700114.126 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-11-06T22:01:54.139Z,1604700114.139 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-11-06T22:01:54.139Z,1604700114.139 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-11-06T22:01:54.141Z,1604700114.141 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-11-06T22:01:54.141Z,1604700114.141 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-11-06T22:01:54.143Z,1604700114.143 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-11-06T22:01:54.143Z,1604700114.143 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-11-06T22:01:54.145Z,1604700114.145 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-11-06T22:01:54.145Z,1604700114.145 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-11-06T22:01:54.147Z,1604700114.147 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-11-06T22:01:54.147Z,1604700114.147 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-11-06T22:01:54.150Z,1604700114.150 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-11-06T22:01:54.150Z,1604700114.150 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-11-06T22:01:54.152Z,1604700114.152 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-11-06T22:01:54.152Z,1604700114.152 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-11-06T22:01:54.154Z,1604700114.154 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.156Z,1604700114.156 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.157Z,1604700114.157 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.157Z,1604700114.157 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.159Z,1604700114.159 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.159Z,1604700114.159 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.161Z,1604700114.161 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.161Z,1604700114.161 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:01:54.163Z,1604700114.163 [WetLabsBB2FL] Loaded
2020-11-06T22:01:54.163Z,1604700114.163 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-11-06T22:01:54.164Z,1604700114.164 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0
2020-11-06T22:01:54.165Z,1604700114.165 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1027
2020-11-06T22:01:54.187Z,1604700114.187 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-11-06T22:01:54.187Z,1604700114.187 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-11-06T22:01:54.188Z,1604700114.188 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-11-06T22:01:54.192Z,1604700114.192 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.emulateHardware
2020-11-06T22:01:54.194Z,1604700114.194 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 
2020-11-06T22:01:54.194Z,1604700114.194 [WetLabsUBAT](CRITICAL): Error loading configuration parameters during WetLabsUBAT::start()
2020-11-06T22:01:54.194Z,1604700114.194 [WetLabsUBAT] Data Fault, FailCount= 1
2020-11-06T22:01:54.194Z,1604700114.194 [WetLabsUBAT](ERROR): Data Fault
2020-11-06T22:01:54.194Z,1604700114.194 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-11-06T22:01:54.194Z,1604700114.194 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-11-06T22:01:54.196Z,1604700114.196 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-11-06T22:01:54.196Z,1604700114.196 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-11-06T22:01:54.199Z,1604700114.199 [WetLabsUBAT] Loaded
2020-11-06T22:01:54.200Z,1604700114.200 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-11-06T22:01:54.201Z,1604700114.201 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B964E0
2020-11-06T22:01:54.201Z,1604700114.201 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1028
2020-11-06T22:01:54.201Z,1604700114.201 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-11-06T22:01:54.205Z,1604700114.205 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-11-06T22:01:54.206Z,1604700114.206 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-11-06T22:01:54.214Z,1604700114.214 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-11-06T22:01:54.215Z,1604700114.215 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC64E0
2020-11-06T22:01:54.216Z,1604700114.216 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1029
2020-11-06T22:01:54.220Z,1604700114.220 [Supervisor](INFO): Main Thread ID is 935
2020-11-06T22:01:54.220Z,1604700114.220 [Supervisor](DEBUG): Running supervisor.
2020-11-06T22:01:54.221Z,1604700114.221 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1030
2020-11-06T22:01:54.226Z,1604700114.226 [controlThread ThreadHandler](INFO): Handler Thread ID is 1031
2020-11-06T22:01:54.226Z,1604700114.226 [controlThread](DEBUG): Initializing ControlThread
2020-11-06T22:01:54.227Z,1604700114.227 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-11-06T22:01:54.229Z,1604700114.229 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-11-06T22:01:54.230Z,1604700114.230 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-11-06T22:01:54.231Z,1604700114.231 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-11-06T22:01:54.231Z,1604700114.231 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-11-06T22:01:54.231Z,1604700114.231 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-11-06T22:01:54.232Z,1604700114.232 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-11-06T22:01:54.232Z,1604700114.232 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-11-06T22:01:54.233Z,1604700114.233 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-11-06T22:01:54.233Z,1604700114.233 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-11-06T22:01:54.238Z,1604700114.238 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-11-06T22:01:54.239Z,1604700114.239 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-11-06T22:01:54.239Z,1604700114.239 [SBIT](INFO): Initialize SBIT Component.
2020-11-06T22:01:54.240Z,1604700114.240 [SBIT](IMPORTANT): git: 2020-11-03
2020-11-06T22:01:54.240Z,1604700114.240 [SBIT](INFO): git hash: 8c5f527fc94ff6615863ceb7263f01b68d6bddc2
2020-11-06T22:01:54.240Z,1604700114.240 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-11-06T22:01:54.241Z,1604700114.241 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-11-06T22:01:54.242Z,1604700114.242 [SBIT](INFO): Beginning SBIT in 26.000000 seconds.
2020-11-06T22:01:54.243Z,1604700114.243 [IBIT](INFO): Initialize IBIT Component.
2020-11-06T22:01:54.244Z,1604700114.244 [CBIT](DEBUG): Initialize CBIT Component.
2020-11-06T22:01:54.245Z,1604700114.245 [logger ThreadHandler](INFO): Handler Thread ID is 1032
2020-11-06T22:01:54.255Z,1604700114.255 [CBIT](DEBUG): Initialized mux pins.
2020-11-06T22:01:54.255Z,1604700114.255 [CBIT](DEBUG): Initializing the watchdog timer.
2020-11-06T22:01:54.264Z,1604700114.264 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1033
2020-11-06T22:01:54.265Z,1604700114.265 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-11-06T22:01:54.276Z,1604700114.276 [Onboard ThreadHandler](INFO): Handler Thread ID is 1034
2020-11-06T22:01:54.280Z,1604700114.280 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-11-06T22:01:54.280Z,1604700114.280 [CBIT](DEBUG): Initializing heartbeat.
2020-11-06T22:01:54.293Z,1604700114.293 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1035
2020-11-06T22:01:54.316Z,1604700114.316 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 1036
2020-11-06T22:01:54.335Z,1604700114.335 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1037
2020-11-06T22:01:54.336Z,1604700114.336 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-11-06T22:01:54.341Z,1604700114.341 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1039
2020-11-06T22:01:54.342Z,1604700114.342 [WetLabsBB2FL](INFO): Powering down
2020-11-06T22:01:54.351Z,1604700114.351 [CBIT](DEBUG): Deactivating GF circuits.
2020-11-06T22:01:54.351Z,1604700114.351 [CBIT](DEBUG): Deactivating emergency mode.
2020-11-06T22:01:54.352Z,1604700114.352 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1040
2020-11-06T22:01:54.360Z,1604700114.360 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1041
2020-11-06T22:01:54.362Z,1604700114.362 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-11-06T22:01:54.363Z,1604700114.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-11-06T22:01:54.363Z,1604700114.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-11-06T22:01:54.363Z,1604700114.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-11-06T22:01:54.363Z,1604700114.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-11-06T22:01:54.363Z,1604700114.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-11-06T22:01:54.364Z,1604700114.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-11-06T22:01:54.364Z,1604700114.364 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-11-06T22:01:54.364Z,1604700114.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-11-06T22:01:54.364Z,1604700114.364 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-11-06T22:01:54.364Z,1604700114.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-11-06T22:01:54.364Z,1604700114.364 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-11-06T22:01:54.365Z,1604700114.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-11-06T22:01:54.365Z,1604700114.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-11-06T22:01:54.365Z,1604700114.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-11-06T22:01:54.365Z,1604700114.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-11-06T22:01:54.387Z,1604700114.387 [CBIT](DEBUG): Backplane powered.
2020-11-06T22:01:54.392Z,1604700114.392 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-11-06T22:01:54.402Z,1604700114.402 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-06T22:01:54.437Z,1604700114.437 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-11-06T22:01:54.438Z,1604700114.438 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-11-06T22:01:54.509Z,1604700114.509 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-11-06T22:01:54.510Z,1604700114.510 [Default:A.Wait](DEBUG): Construct Wait.
2020-11-06T22:01:54.539Z,1604700114.539 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-06T22:01:54.569Z,1604700114.569 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-11-06T22:01:54.588Z,1604700114.588 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-11-06T22:01:54.593Z,1604700114.593 [Default:E.Execute](DEBUG): Construct Execute.
2020-11-06T22:01:54.612Z,1604700114.612 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-11-06T22:01:54.617Z,1604700114.617 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,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,
2020-11-06T22:01:54.626Z,1604700114.626 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-06T22:01:54.699Z,1604700114.699 [Radio_Surface](INFO): Powering up
2020-11-06T22:01:54.774Z,1604700114.774 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-11-06T22:01:54.774Z,1604700114.774 [DAT](INFO): Powering up
2020-11-06T22:01:54.774Z,1604700114.774 [DAT](DEBUG): Initializing DAT.
2020-11-06T22:01:54.823Z,1604700114.823 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-11-06T22:01:55.045Z,1604700115.045 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-11-06T22:01:55.053Z,1604700115.053 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-11-06T22:01:55.055Z,1604700115.055 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-11-06T22:01:55.083Z,1604700115.083 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-11-06T22:01:55.085Z,1604700115.085 [MassServo](DEBUG): Initializing EZServoServo.
2020-11-06T22:01:55.104Z,1604700115.104 [MassServo](DEBUG): Initializing MassServo.
2020-11-06T22:01:55.105Z,1604700115.105 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-06T22:01:55.133Z,1604700115.133 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-06T22:01:55.134Z,1604700115.134 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-11-06T22:01:55.143Z,1604700115.143 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-11-06T22:01:55.146Z,1604700115.146 [CommandLine](FAULT): Scheduling is paused
2020-11-06T22:01:55.146Z,1604700115.146 [CBIT](INFO): Critical error at 20201106T220154
2020-11-06T22:01:55.146Z,1604700115.146 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-11-06T22:01:55.150Z,1604700115.150 [CBIT](ERROR): Data Fault in component: WetLabsUBAT
2020-11-06T22:01:55.150Z,1604700115.150 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-11-06T22:01:55.150Z,1604700115.150 [WetLabsUBAT] No Fault, FailCount= 1
2020-11-06T22:01:55.159Z,1604700115.159 [WetLabsUBAT](INFO): Initializing
2020-11-06T22:01:55.161Z,1604700115.161 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 
2020-11-06T22:01:55.162Z,1604700115.162 [WetLabsUBAT](INFO): Powering up
2020-11-06T22:01:55.162Z,1604700115.162 [WetLabsUBAT](INFO): Checking LCM
2020-11-06T22:01:55.162Z,1604700115.162 [WetLabsUBAT](INFO): LCM OK
2020-11-06T22:01:55.173Z,1604700115.173 [WetLabsUBAT](INFO): setup() - setLineFormat()
2020-11-06T22:01:55.256Z,1604700115.256 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-11-06T22:01:55.256Z,1604700115.256 [DropWeight] Hardware Fault, FailCount= 1
2020-11-06T22:01:55.256Z,1604700115.256 [DropWeight](ERROR): Hardware Fault
2020-11-06T22:01:55.298Z,1604700115.298 [CBIT](INFO): Critical error at 20201106T220155
2020-11-06T22:01:55.308Z,1604700115.308 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-11-06T22:01:55.309Z,1604700115.309 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-11-06T22:01:55.608Z,1604700115.608 [CBIT](INFO): Critical error at 20201106T220155
2020-11-06T22:02:05.611Z,1604700125.611 [WetLabsUBAT](INFO): Got instrument prompt
2020-11-06T22:02:07.753Z,1604700127.753 [WetLabsUBAT](INFO): Got instrument prompt
2020-11-06T22:02:08.057Z,1604700128.057 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004329
2020-11-06T22:02:08.819Z,1604700128.819 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata:
Override SerialDriver::getDeviceMetadata()
2020-11-06T22:02:08.927Z,1604700128.927 [DAT](INFO): commRate: 800
2020-11-06T22:02:10.948Z,1604700130.948 [DAT](INFO): entering command mode
2020-11-06T22:02:11.350Z,1604700131.350 [DAT](INFO): setting verbose to 3
2020-11-06T22:02:11.750Z,1604700131.750 [DAT](INFO): set verbose to 3
2020-11-06T22:02:11.750Z,1604700131.750 [DAT](INFO): setting DatVerbose to 27440
2020-11-06T22:02:12.161Z,1604700132.161 [DAT](INFO): set DatVerbose to 27440
2020-11-06T22:02:12.162Z,1604700132.162 [DAT](INFO): setting transmit power to 8
2020-11-06T22:02:12.565Z,1604700132.565 [DAT](INFO): set transmit power to 8
2020-11-06T22:02:12.565Z,1604700132.565 [DAT](INFO): setting local address to 9
2020-11-06T22:02:12.959Z,1604700132.959 [DAT](INFO): set local address to 9
2020-11-06T22:02:12.960Z,1604700132.960 [WetLabsUBAT](INFO): isDataRequested() is false
2020-11-06T22:02:13.363Z,1604700133.363 [WetLabsUBAT](INFO): Powering down
2020-11-06T22:02:20.715Z,1604700140.715 [SBIT](IMPORTANT): Beginning Startup BIT
2020-11-06T22:02:20.796Z,1604700140.796 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-06T22:02:22.689Z,1604700142.689 [NAL9602](INFO): Powering up NAL9602
2020-11-06T22:02:31.750Z,1604700151.750 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007777
CHAN A1 (24V): 0.001062
CHAN A2 (12V): -0.006243
CHAN A3 (5V): -0.001903
CHAN B0 (3.3V): 0.000838
CHAN B1 (3.15aV): 0.000101
CHAN B2 (3.15bV): 0.000211
CHAN B3 (GND): 0.002171
OPEN: 0.005186
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-06T22:02:33.713Z,1604700153.713 [NAL9602](INFO): NAL9602 initialized
2020-11-06T22:03:14.544Z,1604700194.544 [SBIT](IMPORTANT): SBIT PASSED
2020-11-06T22:03:14.544Z,1604700194.544 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter;

2020-11-06T22:03:14.545Z,1604700194.545 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=256.318046 cubic_centimeter;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): VerticalControl.massDefault=1.572083 millimeter;

2020-11-06T22:03:14.546Z,1604700194.546 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2020-11-06T22:03:14.941Z,1604700194.941 [MissionManager](IMPORTANT): Started mission Startup
2020-11-06T22:03:14.942Z,1604700194.942 [Startup] Running Loop=1
2020-11-06T22:03:14.942Z,1604700194.942 [Startup](DEBUG): Aggregate::initialize Startup
2020-11-06T22:03:14.942Z,1604700194.942 [Startup:A.GoToSurface] Running Loop=1
2020-11-06T22:03:14.942Z,1604700194.942 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-06T22:03:14.943Z,1604700194.943 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-06T22:03:14.943Z,1604700194.943 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-06T22:03:14.944Z,1604700194.944 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-06T22:03:14.944Z,1604700194.944 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-06T22:03:14.944Z,1604700194.944 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-06T22:03:14.946Z,1604700194.946 [Startup:StartupSatComms] Running Loop=1
2020-11-06T22:03:14.946Z,1604700194.946 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-11-06T22:03:14.946Z,1604700194.946 [Startup:StartupSatComms:A] Running Loop=1
2020-11-06T22:03:15.415Z,1604700195.415 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-11-06T22:03:18.798Z,1604700198.798 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:18.798Z,1604700198.798 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:18.799Z,1604700198.799 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-06T22:03:22.558Z,1604700202.558 [CommandLine](IMPORTANT): got command burn on
2020-11-06T22:03:22.558Z,1604700202.558 [CommandLine](IMPORTANT): Activating dropweight wire
2020-11-06T22:03:31.563Z,1604700211.563 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:31.564Z,1604700211.564 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:31.564Z,1604700211.564 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-06T22:03:36.558Z,1604700216.558 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:36.558Z,1604700216.558 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:36.558Z,1604700216.558 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-06T22:03:41.203Z,1604700221.203 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:41.203Z,1604700221.203 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:41.203Z,1604700221.203 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-06T22:03:42.475Z,1604700222.475 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:42.475Z,1604700222.475 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:42.475Z,1604700222.475 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-06T22:03:44.417Z,1604700224.417 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:44.417Z,1604700224.417 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:44.417Z,1604700224.417 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-11-06T22:03:49.557Z,1604700229.557 [CommandLine](IMPORTANT): got command failComponent none DropWeight
2020-11-06T22:03:49.558Z,1604700229.558 [DropWeight] No Fault, FailCount= 1
2020-11-06T22:03:49.558Z,1604700229.558 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault
2020-11-06T22:03:54.959Z,1604700234.959 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:03:54.959Z,1604700234.959 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:03:54.959Z,1604700234.959 [CommandLine](IMPORTANT): No failed Components.
2020-11-06T22:03:59.518Z,1604700239.518 [CommandLine](IMPORTANT): got command burn off
2020-11-06T22:03:59.518Z,1604700239.518 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-11-06T22:04:04.518Z,1604700244.518 [CommandLine](IMPORTANT): got command burn off
2020-11-06T22:04:04.518Z,1604700244.518 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-11-06T22:04:06.798Z,1604700246.798 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:04:06.798Z,1604700246.798 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:04:06.799Z,1604700246.799 [CommandLine](IMPORTANT): No failed Components.
2020-11-06T22:04:15.145Z,1604700255.145 [Startup:StartupSatComms:A](INFO): Timed out from 2020-11-06T22:03:14.9Z
2020-11-06T22:04:15.145Z,1604700255.145 [Startup:StartupSatComms:A] Stopped
2020-11-06T22:04:15.146Z,1604700255.146 [Startup:StartupSatComms:B] Running Loop=1
2020-11-06T22:04:15.545Z,1604700255.545 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-11-06T22:04:25.719Z,1604700265.719 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201106T212259/Courier0007.lzma
2020-11-06T22:04:26.721Z,1604700266.721 [DataOverHttps](INFO): Moved sent file to Logs/20201106T212259/Courier0007.lzma.bak
2020-11-06T22:04:26.721Z,1604700266.721 [DataOverHttps](INFO): SBD MOMSN=13113610
2020-11-06T22:04:42.667Z,1604700282.667 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20201106T213441/Courier0000.lzma
2020-11-06T22:04:43.669Z,1604700283.669 [DataOverHttps](INFO): Moved sent file to Logs/20201106T213441/Courier0000.lzma.bak
2020-11-06T22:04:43.669Z,1604700283.669 [DataOverHttps](INFO): SBD MOMSN=13113615
2020-11-06T22:04:54.488Z,1604700294.488 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-11-06T22:04:54.488Z,1604700294.488 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:04:54.498Z,1604700294.498 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:04:54.869Z,1604700294.869 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:04:54.869Z,1604700294.869 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-11-06T22:04:59.743Z,1604700299.743 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20201106T220144/Courier0000.lzma
2020-11-06T22:05:00.745Z,1604700300.745 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0000.lzma.bak
2020-11-06T22:05:00.745Z,1604700300.745 [DataOverHttps](INFO): SBD MOMSN=13113626
2020-11-06T22:05:15.517Z,1604700315.517 [Startup:StartupSatComms:B](INFO): Timed out from 2020-11-06T22:04:15.1Z
2020-11-06T22:05:15.517Z,1604700315.517 [Startup:StartupSatComms:B] Stopped
2020-11-06T22:05:15.518Z,1604700315.518 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-11-06T22:05:15.518Z,1604700315.518 [Startup:StartupSatComms] Stopped
2020-11-06T22:05:15.519Z,1604700315.519 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-11-06T22:05:15.524Z,1604700315.524 [Startup](INFO): Completed Startup
2020-11-06T22:05:15.525Z,1604700315.525 [MissionManager](INFO): Startup is completed.
2020-11-06T22:05:15.525Z,1604700315.525 [MissionManager](INFO): Uninitializing Mission Startup
2020-11-06T22:05:15.525Z,1604700315.525 [Startup] Stopped
2020-11-06T22:05:15.525Z,1604700315.525 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-11-06T22:05:15.525Z,1604700315.525 [Startup:A.GoToSurface] Stopped
2020-11-06T22:05:15.525Z,1604700315.525 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-06T22:05:15.889Z,1604700315.889 [MissionManager](IMPORTANT): Started mission Default
2020-11-06T22:05:15.889Z,1604700315.889 [Default] Running Loop=1
2020-11-06T22:05:15.890Z,1604700315.890 [Default](DEBUG): Aggregate::initialize Default
2020-11-06T22:05:15.890Z,1604700315.890 [Default:B.GoToSurface] Running Loop=1
2020-11-06T22:05:15.890Z,1604700315.890 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-06T22:05:15.890Z,1604700315.890 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-06T22:05:15.890Z,1604700315.890 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-06T22:05:15.890Z,1604700315.890 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-06T22:05:15.891Z,1604700315.891 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-06T22:05:15.895Z,1604700315.895 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-06T22:05:15.896Z,1604700315.896 [Default:A.Wait] Running Loop=1
2020-11-06T22:05:15.896Z,1604700315.896 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:05:17.031Z,1604700317.031 [DataOverHttps](INFO): Sending 445 bytes from file Logs/20201106T212259/Express0008.lzma
2020-11-06T22:05:18.033Z,1604700318.033 [DataOverHttps](INFO): Moved sent file to Logs/20201106T212259/Express0008.lzma.bak
2020-11-06T22:05:18.033Z,1604700318.033 [DataOverHttps](INFO): SBD MOMSN=13113633
2020-11-06T22:05:29.222Z,1604700329.222 [Default:A.Wait](INFO): Done Waiting.
2020-11-06T22:05:29.222Z,1604700329.222 [Default:A.Wait] Stopped
2020-11-06T22:05:29.222Z,1604700329.222 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:05:29.629Z,1604700329.629 [Default:CheckIn] Running Loop=1
2020-11-06T22:05:29.629Z,1604700329.629 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:05:29.629Z,1604700329.629 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:05:30.050Z,1604700330.050 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-11-06T22:07:36.857Z,1604700456.857 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-06T22:07:55.480Z,1604700475.480 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-11-06T22:07:55.480Z,1604700475.480 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:07:55.525Z,1604700475.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:07:55.870Z,1604700475.870 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:07:55.870Z,1604700475.870 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-11-06T22:09:20.357Z,1604700560.357 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2020-11-06T22:09:20.361Z,1604700560.361 [BPC1](INFO): Received data from all battery sticks.
2020-11-06T22:10:29.846Z,1604700629.846 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-06T22:05:29.6Z
2020-11-06T22:10:29.846Z,1604700629.846 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:10:29.846Z,1604700629.846 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:10:30.268Z,1604700630.268 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-11-06T22:10:37.219Z,1604700637.219 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20201106T220144/Courier0004.lzma
2020-11-06T22:10:38.225Z,1604700638.225 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0004.lzma.bak
2020-11-06T22:10:38.226Z,1604700638.226 [DataOverHttps](INFO): SBD MOMSN=13113698
2020-11-06T22:10:56.501Z,1604700656.501 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-11-06T22:10:56.501Z,1604700656.501 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:10:56.511Z,1604700656.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:10:56.917Z,1604700656.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:10:56.917Z,1604700656.917 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-11-06T22:11:02.845Z,1604700662.845 [DataOverHttps](INFO): Sending 1026 bytes from file Logs/20201106T213441/Express0001.lzma
2020-11-06T22:11:03.845Z,1604700663.845 [DataOverHttps](INFO): Moved sent file to Logs/20201106T213441/Express0001.lzma.bak
2020-11-06T22:11:03.845Z,1604700663.845 [DataOverHttps](INFO): SBD MOMSN=13113703
2020-11-06T22:11:19.621Z,1604700679.621 [DataOverHttps](INFO): Sending 1216 bytes from file Logs/20201106T220144/Express0001.lzma
2020-11-06T22:11:20.621Z,1604700680.621 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0001.lzma.bak
2020-11-06T22:11:20.621Z,1604700680.621 [DataOverHttps](INFO): SBD MOMSN=13113736
2020-11-06T22:11:36.995Z,1604700696.995 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20201106T220144/Express0005.lzma
2020-11-06T22:11:37.997Z,1604700697.997 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0005.lzma.bak
2020-11-06T22:11:37.997Z,1604700697.997 [DataOverHttps](INFO): SBD MOMSN=13113796
2020-11-06T22:11:39.737Z,1604700699.737 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:11:39.737Z,1604700699.737 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T22:11:39.737Z,1604700699.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:12:34.705Z,1604700754.705 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-06T22:12:34.705Z,1604700754.705 [NAL9602] Data Fault, FailCount= 1
2020-11-06T22:12:34.705Z,1604700754.705 [NAL9602](ERROR): Data Fault
2020-11-06T22:12:34.762Z,1604700754.762 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-06T22:12:35.108Z,1604700755.108 [NAL9602](INFO): Powering down
2020-11-06T22:12:35.934Z,1604700755.934 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-06T22:12:35.934Z,1604700755.934 [NAL9602] No Fault, FailCount= 1
2020-11-06T22:13:05.405Z,1604700785.405 [NAL9602](INFO): Powering up NAL9602
2020-11-06T22:13:16.313Z,1604700796.313 [NAL9602](INFO): NAL9602 initialized
2020-11-06T22:13:57.548Z,1604700837.548 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-11-06T22:13:57.548Z,1604700837.548 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:13:57.586Z,1604700837.586 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:13:57.958Z,1604700837.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:13:57.958Z,1604700837.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-11-06T22:16:40.420Z,1604701000.420 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T22:16:40.420Z,1604701000.420 [Default:CheckIn:C.Wait] Stopped
2020-11-06T22:16:40.420Z,1604701000.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:16:40.420Z,1604701000.420 [Default:CheckIn:D] Running Loop=1
2020-11-06T22:16:40.805Z,1604701000.805 [Default:CheckIn:D] Stopped
2020-11-06T22:16:40.805Z,1604701000.805 [Default:CheckIn:E] Running Loop=1
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.415264 min 
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn:E] Stopped
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn] Stopped
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn](INFO): Running loop #2
2020-11-06T22:16:41.207Z,1604701001.207 [Default:CheckIn] Running Loop=2
2020-11-06T22:16:41.208Z,1604701001.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:16:41.208Z,1604701001.208 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:16:58.603Z,1604701018.603 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-11-06T22:16:58.603Z,1604701018.603 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:16:58.621Z,1604701018.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:16:58.993Z,1604701018.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:16:58.993Z,1604701018.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-11-06T22:19:45.929Z,1604701185.929 [CommandLine](IMPORTANT): got command get Onboard.Pressure
2020-11-06T22:19:45.929Z,1604701185.929 [CommandLine](IMPORTANT): Onboard.Pressure 48893.519531 Pa
2020-11-06T22:19:52.374Z,1604701192.374 [CommandLine](IMPORTANT): got command get Onboard.Humidity
2020-11-06T22:19:52.375Z,1604701192.375 [CommandLine](IMPORTANT): Onboard.Humidity 12.031498 %
2020-11-06T22:19:59.603Z,1604701199.603 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-11-06T22:19:59.603Z,1604701199.603 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:19:59.641Z,1604701199.641 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:20:00.001Z,1604701200.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:20:00.001Z,1604701200.001 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-11-06T22:21:23.179Z,1604701283.179 [CommandLine](IMPORTANT): got command gfscan
2020-11-06T22:21:23.242Z,1604701283.242 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-06T22:21:34.121Z,1604701294.121 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007615
CHAN A1 (24V): 0.000626
CHAN A2 (12V): -0.006841
CHAN A3 (5V): -0.002039
CHAN B0 (3.3V): 0.001043
CHAN B1 (3.15aV): 0.000172
CHAN B2 (3.15bV): 0.000737
CHAN B3 (GND): 0.001771
OPEN: 0.005486
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-06T22:21:41.414Z,1604701301.414 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-06T22:16:41.2Z
2020-11-06T22:21:41.414Z,1604701301.414 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:21:41.414Z,1604701301.414 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:21:45.298Z,1604701305.298 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2020-11-06T22:21:45.298Z,1604701305.298 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2020-11-06T22:21:45.680Z,1604701305.680 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2020-11-06T22:21:45.687Z,1604701305.687 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2020-11-06T22:21:45.690Z,1604701305.690 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2020-11-06T22:21:45.696Z,1604701305.696 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2020-11-06T22:21:45.752Z,1604701305.752 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2020-11-06T22:21:45.754Z,1604701305.754 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2020-11-06T22:21:45.766Z,1604701305.766 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2020-11-06T22:21:45.773Z,1604701305.773 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2020-11-06T22:21:45.792Z,1604701305.792 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2020-11-06T22:21:45.794Z,1604701305.794 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2020-11-06T22:21:45.805Z,1604701305.805 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-11-06T22:21:45.813Z,1604701305.813 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2020-11-06T22:21:45.877Z,1604701305.877 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2020-11-06T22:21:45.888Z,1604701305.888 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2020-11-06T22:21:45.904Z,1604701305.904 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-11-06T22:21:45.911Z,1604701305.911 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-11-06T22:21:45.915Z,1604701305.915 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 1.000000 bool
2020-11-06T22:21:45.934Z,1604701305.934 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2020-11-06T22:21:45.945Z,1604701305.945 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2020-11-06T22:21:45.968Z,1604701305.968 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2020-11-06T22:21:45.979Z,1604701305.979 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2020-11-06T22:21:45.982Z,1604701305.982 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2020-11-06T22:21:45.993Z,1604701305.993 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2020-11-06T22:21:46.009Z,1604701306.009 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2020-11-06T22:21:46.028Z,1604701306.028 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2020-11-06T22:21:46.036Z,1604701306.036 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2020-11-06T22:21:46.057Z,1604701306.057 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2020-11-06T22:21:46.092Z,1604701306.092 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2020-11-06T22:21:46.095Z,1604701306.095 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2020-11-06T22:21:46.098Z,1604701306.098 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2020-11-06T22:21:46.121Z,1604701306.121 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2020-11-06T22:21:46.129Z,1604701306.129 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2020-11-06T22:21:46.148Z,1604701306.148 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2020-11-06T22:21:46.160Z,1604701306.160 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2020-11-06T22:21:46.163Z,1604701306.163 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2020-11-06T22:21:46.174Z,1604701306.174 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2020-11-06T22:21:46.181Z,1604701306.181 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2020-11-06T22:21:46.197Z,1604701306.197 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2020-11-06T22:21:46.204Z,1604701306.204 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2020-11-06T22:21:46.219Z,1604701306.219 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2020-11-06T22:21:46.222Z,1604701306.222 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2020-11-06T22:21:46.288Z,1604701306.288 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2020-11-06T22:21:46.305Z,1604701306.305 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2020-11-06T22:21:46.333Z,1604701306.333 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2020-11-06T22:21:46.341Z,1604701306.341 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2020-11-06T22:21:46.360Z,1604701306.360 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2020-11-06T22:21:46.458Z,1604701306.458 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-06T22:21:46.520Z,1604701306.520 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-11-06T22:21:46.556Z,1604701306.556 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-06T22:21:46.589Z,1604701306.589 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-06T22:21:46.641Z,1604701306.641 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-11-06T22:21:46.761Z,1604701306.761 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

    <DefineArg Name="TimeWindowPeakReport"><Description>
        If greater than zero, report a peak every window. If NaN or zero, this
        variable is ignored.
    </Description><Units:minute/><Value>NaN</Value></DefineArg>

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

    <DefineArg Name="OffPeakFractionHorizontal"><Description>
        When filtered horizontal value is this fraction of the peak, consider it
        outside the patch.
    </Description><Units:percent/><Value>80</Value></DefineArg>

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectOilActive"><Description>
        Turns on peak detection of Dissolved Oil.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor.
    </Description><False/></DefineArg>
    
    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsSeaOWL_UV_A"><Description>
        Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set
        to false to disable the WetLabs SeaOWL UV-A.
    </Description><Science:WetLabsSeaOWL_UV_A.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsUBAT"><Description>
        Automatically set to true if the the WetLabs UBAT is enabled. Set
        to false to disable the WetLabs UBAT.
    </Description><Science:WetLabsUBAT.loadAtStartup/></DefineArg>

    <DefineArg Name="LowPassWindowLength"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="MedianFilterLen"><Description>
        Median filter length (only for chlorophyll fluorescence which tends to have spikes)
    </Description><Units:count/><Value>5</Value></DefineArg>

    <DefineArg Name="PeakChlShallowBound"><Description>
        Shallow depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakChlDeepBound"><Description>
        Deep depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakShallowBound"><Description>
        Shallow depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakDeepBound"><Description>
        Deep depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlTemperature"><Units:celsius/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Depth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Latitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Longitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChl"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlDistance"><Units:meter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOil"><Units:kilogram_per_cubic_meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOilLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOM"><Units:part_per_billion/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>
    
    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>NaN</Value></DefineOutput>    
    
    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledSeabird"/>
        </While>
        <Science:CTD_Seabird.sea_water_temperature/>
        <Science:CTD_Seabird.sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsUBAT"/>
        </While>
        <Science:WetLabsUBAT.biolum_potential/>
    </ReadData>

    <ReadData Id="Read_Oil" Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsSeaOWL_UV_A"/>
            <Or><Arg Name="PeakDetectOilActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <Aggregate Id="OceanCurrent">
        <Estimation:CurrentEstimator>
            <Parallel/>
        </Estimation:CurrentEstimator>	
    </Aggregate>    	

    <Aggregate Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.medianFilterLength/><Arg Name="MedianFilterLen"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakChlShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakChlDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakChl, PeakChlLatitude, PeakChlLongitude = <Arg Name="PeakChl"/><Units:microgram_per_liter/>, <Arg Name="PeakChlLatitude"/><Units:degree/>, <Arg Name="PeakChlLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFractionHorizontal"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

        <While>
            <Arg Name="PeakDetectOilActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakOil"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakOilDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakOilLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakOilLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakOil"/><Arg Name="PeakOilDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

        <While>
            <Arg Name="PeakDetectFDOMActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakFDOM"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakFDOMDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakFDOMTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakFDOMLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakFDOMLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakFDOM"/><Arg Name="PeakFDOMDepth"/>
        </SendData>

    </Aggregate>

</Aggregate>


2020-11-06T22:21:46.772Z,1604701306.772 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2020-11-06T22:21:47.108Z,1604701307.108 [Default] Stopped
2020-11-06T22:21:47.108Z,1604701307.108 [Default](DEBUG): Aggregate::uninitialize Default
2020-11-06T22:21:47.108Z,1604701307.108 [Default:B.GoToSurface] Stopped
2020-11-06T22:21:47.108Z,1604701307.108 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-06T22:21:47.108Z,1604701307.108 [Default:CheckIn] Stopped
2020-11-06T22:21:47.108Z,1604701307.108 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T22:21:47.109Z,1604701307.109 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:21:47.109Z,1604701307.109 [MissionManager](IMPORTANT): Started mission Science
2020-11-06T22:21:47.109Z,1604701307.109 [Science] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science](DEBUG): Aggregate::initialize Science
2020-11-06T22:21:47.109Z,1604701307.109 [Science:A] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:B] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:C] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:D] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:E] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:F] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:Read_Oil] Running Loop=1
2020-11-06T22:21:47.109Z,1604701307.109 [Science:PeakDetectChl] Running Loop=1
2020-11-06T22:21:47.110Z,1604701307.110 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2020-11-06T22:21:47.110Z,1604701307.110 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-11-06T22:21:47.110Z,1604701307.110 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-11-06T22:21:47.116Z,1604701307.116 [Science:HighestChlPeakReport] Running Loop=1
2020-11-06T22:21:47.116Z,1604701307.116 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2020-11-06T22:21:47.116Z,1604701307.116 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-11-06T22:21:47.116Z,1604701307.116 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-11-06T22:21:47.117Z,1604701307.117 [Science:PeakDetectNO3] Running Loop=1
2020-11-06T22:21:47.117Z,1604701307.117 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2020-11-06T22:21:47.117Z,1604701307.117 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-11-06T22:21:47.117Z,1604701307.117 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-11-06T22:21:47.118Z,1604701307.118 [Science:PeakDetectOil] Running Loop=1
2020-11-06T22:21:47.119Z,1604701307.119 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2020-11-06T22:21:47.119Z,1604701307.119 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-11-06T22:21:47.119Z,1604701307.119 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-11-06T22:21:47.137Z,1604701307.137 [Science:PeakDetectFDOM] Running Loop=1
2020-11-06T22:21:47.137Z,1604701307.137 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2020-11-06T22:21:47.137Z,1604701307.137 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-11-06T22:21:47.138Z,1604701307.138 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-11-06T22:21:47.144Z,1604701307.144 [Science:OceanCurrent] Running Loop=1
2020-11-06T22:21:47.144Z,1604701307.144 [Science:OceanCurrent](DEBUG): Aggregate::initialize Science:OceanCurrent
2020-11-06T22:21:47.144Z,1604701307.144 [Science:OceanCurrent:A.] Running Loop=1
2020-11-06T22:21:47.144Z,1604701307.144 [Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectFDOM] Stopped
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectOil] Stopped
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectNO3] Stopped
2020-11-06T22:21:47.145Z,1604701307.145 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2020-11-06T22:21:47.146Z,1604701307.146 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-11-06T22:21:47.146Z,1604701307.146 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-11-06T22:21:47.146Z,1604701307.146 [Science:HighestChlPeakReport] Stopped
2020-11-06T22:21:47.146Z,1604701307.146 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2020-11-06T22:21:47.146Z,1604701307.146 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-11-06T22:21:47.146Z,1604701307.146 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-11-06T22:21:47.146Z,1604701307.146 [Science:PeakDetectChl] Stopped
2020-11-06T22:21:47.146Z,1604701307.146 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2020-11-06T22:21:47.146Z,1604701307.146 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-11-06T22:21:47.146Z,1604701307.146 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-11-06T22:21:47.146Z,1604701307.146 [Science:OceanCurrent:A.] Running Loop=1
2020-11-06T22:21:47.160Z,1604701307.160 [Science:Read_Oil] Stopped
2020-11-06T22:21:47.160Z,1604701307.160 [Science:F] Running Loop=1
2020-11-06T22:21:47.161Z,1604701307.161 [Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.biolum_potential
2020-11-06T22:21:47.161Z,1604701307.161 [Science:E] Running Loop=1
2020-11-06T22:21:47.162Z,1604701307.162 [Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-11-06T22:21:47.162Z,1604701307.162 [Science:D] Stopped
2020-11-06T22:21:47.162Z,1604701307.162 [Science:C] Running Loop=1
2020-11-06T22:21:47.163Z,1604701307.163 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-11-06T22:21:47.163Z,1604701307.163 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-11-06T22:21:47.163Z,1604701307.163 [Science:B] Running Loop=1
2020-11-06T22:21:47.164Z,1604701307.164 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-11-06T22:21:47.165Z,1604701307.165 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-11-06T22:21:47.165Z,1604701307.165 [Science:A] Stopped
2020-11-06T22:21:47.272Z,1604701307.272 [WetLabsBB2FL](INFO): Powering up
2020-11-06T22:21:47.623Z,1604701307.623 [WetLabsUBAT](INFO): Initializing
2020-11-06T22:21:47.625Z,1604701307.625 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 
2020-11-06T22:21:47.625Z,1604701307.625 [WetLabsUBAT](INFO): Powering up
2020-11-06T22:21:47.625Z,1604701307.625 [WetLabsUBAT](INFO): Checking LCM
2020-11-06T22:21:47.625Z,1604701307.625 [WetLabsUBAT](INFO): LCM OK
2020-11-06T22:21:47.721Z,1604701307.721 [WetLabsUBAT](INFO): setup() - setLineFormat()
2020-11-06T22:21:50.214Z,1604701310.214 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201106T220144/Courier0007.lzma
2020-11-06T22:21:50.405Z,1604701310.405 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-11-06T22:21:51.217Z,1604701311.217 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0007.lzma.bak
2020-11-06T22:21:51.217Z,1604701311.217 [DataOverHttps](INFO): SBD MOMSN=13113886
2020-11-06T22:21:52.410Z,1604701312.410 [CTD_Seabird](ERROR): Salinity reading out of range: 13.716413 psu
2020-11-06T22:21:53.413Z,1604701313.413 [CTD_Seabird](ERROR): Salinity reading out of range: 12.859590 psu
2020-11-06T22:21:54.418Z,1604701314.418 [CTD_Seabird](ERROR): Salinity reading out of range: 13.357533 psu
2020-11-06T22:21:55.422Z,1604701315.422 [CTD_Seabird](ERROR): Salinity reading out of range: 13.489044 psu
2020-11-06T22:21:56.425Z,1604701316.425 [CTD_Seabird](ERROR): Salinity reading out of range: 13.121599 psu
2020-11-06T22:21:57.430Z,1604701317.430 [CTD_Seabird](ERROR): Salinity reading out of range: 13.347277 psu
2020-11-06T22:21:58.028Z,1604701318.028 [WetLabsUBAT](INFO): Got instrument prompt
2020-11-06T22:21:58.433Z,1604701318.433 [CTD_Seabird](ERROR): Salinity reading out of range: 13.278878 psu
2020-11-06T22:21:59.437Z,1604701319.437 [CTD_Seabird](ERROR): Salinity reading out of range: 13.209850 psu
2020-11-06T22:22:00.169Z,1604701320.169 [WetLabsUBAT](INFO): Got instrument prompt
2020-11-06T22:22:00.441Z,1604701320.441 [CTD_Seabird](ERROR): Salinity reading out of range: 13.290559 psu
2020-11-06T22:22:01.235Z,1604701321.235 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata:
Override SerialDriver::getDeviceMetadata()
2020-11-06T22:22:01.445Z,1604701321.445 [CTD_Seabird](ERROR): Salinity reading out of range: 13.360732 psu
2020-11-06T22:22:02.449Z,1604701322.449 [CTD_Seabird](ERROR): Salinity reading out of range: 13.069402 psu
2020-11-06T22:22:03.453Z,1604701323.453 [CTD_Seabird](ERROR): Salinity reading out of range: 13.518902 psu
2020-11-06T22:22:04.458Z,1604701324.458 [CTD_Seabird](ERROR): Salinity reading out of range: 14.235046 psu
2020-11-06T22:22:05.461Z,1604701325.461 [CTD_Seabird](ERROR): Salinity reading out of range: 14.517462 psu
2020-11-06T22:22:06.465Z,1604701326.465 [CTD_Seabird](ERROR): Salinity reading out of range: 14.575717 psu
2020-11-06T22:22:07.470Z,1604701327.470 [CTD_Seabird](ERROR): Salinity reading out of range: 14.425089 psu
2020-11-06T22:22:08.473Z,1604701328.473 [CTD_Seabird](ERROR): Salinity reading out of range: 14.436629 psu
2020-11-06T22:22:09.478Z,1604701329.478 [CTD_Seabird](ERROR): Salinity reading out of range: 14.459716 psu
2020-11-06T22:22:10.482Z,1604701330.482 [CTD_Seabird](ERROR): Salinity reading out of range: 14.393233 psu
2020-11-06T22:22:11.485Z,1604701331.485 [CTD_Seabird](ERROR): Salinity reading out of range: 14.367126 psu
2020-11-06T22:22:12.489Z,1604701332.489 [CTD_Seabird](ERROR): Salinity reading out of range: 14.321474 psu
2020-11-06T22:22:13.493Z,1604701333.493 [CTD_Seabird](ERROR): Salinity reading out of range: 14.249564 psu
2020-11-06T22:22:14.497Z,1604701334.497 [CTD_Seabird](ERROR): Salinity reading out of range: 14.219020 psu
2020-11-06T22:22:15.501Z,1604701335.501 [CTD_Seabird](ERROR): Salinity reading out of range: 14.200451 psu
2020-11-06T22:22:16.507Z,1604701336.507 [CTD_Seabird](ERROR): Salinity reading out of range: 14.148321 psu
2020-11-06T22:22:17.510Z,1604701337.510 [CTD_Seabird](ERROR): Salinity reading out of range: 14.180338 psu
2020-11-06T22:22:18.513Z,1604701338.513 [CTD_Seabird](ERROR): Salinity reading out of range: 14.209738 psu
2020-11-06T22:22:19.517Z,1604701339.517 [CTD_Seabird](ERROR): Salinity reading out of range: 14.240479 psu
2020-11-06T22:22:20.522Z,1604701340.522 [CTD_Seabird](ERROR): Salinity reading out of range: 14.222285 psu
2020-11-06T22:22:21.525Z,1604701341.525 [CTD_Seabird](ERROR): Salinity reading out of range: 14.353735 psu
2020-11-06T22:22:22.529Z,1604701342.529 [CTD_Seabird](ERROR): Salinity reading out of range: 14.072160 psu
2020-11-06T22:22:23.537Z,1604701343.537 [CTD_Seabird](ERROR): Salinity reading out of range: 5.003200 psu
2020-11-06T22:22:24.542Z,1604701344.542 [CTD_Seabird](ERROR): Salinity reading out of range: 2.674118 psu
2020-11-06T22:22:25.549Z,1604701345.549 [CTD_Seabird](ERROR): Salinity reading out of range: 2.904412 psu
2020-11-06T22:22:26.553Z,1604701346.553 [CTD_Seabird](ERROR): Salinity reading out of range: 2.397051 psu
2020-11-06T22:22:27.562Z,1604701347.562 [CTD_Seabird](ERROR): Salinity reading out of range: 1.850466 psu
2020-11-06T22:22:28.565Z,1604701348.565 [CTD_Seabird](ERROR): Salinity reading out of range: 2.206934 psu
2020-11-06T22:22:29.569Z,1604701349.569 [CTD_Seabird](ERROR): Salinity reading out of range: 1.416727 psu
2020-11-06T22:22:30.575Z,1604701350.575 [CTD_Seabird](ERROR): Salinity reading out of range: 20.279179 psu
2020-11-06T22:22:31.577Z,1604701351.577 [CTD_Seabird](ERROR): Salinity reading out of range: 18.049948 psu
2020-11-06T22:22:32.585Z,1604701352.585 [CTD_Seabird](ERROR): Salinity reading out of range: 12.063136 psu
2020-11-06T22:22:33.593Z,1604701353.593 [CTD_Seabird](ERROR): Salinity reading out of range: 26.770922 psu
2020-11-06T22:22:35.609Z,1604701355.609 [CTD_Seabird](ERROR): Salinity reading out of range: 19.462667 psu
2020-11-06T22:22:38.629Z,1604701358.629 [CTD_Seabird](ERROR): Salinity reading out of range: 24.461388 psu
2020-11-06T22:22:43.649Z,1604701363.649 [CTD_Seabird](ERROR): Salinity reading out of range: 25.550249 psu
2020-11-06T22:22:45.657Z,1604701365.657 [CTD_Seabird](ERROR): Salinity reading out of range: 26.553205 psu
2020-11-06T22:22:46.661Z,1604701366.661 [CTD_Seabird](ERROR): Salinity reading out of range: 25.832018 psu
2020-11-06T22:22:53.692Z,1604701373.692 [CTD_Seabird](ERROR): Salinity reading out of range: 27.959808 psu
2020-11-06T22:22:54.693Z,1604701374.693 [CTD_Seabird](ERROR): Salinity reading out of range: 23.247686 psu
2020-11-06T22:22:55.697Z,1604701375.697 [CTD_Seabird](ERROR): Salinity reading out of range: 19.517279 psu
2020-11-06T22:22:56.701Z,1604701376.701 [CTD_Seabird](ERROR): Salinity reading out of range: 16.465618 psu
2020-11-06T22:22:57.706Z,1604701377.706 [CTD_Seabird](ERROR): Salinity reading out of range: 16.395985 psu
2020-11-06T22:22:58.709Z,1604701378.709 [CTD_Seabird](ERROR): Salinity reading out of range: 16.111149 psu
2020-11-06T22:22:59.713Z,1604701379.713 [CTD_Seabird](ERROR): Salinity reading out of range: 15.984189 psu
2020-11-06T22:23:00.704Z,1604701380.704 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-11-06T22:23:00.704Z,1604701380.704 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:23:00.717Z,1604701380.717 [CTD_Seabird](ERROR): Salinity reading out of range: 15.924120 psu
2020-11-06T22:23:00.720Z,1604701380.720 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:23:01.110Z,1604701381.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:23:01.110Z,1604701381.110 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-11-06T22:23:01.257Z,1604701381.257 [WetLabsUBAT](ERROR): Invalid data record: got 70 tokens
2020-11-06T22:23:01.721Z,1604701381.721 [CTD_Seabird](ERROR): Salinity reading out of range: 15.878243 psu
2020-11-06T22:23:02.727Z,1604701382.727 [CTD_Seabird](ERROR): Salinity reading out of range: 15.848934 psu
2020-11-06T22:23:03.729Z,1604701383.729 [CTD_Seabird](ERROR): Salinity reading out of range: 15.674970 psu
2020-11-06T22:23:04.733Z,1604701384.733 [CTD_Seabird](ERROR): Salinity reading out of range: 2.884902 psu
2020-11-06T22:23:05.737Z,1604701385.737 [CTD_Seabird](ERROR): Salinity reading out of range: 0.979533 psu
2020-11-06T22:23:06.741Z,1604701386.741 [CTD_Seabird](ERROR): Salinity reading out of range: 0.451478 psu
2020-11-06T22:23:07.745Z,1604701387.745 [CTD_Seabird](ERROR): Salinity reading out of range: 0.296760 psu
2020-11-06T22:23:08.749Z,1604701388.749 [CTD_Seabird](ERROR): Salinity reading out of range: 0.257906 psu
2020-11-06T22:23:09.756Z,1604701389.756 [CTD_Seabird](ERROR): Salinity reading out of range: 0.206486 psu
2020-11-06T22:23:10.758Z,1604701390.758 [CTD_Seabird](ERROR): Salinity reading out of range: 0.190686 psu
2020-11-06T22:23:11.762Z,1604701391.762 [CTD_Seabird](ERROR): Salinity reading out of range: 0.182250 psu
2020-11-06T22:23:12.765Z,1604701392.765 [CTD_Seabird](ERROR): Salinity reading out of range: 0.160190 psu
2020-11-06T22:23:13.770Z,1604701393.770 [CTD_Seabird](ERROR): Salinity reading out of range: 0.153309 psu
2020-11-06T22:23:14.774Z,1604701394.774 [CTD_Seabird](ERROR): Salinity reading out of range: 0.148356 psu
2020-11-06T22:23:15.777Z,1604701395.777 [CTD_Seabird](ERROR): Salinity reading out of range: 0.144459 psu
2020-11-06T22:23:16.781Z,1604701396.781 [CTD_Seabird](ERROR): Salinity reading out of range: 0.140746 psu
2020-11-06T22:23:17.785Z,1604701397.785 [CTD_Seabird](ERROR): Salinity reading out of range: 0.140128 psu
2020-11-06T22:23:18.790Z,1604701398.790 [CTD_Seabird](ERROR): Salinity reading out of range: 0.143668 psu
2020-11-06T22:23:19.669Z,1604701399.669 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-06T22:23:19.669Z,1604701399.669 [NAL9602] Data Fault, FailCount= 2
2020-11-06T22:23:19.669Z,1604701399.669 [NAL9602](ERROR): Data Fault
2020-11-06T22:23:19.695Z,1604701399.695 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-06T22:23:19.794Z,1604701399.794 [CTD_Seabird](ERROR): Salinity reading out of range: 0.143168 psu
2020-11-06T22:23:20.069Z,1604701400.069 [NAL9602](INFO): Powering down
2020-11-06T22:23:20.798Z,1604701400.798 [CTD_Seabird](ERROR): Salinity reading out of range: 0.141909 psu
2020-11-06T22:23:20.896Z,1604701400.896 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-06T22:23:20.896Z,1604701400.896 [NAL9602] No Fault, FailCount= 2
2020-11-06T22:23:21.802Z,1604701401.802 [CTD_Seabird](ERROR): Salinity reading out of range: 0.139027 psu
2020-11-06T22:23:22.806Z,1604701402.806 [CTD_Seabird](ERROR): Salinity reading out of range: 0.137247 psu
2020-11-06T22:23:23.811Z,1604701403.811 [CTD_Seabird](ERROR): Salinity reading out of range: 0.136638 psu
2020-11-06T22:23:24.813Z,1604701404.813 [CTD_Seabird](ERROR): Salinity reading out of range: 0.139782 psu
2020-11-06T22:23:25.817Z,1604701405.817 [CTD_Seabird](ERROR): Salinity reading out of range: 0.144873 psu
2020-11-06T22:23:26.822Z,1604701406.822 [CTD_Seabird](ERROR): Salinity reading out of range: 0.150050 psu
2020-11-06T22:23:27.825Z,1604701407.825 [CTD_Seabird](ERROR): Salinity reading out of range: 0.155906 psu
2020-11-06T22:23:28.831Z,1604701408.831 [CTD_Seabird](ERROR): Salinity reading out of range: 0.160031 psu
2020-11-06T22:23:29.837Z,1604701409.837 [CTD_Seabird](ERROR): Salinity reading out of range: 0.162546 psu
2020-11-06T22:23:30.842Z,1604701410.842 [CTD_Seabird](ERROR): Salinity reading out of range: 0.164403 psu
2020-11-06T22:23:31.846Z,1604701411.846 [CTD_Seabird](ERROR): Salinity reading out of range: 0.165442 psu
2020-11-06T22:23:32.850Z,1604701412.850 [CTD_Seabird](ERROR): Salinity reading out of range: 0.167867 psu
2020-11-06T22:23:33.853Z,1604701413.853 [CTD_Seabird](ERROR): Salinity reading out of range: 0.172861 psu
2020-11-06T22:23:34.857Z,1604701414.857 [CTD_Seabird](ERROR): Salinity reading out of range: 0.178999 psu
2020-11-06T22:23:35.861Z,1604701415.861 [CTD_Seabird](ERROR): Salinity reading out of range: 0.184175 psu
2020-11-06T22:23:36.865Z,1604701416.865 [CTD_Seabird](ERROR): Salinity reading out of range: 0.187952 psu
2020-11-06T22:23:37.869Z,1604701417.869 [CTD_Seabird](ERROR): Salinity reading out of range: 0.190213 psu
2020-11-06T22:23:38.874Z,1604701418.874 [CTD_Seabird](ERROR): Salinity reading out of range: 0.191527 psu
2020-11-06T22:23:39.877Z,1604701419.877 [CTD_Seabird](ERROR): Salinity reading out of range: 0.192410 psu
2020-11-06T22:23:40.881Z,1604701420.881 [CTD_Seabird](ERROR): Salinity reading out of range: 0.193391 psu
2020-11-06T22:23:41.885Z,1604701421.885 [CTD_Seabird](ERROR): Salinity reading out of range: 0.194543 psu
2020-11-06T22:23:42.890Z,1604701422.890 [CTD_Seabird](ERROR): Salinity reading out of range: 0.196168 psu
2020-11-06T22:23:43.893Z,1604701423.893 [CTD_Seabird](ERROR): Salinity reading out of range: 0.198283 psu
2020-11-06T22:23:44.898Z,1604701424.898 [CTD_Seabird](ERROR): Salinity reading out of range: 0.199824 psu
2020-11-06T22:23:45.901Z,1604701425.901 [CTD_Seabird](ERROR): Salinity reading out of range: 0.200792 psu
2020-11-06T22:23:46.906Z,1604701426.906 [CTD_Seabird](ERROR): Salinity reading out of range: 0.201169 psu
2020-11-06T22:23:47.909Z,1604701427.909 [CTD_Seabird](ERROR): Salinity reading out of range: 0.201366 psu
2020-11-06T22:23:48.913Z,1604701428.913 [CTD_Seabird](ERROR): Salinity reading out of range: 0.201133 psu
2020-11-06T22:23:49.917Z,1604701429.917 [CTD_Seabird](ERROR): Salinity reading out of range: 0.201475 psu
2020-11-06T22:23:50.377Z,1604701430.377 [NAL9602](INFO): Powering up NAL9602
2020-11-06T22:23:50.921Z,1604701430.921 [CTD_Seabird](ERROR): Salinity reading out of range: 0.202163 psu
2020-11-06T22:23:51.925Z,1604701431.925 [CTD_Seabird](ERROR): Salinity reading out of range: 0.203033 psu
2020-11-06T22:23:52.929Z,1604701432.929 [CTD_Seabird](ERROR): Salinity reading out of range: 0.203845 psu
2020-11-06T22:23:53.933Z,1604701433.933 [CTD_Seabird](ERROR): Salinity reading out of range: 0.204844 psu
2020-11-06T22:23:54.937Z,1604701434.937 [CTD_Seabird](ERROR): Salinity reading out of range: 0.205264 psu
2020-11-06T22:23:55.941Z,1604701435.941 [CTD_Seabird](ERROR): Salinity reading out of range: 0.204860 psu
2020-11-06T22:23:56.126Z,1604701436.126 [CommandLine](IMPORTANT): got command stop
2020-11-06T22:23:56.126Z,1604701436.126 [CommandLine](IMPORTANT): Scheduling is paused
2020-11-06T22:23:56.127Z,1604701436.127 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-11-06T22:23:56.458Z,1604701436.458 [MissionManager](INFO): MissionManager is completed.
2020-11-06T22:23:56.458Z,1604701436.458 [MissionManager](INFO): Uninitializing Mission Science
2020-11-06T22:23:56.459Z,1604701436.459 [Science] Stopped
2020-11-06T22:23:56.459Z,1604701436.459 [Science](DEBUG): Aggregate::uninitialize Science
2020-11-06T22:23:56.459Z,1604701436.459 [Science:B] Stopped
2020-11-06T22:23:56.459Z,1604701436.459 [Science:C] Stopped
2020-11-06T22:23:56.459Z,1604701436.459 [Science:E] Stopped
2020-11-06T22:23:56.459Z,1604701436.459 [Science:F] Stopped
2020-11-06T22:23:56.459Z,1604701436.459 [Science:OceanCurrent] Stopped
2020-11-06T22:23:56.459Z,1604701436.459 [Science:OceanCurrent](DEBUG): Aggregate::uninitialize Science:OceanCurrent
2020-11-06T22:23:56.459Z,1604701436.459 [Science:OceanCurrent:A.] Stopped
2020-11-06T22:23:56.868Z,1604701436.868 [MissionManager](IMPORTANT): Started mission Default
2020-11-06T22:23:56.868Z,1604701436.868 [Default] Running Loop=1
2020-11-06T22:23:56.868Z,1604701436.868 [Default](DEBUG): Aggregate::initialize Default
2020-11-06T22:23:56.868Z,1604701436.868 [Default:B.GoToSurface] Running Loop=1
2020-11-06T22:23:56.868Z,1604701436.868 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-06T22:23:56.868Z,1604701436.868 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-06T22:23:56.869Z,1604701436.869 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-06T22:23:56.869Z,1604701436.869 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-06T22:23:56.869Z,1604701436.869 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-06T22:23:56.869Z,1604701436.869 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-06T22:23:56.870Z,1604701436.870 [Default:A.Wait] Running Loop=1
2020-11-06T22:23:56.870Z,1604701436.870 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:23:56.928Z,1604701436.928 [WetLabsBB2FL](INFO): Powering down
2020-11-06T22:23:57.575Z,1604701437.575 [WetLabsUBAT](INFO): Powering down
2020-11-06T22:24:01.295Z,1604701441.295 [NAL9602](INFO): NAL9602 initialized
2020-11-06T22:24:10.202Z,1604701450.202 [Default:A.Wait](INFO): Done Waiting.
2020-11-06T22:24:10.202Z,1604701450.202 [Default:A.Wait] Stopped
2020-11-06T22:24:10.202Z,1604701450.202 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:24:10.611Z,1604701450.611 [Default:CheckIn] Running Loop=1
2020-11-06T22:24:10.611Z,1604701450.611 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:24:10.612Z,1604701450.612 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:26:01.722Z,1604701561.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-11-06T22:26:01.722Z,1604701561.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-11-06T22:26:01.732Z,1604701561.732 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-11-06T22:26:02.153Z,1604701562.153 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-11-06T22:26:02.153Z,1604701562.153 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-11-06T22:26:04.942Z,1604701564.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222603.00,A,3648.16843,N,12147.28991,W,0.505,308.44,061120,,,A*7F
2020-11-06T22:26:04.999Z,1604701564.999 [NAL9602](INFO): GPS fix at 20201106T222603: (36.802807, -121.788165)
2020-11-06T22:26:05.050Z,1604701565.050 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:26:05.050Z,1604701565.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:26:06.928Z,1604701566.928 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201106T220144/Courier0010.lzma
2020-11-06T22:26:07.597Z,1604701567.597 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0010.lzma.bak
2020-11-06T22:26:07.597Z,1604701567.597 [DataOverHttps](INFO): SBD MOMSN=13113932
2020-11-06T22:26:29.996Z,1604701589.996 [DataOverHttps](INFO): Sending 582 bytes from file Logs/20201106T220144/Express0008.lzma
2020-11-06T22:26:30.997Z,1604701590.997 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0008.lzma.bak
2020-11-06T22:26:30.997Z,1604701590.997 [DataOverHttps](INFO): SBD MOMSN=13113935
2020-11-06T22:26:37.556Z,1604701597.556 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-06T22:26:50.928Z,1604701610.928 [DataOverHttps](INFO): Sending 669 bytes from file Logs/20201106T220144/Express0011.lzma
2020-11-06T22:26:51.929Z,1604701611.929 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0011.lzma.bak
2020-11-06T22:26:51.929Z,1604701611.929 [DataOverHttps](INFO): SBD MOMSN=13113946
2020-11-06T22:26:53.755Z,1604701613.755 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:26:53.756Z,1604701613.756 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T22:26:53.756Z,1604701613.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:31:54.405Z,1604701914.405 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T22:31:54.405Z,1604701914.405 [Default:CheckIn:C.Wait] Stopped
2020-11-06T22:31:54.405Z,1604701914.405 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:31:54.406Z,1604701914.406 [Default:CheckIn:D] Running Loop=1
2020-11-06T22:31:54.803Z,1604701914.803 [Default:CheckIn:D] Stopped
2020-11-06T22:31:54.803Z,1604701914.803 [Default:CheckIn:E] Running Loop=1
2020-11-06T22:31:55.219Z,1604701915.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.965582 min 
2020-11-06T22:31:55.219Z,1604701915.219 [Default:CheckIn:E] Stopped
2020-11-06T22:31:55.219Z,1604701915.219 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T22:31:55.219Z,1604701915.219 [Default:CheckIn] Stopped
2020-11-06T22:31:55.219Z,1604701915.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T22:31:55.220Z,1604701915.220 [Default:CheckIn](INFO): Running loop #2
2020-11-06T22:31:55.220Z,1604701915.220 [Default:CheckIn] Running Loop=2
2020-11-06T22:31:55.220Z,1604701915.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:31:55.220Z,1604701915.220 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:31:57.223Z,1604701917.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223156.00,A,3648.16568,N,12147.28539,W,0.097,119.90,061120,,,D*73
2020-11-06T22:31:57.225Z,1604701917.225 [NAL9602](INFO): GPS fix at 20201106T223156: (36.802761, -121.788090)
2020-11-06T22:31:57.242Z,1604701917.242 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:31:57.243Z,1604701917.243 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:32:14.594Z,1604701934.594 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201106T220144/Courier0013.lzma
2020-11-06T22:32:15.597Z,1604701935.597 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0013.lzma.bak
2020-11-06T22:32:15.597Z,1604701935.597 [DataOverHttps](INFO): SBD MOMSN=13113997
2020-11-06T22:32:25.194Z,1604701945.194 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0
2020-11-06T22:32:25.194Z,1604701945.194 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-06T22:32:39.851Z,1604701959.851 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20201106T220144/Express0014.lzma
2020-11-06T22:32:40.853Z,1604701960.853 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0014.lzma.bak
2020-11-06T22:32:40.853Z,1604701960.853 [DataOverHttps](INFO): SBD MOMSN=13114000
2020-11-06T22:32:43.434Z,1604701963.434 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0
2020-11-06T22:32:43.434Z,1604701963.434 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-06T22:32:43.611Z,1604701963.611 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:32:43.639Z,1604701963.639 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T22:32:43.639Z,1604701963.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:33:41.206Z,1604702021.206 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0
2020-11-06T22:33:41.206Z,1604702021.206 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-06T22:33:56.158Z,1604702036.158 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0
2020-11-06T22:33:56.158Z,1604702036.158 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-06T22:34:14.338Z,1604702054.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0
2020-11-06T22:34:14.338Z,1604702054.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-06T22:34:30.731Z,1604702070.731 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:34:30.731Z,1604702070.731 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:34:30.731Z,1604702070.731 [CommandLine](IMPORTANT): No failed Components.
2020-11-06T22:34:52.488Z,1604702092.488 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 250.000000 cubic_centimeter
2020-11-06T22:34:52.489Z,1604702092.489 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2020-11-06T22:34:52.760Z,1604702092.760 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-11-06T22:35:01.968Z,1604702101.968 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2020-11-06T22:35:02.093Z,1604702102.093 [Reporter](INFO): platform_buoyancy_position 925.971313 cc
2020-11-06T22:35:02.513Z,1604702102.513 [Reporter](INFO): platform_buoyancy_position 921.539429 cc
2020-11-06T22:35:02.889Z,1604702102.889 [Reporter](INFO): platform_buoyancy_position 915.227295 cc
2020-11-06T22:35:03.337Z,1604702103.337 [Reporter](INFO): platform_buoyancy_position 910.795410 cc
2020-11-06T22:35:03.709Z,1604702103.709 [Reporter](INFO): platform_buoyancy_position 904.617554 cc
2020-11-06T22:35:04.085Z,1604702104.085 [Reporter](INFO): platform_buoyancy_position 900.051392 cc
2020-11-06T22:35:04.517Z,1604702104.517 [Reporter](INFO): platform_buoyancy_position 893.739258 cc
2020-11-06T22:35:04.885Z,1604702104.885 [Reporter](INFO): platform_buoyancy_position 889.441650 cc
2020-11-06T22:35:05.337Z,1604702105.337 [Reporter](INFO): platform_buoyancy_position 882.995239 cc
2020-11-06T22:35:05.722Z,1604702105.722 [Reporter](INFO): platform_buoyancy_position 878.563354 cc
2020-11-06T22:35:06.105Z,1604702106.105 [Reporter](INFO): platform_buoyancy_position 874.265747 cc
2020-11-06T22:35:06.521Z,1604702106.521 [Reporter](INFO): platform_buoyancy_position 867.819336 cc
2020-11-06T22:35:06.937Z,1604702106.937 [Reporter](INFO): platform_buoyancy_position 863.521729 cc
2020-11-06T22:35:07.391Z,1604702107.391 [Reporter](INFO): platform_buoyancy_position 857.209717 cc
2020-11-06T22:35:07.754Z,1604702107.754 [Reporter](INFO): platform_buoyancy_position 852.777832 cc
2020-11-06T22:35:08.141Z,1604702108.141 [Reporter](INFO): platform_buoyancy_position 846.465698 cc
2020-11-06T22:35:08.556Z,1604702108.556 [Reporter](INFO): platform_buoyancy_position 842.168091 cc
2020-11-06T22:35:08.945Z,1604702108.945 [Reporter](INFO): platform_buoyancy_position 837.198975 cc
2020-11-06T22:35:09.381Z,1604702109.381 [Reporter](INFO): platform_buoyancy_position 831.289795 cc
2020-11-06T22:35:09.733Z,1604702109.733 [Reporter](INFO): platform_buoyancy_position 826.992188 cc
2020-11-06T22:35:10.161Z,1604702110.161 [Reporter](INFO): platform_buoyancy_position 820.545776 cc
2020-11-06T22:35:10.581Z,1604702110.581 [Reporter](INFO): platform_buoyancy_position 814.905151 cc
2020-11-06T22:35:10.953Z,1604702110.953 [Reporter](INFO): platform_buoyancy_position 809.936096 cc
2020-11-06T22:35:11.369Z,1604702111.369 [Reporter](INFO): platform_buoyancy_position 805.504150 cc
2020-11-06T22:35:11.785Z,1604702111.785 [Reporter](INFO): platform_buoyancy_position 799.192078 cc
2020-11-06T22:35:12.201Z,1604702112.201 [Reporter](INFO): platform_buoyancy_position 794.894470 cc
2020-11-06T22:35:12.624Z,1604702112.624 [Reporter](INFO): platform_buoyancy_position 788.448059 cc
2020-11-06T22:35:12.989Z,1604702112.989 [Reporter](INFO): platform_buoyancy_position 784.016174 cc
2020-11-06T22:35:13.409Z,1604702113.409 [Reporter](INFO): platform_buoyancy_position 777.704102 cc
2020-11-06T22:35:13.809Z,1604702113.809 [Reporter](INFO): platform_buoyancy_position 773.272156 cc
2020-11-06T22:35:14.205Z,1604702114.205 [Reporter](INFO): platform_buoyancy_position 768.840271 cc
2020-11-06T22:35:14.616Z,1604702114.616 [Reporter](INFO): platform_buoyancy_position 762.662476 cc
2020-11-06T22:35:15.009Z,1604702115.009 [Reporter](INFO): platform_buoyancy_position 758.230591 cc
2020-11-06T22:35:15.449Z,1604702115.449 [Reporter](INFO): platform_buoyancy_position 751.784180 cc
2020-11-06T22:35:15.817Z,1604702115.817 [Reporter](INFO): platform_buoyancy_position 747.352295 cc
2020-11-06T22:35:16.229Z,1604702116.229 [Reporter](INFO): platform_buoyancy_position 741.174500 cc
2020-11-06T22:35:16.568Z,1604702116.568 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-11-06T22:35:16.641Z,1604702116.641 [Reporter](INFO): platform_buoyancy_position 736.742554 cc
2020-11-06T22:35:17.013Z,1604702117.013 [Reporter](INFO): platform_buoyancy_position 730.430481 cc
2020-11-06T22:35:17.429Z,1604702117.429 [Reporter](INFO): platform_buoyancy_position 725.998596 cc
2020-11-06T22:35:17.853Z,1604702117.853 [Reporter](INFO): platform_buoyancy_position 719.686462 cc
2020-11-06T22:35:18.289Z,1604702118.289 [Reporter](INFO): platform_buoyancy_position 715.120239 cc
2020-11-06T22:35:18.681Z,1604702118.681 [Reporter](INFO): platform_buoyancy_position 708.808167 cc
2020-11-06T22:35:19.088Z,1604702119.088 [Reporter](INFO): platform_buoyancy_position 702.630371 cc
2020-11-06T22:35:19.498Z,1604702119.498 [Reporter](INFO): platform_buoyancy_position 698.198486 cc
2020-11-06T22:35:19.869Z,1604702119.869 [Reporter](INFO): platform_buoyancy_position 693.900879 cc
2020-11-06T22:35:20.302Z,1604702120.302 [Reporter](INFO): platform_buoyancy_position 687.320190 cc
2020-11-06T22:35:20.685Z,1604702120.685 [Reporter](INFO): platform_buoyancy_position 683.022583 cc
2020-11-06T22:35:21.109Z,1604702121.109 [Reporter](INFO): platform_buoyancy_position 676.710449 cc
2020-11-06T22:35:21.469Z,1604702121.469 [Reporter](INFO): platform_buoyancy_position 672.278564 cc
2020-11-06T22:35:21.888Z,1604702121.888 [Reporter](INFO): platform_buoyancy_position 666.100769 cc
2020-11-06T22:35:22.308Z,1604702122.308 [Reporter](INFO): platform_buoyancy_position 661.534546 cc
2020-11-06T22:35:22.677Z,1604702122.677 [Reporter](INFO): platform_buoyancy_position 657.102661 cc
2020-11-06T22:35:23.082Z,1604702123.082 [Reporter](INFO): platform_buoyancy_position 651.059143 cc
2020-11-06T22:35:23.505Z,1604702123.505 [Reporter](INFO): platform_buoyancy_position 646.224365 cc
2020-11-06T22:35:23.897Z,1604702123.897 [Reporter](INFO): platform_buoyancy_position 640.046570 cc
2020-11-06T22:35:24.297Z,1604702124.297 [Reporter](INFO): platform_buoyancy_position 635.748962 cc
2020-11-06T22:35:24.701Z,1604702124.701 [Reporter](INFO): platform_buoyancy_position 629.302551 cc
2020-11-06T22:35:25.117Z,1604702125.117 [Reporter](INFO): platform_buoyancy_position 625.004944 cc
2020-11-06T22:35:25.533Z,1604702125.533 [Reporter](INFO): platform_buoyancy_position 618.692871 cc
2020-11-06T22:35:25.901Z,1604702125.901 [Reporter](INFO): platform_buoyancy_position 614.260986 cc
2020-11-06T22:35:26.349Z,1604702126.349 [Reporter](INFO): platform_buoyancy_position 607.948853 cc
2020-11-06T22:35:26.733Z,1604702126.733 [Reporter](INFO): platform_buoyancy_position 603.382629 cc
2020-11-06T22:35:27.129Z,1604702127.129 [Reporter](INFO): platform_buoyancy_position 597.473450 cc
2020-11-06T22:35:27.525Z,1604702127.525 [Reporter](INFO): platform_buoyancy_position 593.041565 cc
2020-11-06T22:35:27.937Z,1604702127.937 [Reporter](INFO): platform_buoyancy_position 588.475342 cc
2020-11-06T22:35:28.357Z,1604702128.357 [Reporter](INFO): platform_buoyancy_position 582.163269 cc
2020-11-06T22:35:28.729Z,1604702128.729 [Reporter](INFO): platform_buoyancy_position 577.597046 cc
2020-11-06T22:35:29.133Z,1604702129.133 [Reporter](INFO): platform_buoyancy_position 571.284973 cc
2020-11-06T22:35:29.549Z,1604702129.549 [Reporter](INFO): platform_buoyancy_position 566.987366 cc
2020-11-06T22:35:29.957Z,1604702129.957 [Reporter](INFO): platform_buoyancy_position 560.540955 cc
2020-11-06T22:35:30.385Z,1604702130.385 [Reporter](INFO): platform_buoyancy_position 556.243347 cc
2020-11-06T22:35:30.785Z,1604702130.785 [Reporter](INFO): platform_buoyancy_position 550.065552 cc
2020-11-06T22:35:31.164Z,1604702131.164 [Reporter](INFO): platform_buoyancy_position 545.633667 cc
2020-11-06T22:35:31.581Z,1604702131.581 [Reporter](INFO): platform_buoyancy_position 539.052979 cc
2020-11-06T22:35:31.995Z,1604702131.995 [Reporter](INFO): platform_buoyancy_position 534.889648 cc
2020-11-06T22:35:32.394Z,1604702132.394 [Reporter](INFO): platform_buoyancy_position 528.577576 cc
2020-11-06T22:35:32.784Z,1604702132.784 [Reporter](INFO): platform_buoyancy_position 524.011353 cc
2020-11-06T22:35:33.196Z,1604702133.196 [Reporter](INFO): platform_buoyancy_position 519.713745 cc
2020-11-06T22:35:33.613Z,1604702133.613 [Reporter](INFO): platform_buoyancy_position 513.401672 cc
2020-11-06T22:35:33.989Z,1604702133.989 [Reporter](INFO): platform_buoyancy_position 508.969696 cc
2020-11-06T22:35:34.397Z,1604702134.397 [Reporter](INFO): platform_buoyancy_position 502.657684 cc
2020-11-06T22:35:34.810Z,1604702134.810 [Reporter](INFO): platform_buoyancy_position 497.822876 cc
2020-11-06T22:35:35.229Z,1604702135.229 [Reporter](INFO): platform_buoyancy_position 491.913635 cc
2020-11-06T22:35:35.605Z,1604702135.605 [Reporter](INFO): platform_buoyancy_position 487.481750 cc
2020-11-06T22:35:36.013Z,1604702136.013 [Reporter](INFO): platform_buoyancy_position 481.169678 cc
2020-11-06T22:35:36.437Z,1604702136.437 [Reporter](INFO): platform_buoyancy_position 476.737732 cc
2020-11-06T22:35:36.809Z,1604702136.809 [Reporter](INFO): platform_buoyancy_position 470.425659 cc
2020-11-06T22:35:37.224Z,1604702137.224 [Reporter](INFO): platform_buoyancy_position 465.993774 cc
2020-11-06T22:35:37.641Z,1604702137.641 [Reporter](INFO): platform_buoyancy_position 459.681641 cc
2020-11-06T22:35:38.038Z,1604702138.038 [Reporter](INFO): platform_buoyancy_position 455.249756 cc
2020-11-06T22:35:38.433Z,1604702138.433 [Reporter](INFO): platform_buoyancy_position 450.817871 cc
2020-11-06T22:35:38.841Z,1604702138.841 [Reporter](INFO): platform_buoyancy_position 444.640076 cc
2020-11-06T22:35:39.270Z,1604702139.270 [Reporter](INFO): platform_buoyancy_position 438.462219 cc
2020-11-06T22:35:39.677Z,1604702139.677 [Reporter](INFO): platform_buoyancy_position 433.761719 cc
2020-11-06T22:35:40.081Z,1604702140.081 [Reporter](INFO): platform_buoyancy_position 427.583923 cc
2020-11-06T22:35:40.469Z,1604702140.469 [Reporter](INFO): platform_buoyancy_position 423.017761 cc
2020-11-06T22:35:40.873Z,1604702140.873 [Reporter](INFO): platform_buoyancy_position 418.451538 cc
2020-11-06T22:35:41.289Z,1604702141.289 [Reporter](INFO): platform_buoyancy_position 412.273743 cc
2020-11-06T22:35:41.686Z,1604702141.686 [Reporter](INFO): platform_buoyancy_position 405.961670 cc
2020-11-06T22:35:42.077Z,1604702142.077 [Reporter](INFO): platform_buoyancy_position 401.529724 cc
2020-11-06T22:35:42.493Z,1604702142.493 [Reporter](INFO): platform_buoyancy_position 395.351929 cc
2020-11-06T22:35:42.886Z,1604702142.886 [Reporter](INFO): platform_buoyancy_position 390.920044 cc
2020-11-06T22:35:43.282Z,1604702143.282 [Reporter](INFO): platform_buoyancy_position 386.622437 cc
2020-11-06T22:35:43.693Z,1604702143.693 [Reporter](INFO): platform_buoyancy_position 380.310364 cc
2020-11-06T22:35:44.113Z,1604702144.113 [Reporter](INFO): platform_buoyancy_position 375.341248 cc
2020-11-06T22:35:44.529Z,1604702144.529 [Reporter](INFO): platform_buoyancy_position 369.432068 cc
2020-11-06T22:35:44.929Z,1604702144.929 [Reporter](INFO): platform_buoyancy_position 365.134460 cc
2020-11-06T22:35:45.349Z,1604702145.349 [Reporter](INFO): platform_buoyancy_position 358.822327 cc
2020-11-06T22:35:45.725Z,1604702145.725 [Reporter](INFO): platform_buoyancy_position 354.390442 cc
2020-11-06T22:35:46.141Z,1604702146.141 [Reporter](INFO): platform_buoyancy_position 348.212646 cc
2020-11-06T22:35:46.509Z,1604702146.509 [Reporter](INFO): platform_buoyancy_position 343.780762 cc
2020-11-06T22:35:46.921Z,1604702146.921 [Reporter](INFO): platform_buoyancy_position 339.214539 cc
2020-11-06T22:35:47.366Z,1604702147.366 [Reporter](INFO): platform_buoyancy_position 332.902466 cc
2020-11-06T22:35:47.709Z,1604702147.709 [Reporter](INFO): platform_buoyancy_position 328.470520 cc
2020-11-06T22:35:48.125Z,1604702148.125 [Reporter](INFO): platform_buoyancy_position 322.158447 cc
2020-11-06T22:35:48.545Z,1604702148.545 [Reporter](INFO): platform_buoyancy_position 317.860840 cc
2020-11-06T22:35:48.961Z,1604702148.961 [Reporter](INFO): platform_buoyancy_position 311.280151 cc
2020-11-06T22:35:49.373Z,1604702149.373 [Reporter](INFO): platform_buoyancy_position 306.982544 cc
2020-11-06T22:35:49.781Z,1604702149.781 [Reporter](INFO): platform_buoyancy_position 300.536133 cc
2020-11-06T22:35:50.157Z,1604702150.157 [Reporter](INFO): platform_buoyancy_position 296.238525 cc
2020-11-06T22:35:50.569Z,1604702150.569 [Reporter](INFO): platform_buoyancy_position 289.792145 cc
2020-11-06T22:35:50.961Z,1604702150.961 [Reporter](INFO): platform_buoyancy_position 285.494537 cc
2020-11-06T22:35:51.381Z,1604702151.381 [Reporter](INFO): platform_buoyancy_position 279.182434 cc
2020-11-06T22:35:51.777Z,1604702151.777 [Reporter](INFO): platform_buoyancy_position 274.750549 cc
2020-11-06T22:35:52.178Z,1604702152.178 [Reporter](INFO): platform_buoyancy_position 270.452942 cc
2020-11-06T22:35:52.601Z,1604702152.601 [Reporter](INFO): platform_buoyancy_position 264.140839 cc
2020-11-06T22:35:53.009Z,1604702153.009 [Reporter](INFO): platform_buoyancy_position 259.574646 cc
2020-11-06T22:36:52.883Z,1604702212.883 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2020-11-06T22:36:58.561Z,1604702218.561 [Reporter](INFO): platform_buoyancy_position 249.099243 cc
2020-11-06T22:36:58.901Z,1604702218.901 [Reporter](INFO): platform_buoyancy_position 248.964935 cc
2020-11-06T22:36:59.329Z,1604702219.329 [Reporter](INFO): platform_buoyancy_position 248.830627 cc
2020-11-06T22:36:59.644Z,1604702219.644 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-06T22:36:59.737Z,1604702219.737 [Reporter](INFO): platform_buoyancy_position 246.681824 cc
2020-11-06T22:37:00.093Z,1604702220.093 [Reporter](INFO): platform_buoyancy_position 244.801636 cc
2020-11-06T22:37:00.513Z,1604702220.513 [Reporter](INFO): platform_buoyancy_position 245.338837 cc
2020-11-06T22:37:00.929Z,1604702220.929 [Reporter](INFO): platform_buoyancy_position 244.667313 cc
2020-11-06T22:37:01.341Z,1604702221.341 [Reporter](INFO): platform_buoyancy_position 242.787140 cc
2020-11-06T22:37:01.725Z,1604702221.725 [Reporter](INFO): platform_buoyancy_position 242.518524 cc
2020-11-06T22:37:02.133Z,1604702222.133 [Reporter](INFO): platform_buoyancy_position 240.906921 cc
2020-11-06T22:37:02.553Z,1604702222.553 [Reporter](INFO): platform_buoyancy_position 240.369720 cc
2020-11-06T22:37:02.921Z,1604702222.921 [Reporter](INFO): platform_buoyancy_position 238.220947 cc
2020-11-06T22:37:03.377Z,1604702223.377 [Reporter](INFO): platform_buoyancy_position 236.475037 cc
2020-11-06T22:37:03.745Z,1604702223.745 [Reporter](INFO): platform_buoyancy_position 236.206421 cc
2020-11-06T22:37:04.161Z,1604702224.161 [Reporter](INFO): platform_buoyancy_position 233.923340 cc
2020-11-06T22:37:04.576Z,1604702224.576 [Reporter](INFO): platform_buoyancy_position 233.789032 cc
2020-11-06T22:37:04.945Z,1604702224.945 [Reporter](INFO): platform_buoyancy_position 231.774536 cc
2020-11-06T22:37:05.419Z,1604702225.419 [Reporter](INFO): platform_buoyancy_position 229.760040 cc
2020-11-06T22:37:05.790Z,1604702225.790 [Reporter](INFO): platform_buoyancy_position 229.491425 cc
2020-11-06T22:37:06.204Z,1604702226.204 [Reporter](INFO): platform_buoyancy_position 227.476929 cc
2020-11-06T22:37:06.610Z,1604702226.610 [Reporter](INFO): platform_buoyancy_position 225.462433 cc
2020-11-06T22:37:07.417Z,1604702227.417 [Reporter](INFO): platform_buoyancy_position 223.313629 cc
2020-11-06T22:37:07.793Z,1604702227.793 [Reporter](INFO): platform_buoyancy_position 223.045044 cc
2020-11-06T22:37:08.197Z,1604702228.197 [Reporter](INFO): platform_buoyancy_position 221.030518 cc
2020-11-06T22:37:08.602Z,1604702228.602 [Reporter](INFO): platform_buoyancy_position 219.150330 cc
2020-11-06T22:37:08.985Z,1604702228.985 [Reporter](INFO): platform_buoyancy_position 218.881744 cc
2020-11-06T22:37:09.408Z,1604702229.408 [Reporter](INFO): platform_buoyancy_position 216.867218 cc
2020-11-06T22:37:09.817Z,1604702229.817 [Reporter](INFO): platform_buoyancy_position 216.732941 cc
2020-11-06T22:37:10.205Z,1604702230.205 [Reporter](INFO): platform_buoyancy_position 214.584137 cc
2020-11-06T22:37:10.621Z,1604702230.621 [Reporter](INFO): platform_buoyancy_position 212.703918 cc
2020-11-06T22:37:11.025Z,1604702231.025 [Reporter](INFO): platform_buoyancy_position 212.435333 cc
2020-11-06T22:37:11.441Z,1604702231.441 [Reporter](INFO): platform_buoyancy_position 210.286530 cc
2020-11-06T22:37:12.229Z,1604702232.229 [Reporter](INFO): platform_buoyancy_position 208.272034 cc
2020-11-06T22:37:30.342Z,1604702250.342 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-06T22:37:44.129Z,1604702264.129 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T22:37:44.129Z,1604702264.129 [Default:CheckIn:C.Wait] Stopped
2020-11-06T22:37:44.129Z,1604702264.129 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:37:44.129Z,1604702264.129 [Default:CheckIn:D] Running Loop=1
2020-11-06T22:37:44.535Z,1604702264.535 [Default:CheckIn:D] Stopped
2020-11-06T22:37:44.536Z,1604702264.536 [Default:CheckIn:E] Running Loop=1
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.794461 min 
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn:E] Stopped
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn] Stopped
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn](INFO): Running loop #3
2020-11-06T22:37:44.906Z,1604702264.906 [Default:CheckIn] Running Loop=3
2020-11-06T22:37:44.907Z,1604702264.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:37:44.907Z,1604702264.907 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:37:46.914Z,1604702266.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223746.00,A,3648.16170,N,12147.28557,W,0.156,57.92,061120,,,A*41
2020-11-06T22:37:46.926Z,1604702266.926 [NAL9602](INFO): GPS fix at 20201106T223746: (36.802695, -121.788093)
2020-11-06T22:37:46.938Z,1604702266.938 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:37:46.938Z,1604702266.938 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:37:55.039Z,1604702275.039 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201106T220144/Courier0016.lzma
2020-11-06T22:37:56.041Z,1604702276.041 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0016.lzma.bak
2020-11-06T22:37:56.041Z,1604702276.041 [DataOverHttps](INFO): SBD MOMSN=13114051
2020-11-06T22:38:12.199Z,1604702292.199 [DataOverHttps](INFO): Sending 366 bytes from file Logs/20201106T220144/Express0017.lzma
2020-11-06T22:38:13.213Z,1604702293.213 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0017.lzma.bak
2020-11-06T22:38:13.213Z,1604702293.213 [DataOverHttps](INFO): SBD MOMSN=13114054
2020-11-06T22:38:14.808Z,1604702294.808 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:38:14.808Z,1604702294.808 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T22:38:14.808Z,1604702294.808 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:38:17.629Z,1604702297.629 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-06T22:38:17.711Z,1604702297.711 [NAL9602](FAULT): received: 
+CSQ:0

OK126, 2, 0, 0, 0

OK
2020-11-06T22:38:17.711Z,1604702297.711 [NAL9602] Data Fault, FailCount= 3
2020-11-06T22:38:17.711Z,1604702297.711 [NAL9602](ERROR): Data Fault
2020-11-06T22:38:17.846Z,1604702297.846 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-06T22:38:18.036Z,1604702298.036 [NAL9602](INFO): Powering down
2020-11-06T22:38:18.948Z,1604702298.948 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-06T22:38:18.948Z,1604702298.948 [NAL9602] No Fault, FailCount= 3
2020-11-06T22:38:48.392Z,1604702328.392 [NAL9602](INFO): Powering up NAL9602
2020-11-06T22:38:59.306Z,1604702339.306 [NAL9602](INFO): NAL9602 initialized
2020-11-06T22:39:30.400Z,1604702370.400 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-06T22:39:51.415Z,1604702391.415 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-06T22:42:06.231Z,1604702526.231 [CommandLine](IMPORTANT): got command failComponent
2020-11-06T22:42:06.231Z,1604702526.231 [CommandLine](IMPORTANT): Failed components:
2020-11-06T22:42:06.231Z,1604702526.231 [CommandLine](IMPORTANT): No failed Components.
2020-11-06T22:42:08.711Z,1604702528.711 [CommandLine](IMPORTANT): got command gfscan
2020-11-06T22:42:08.800Z,1604702528.800 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-06T22:42:19.730Z,1604702539.730 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008166
CHAN A1 (24V): 0.000404
CHAN A2 (12V): -0.005593
CHAN A3 (5V): -0.001898
CHAN B0 (3.3V): 0.000917
CHAN B1 (3.15aV): -0.000075
CHAN B2 (3.15bV): 0.000957
CHAN B3 (GND): 0.002057
OPEN: 0.005262
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-06T22:43:15.527Z,1604702595.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T22:43:15.527Z,1604702595.527 [Default:CheckIn:C.Wait] Stopped
2020-11-06T22:43:15.527Z,1604702595.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:43:15.528Z,1604702595.528 [Default:CheckIn:D] Running Loop=1
2020-11-06T22:43:15.901Z,1604702595.901 [Default:CheckIn:D] Stopped
2020-11-06T22:43:15.901Z,1604702595.901 [Default:CheckIn:E] Running Loop=1
2020-11-06T22:43:16.326Z,1604702596.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.317218 min 
2020-11-06T22:43:16.326Z,1604702596.326 [Default:CheckIn:E] Stopped
2020-11-06T22:43:16.326Z,1604702596.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T22:43:16.326Z,1604702596.326 [Default:CheckIn] Stopped
2020-11-06T22:43:16.327Z,1604702596.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T22:43:16.327Z,1604702596.327 [Default:CheckIn](INFO): Running loop #4
2020-11-06T22:43:16.327Z,1604702596.327 [Default:CheckIn] Running Loop=4
2020-11-06T22:43:16.327Z,1604702596.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:43:16.327Z,1604702596.327 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:43:19.932Z,1604702599.932 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-06T22:46:17.109Z,1604702777.109 [CommandLine](IMPORTANT): got command maintain clear
2020-11-06T22:46:17.513Z,1604702777.513 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,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,
2020-11-06T22:46:23.549Z,1604702783.549 [Reporter](INFO): platform_buoyancy_position 203.840118 cc
2020-11-06T22:46:24.665Z,1604702784.665 [Reporter](INFO): platform_buoyancy_position 205.988922 cc
2020-11-06T22:46:25.024Z,1604702785.024 [Reporter](INFO): platform_buoyancy_position 210.286530 cc
2020-11-06T22:46:25.457Z,1604702785.457 [Reporter](INFO): platform_buoyancy_position 216.867218 cc
2020-11-06T22:46:25.853Z,1604702785.853 [Reporter](INFO): platform_buoyancy_position 218.881744 cc
2020-11-06T22:46:26.262Z,1604702786.262 [Reporter](INFO): platform_buoyancy_position 225.328125 cc
2020-11-06T22:46:26.717Z,1604702786.717 [Reporter](INFO): platform_buoyancy_position 231.774536 cc
2020-11-06T22:46:27.045Z,1604702787.045 [Reporter](INFO): platform_buoyancy_position 236.072144 cc
2020-11-06T22:46:27.477Z,1604702787.477 [Reporter](INFO): platform_buoyancy_position 240.638336 cc
2020-11-06T22:46:27.865Z,1604702787.865 [Reporter](INFO): platform_buoyancy_position 246.816147 cc
2020-11-06T22:46:28.291Z,1604702788.291 [Reporter](INFO): platform_buoyancy_position 251.382324 cc
2020-11-06T22:46:28.697Z,1604702788.697 [Reporter](INFO): platform_buoyancy_position 257.560120 cc
2020-11-06T22:46:29.076Z,1604702789.076 [Reporter](INFO): platform_buoyancy_position 262.394928 cc
2020-11-06T22:46:29.485Z,1604702789.485 [Reporter](INFO): platform_buoyancy_position 268.572723 cc
2020-11-06T22:46:29.890Z,1604702789.890 [Reporter](INFO): platform_buoyancy_position 273.944733 cc
2020-11-06T22:46:30.295Z,1604702790.295 [Reporter](INFO): platform_buoyancy_position 279.048126 cc
2020-11-06T22:46:30.700Z,1604702790.700 [Reporter](INFO): platform_buoyancy_position 283.480042 cc
2020-11-06T22:46:31.081Z,1604702791.081 [Reporter](INFO): platform_buoyancy_position 289.926422 cc
2020-11-06T22:46:31.510Z,1604702791.510 [Reporter](INFO): platform_buoyancy_position 294.626923 cc
2020-11-06T22:46:31.925Z,1604702791.925 [Reporter](INFO): platform_buoyancy_position 300.670441 cc
2020-11-06T22:46:32.353Z,1604702792.353 [Reporter](INFO): platform_buoyancy_position 306.848236 cc
2020-11-06T22:46:32.724Z,1604702792.724 [Reporter](INFO): platform_buoyancy_position 311.280151 cc
2020-11-06T22:46:33.105Z,1604702793.105 [Reporter](INFO): platform_buoyancy_position 315.712036 cc
2020-11-06T22:46:33.640Z,1604702793.640 [Reporter](INFO): platform_buoyancy_position 322.158447 cc
2020-11-06T22:46:33.960Z,1604702793.960 [Reporter](INFO): platform_buoyancy_position 326.321716 cc
2020-11-06T22:46:34.465Z,1604702794.465 [Reporter](INFO): platform_buoyancy_position 332.768127 cc
2020-11-06T22:46:34.769Z,1604702794.769 [Reporter](INFO): platform_buoyancy_position 337.200012 cc
2020-11-06T22:46:35.160Z,1604702795.160 [Reporter](INFO): platform_buoyancy_position 341.363342 cc
2020-11-06T22:46:35.561Z,1604702795.561 [Reporter](INFO): platform_buoyancy_position 347.809753 cc
2020-11-06T22:46:35.948Z,1604702795.948 [Reporter](INFO): platform_buoyancy_position 352.241638 cc
2020-11-06T22:46:36.417Z,1604702796.417 [Reporter](INFO): platform_buoyancy_position 358.688049 cc
2020-11-06T22:46:36.779Z,1604702796.779 [Reporter](INFO): platform_buoyancy_position 362.985657 cc
2020-11-06T22:46:37.153Z,1604702797.153 [Reporter](INFO): platform_buoyancy_position 367.283264 cc
2020-11-06T22:46:37.616Z,1604702797.616 [Reporter](INFO): platform_buoyancy_position 374.804016 cc
2020-11-06T22:46:37.985Z,1604702797.985 [Reporter](INFO): platform_buoyancy_position 380.041748 cc
2020-11-06T22:46:38.453Z,1604702798.453 [Reporter](INFO): platform_buoyancy_position 386.622437 cc
2020-11-06T22:46:38.763Z,1604702798.763 [Reporter](INFO): platform_buoyancy_position 390.517151 cc
2020-11-06T22:46:39.197Z,1604702799.197 [Reporter](INFO): platform_buoyancy_position 395.083374 cc
2020-11-06T22:46:39.601Z,1604702799.601 [Reporter](INFO): platform_buoyancy_position 401.529724 cc
2020-11-06T22:46:40.004Z,1604702800.004 [Reporter](INFO): platform_buoyancy_position 405.961670 cc
2020-11-06T22:46:40.429Z,1604702800.429 [Reporter](INFO): platform_buoyancy_position 412.005127 cc
2020-11-06T22:46:40.799Z,1604702800.799 [Reporter](INFO): platform_buoyancy_position 416.571350 cc
2020-11-06T22:46:41.201Z,1604702801.201 [Reporter](INFO): platform_buoyancy_position 421.137573 cc
2020-11-06T22:46:41.617Z,1604702801.617 [Reporter](INFO): platform_buoyancy_position 427.449646 cc
2020-11-06T22:46:41.989Z,1604702801.989 [Reporter](INFO): platform_buoyancy_position 431.612976 cc
2020-11-06T22:46:42.406Z,1604702802.406 [Reporter](INFO): platform_buoyancy_position 438.059326 cc
2020-11-06T22:46:42.817Z,1604702802.817 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2020-11-06T22:46:43.209Z,1604702803.209 [Reporter](INFO): platform_buoyancy_position 448.669067 cc
2020-11-06T22:46:43.637Z,1604702803.637 [Reporter](INFO): platform_buoyancy_position 453.235229 cc
2020-11-06T22:46:44.027Z,1604702804.027 [Reporter](INFO): platform_buoyancy_position 459.547363 cc
2020-11-06T22:46:44.447Z,1604702804.447 [Reporter](INFO): platform_buoyancy_position 464.247864 cc
2020-11-06T22:46:44.817Z,1604702804.817 [Reporter](INFO): platform_buoyancy_position 470.291321 cc
2020-11-06T22:46:45.233Z,1604702805.233 [Reporter](INFO): platform_buoyancy_position 474.723267 cc
2020-11-06T22:46:45.653Z,1604702805.653 [Reporter](INFO): platform_buoyancy_position 481.169678 cc
2020-11-06T22:46:46.021Z,1604702806.021 [Reporter](INFO): platform_buoyancy_position 485.332947 cc
2020-11-06T22:46:46.481Z,1604702806.481 [Reporter](INFO): platform_buoyancy_position 491.510742 cc
2020-11-06T22:46:46.862Z,1604702806.862 [Reporter](INFO): platform_buoyancy_position 496.076935 cc
2020-11-06T22:46:47.286Z,1604702807.286 [Reporter](INFO): platform_buoyancy_position 500.508881 cc
2020-11-06T22:46:47.657Z,1604702807.657 [Reporter](INFO): platform_buoyancy_position 506.820923 cc
2020-11-06T22:46:48.049Z,1604702808.049 [Reporter](INFO): platform_buoyancy_position 511.252838 cc
2020-11-06T22:46:48.479Z,1604702808.479 [Reporter](INFO): platform_buoyancy_position 517.699280 cc
2020-11-06T22:46:48.861Z,1604702808.861 [Reporter](INFO): platform_buoyancy_position 521.996826 cc
2020-11-06T22:46:49.271Z,1604702809.271 [Reporter](INFO): platform_buoyancy_position 528.308960 cc
2020-11-06T22:46:49.687Z,1604702809.687 [Reporter](INFO): platform_buoyancy_position 532.740845 cc
2020-11-06T22:46:50.108Z,1604702810.108 [Reporter](INFO): platform_buoyancy_position 539.052979 cc
2020-11-06T22:46:50.520Z,1604702810.520 [Reporter](INFO): platform_buoyancy_position 543.484863 cc
2020-11-06T22:46:50.889Z,1604702810.889 [Reporter](INFO): platform_buoyancy_position 548.051025 cc
2020-11-06T22:46:51.320Z,1604702811.320 [Reporter](INFO): platform_buoyancy_position 554.228882 cc
2020-11-06T22:46:51.729Z,1604702811.729 [Reporter](INFO): platform_buoyancy_position 560.540955 cc
2020-11-06T22:46:52.085Z,1604702812.085 [Reporter](INFO): platform_buoyancy_position 564.972839 cc
2020-11-06T22:46:52.533Z,1604702812.533 [Reporter](INFO): platform_buoyancy_position 571.150635 cc
2020-11-06T22:46:52.928Z,1604702812.928 [Reporter](INFO): platform_buoyancy_position 575.716858 cc
2020-11-06T22:46:53.345Z,1604702813.345 [Reporter](INFO): platform_buoyancy_position 582.028931 cc
2020-11-06T22:46:53.709Z,1604702813.709 [Reporter](INFO): platform_buoyancy_position 586.326538 cc
2020-11-06T22:46:54.121Z,1604702814.121 [Reporter](INFO): platform_buoyancy_position 590.758484 cc
2020-11-06T22:46:54.552Z,1604702814.552 [Reporter](INFO): platform_buoyancy_position 597.070557 cc
2020-11-06T22:46:54.933Z,1604702814.933 [Reporter](INFO): platform_buoyancy_position 601.502441 cc
2020-11-06T22:46:55.369Z,1604702815.369 [Reporter](INFO): platform_buoyancy_position 607.814575 cc
2020-11-06T22:46:55.749Z,1604702815.749 [Reporter](INFO): platform_buoyancy_position 612.246460 cc
2020-11-06T22:46:56.161Z,1604702816.161 [Reporter](INFO): platform_buoyancy_position 618.558533 cc
2020-11-06T22:46:56.553Z,1604702816.553 [Reporter](INFO): platform_buoyancy_position 622.990479 cc
2020-11-06T22:46:56.953Z,1604702816.953 [Reporter](INFO): platform_buoyancy_position 627.422363 cc
2020-11-06T22:46:57.377Z,1604702817.377 [Reporter](INFO): platform_buoyancy_position 633.734436 cc
2020-11-06T22:46:57.753Z,1604702817.753 [Reporter](INFO): platform_buoyancy_position 638.032043 cc
2020-11-06T22:46:58.169Z,1604702818.169 [Reporter](INFO): platform_buoyancy_position 644.344177 cc
2020-11-06T22:46:58.585Z,1604702818.585 [Reporter](INFO): platform_buoyancy_position 650.924866 cc
2020-11-06T22:46:58.986Z,1604702818.986 [Reporter](INFO): platform_buoyancy_position 655.356750 cc
2020-11-06T22:46:59.413Z,1604702819.413 [Reporter](INFO): platform_buoyancy_position 661.534546 cc
2020-11-06T22:46:59.781Z,1604702819.781 [Reporter](INFO): platform_buoyancy_position 665.832153 cc
2020-11-06T22:47:00.182Z,1604702820.182 [Reporter](INFO): platform_buoyancy_position 670.398376 cc
2020-11-06T22:47:00.598Z,1604702820.598 [Reporter](INFO): platform_buoyancy_position 676.710449 cc
2020-11-06T22:47:00.985Z,1604702820.985 [Reporter](INFO): platform_buoyancy_position 681.008057 cc
2020-11-06T22:47:01.401Z,1604702821.401 [Reporter](INFO): platform_buoyancy_position 687.454468 cc
2020-11-06T22:47:01.797Z,1604702821.797 [Reporter](INFO): platform_buoyancy_position 691.752075 cc
2020-11-06T22:47:02.237Z,1604702822.237 [Reporter](INFO): platform_buoyancy_position 698.198486 cc
2020-11-06T22:47:02.617Z,1604702822.617 [Reporter](INFO): platform_buoyancy_position 702.361755 cc
2020-11-06T22:47:03.001Z,1604702823.001 [Reporter](INFO): platform_buoyancy_position 708.808167 cc
2020-11-06T22:47:03.417Z,1604702823.417 [Reporter](INFO): platform_buoyancy_position 713.240051 cc
2020-11-06T22:47:03.801Z,1604702823.801 [Reporter](INFO): platform_buoyancy_position 718.209167 cc
2020-11-06T22:47:04.217Z,1604702824.217 [Reporter](INFO): platform_buoyancy_position 723.984070 cc
2020-11-06T22:47:04.640Z,1604702824.640 [Reporter](INFO): platform_buoyancy_position 730.296143 cc
2020-11-06T22:47:05.053Z,1604702825.053 [Reporter](INFO): platform_buoyancy_position 734.728088 cc
2020-11-06T22:47:05.466Z,1604702825.466 [Reporter](INFO): platform_buoyancy_position 739.159973 cc
2020-11-06T22:47:05.832Z,1604702825.832 [Reporter](INFO): platform_buoyancy_position 745.337769 cc
2020-11-06T22:47:06.251Z,1604702826.251 [Reporter](INFO): platform_buoyancy_position 749.635376 cc
2020-11-06T22:47:06.673Z,1604702826.673 [Reporter](INFO): platform_buoyancy_position 756.081787 cc
2020-11-06T22:47:07.049Z,1604702827.049 [Reporter](INFO): platform_buoyancy_position 760.647949 cc
2020-11-06T22:47:07.464Z,1604702827.464 [Reporter](INFO): platform_buoyancy_position 766.288574 cc
2020-11-06T22:47:07.861Z,1604702827.861 [Reporter](INFO): platform_buoyancy_position 771.257690 cc
2020-11-06T22:47:08.286Z,1604702828.286 [Reporter](INFO): platform_buoyancy_position 777.704102 cc
2020-11-06T22:47:08.699Z,1604702828.699 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-11-06T22:47:09.065Z,1604702829.065 [Reporter](INFO): platform_buoyancy_position 786.299255 cc
2020-11-06T22:47:09.485Z,1604702829.485 [Reporter](INFO): platform_buoyancy_position 792.745667 cc
2020-11-06T22:47:09.869Z,1604702829.869 [Reporter](INFO): platform_buoyancy_position 797.446167 cc
2020-11-06T22:47:10.276Z,1604702830.276 [Reporter](INFO): platform_buoyancy_position 803.355347 cc
2020-11-06T22:47:10.713Z,1604702830.713 [Reporter](INFO): platform_buoyancy_position 808.190186 cc
2020-11-06T22:47:11.101Z,1604702831.101 [Reporter](INFO): platform_buoyancy_position 814.233704 cc
2020-11-06T22:47:11.532Z,1604702831.532 [Reporter](INFO): platform_buoyancy_position 820.545776 cc
2020-11-06T22:47:11.906Z,1604702831.906 [Reporter](INFO): platform_buoyancy_position 824.977661 cc
2020-11-06T22:47:12.369Z,1604702832.369 [Reporter](INFO): platform_buoyancy_position 831.424072 cc
2020-11-06T22:47:12.739Z,1604702832.739 [Reporter](INFO): platform_buoyancy_position 835.587402 cc
2020-11-06T22:47:13.129Z,1604702833.129 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2020-11-06T22:47:13.573Z,1604702833.573 [Reporter](INFO): platform_buoyancy_position 846.331421 cc
2020-11-06T22:47:13.937Z,1604702833.937 [Reporter](INFO): platform_buoyancy_position 850.897583 cc
2020-11-06T22:47:14.405Z,1604702834.405 [Reporter](INFO): platform_buoyancy_position 857.075317 cc
2020-11-06T22:47:14.745Z,1604702834.745 [Reporter](INFO): platform_buoyancy_position 861.507324 cc
2020-11-06T22:47:15.137Z,1604702835.137 [Reporter](INFO): platform_buoyancy_position 867.819336 cc
2020-11-06T22:47:15.545Z,1604702835.545 [Reporter](INFO): platform_buoyancy_position 872.116943 cc
2020-11-06T22:47:15.946Z,1604702835.946 [Reporter](INFO): platform_buoyancy_position 876.951782 cc
2020-11-06T22:47:16.381Z,1604702836.381 [Reporter](INFO): platform_buoyancy_position 882.995239 cc
2020-11-06T22:47:16.757Z,1604702836.757 [Reporter](INFO): platform_buoyancy_position 887.158569 cc
2020-11-06T22:47:17.161Z,1604702837.161 [Reporter](INFO): platform_buoyancy_position 893.604980 cc
2020-11-06T22:47:17.580Z,1604702837.580 [Reporter](INFO): platform_buoyancy_position 898.036865 cc
2020-11-06T22:47:17.941Z,1604702837.941 [Reporter](INFO): platform_buoyancy_position 903.946045 cc
2020-11-06T22:47:18.368Z,1604702838.368 [Reporter](INFO): platform_buoyancy_position 908.780884 cc
2020-11-06T22:47:18.769Z,1604702838.769 [Reporter](INFO): platform_buoyancy_position 913.078491 cc
2020-11-06T22:47:19.157Z,1604702839.157 [Reporter](INFO): platform_buoyancy_position 919.524902 cc
2020-11-06T22:47:19.609Z,1604702839.609 [Reporter](INFO): platform_buoyancy_position 923.822510 cc
2020-11-06T22:47:19.985Z,1604702839.985 [Reporter](INFO): platform_buoyancy_position 930.268921 cc
2020-11-06T22:47:20.405Z,1604702840.405 [Reporter](INFO): platform_buoyancy_position 934.432129 cc
2020-11-06T22:47:20.773Z,1604702840.773 [Reporter](INFO): platform_buoyancy_position 938.729736 cc
2020-11-06T22:47:24.862Z,1604702844.862 [Reporter](INFO): platform_buoyancy_position 953.905640 cc
2020-11-06T22:48:16.504Z,1604702896.504 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-06T22:43:16.3Z
2020-11-06T22:48:16.505Z,1604702896.505 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:48:16.505Z,1604702896.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:48:26.467Z,1604702906.467 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201106T220144/Courier0019.lzma
2020-11-06T22:48:27.469Z,1604702907.469 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0019.lzma.bak
2020-11-06T22:48:27.469Z,1604702907.469 [DataOverHttps](INFO): SBD MOMSN=13114161
2020-11-06T22:48:47.931Z,1604702927.931 [DataOverHttps](INFO): Sending 516 bytes from file Logs/20201106T220144/Express0020.lzma
2020-11-06T22:48:48.933Z,1604702928.933 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0020.lzma.bak
2020-11-06T22:48:48.933Z,1604702928.933 [DataOverHttps](INFO): SBD MOMSN=13114163
2020-11-06T22:48:49.834Z,1604702929.834 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-06T22:48:50.684Z,1604702930.684 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:48:50.684Z,1604702930.684 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T22:48:50.684Z,1604702930.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:50:32.080Z,1604703032.080 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-11-06T22:53:51.333Z,1604703231.333 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T22:53:51.333Z,1604703231.333 [Default:CheckIn:C.Wait] Stopped
2020-11-06T22:53:51.333Z,1604703231.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T22:53:51.333Z,1604703231.333 [Default:CheckIn:D] Running Loop=1
2020-11-06T22:53:51.725Z,1604703231.725 [Default:CheckIn:D] Stopped
2020-11-06T22:53:51.725Z,1604703231.725 [Default:CheckIn:E] Running Loop=1
2020-11-06T22:53:52.126Z,1604703232.126 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.914282 min 
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn:E] Stopped
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn] Stopped
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn](INFO): Running loop #5
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn] Running Loop=5
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T22:53:52.127Z,1604703232.127 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T22:53:53.729Z,1604703233.729 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-06T22:53:53.729Z,1604703233.729 [NAL9602] Data Fault, FailCount= 1
2020-11-06T22:53:53.729Z,1604703233.729 [NAL9602](ERROR): Data Fault
2020-11-06T22:53:53.769Z,1604703233.769 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-06T22:53:54.136Z,1604703234.136 [NAL9602](INFO): Powering down
2020-11-06T22:53:54.973Z,1604703234.973 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-06T22:53:54.973Z,1604703234.973 [NAL9602] No Fault, FailCount= 1
2020-11-06T22:54:24.430Z,1604703264.430 [NAL9602](INFO): Powering up NAL9602
2020-11-06T22:54:35.338Z,1604703275.338 [NAL9602](INFO): NAL9602 initialized
2020-11-06T22:58:52.338Z,1604703532.338 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-06T22:53:52.1Z
2020-11-06T22:58:52.338Z,1604703532.338 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T22:58:52.338Z,1604703532.338 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T22:58:59.710Z,1604703539.710 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201106T220144/Courier0022.lzma
2020-11-06T22:59:00.713Z,1604703540.713 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0022.lzma.bak
2020-11-06T22:59:00.713Z,1604703540.713 [DataOverHttps](INFO): SBD MOMSN=13114265
2020-11-06T22:59:17.043Z,1604703557.043 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20201106T220144/Express0023.lzma
2020-11-06T22:59:18.045Z,1604703558.045 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0023.lzma.bak
2020-11-06T22:59:18.045Z,1604703558.045 [DataOverHttps](INFO): SBD MOMSN=13114267
2020-11-06T22:59:19.796Z,1604703559.796 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T22:59:19.796Z,1604703559.796 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T22:59:19.796Z,1604703559.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T22:59:38.345Z,1604703578.345 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-06T23:00:09.057Z,1604703609.057 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-06T23:02:47.463Z,1604703767.463 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-11-06T23:04:20.402Z,1604703860.402 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T23:04:20.402Z,1604703860.402 [Default:CheckIn:C.Wait] Stopped
2020-11-06T23:04:20.402Z,1604703860.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T23:04:20.402Z,1604703860.402 [Default:CheckIn:D] Running Loop=1
2020-11-06T23:04:20.786Z,1604703860.786 [Default:CheckIn:D] Stopped
2020-11-06T23:04:20.787Z,1604703860.787 [Default:CheckIn:E] Running Loop=1
2020-11-06T23:04:21.194Z,1604703861.194 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.398645 min 
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn:E] Stopped
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn] Stopped
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn](INFO): Running loop #6
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn] Running Loop=6
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T23:04:21.195Z,1604703861.195 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T23:04:53.905Z,1604703893.905 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-06T23:04:53.987Z,1604703893.987 [NAL9602](FAULT): received: 
+CSQ:0

OK126, 2, 0, 0, 0

OK
2020-11-06T23:04:53.987Z,1604703893.987 [NAL9602] Data Fault, FailCount= 2
2020-11-06T23:04:53.987Z,1604703893.987 [NAL9602](ERROR): Data Fault
2020-11-06T23:04:54.013Z,1604703894.013 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-06T23:04:54.308Z,1604703894.308 [NAL9602](INFO): Powering down
2020-11-06T23:04:55.156Z,1604703895.156 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-06T23:04:55.156Z,1604703895.156 [NAL9602] No Fault, FailCount= 2
2020-11-06T23:05:24.612Z,1604703924.612 [NAL9602](INFO): Powering up NAL9602
2020-11-06T23:05:35.526Z,1604703935.526 [NAL9602](INFO): NAL9602 initialized
2020-11-06T23:05:36.325Z,1604703936.325 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-06T23:05:36.325Z,1604703936.325 [NAL9602] Data Fault, FailCount= 3
2020-11-06T23:05:36.325Z,1604703936.325 [NAL9602](ERROR): Data Fault
2020-11-06T23:05:36.375Z,1604703936.375 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-06T23:05:36.729Z,1604703936.729 [NAL9602](INFO): Powering down
2020-11-06T23:05:37.593Z,1604703937.593 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-06T23:05:37.593Z,1604703937.593 [NAL9602] No Fault, FailCount= 3
2020-11-06T23:06:07.029Z,1604703967.029 [NAL9602](INFO): Powering up NAL9602
2020-11-06T23:06:17.940Z,1604703977.940 [NAL9602](INFO): NAL9602 initialized
2020-11-06T23:09:21.396Z,1604704161.396 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-06T23:04:21.2Z
2020-11-06T23:09:21.396Z,1604704161.396 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T23:09:21.396Z,1604704161.396 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-06T23:09:25.392Z,1604704165.392 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-06T23:09:29.374Z,1604704169.374 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201106T220144/Courier0025.lzma
2020-11-06T23:09:30.377Z,1604704170.377 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Courier0025.lzma.bak
2020-11-06T23:09:30.377Z,1604704170.377 [DataOverHttps](INFO): SBD MOMSN=13114358
2020-11-06T23:09:46.863Z,1604704186.863 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20201106T220144/Express0026.lzma
2020-11-06T23:09:47.865Z,1604704187.865 [DataOverHttps](INFO): Moved sent file to Logs/20201106T220144/Express0026.lzma.bak
2020-11-06T23:09:47.865Z,1604704187.865 [DataOverHttps](INFO): SBD MOMSN=13114360
2020-11-06T23:09:49.256Z,1604704189.256 [Default:CheckIn:Read_Iridium] Stopped
2020-11-06T23:09:49.257Z,1604704189.257 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-06T23:09:49.257Z,1604704189.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-06T23:09:56.096Z,1604704196.096 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-06T23:14:49.897Z,1604704489.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-06T23:14:49.897Z,1604704489.897 [Default:CheckIn:C.Wait] Stopped
2020-11-06T23:14:49.897Z,1604704489.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-06T23:14:49.897Z,1604704489.897 [Default:CheckIn:D] Running Loop=1
2020-11-06T23:14:50.356Z,1604704490.356 [Default:CheckIn:D] Stopped
2020-11-06T23:14:50.356Z,1604704490.356 [Default:CheckIn:E] Running Loop=1
2020-11-06T23:14:50.711Z,1604704490.711 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.891463 min 
2020-11-06T23:14:50.711Z,1604704490.711 [Default:CheckIn:E] Stopped
2020-11-06T23:14:50.711Z,1604704490.711 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-06T23:14:50.711Z,1604704490.711 [Default:CheckIn] Stopped
2020-11-06T23:14:50.712Z,1604704490.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T23:14:50.712Z,1604704490.712 [Default:CheckIn](INFO): Running loop #7
2020-11-06T23:14:50.712Z,1604704490.712 [Default:CheckIn] Running Loop=7
2020-11-06T23:14:50.712Z,1604704490.712 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-06T23:14:50.712Z,1604704490.712 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-06T23:16:13.666Z,1604704573.666 [CommandLine](IMPORTANT): got command quit
2020-11-06T23:16:14.671Z,1604704574.671 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:14.672Z,1604704574.672 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:14.743Z,1604704574.743 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-11-06T23:16:14.743Z,1604704574.743 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:14.744Z,1604704574.744 [CommandLine](INFO): Join timeout helper Thread ID is 1251
2020-11-06T23:16:14.775Z,1604704574.775 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-11-06T23:16:14.776Z,1604704574.776 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:14.777Z,1604704574.777 [NavChartDb](INFO): Join timeout helper Thread ID is 1252
2020-11-06T23:16:15.083Z,1604704575.083 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:15.084Z,1604704575.084 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.087Z,1604704575.087 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-11-06T23:16:15.087Z,1604704575.087 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.088Z,1604704575.088 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1253
2020-11-06T23:16:15.367Z,1604704575.367 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:15.368Z,1604704575.368 [WetLabsUBAT](INFO): Powering down
2020-11-06T23:16:15.368Z,1604704575.368 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.375Z,1604704575.375 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-11-06T23:16:15.375Z,1604704575.375 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.376Z,1604704575.376 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1254
2020-11-06T23:16:15.408Z,1604704575.408 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:15.408Z,1604704575.408 [WetLabsBB2FL](INFO): Powering down
2020-11-06T23:16:15.408Z,1604704575.408 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.415Z,1604704575.415 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-11-06T23:16:15.415Z,1604704575.415 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.416Z,1604704575.416 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1255
2020-11-06T23:16:15.547Z,1604704575.547 [CTD_Seabird](INFO): Powering down
2020-11-06T23:16:15.559Z,1604704575.559 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:15.559Z,1604704575.559 [CTD_Seabird](INFO): Powering down
2020-11-06T23:16:15.571Z,1604704575.571 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.580Z,1604704575.580 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-11-06T23:16:15.580Z,1604704575.580 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.581Z,1604704575.581 [BackSeatDriver](INFO): Join timeout helper Thread ID is 1256
2020-11-06T23:16:15.707Z,1604704575.707 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:15.708Z,1604704575.708 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.728Z,1604704575.728 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-11-06T23:16:15.728Z,1604704575.728 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:15.728Z,1604704575.728 [Radio_Surface](INFO): Join timeout helper Thread ID is 1257
2020-11-06T23:16:16.115Z,1604704576.115 [Radio_Surface](INFO): Powering down
2020-11-06T23:16:16.116Z,1604704576.116 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:16.116Z,1604704576.116 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:16.124Z,1604704576.124 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-11-06T23:16:16.124Z,1604704576.124 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:16.124Z,1604704576.124 [Onboard](INFO): Join timeout helper Thread ID is 1258
2020-11-06T23:16:16.491Z,1604704576.491 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-11-06T23:16:18.096Z,1604704578.096 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:18.096Z,1604704578.096 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.100Z,1604704578.100 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-11-06T23:16:18.100Z,1604704578.100 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.100Z,1604704578.100 [DataOverHttps](INFO): Join timeout helper Thread ID is 1259
2020-11-06T23:16:18.499Z,1604704578.499 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:18.500Z,1604704578.500 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.512Z,1604704578.512 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-11-06T23:16:18.512Z,1604704578.512 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.512Z,1604704578.512 [logger](INFO): Join timeout helper Thread ID is 1260
2020-11-06T23:16:18.515Z,1604704578.515 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:18.516Z,1604704578.516 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.532Z,1604704578.532 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-11-06T23:16:18.532Z,1604704578.532 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.532Z,1604704578.532 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-11-06T23:16:18.532Z,1604704578.532 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:18.532Z,1604704578.532 [controlThread](INFO): Join timeout helper Thread ID is 1261
2020-11-06T23:16:18.751Z,1604704578.751 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-06T23:16:18.752Z,1604704578.752 [controlThread](DEBUG): Uninitializing ControlThread
2020-11-06T23:16:18.752Z,1604704578.752 [AHRS_M2](INFO): Powering down
2020-11-06T23:16:18.824Z,1604704578.824 [NAL9602](INFO): Powering down
2020-11-06T23:16:18.895Z,1604704578.895 [RDI_Pathfinder](INFO): Powering down
2020-11-06T23:16:18.896Z,1604704578.896 [DAT](INFO): Powering down
2020-11-06T23:16:19.016Z,1604704579.016 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-11-06T23:16:19.017Z,1604704579.017 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-11-06T23:16:19.018Z,1604704579.018 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-11-06T23:16:19.018Z,1604704579.018 [MissionManager](INFO): Uninitializing Mission Default
2020-11-06T23:16:19.018Z,1604704579.018 [Default] Stopped
2020-11-06T23:16:19.018Z,1604704579.018 [Default](DEBUG): Aggregate::uninitialize Default
2020-11-06T23:16:19.018Z,1604704579.018 [Default:B.GoToSurface] Stopped
2020-11-06T23:16:19.018Z,1604704579.018 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-06T23:16:19.018Z,1604704579.018 [Default:CheckIn] Stopped
2020-11-06T23:16:19.019Z,1604704579.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-06T23:16:19.019Z,1604704579.019 [Default:CheckIn:Read_GPS] Stopped
2020-11-06T23:16:19.021Z,1604704579.021 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-11-06T23:16:19.021Z,1604704579.021 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-11-06T23:16:19.021Z,1604704579.021 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-11-06T23:16:19.022Z,1604704579.022 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-11-06T23:16:19.022Z,1604704579.022 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-11-06T23:16:19.022Z,1604704579.022 [BuoyancyServo](INFO): Powering down
2020-11-06T23:16:19.035Z,1604704579.035 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-11-06T23:16:19.035Z,1604704579.035 [ElevatorServo](INFO): Powering down
2020-11-06T23:16:19.036Z,1604704579.036 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-11-06T23:16:19.036Z,1604704579.036 [MassServo](INFO): Powering down
2020-11-06T23:16:19.037Z,1604704579.037 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-06T23:16:19.037Z,1604704579.037 [RudderServo](INFO): Powering down
2020-11-06T23:16:19.038Z,1604704579.038 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-11-06T23:16:19.038Z,1604704579.038 [ThrusterServo](INFO): Powering down
2020-11-06T23:16:19.039Z,1604704579.039 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-11-06T23:16:19.039Z,1604704579.039 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-11-06T23:16:19.039Z,1604704579.039 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-06T23:16:19.039Z,1604704579.039 [CBIT](DEBUG): Powering off loads.
2020-11-06T23:16:19.051Z,1604704579.051 [CBIT](DEBUG): Disabling WDT.
2020-11-06T23:16:19.063Z,1604704579.063 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-06T23:16:19.064Z,1604704579.064 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.153Z,1604704579.153 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.155Z,1604704579.155 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.161Z,1604704579.161 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.218Z,1604704579.218 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.219Z,1604704579.219 [WetLabsUBAT](INFO): WetLabs destructor: dataLog is open - close it
2020-11-06T23:16:19.220Z,1604704579.220 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-11-06T23:16:19.222Z,1604704579.222 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.226Z,1604704579.226 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.229Z,1604704579.229 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.238Z,1604704579.238 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-06T23:16:19.312Z,1604704579.312 [logger ThreadHandler](INFO): Thread cancelled.