2020-12-01T16:47:21.483Z,1606841241.483 [Supervisor](DEBUG): Initializing supervisor. 2020-12-01T16:47:21.486Z,1606841241.486 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-12-01T16:47:21.486Z,1606841241.486 [SyncHandler](INFO): Protected caller Thread ID is 5666 2020-12-01T16:47:21.487Z,1606841241.487 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-12-01T16:47:21.488Z,1606841241.488 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-12-01T16:47:21.488Z,1606841241.488 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5667 2020-12-01T16:47:21.491Z,1606841241.491 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-12-01T16:47:21.507Z,1606841241.507 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-12-01T16:47:21.508Z,1606841241.508 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-12-01T16:47:21.509Z,1606841241.509 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5668 2020-12-01T16:47:21.509Z,1606841241.509 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-12-01T16:47:21.510Z,1606841241.510 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-12-01T16:47:21.511Z,1606841241.511 [logger ThreadHandler](INFO): Protected caller Thread ID is 5669 2020-12-01T16:47:21.513Z,1606841241.513 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-12-01T16:47:21.513Z,1606841241.513 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-12-01T16:47:21.515Z,1606841241.515 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-12-01T16:47:21.607Z,1606841241.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-12-01T16:47:21.607Z,1606841241.607 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-12-01T16:47:22.172Z,1606841242.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-12-01T16:47:22.172Z,1606841242.172 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-12-01T16:47:22.365Z,1606841242.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-12-01T16:47:22.365Z,1606841242.365 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-12-01T16:47:22.460Z,1606841242.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-12-01T16:47:22.461Z,1606841242.461 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-12-01T16:47:22.562Z,1606841242.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-12-01T16:47:22.562Z,1606841242.562 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-12-01T16:47:22.640Z,1606841242.640 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-12-01T16:47:22.775Z,1606841242.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-12-01T16:47:22.775Z,1606841242.775 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-12-01T16:47:23.053Z,1606841243.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-12-01T16:47:23.053Z,1606841243.053 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-12-01T16:47:23.539Z,1606841243.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-12-01T16:47:23.539Z,1606841243.539 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-12-01T16:47:23.678Z,1606841243.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-12-01T16:47:23.678Z,1606841243.678 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-12-01T16:47:23.858Z,1606841243.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-12-01T16:47:23.858Z,1606841243.858 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-12-01T16:47:24.326Z,1606841244.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-12-01T16:47:24.327Z,1606841244.327 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-12-01T16:47:24.534Z,1606841244.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-12-01T16:47:24.535Z,1606841244.535 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-12-01T16:47:24.726Z,1606841244.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-12-01T16:47:24.727Z,1606841244.727 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-12-01T16:47:25.124Z,1606841245.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-12-01T16:47:25.125Z,1606841245.125 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-12-01T16:47:25.435Z,1606841245.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-12-01T16:47:25.437Z,1606841245.437 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-12-01T16:47:25.438Z,1606841245.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-12-01T16:47:25.519Z,1606841245.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-12-01T16:47:25.666Z,1606841245.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-12-01T16:47:25.770Z,1606841245.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-12-01T16:47:25.852Z,1606841245.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-12-01T16:47:25.943Z,1606841245.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-12-01T16:47:26.405Z,1606841246.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-12-01T16:47:26.765Z,1606841246.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-12-01T16:47:26.766Z,1606841246.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-12-01T16:47:26.859Z,1606841246.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-12-01T16:47:26.953Z,1606841246.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-12-01T16:47:27.090Z,1606841247.090 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-12-01T16:47:27.186Z,1606841247.186 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-12-01T16:47:27.198Z,1606841247.198 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-12-01T16:47:27.275Z,1606841247.275 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-12-01T16:47:27.276Z,1606841247.276 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-12-01T16:47:27.325Z,1606841247.325 [VerticalControl](DEBUG): Construct VerticalControl. 2020-12-01T16:47:27.381Z,1606841247.381 [VerticalControl] Loaded 2020-12-01T16:47:27.381Z,1606841247.381 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-12-01T16:47:27.382Z,1606841247.382 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-12-01T16:47:27.418Z,1606841247.418 [HorizontalControl] Loaded 2020-12-01T16:47:27.418Z,1606841247.418 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-12-01T16:47:27.419Z,1606841247.419 [SpeedControl](DEBUG): Construct SpeedControl. 2020-12-01T16:47:27.422Z,1606841247.422 [SpeedControl] Loaded 2020-12-01T16:47:27.422Z,1606841247.422 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-12-01T16:47:27.422Z,1606841247.422 [LoopControl](DEBUG): Construct LoopControl. 2020-12-01T16:47:27.423Z,1606841247.423 [LoopControl] Loaded 2020-12-01T16:47:27.423Z,1606841247.423 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-12-01T16:47:27.423Z,1606841247.423 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-12-01T16:47:27.424Z,1606841247.424 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-12-01T16:47:27.463Z,1606841247.463 [DepthRateCalculator] Loaded 2020-12-01T16:47:27.463Z,1606841247.463 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-12-01T16:47:27.466Z,1606841247.466 [PitchRateCalculator] Loaded 2020-12-01T16:47:27.466Z,1606841247.466 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-12-01T16:47:27.474Z,1606841247.474 [SpeedCalculator] Loaded 2020-12-01T16:47:27.474Z,1606841247.474 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-12-01T16:47:27.485Z,1606841247.485 [TempGradientCalculator] Loaded 2020-12-01T16:47:27.485Z,1606841247.485 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-12-01T16:47:27.487Z,1606841247.487 [YawRateCalculator] Loaded 2020-12-01T16:47:27.488Z,1606841247.488 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-12-01T16:47:27.502Z,1606841247.502 [ElevatorOffsetCalculator] Loaded 2020-12-01T16:47:27.502Z,1606841247.502 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-12-01T16:47:27.502Z,1606841247.502 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-12-01T16:47:27.503Z,1606841247.503 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-12-01T16:47:27.529Z,1606841247.529 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-12-01T16:47:27.530Z,1606841247.530 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-12-01T16:47:27.613Z,1606841247.613 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-12-01T16:47:27.613Z,1606841247.613 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-12-01T16:47:27.896Z,1606841247.896 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-12-01T16:47:27.897Z,1606841247.897 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-12-01T16:47:27.993Z,1606841247.993 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-12-01T16:47:27.994Z,1606841247.994 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-12-01T16:47:28.318Z,1606841248.318 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-12-01T16:47:28.321Z,1606841248.321 [AHRS_M2](INFO): created writer for : platform_orientation 2020-12-01T16:47:28.322Z,1606841248.322 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-12-01T16:47:28.325Z,1606841248.325 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-12-01T16:47:28.325Z,1606841248.325 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-12-01T16:47:28.328Z,1606841248.328 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-12-01T16:47:28.329Z,1606841248.329 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-12-01T16:47:28.331Z,1606841248.331 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-12-01T16:47:28.367Z,1606841248.367 [AHRS_M2] Loaded 2020-12-01T16:47:28.368Z,1606841248.368 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-12-01T16:47:28.445Z,1606841248.445 [DataOverHttps] Loaded 2020-12-01T16:47:28.446Z,1606841248.446 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-12-01T16:47:28.447Z,1606841248.447 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2020-12-01T16:47:28.447Z,1606841248.447 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5751 2020-12-01T16:47:28.463Z,1606841248.463 [Depth_Keller] Loaded 2020-12-01T16:47:28.464Z,1606841248.464 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-12-01T16:47:28.466Z,1606841248.466 [DropWeight] Loaded 2020-12-01T16:47:28.466Z,1606841248.466 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-12-01T16:47:28.520Z,1606841248.520 [NAL9602] Loaded 2020-12-01T16:47:28.520Z,1606841248.520 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-12-01T16:47:28.545Z,1606841248.545 [Onboard] Loaded 2020-12-01T16:47:28.545Z,1606841248.545 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-12-01T16:47:28.546Z,1606841248.546 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2020-12-01T16:47:28.546Z,1606841248.546 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5752 2020-12-01T16:47:28.556Z,1606841248.556 [Radio_Surface] Loaded 2020-12-01T16:47:28.557Z,1606841248.557 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-12-01T16:47:28.558Z,1606841248.558 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2020-12-01T16:47:28.558Z,1606841248.558 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5753 2020-12-01T16:47:28.584Z,1606841248.584 [RDI_Pathfinder] Loaded 2020-12-01T16:47:28.584Z,1606841248.584 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-12-01T16:47:28.654Z,1606841248.654 [DAT] Loaded 2020-12-01T16:47:28.655Z,1606841248.655 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-12-01T16:47:29.425Z,1606841249.425 [BPC1] Loaded 2020-12-01T16:47:29.426Z,1606841249.426 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-12-01T16:47:29.426Z,1606841249.426 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-12-01T16:47:29.427Z,1606841249.427 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-12-01T16:47:29.503Z,1606841249.503 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-12-01T16:47:29.504Z,1606841249.504 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-12-01T16:47:29.514Z,1606841249.514 [NavChart] Loaded 2020-12-01T16:47:29.514Z,1606841249.514 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-12-01T16:47:29.518Z,1606841249.518 [UniversalFixResidualReporter] Loaded 2020-12-01T16:47:29.519Z,1606841249.519 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-12-01T16:47:29.519Z,1606841249.519 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-12-01T16:47:29.519Z,1606841249.519 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-12-01T16:47:29.649Z,1606841249.649 [SBIT](DEBUG): Construct Startup Built In Test. 2020-12-01T16:47:29.657Z,1606841249.657 [SBIT] Loaded 2020-12-01T16:47:29.658Z,1606841249.658 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-12-01T16:47:29.658Z,1606841249.658 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-12-01T16:47:29.670Z,1606841249.670 [IBIT] Loaded 2020-12-01T16:47:29.671Z,1606841249.671 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-12-01T16:47:29.674Z,1606841249.674 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-12-01T16:47:29.766Z,1606841249.766 [CBIT] Loaded 2020-12-01T16:47:29.767Z,1606841249.767 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-12-01T16:47:29.767Z,1606841249.767 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-12-01T16:47:29.767Z,1606841249.767 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-12-01T16:47:29.878Z,1606841249.878 [BuoyancyServo] Loaded 2020-12-01T16:47:29.878Z,1606841249.878 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-12-01T16:47:29.895Z,1606841249.895 [ElevatorServo] Loaded 2020-12-01T16:47:29.895Z,1606841249.895 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-12-01T16:47:29.910Z,1606841249.910 [MassServo] Loaded 2020-12-01T16:47:29.911Z,1606841249.911 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-12-01T16:47:29.926Z,1606841249.926 [RudderServo] Loaded 2020-12-01T16:47:29.926Z,1606841249.926 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-12-01T16:47:29.940Z,1606841249.940 [ThrusterServo] Loaded 2020-12-01T16:47:29.941Z,1606841249.941 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-12-01T16:47:29.941Z,1606841249.941 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-12-01T16:47:29.942Z,1606841249.942 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-12-01T16:47:29.959Z,1606841249.959 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-12-01T16:47:29.960Z,1606841249.960 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-12-01T16:47:30.180Z,1606841250.180 [BackSeatDriver] Loaded 2020-12-01T16:47:30.180Z,1606841250.180 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-12-01T16:47:30.181Z,1606841250.181 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B074E0 2020-12-01T16:47:30.182Z,1606841250.182 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 5754 2020-12-01T16:47:30.201Z,1606841250.201 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-12-01T16:47:30.204Z,1606841250.204 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-12-01T16:47:30.204Z,1606841250.204 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-12-01T16:47:30.207Z,1606841250.207 [CTD_Seabird](INFO): created writer for : depth 2020-12-01T16:47:30.207Z,1606841250.207 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-12-01T16:47:30.210Z,1606841250.210 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-12-01T16:47:30.210Z,1606841250.210 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-12-01T16:47:30.213Z,1606841250.213 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-12-01T16:47:30.214Z,1606841250.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-12-01T16:47:30.217Z,1606841250.217 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-12-01T16:47:30.217Z,1606841250.217 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-12-01T16:47:30.220Z,1606841250.220 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-12-01T16:47:30.221Z,1606841250.221 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-12-01T16:47:30.224Z,1606841250.224 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-12-01T16:47:30.224Z,1606841250.224 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water 2020-12-01T16:47:30.227Z,1606841250.227 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water 2020-12-01T16:47:30.227Z,1606841250.227 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq 2020-12-01T16:47:30.228Z,1606841250.228 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq 2020-12-01T16:47:30.243Z,1606841250.243 [CTD_Seabird] Loaded 2020-12-01T16:47:30.244Z,1606841250.244 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-12-01T16:47:30.245Z,1606841250.245 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B374E0 2020-12-01T16:47:30.245Z,1606841250.245 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5755 2020-12-01T16:47:30.262Z,1606841250.262 [PAR_Licor] Loaded 2020-12-01T16:47:30.262Z,1606841250.262 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-12-01T16:47:30.274Z,1606841250.274 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-12-01T16:47:30.275Z,1606841250.275 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-12-01T16:47:30.277Z,1606841250.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-12-01T16:47:30.277Z,1606841250.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-12-01T16:47:30.279Z,1606841250.279 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-12-01T16:47:30.279Z,1606841250.279 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-12-01T16:47:30.281Z,1606841250.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-12-01T16:47:30.281Z,1606841250.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-12-01T16:47:30.283Z,1606841250.283 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-12-01T16:47:30.283Z,1606841250.283 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-12-01T16:47:30.285Z,1606841250.285 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-12-01T16:47:30.285Z,1606841250.285 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-12-01T16:47:30.287Z,1606841250.287 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-12-01T16:47:30.287Z,1606841250.287 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-12-01T16:47:30.289Z,1606841250.289 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.291Z,1606841250.291 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.292Z,1606841250.292 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.292Z,1606841250.292 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.294Z,1606841250.294 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.294Z,1606841250.294 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.296Z,1606841250.296 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.296Z,1606841250.296 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-12-01T16:47:30.298Z,1606841250.298 [WetLabsBB2FL] Loaded 2020-12-01T16:47:30.298Z,1606841250.298 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-12-01T16:47:30.299Z,1606841250.299 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0 2020-12-01T16:47:30.300Z,1606841250.300 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5756 2020-12-01T16:47:30.319Z,1606841250.319 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-12-01T16:47:30.320Z,1606841250.320 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-12-01T16:47:30.321Z,1606841250.321 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-12-01T16:47:30.325Z,1606841250.325 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 2020-12-01T16:47:30.326Z,1606841250.326 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-12-01T16:47:30.326Z,1606841250.326 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-12-01T16:47:30.327Z,1606841250.327 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-12-01T16:47:30.328Z,1606841250.328 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-12-01T16:47:30.331Z,1606841250.331 [WetLabsUBAT] Loaded 2020-12-01T16:47:30.331Z,1606841250.331 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-12-01T16:47:30.332Z,1606841250.332 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B974E0 2020-12-01T16:47:30.332Z,1606841250.332 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 5757 2020-12-01T16:47:30.333Z,1606841250.333 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-12-01T16:47:30.337Z,1606841250.337 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-12-01T16:47:30.337Z,1606841250.337 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-12-01T16:47:30.346Z,1606841250.346 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-12-01T16:47:30.347Z,1606841250.347 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC74E0 2020-12-01T16:47:30.347Z,1606841250.347 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5758 2020-12-01T16:47:30.351Z,1606841250.351 [Supervisor](INFO): Main Thread ID is 5457 2020-12-01T16:47:30.351Z,1606841250.351 [Supervisor](DEBUG): Running supervisor. 2020-12-01T16:47:30.352Z,1606841250.352 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5759 2020-12-01T16:47:30.355Z,1606841250.355 [controlThread ThreadHandler](INFO): Handler Thread ID is 5760 2020-12-01T16:47:30.355Z,1606841250.355 [controlThread](DEBUG): Initializing ControlThread 2020-12-01T16:47:30.356Z,1606841250.356 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-12-01T16:47:30.358Z,1606841250.358 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-12-01T16:47:30.358Z,1606841250.358 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-12-01T16:47:30.359Z,1606841250.359 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-12-01T16:47:30.359Z,1606841250.359 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-12-01T16:47:30.359Z,1606841250.359 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-12-01T16:47:30.360Z,1606841250.360 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-12-01T16:47:30.360Z,1606841250.360 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-12-01T16:47:30.361Z,1606841250.361 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-12-01T16:47:30.361Z,1606841250.361 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-12-01T16:47:30.367Z,1606841250.367 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-12-01T16:47:30.367Z,1606841250.367 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-12-01T16:47:30.368Z,1606841250.368 [SBIT](INFO): Initialize SBIT Component. 2020-12-01T16:47:30.368Z,1606841250.368 [SBIT](IMPORTANT): git: 2020-11-24 2020-12-01T16:47:30.368Z,1606841250.368 [SBIT](INFO): git hash: adde971cea593344b7c42ced3277a520a74b3766 2020-12-01T16:47:30.369Z,1606841250.369 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-12-01T16:47:30.370Z,1606841250.370 [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-12-01T16:47:30.371Z,1606841250.371 [SBIT](INFO): Beginning SBIT in 49.000000 seconds. 2020-12-01T16:47:30.371Z,1606841250.371 [IBIT](INFO): Initialize IBIT Component. 2020-12-01T16:47:30.372Z,1606841250.372 [CBIT](DEBUG): Initialize CBIT Component. 2020-12-01T16:47:30.373Z,1606841250.373 [logger ThreadHandler](INFO): Handler Thread ID is 5761 2020-12-01T16:47:30.384Z,1606841250.384 [CBIT](DEBUG): Initialized mux pins. 2020-12-01T16:47:30.384Z,1606841250.384 [CBIT](DEBUG): Initializing the watchdog timer. 2020-12-01T16:47:30.392Z,1606841250.392 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5762 2020-12-01T16:47:30.393Z,1606841250.393 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-12-01T16:47:30.404Z,1606841250.404 [Onboard ThreadHandler](INFO): Handler Thread ID is 5763 2020-12-01T16:47:30.408Z,1606841250.408 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-12-01T16:47:30.409Z,1606841250.409 [CBIT](DEBUG): Initializing heartbeat. 2020-12-01T16:47:30.422Z,1606841250.422 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5764 2020-12-01T16:47:30.444Z,1606841250.444 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 5765 2020-12-01T16:47:30.464Z,1606841250.464 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5766 2020-12-01T16:47:30.465Z,1606841250.465 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-12-01T16:47:30.469Z,1606841250.469 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5768 2020-12-01T16:47:30.470Z,1606841250.470 [WetLabsBB2FL](INFO): Powering down 2020-12-01T16:47:30.480Z,1606841250.480 [CBIT](DEBUG): Deactivating GF circuits. 2020-12-01T16:47:30.480Z,1606841250.480 [CBIT](DEBUG): Deactivating emergency mode. 2020-12-01T16:47:30.481Z,1606841250.481 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 5769 2020-12-01T16:47:30.492Z,1606841250.492 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5770 2020-12-01T16:47:30.495Z,1606841250.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-12-01T16:47:30.495Z,1606841250.495 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-12-01T16:47:30.496Z,1606841250.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-12-01T16:47:30.496Z,1606841250.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-12-01T16:47:30.496Z,1606841250.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-12-01T16:47:30.496Z,1606841250.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-12-01T16:47:30.496Z,1606841250.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-12-01T16:47:30.496Z,1606841250.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-12-01T16:47:30.497Z,1606841250.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-12-01T16:47:30.497Z,1606841250.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-12-01T16:47:30.497Z,1606841250.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-12-01T16:47:30.497Z,1606841250.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-12-01T16:47:30.497Z,1606841250.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-12-01T16:47:30.497Z,1606841250.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-12-01T16:47:30.498Z,1606841250.498 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-12-01T16:47:30.498Z,1606841250.498 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-12-01T16:47:30.516Z,1606841250.516 [CBIT](DEBUG): Backplane powered. 2020-12-01T16:47:30.521Z,1606841250.521 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-12-01T16:47:30.539Z,1606841250.539 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-12-01T16:47:30.577Z,1606841250.577 [MissionManager](DEBUG): 2020-12-01T16:47:30.578Z,1606841250.578 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-12-01T16:47:30.662Z,1606841250.662 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-12-01T16:47:30.663Z,1606841250.663 [Default:A.Wait](DEBUG): Construct Wait. 2020-12-01T16:47:30.681Z,1606841250.681 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-12-01T16:47:30.707Z,1606841250.707 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-12-01T16:47:30.710Z,1606841250.710 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-12-01T16:47:30.731Z,1606841250.731 [Default:E.Execute](DEBUG): Construct Execute. 2020-12-01T16:47:30.734Z,1606841250.734 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-12-01T16:47:30.744Z,1606841250.744 [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-12-01T16:47:30.753Z,1606841250.753 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-12-01T16:47:30.828Z,1606841250.828 [Radio_Surface](INFO): Powering up 2020-12-01T16:47:30.907Z,1606841250.907 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-12-01T16:47:30.907Z,1606841250.907 [DAT](INFO): Powering up 2020-12-01T16:47:30.907Z,1606841250.907 [DAT](DEBUG): Initializing DAT. 2020-12-01T16:47:30.952Z,1606841250.952 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-12-01T16:47:31.038Z,1606841251.038 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-12-01T16:47:31.048Z,1606841251.048 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-12-01T16:47:31.049Z,1606841251.049 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-12-01T16:47:31.060Z,1606841251.060 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-12-01T16:47:31.061Z,1606841251.061 [MassServo](DEBUG): Initializing EZServoServo. 2020-12-01T16:47:31.072Z,1606841251.072 [MassServo](DEBUG): Initializing MassServo. 2020-12-01T16:47:31.073Z,1606841251.073 [RudderServo](DEBUG): Initializing EZServoServo. 2020-12-01T16:47:31.080Z,1606841251.080 [RudderServo](DEBUG): Initializing RudderServo. 2020-12-01T16:47:31.081Z,1606841251.081 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-12-01T16:47:31.088Z,1606841251.088 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-12-01T16:47:31.176Z,1606841251.176 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-12-01T16:47:31.176Z,1606841251.176 [DropWeight] Hardware Fault, FailCount= 1 2020-12-01T16:47:31.176Z,1606841251.176 [DropWeight](ERROR): Hardware Fault 2020-12-01T16:47:31.280Z,1606841251.280 [CommandLine](FAULT): Scheduling is paused 2020-12-01T16:47:31.280Z,1606841251.280 [CBIT](INFO): Critical error at 20201201T164731 2020-12-01T16:47:31.281Z,1606841251.281 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-12-01T16:47:31.283Z,1606841251.283 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-12-01T16:47:31.283Z,1606841251.283 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-12-01T16:47:31.576Z,1606841251.576 [CBIT](INFO): Critical error at 20201201T164731 2020-12-01T16:47:44.899Z,1606841264.899 [DAT](INFO): commRate: 800 2020-12-01T16:47:46.933Z,1606841266.933 [DAT](INFO): entering command mode 2020-12-01T16:47:47.322Z,1606841267.322 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:47.738Z,1606841267.738 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:48.148Z,1606841268.148 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:48.561Z,1606841268.561 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:48.946Z,1606841268.946 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:49.342Z,1606841269.342 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:49.775Z,1606841269.775 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:50.158Z,1606841270.158 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:50.564Z,1606841270.564 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:50.959Z,1606841270.959 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:51.387Z,1606841271.387 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:51.772Z,1606841271.772 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:52.170Z,1606841272.170 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:52.592Z,1606841272.592 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:52.978Z,1606841272.978 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:53.378Z,1606841273.378 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:53.800Z,1606841273.800 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:54.190Z,1606841274.190 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:54.611Z,1606841274.611 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:55.006Z,1606841275.006 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:55.398Z,1606841275.398 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:55.806Z,1606841275.806 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:56.236Z,1606841276.236 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:56.615Z,1606841276.615 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:57.064Z,1606841277.064 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:57.456Z,1606841277.456 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:57.822Z,1606841277.822 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:58.226Z,1606841278.226 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:58.629Z,1606841278.629 [NAL9602](INFO): Powering up NAL9602 2020-12-01T16:47:58.645Z,1606841278.645 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:59.038Z,1606841279.038 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:59.458Z,1606841279.458 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:47:59.846Z,1606841279.846 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:00.246Z,1606841280.246 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:00.664Z,1606841280.664 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:01.059Z,1606841281.059 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:01.486Z,1606841281.486 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:01.862Z,1606841281.862 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:02.270Z,1606841282.270 [DAT](DEBUG): checking for command mode acknowledgment 2020-12-01T16:48:02.270Z,1606841282.270 [DAT](FAULT): failed to enter command mode 2020-12-01T16:48:02.685Z,1606841282.685 [DAT](INFO): entering command mode 2020-12-01T16:48:03.075Z,1606841283.075 [DAT](INFO): setting verbose to 3 2020-12-01T16:48:03.479Z,1606841283.479 [DAT](INFO): set verbose to 3 2020-12-01T16:48:03.479Z,1606841283.479 [DAT](INFO): setting DatVerbose to 27440 2020-12-01T16:48:03.893Z,1606841283.893 [DAT](INFO): set DatVerbose to 27440 2020-12-01T16:48:03.893Z,1606841283.893 [DAT](INFO): setting transmit power to 8 2020-12-01T16:48:04.287Z,1606841284.287 [DAT](INFO): set transmit power to 8 2020-12-01T16:48:04.287Z,1606841284.287 [DAT](INFO): setting local address to 9 2020-12-01T16:48:04.695Z,1606841284.695 [DAT](INFO): set local address to 9 2020-12-01T16:48:09.549Z,1606841289.549 [NAL9602](INFO): NAL9602 initialized 2020-12-01T16:48:20.059Z,1606841300.059 [SBIT](IMPORTANT): Beginning Startup BIT 2020-12-01T16:48:20.063Z,1606841300.063 [CBIT](IMPORTANT): Beginning ground fault scan 2020-12-01T16:48:30.596Z,1606841310.596 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2020-12-01T16:48:30.596Z,1606841310.596 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-12-01T16:48:30.596Z,1606841310.596 [RDI_Pathfinder](ERROR): Communications Fault 2020-12-01T16:48:30.672Z,1606841310.672 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-12-01T16:48:31.036Z,1606841311.036 [RDI_Pathfinder](INFO): Powering down 2020-12-01T16:48:31.109Z,1606841311.109 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010960 CHAN A1 (24V): -0.001920 CHAN A2 (12V): -0.006802 CHAN A3 (5V): -0.001500 CHAN B0 (3.3V): 0.000287 CHAN B1 (3.15aV): 0.000304 CHAN B2 (3.15bV): -0.000010 CHAN B3 (GND): 0.002416 OPEN: 0.005005 Full Scale Calc: 4.765 mA, -1.589 mA 2020-12-01T16:48:31.849Z,1606841311.849 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-12-01T16:48:31.849Z,1606841311.849 [RDI_Pathfinder] No Fault, FailCount= 1 2020-12-01T16:49:13.831Z,1606841353.831 [SBIT](IMPORTANT): SBIT PASSED 2020-12-01T16:49:13.831Z,1606841353.831 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-12-01T16:49:13.836Z,1606841353.836 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2020-12-01T16:49:13.836Z,1606841353.836 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere; 2020-12-01T16:49:13.836Z,1606841353.836 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-12-01T16:49:13.836Z,1606841353.836 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-12-01T16:49:13.836Z,1606841353.836 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter; 2020-12-01T16:49:13.836Z,1606841353.836 [SBIT](IMPORTANT): DAT.verbosity=0 count; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=254.6 cubic_centimeter; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): VerticalControl.massDefault=7.48 millimeter; 2020-12-01T16:49:13.837Z,1606841353.837 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2020-12-01T16:49:14.226Z,1606841354.226 [MissionManager](IMPORTANT): Started mission Startup 2020-12-01T16:49:14.226Z,1606841354.226 [Startup] Running Loop=1 2020-12-01T16:49:14.226Z,1606841354.226 [Startup](DEBUG): Aggregate::initialize Startup 2020-12-01T16:49:14.226Z,1606841354.226 [Startup:A.GoToSurface] Running Loop=1 2020-12-01T16:49:14.226Z,1606841354.226 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-12-01T16:49:14.227Z,1606841354.227 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-12-01T16:49:14.228Z,1606841354.228 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-12-01T16:49:14.228Z,1606841354.228 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-12-01T16:49:14.229Z,1606841354.229 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-12-01T16:49:14.229Z,1606841354.229 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-12-01T16:49:14.230Z,1606841354.230 [Startup:StartupSatComms] Running Loop=1 2020-12-01T16:49:14.230Z,1606841354.230 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-12-01T16:49:14.230Z,1606841354.230 [Startup:StartupSatComms:A] Running Loop=1 2020-12-01T16:49:14.630Z,1606841354.630 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-12-01T16:50:14.430Z,1606841414.430 [Startup:StartupSatComms:A](INFO): Timed out from 2020-12-01T16:49:14.2Z 2020-12-01T16:50:14.430Z,1606841414.430 [Startup:StartupSatComms:A] Stopped 2020-12-01T16:50:14.430Z,1606841414.430 [Startup:StartupSatComms:B] Running Loop=1 2020-12-01T16:50:14.858Z,1606841414.858 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-12-01T16:50:30.621Z,1606841430.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-12-01T16:50:30.621Z,1606841430.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-12-01T16:50:30.631Z,1606841430.631 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-12-01T16:50:30.997Z,1606841430.997 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-12-01T16:50:30.997Z,1606841430.997 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-12-01T16:50:53.719Z,1606841453.719 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004638 2020-12-01T16:51:03.107Z,1606841463.107 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T155147/Courier0019.lzma 2020-12-01T16:51:04.110Z,1606841464.110 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155147/Courier0019.lzma.bak 2020-12-01T16:51:04.110Z,1606841464.110 [DataOverHttps](INFO): SBD MOMSN=13372326 2020-12-01T16:51:14.654Z,1606841474.654 [Startup:StartupSatComms:B](INFO): Timed out from 2020-12-01T16:50:14.4Z 2020-12-01T16:51:14.654Z,1606841474.654 [Startup:StartupSatComms:B] Stopped 2020-12-01T16:51:14.654Z,1606841474.654 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-12-01T16:51:14.655Z,1606841474.655 [Startup:StartupSatComms] Stopped 2020-12-01T16:51:14.655Z,1606841474.655 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-12-01T16:51:14.655Z,1606841474.655 [Startup](INFO): Completed Startup 2020-12-01T16:51:14.655Z,1606841474.655 [MissionManager](INFO): Startup is completed. 2020-12-01T16:51:14.656Z,1606841474.656 [MissionManager](INFO): Uninitializing Mission Startup 2020-12-01T16:51:14.656Z,1606841474.656 [Startup] Stopped 2020-12-01T16:51:14.656Z,1606841474.656 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-12-01T16:51:14.656Z,1606841474.656 [Startup:A.GoToSurface] Stopped 2020-12-01T16:51:14.656Z,1606841474.656 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-12-01T16:51:15.022Z,1606841475.022 [MissionManager](IMPORTANT): Started mission Default 2020-12-01T16:51:15.022Z,1606841475.022 [Default] Running Loop=1 2020-12-01T16:51:15.022Z,1606841475.022 [Default](DEBUG): Aggregate::initialize Default 2020-12-01T16:51:15.022Z,1606841475.022 [Default:B.GoToSurface] Running Loop=1 2020-12-01T16:51:15.022Z,1606841475.022 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-12-01T16:51:15.023Z,1606841475.023 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-12-01T16:51:15.023Z,1606841475.023 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-12-01T16:51:15.023Z,1606841475.023 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-12-01T16:51:15.023Z,1606841475.023 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-12-01T16:51:15.024Z,1606841475.024 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-12-01T16:51:15.024Z,1606841475.024 [Default:A.Wait] Running Loop=1 2020-12-01T16:51:15.024Z,1606841475.024 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-12-01T16:51:20.224Z,1606841480.224 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20201201T164721/Courier0000.lzma 2020-12-01T16:51:21.226Z,1606841481.226 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Courier0000.lzma.bak 2020-12-01T16:51:21.226Z,1606841481.226 [DataOverHttps](INFO): SBD MOMSN=13372328 2020-12-01T16:51:28.362Z,1606841488.362 [Default:A.Wait](INFO): Done Waiting. 2020-12-01T16:51:28.362Z,1606841488.362 [Default:A.Wait] Stopped 2020-12-01T16:51:28.362Z,1606841488.362 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-12-01T16:51:28.776Z,1606841488.776 [Default:CheckIn] Running Loop=1 2020-12-01T16:51:28.777Z,1606841488.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-12-01T16:51:28.777Z,1606841488.777 [Default:CheckIn:Read_GPS] Running Loop=1 2020-12-01T16:51:29.166Z,1606841489.166 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-12-01T16:51:35.755Z,1606841495.755 [CommandLine](IMPORTANT): got command failComponent 2020-12-01T16:51:35.755Z,1606841495.755 [CommandLine](IMPORTANT): Failed components: 2020-12-01T16:51:35.755Z,1606841495.755 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-12-01T16:52:06.669Z,1606841526.669 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2020-12-01T16:52:06.670Z,1606841526.670 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2020-12-01T16:52:06.671Z,1606841526.671 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2020-12-01T16:52:06.766Z,1606841526.766 [Reporter](INFO): platform_buoyancy_position 962.366577 cc 2020-12-01T16:52:06.772Z,1606841526.772 [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-12-01T16:52:12.903Z,1606841532.903 [Reporter](INFO): platform_buoyancy_position 966.664185 cc 2020-12-01T16:52:13.242Z,1606841533.242 [Reporter](INFO): platform_buoyancy_position 966.798462 cc 2020-12-01T16:52:13.646Z,1606841533.646 [Reporter](INFO): platform_buoyancy_position 964.649658 cc 2020-12-01T16:52:14.063Z,1606841534.063 [Reporter](INFO): platform_buoyancy_position 953.771362 cc 2020-12-01T16:52:14.462Z,1606841534.462 [Reporter](INFO): platform_buoyancy_position 951.756836 cc 2020-12-01T16:52:14.923Z,1606841534.923 [Reporter](INFO): platform_buoyancy_position 947.593628 cc 2020-12-01T16:52:15.370Z,1606841535.370 [Reporter](INFO): platform_buoyancy_position 943.161743 cc 2020-12-01T16:52:15.742Z,1606841535.742 [Reporter](INFO): platform_buoyancy_position 938.864136 cc 2020-12-01T16:52:16.135Z,1606841536.135 [Reporter](INFO): platform_buoyancy_position 934.432129 cc 2020-12-01T16:52:16.423Z,1606841536.423 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-12-01T16:52:16.511Z,1606841536.511 [Reporter](INFO): platform_buoyancy_position 930.134644 cc 2020-12-01T16:52:16.922Z,1606841536.922 [Reporter](INFO): platform_buoyancy_position 923.688232 cc 2020-12-01T16:52:17.307Z,1606841537.307 [Reporter](INFO): platform_buoyancy_position 919.256226 cc 2020-12-01T16:52:17.774Z,1606841537.774 [Reporter](INFO): platform_buoyancy_position 912.944214 cc 2020-12-01T16:52:18.134Z,1606841538.134 [Reporter](INFO): platform_buoyancy_position 907.303589 cc 2020-12-01T16:52:18.533Z,1606841538.533 [Reporter](INFO): platform_buoyancy_position 902.200195 cc 2020-12-01T16:52:18.911Z,1606841538.911 [Reporter](INFO): platform_buoyancy_position 895.888062 cc 2020-12-01T16:52:19.314Z,1606841539.314 [Reporter](INFO): platform_buoyancy_position 891.590454 cc 2020-12-01T16:52:19.747Z,1606841539.747 [Reporter](INFO): platform_buoyancy_position 887.158569 cc 2020-12-01T16:52:20.118Z,1606841540.118 [Reporter](INFO): platform_buoyancy_position 882.458130 cc 2020-12-01T16:52:20.563Z,1606841540.563 [Reporter](INFO): platform_buoyancy_position 876.414551 cc 2020-12-01T16:52:20.953Z,1606841540.953 [Reporter](INFO): platform_buoyancy_position 871.445435 cc 2020-12-01T16:52:21.333Z,1606841541.333 [Reporter](INFO): platform_buoyancy_position 865.804932 cc 2020-12-01T16:52:21.750Z,1606841541.750 [Reporter](INFO): platform_buoyancy_position 860.835815 cc 2020-12-01T16:52:22.130Z,1606841542.130 [Reporter](INFO): platform_buoyancy_position 855.060913 cc 2020-12-01T16:52:22.553Z,1606841542.553 [Reporter](INFO): platform_buoyancy_position 850.629028 cc 2020-12-01T16:52:22.969Z,1606841542.969 [Reporter](INFO): platform_buoyancy_position 844.182617 cc 2020-12-01T16:52:23.346Z,1606841543.346 [Reporter](INFO): platform_buoyancy_position 839.885010 cc 2020-12-01T16:52:23.761Z,1606841543.761 [Reporter](INFO): platform_buoyancy_position 833.438599 cc 2020-12-01T16:52:24.191Z,1606841544.191 [Reporter](INFO): platform_buoyancy_position 829.140991 cc 2020-12-01T16:52:24.547Z,1606841544.547 [Reporter](INFO): platform_buoyancy_position 823.634705 cc 2020-12-01T16:52:24.978Z,1606841544.978 [Reporter](INFO): platform_buoyancy_position 818.396973 cc 2020-12-01T16:52:25.403Z,1606841545.403 [Reporter](INFO): platform_buoyancy_position 812.084900 cc 2020-12-01T16:52:25.810Z,1606841545.810 [Reporter](INFO): platform_buoyancy_position 807.787292 cc 2020-12-01T16:52:26.190Z,1606841546.190 [Reporter](INFO): platform_buoyancy_position 802.952454 cc 2020-12-01T16:52:26.611Z,1606841546.611 [Reporter](INFO): platform_buoyancy_position 796.908997 cc 2020-12-01T16:52:27.037Z,1606841547.037 [Reporter](INFO): platform_buoyancy_position 790.596863 cc 2020-12-01T16:52:27.461Z,1606841547.461 [Reporter](INFO): platform_buoyancy_position 786.164978 cc 2020-12-01T16:52:27.907Z,1606841547.907 [Reporter](INFO): platform_buoyancy_position 779.718567 cc 2020-12-01T16:52:28.286Z,1606841548.286 [Reporter](INFO): platform_buoyancy_position 775.420959 cc 2020-12-01T16:52:28.897Z,1606841548.897 [Reporter](INFO): platform_buoyancy_position 767.094360 cc 2020-12-01T16:52:29.311Z,1606841549.311 [Reporter](INFO): platform_buoyancy_position 762.528198 cc 2020-12-01T16:52:29.581Z,1606841549.581 [Reporter](INFO): platform_buoyancy_position 758.230591 cc 2020-12-01T16:52:29.965Z,1606841549.965 [Reporter](INFO): platform_buoyancy_position 752.589966 cc 2020-12-01T16:52:30.337Z,1606841550.337 [Reporter](INFO): platform_buoyancy_position 747.620850 cc 2020-12-01T16:52:30.794Z,1606841550.794 [Reporter](INFO): platform_buoyancy_position 742.651794 cc 2020-12-01T16:52:31.129Z,1606841551.129 [Reporter](INFO): platform_buoyancy_position 737.145447 cc 2020-12-01T16:52:31.528Z,1606841551.528 [Reporter](INFO): platform_buoyancy_position 732.579285 cc 2020-12-01T16:52:31.993Z,1606841551.993 [Reporter](INFO): platform_buoyancy_position 726.132874 cc 2020-12-01T16:52:32.368Z,1606841552.368 [Reporter](INFO): platform_buoyancy_position 721.566650 cc 2020-12-01T16:52:32.754Z,1606841552.754 [Reporter](INFO): platform_buoyancy_position 715.523193 cc 2020-12-01T16:52:33.195Z,1606841553.195 [Reporter](INFO): platform_buoyancy_position 710.688354 cc 2020-12-01T16:52:33.580Z,1606841553.580 [Reporter](INFO): platform_buoyancy_position 704.644836 cc 2020-12-01T16:52:33.966Z,1606841553.966 [Reporter](INFO): platform_buoyancy_position 700.212952 cc 2020-12-01T16:52:34.362Z,1606841554.362 [Reporter](INFO): platform_buoyancy_position 695.915344 cc 2020-12-01T16:52:34.774Z,1606841554.774 [Reporter](INFO): platform_buoyancy_position 689.468994 cc 2020-12-01T16:52:35.145Z,1606841555.145 [Reporter](INFO): platform_buoyancy_position 685.171387 cc 2020-12-01T16:52:35.594Z,1606841555.594 [Reporter](INFO): platform_buoyancy_position 678.724976 cc 2020-12-01T16:52:35.978Z,1606841555.978 [Reporter](INFO): platform_buoyancy_position 674.427368 cc 2020-12-01T16:52:36.350Z,1606841556.350 [Reporter](INFO): platform_buoyancy_position 668.115234 cc 2020-12-01T16:52:36.770Z,1606841556.770 [Reporter](INFO): platform_buoyancy_position 663.817688 cc 2020-12-01T16:52:37.150Z,1606841557.150 [Reporter](INFO): platform_buoyancy_position 659.117188 cc 2020-12-01T16:52:37.714Z,1606841557.714 [Reporter](INFO): platform_buoyancy_position 650.924866 cc 2020-12-01T16:52:37.985Z,1606841557.985 [Reporter](INFO): platform_buoyancy_position 647.433044 cc 2020-12-01T16:52:38.438Z,1606841558.438 [Reporter](INFO): platform_buoyancy_position 642.195374 cc 2020-12-01T16:52:38.822Z,1606841558.822 [Reporter](INFO): platform_buoyancy_position 635.883240 cc 2020-12-01T16:52:39.187Z,1606841559.187 [Reporter](INFO): platform_buoyancy_position 631.585632 cc 2020-12-01T16:52:39.614Z,1606841559.614 [Reporter](INFO): platform_buoyancy_position 627.153748 cc 2020-12-01T16:52:39.986Z,1606841559.986 [Reporter](INFO): platform_buoyancy_position 621.781738 cc 2020-12-01T16:52:40.400Z,1606841560.400 [Reporter](INFO): platform_buoyancy_position 616.409790 cc 2020-12-01T16:52:40.818Z,1606841560.818 [Reporter](INFO): platform_buoyancy_position 610.097656 cc 2020-12-01T16:52:41.194Z,1606841561.194 [Reporter](INFO): platform_buoyancy_position 605.665771 cc 2020-12-01T16:52:41.594Z,1606841561.594 [Reporter](INFO): platform_buoyancy_position 599.487976 cc 2020-12-01T16:52:42.118Z,1606841562.118 [Reporter](INFO): platform_buoyancy_position 592.907288 cc 2020-12-01T16:52:42.499Z,1606841562.499 [Reporter](INFO): platform_buoyancy_position 588.609680 cc 2020-12-01T16:52:42.854Z,1606841562.854 [Reporter](INFO): platform_buoyancy_position 584.446350 cc 2020-12-01T16:52:43.278Z,1606841563.278 [Reporter](INFO): platform_buoyancy_position 577.865662 cc 2020-12-01T16:52:43.658Z,1606841563.658 [Reporter](INFO): platform_buoyancy_position 573.568054 cc 2020-12-01T16:52:44.031Z,1606841564.031 [Reporter](INFO): platform_buoyancy_position 568.733276 cc 2020-12-01T16:52:44.423Z,1606841564.423 [Reporter](INFO): platform_buoyancy_position 562.689758 cc 2020-12-01T16:52:44.838Z,1606841564.838 [Reporter](INFO): platform_buoyancy_position 558.392151 cc 2020-12-01T16:52:45.230Z,1606841565.230 [Reporter](INFO): platform_buoyancy_position 552.080078 cc 2020-12-01T16:52:45.670Z,1606841565.670 [Reporter](INFO): platform_buoyancy_position 547.513855 cc 2020-12-01T16:52:46.034Z,1606841566.034 [Reporter](INFO): platform_buoyancy_position 541.470337 cc 2020-12-01T16:52:46.446Z,1606841566.446 [Reporter](INFO): platform_buoyancy_position 536.904175 cc 2020-12-01T16:52:46.869Z,1606841566.869 [Reporter](INFO): platform_buoyancy_position 530.457764 cc 2020-12-01T16:52:47.258Z,1606841567.258 [Reporter](INFO): platform_buoyancy_position 526.294434 cc 2020-12-01T16:52:47.674Z,1606841567.674 [Reporter](INFO): platform_buoyancy_position 519.848022 cc 2020-12-01T16:52:48.058Z,1606841568.058 [Reporter](INFO): platform_buoyancy_position 515.416138 cc 2020-12-01T16:52:48.475Z,1606841568.475 [Reporter](INFO): platform_buoyancy_position 511.118500 cc 2020-12-01T16:52:48.890Z,1606841568.890 [Reporter](INFO): platform_buoyancy_position 504.806458 cc 2020-12-01T16:52:49.266Z,1606841569.266 [Reporter](INFO): platform_buoyancy_position 499.165863 cc 2020-12-01T16:52:49.687Z,1606841569.687 [Reporter](INFO): platform_buoyancy_position 494.062439 cc 2020-12-01T16:52:50.094Z,1606841570.094 [Reporter](INFO): platform_buoyancy_position 489.630554 cc 2020-12-01T16:52:50.502Z,1606841570.502 [Reporter](INFO): platform_buoyancy_position 483.452759 cc 2020-12-01T16:52:50.882Z,1606841570.882 [Reporter](INFO): platform_buoyancy_position 479.020874 cc 2020-12-01T16:52:51.322Z,1606841571.322 [Reporter](INFO): platform_buoyancy_position 472.305847 cc 2020-12-01T16:52:51.710Z,1606841571.710 [Reporter](INFO): platform_buoyancy_position 468.008240 cc 2020-12-01T16:52:52.126Z,1606841572.126 [Reporter](INFO): platform_buoyancy_position 461.830444 cc 2020-12-01T16:52:52.494Z,1606841572.494 [Reporter](INFO): platform_buoyancy_position 457.532837 cc 2020-12-01T16:52:52.910Z,1606841572.910 [Reporter](INFO): platform_buoyancy_position 451.086426 cc 2020-12-01T16:52:53.325Z,1606841573.325 [Reporter](INFO): platform_buoyancy_position 446.654541 cc 2020-12-01T16:52:53.738Z,1606841573.738 [Reporter](INFO): platform_buoyancy_position 440.208130 cc 2020-12-01T16:52:54.114Z,1606841574.114 [Reporter](INFO): platform_buoyancy_position 435.910522 cc 2020-12-01T16:52:54.529Z,1606841574.529 [Reporter](INFO): platform_buoyancy_position 429.598450 cc 2020-12-01T16:52:55.010Z,1606841575.010 [Reporter](INFO): platform_buoyancy_position 425.166565 cc 2020-12-01T16:52:55.354Z,1606841575.354 [Reporter](INFO): platform_buoyancy_position 418.854431 cc 2020-12-01T16:52:55.878Z,1606841575.878 [Reporter](INFO): platform_buoyancy_position 412.408020 cc 2020-12-01T16:52:56.194Z,1606841576.194 [Reporter](INFO): platform_buoyancy_position 407.976135 cc 2020-12-01T16:52:56.586Z,1606841576.586 [Reporter](INFO): platform_buoyancy_position 403.678528 cc 2020-12-01T16:52:56.974Z,1606841576.974 [Reporter](INFO): platform_buoyancy_position 397.500732 cc 2020-12-01T16:52:57.362Z,1606841577.362 [Reporter](INFO): platform_buoyancy_position 392.934570 cc 2020-12-01T16:52:57.766Z,1606841577.766 [Reporter](INFO): platform_buoyancy_position 386.488159 cc 2020-12-01T16:52:58.166Z,1606841578.166 [Reporter](INFO): platform_buoyancy_position 382.190552 cc 2020-12-01T16:52:58.570Z,1606841578.570 [Reporter](INFO): platform_buoyancy_position 378.027222 cc 2020-12-01T16:52:58.991Z,1606841578.991 [Reporter](INFO): platform_buoyancy_position 371.580872 cc 2020-12-01T16:52:59.374Z,1606841579.374 [Reporter](INFO): platform_buoyancy_position 367.283264 cc 2020-12-01T16:52:59.790Z,1606841579.790 [Reporter](INFO): platform_buoyancy_position 360.836853 cc 2020-12-01T16:53:00.202Z,1606841580.202 [Reporter](INFO): platform_buoyancy_position 356.404968 cc 2020-12-01T16:53:00.574Z,1606841580.574 [Reporter](INFO): platform_buoyancy_position 352.107361 cc 2020-12-01T16:53:00.986Z,1606841580.986 [Reporter](INFO): platform_buoyancy_position 345.795227 cc 2020-12-01T16:53:01.407Z,1606841581.407 [Reporter](INFO): platform_buoyancy_position 340.423218 cc 2020-12-01T16:53:01.834Z,1606841581.834 [Reporter](INFO): platform_buoyancy_position 334.916931 cc 2020-12-01T16:53:02.231Z,1606841582.231 [Reporter](INFO): platform_buoyancy_position 330.753662 cc 2020-12-01T16:53:02.650Z,1606841582.650 [Reporter](INFO): platform_buoyancy_position 324.307251 cc 2020-12-01T16:53:03.022Z,1606841583.022 [Reporter](INFO): platform_buoyancy_position 319.741028 cc 2020-12-01T16:53:03.436Z,1606841583.436 [Reporter](INFO): platform_buoyancy_position 313.563232 cc 2020-12-01T16:53:03.870Z,1606841583.870 [Reporter](INFO): platform_buoyancy_position 307.116852 cc 2020-12-01T16:53:04.242Z,1606841584.242 [Reporter](INFO): platform_buoyancy_position 302.819244 cc 2020-12-01T16:53:04.675Z,1606841584.675 [Reporter](INFO): platform_buoyancy_position 297.312927 cc 2020-12-01T16:53:05.057Z,1606841585.057 [Reporter](INFO): platform_buoyancy_position 291.940948 cc 2020-12-01T16:53:05.431Z,1606841585.431 [Reporter](INFO): platform_buoyancy_position 287.777649 cc 2020-12-01T16:53:05.874Z,1606841585.874 [Reporter](INFO): platform_buoyancy_position 281.196930 cc 2020-12-01T16:53:06.262Z,1606841586.262 [Reporter](INFO): platform_buoyancy_position 276.765045 cc 2020-12-01T16:53:06.677Z,1606841586.677 [Reporter](INFO): platform_buoyancy_position 270.855835 cc 2020-12-01T16:53:07.050Z,1606841587.050 [Reporter](INFO): platform_buoyancy_position 266.423920 cc 2020-12-01T16:53:07.458Z,1606841587.458 [Reporter](INFO): platform_buoyancy_position 261.992035 cc 2020-12-01T16:53:07.869Z,1606841587.869 [Reporter](INFO): platform_buoyancy_position 255.545624 cc 2020-12-01T16:53:08.277Z,1606841588.277 [Reporter](INFO): platform_buoyancy_position 250.710846 cc 2020-12-01T16:53:08.805Z,1606841588.805 [Reporter](INFO): platform_buoyancy_position 243.727234 cc 2020-12-01T16:53:09.142Z,1606841589.142 [Reporter](INFO): platform_buoyancy_position 238.220947 cc 2020-12-01T16:53:09.502Z,1606841589.502 [Reporter](INFO): platform_buoyancy_position 233.923340 cc 2020-12-01T16:53:09.905Z,1606841589.905 [Reporter](INFO): platform_buoyancy_position 228.417023 cc 2020-12-01T16:53:10.322Z,1606841590.322 [Reporter](INFO): platform_buoyancy_position 223.179321 cc 2020-12-01T16:53:10.729Z,1606841590.729 [Reporter](INFO): platform_buoyancy_position 218.881744 cc 2020-12-01T16:53:11.098Z,1606841591.098 [Reporter](INFO): platform_buoyancy_position 212.972534 cc 2020-12-01T16:53:11.542Z,1606841591.542 [Reporter](INFO): platform_buoyancy_position 208.137726 cc 2020-12-01T16:53:13.457Z,1606841593.457 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-12-01T16:53:17.907Z,1606841597.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165316.00,A,3648.16126,N,12147.27809,W,0.194,138.87,011220,,,A*7D 2020-12-01T16:53:17.919Z,1606841597.919 [NAL9602](INFO): GPS fix at 20201201T165316: (36.802688, -121.787968) 2020-12-01T16:53:17.930Z,1606841597.930 [Default:CheckIn:Read_GPS] Stopped 2020-12-01T16:53:17.930Z,1606841597.930 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-12-01T16:53:18.379Z,1606841598.379 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-12-01T16:53:27.621Z,1606841607.621 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201201T164721/Courier0004.lzma 2020-12-01T16:53:28.622Z,1606841608.622 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Courier0004.lzma.bak 2020-12-01T16:53:28.622Z,1606841608.622 [DataOverHttps](INFO): SBD MOMSN=13372334 2020-12-01T16:53:46.263Z,1606841626.263 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20201201T155147/Express0020.lzma 2020-12-01T16:53:47.266Z,1606841627.266 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155147/Express0020.lzma.bak 2020-12-01T16:53:47.266Z,1606841627.266 [DataOverHttps](INFO): SBD MOMSN=13372337 2020-12-01T16:53:50.222Z,1606841630.222 [NAL9602](INFO): Not Powering down - fast GPS 2020-12-01T16:54:03.529Z,1606841643.529 [DataOverHttps](INFO): Sending 976 bytes from file Logs/20201201T164721/Express0001.lzma 2020-12-01T16:54:04.535Z,1606841644.535 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Express0001.lzma.bak 2020-12-01T16:54:04.535Z,1606841644.535 [DataOverHttps](INFO): SBD MOMSN=13372342 2020-12-01T16:54:24.624Z,1606841664.624 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20201201T164721/Express0005.lzma 2020-12-01T16:54:25.626Z,1606841665.626 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Express0005.lzma.bak 2020-12-01T16:54:25.626Z,1606841665.626 [DataOverHttps](INFO): SBD MOMSN=13372369 2020-12-01T16:54:26.805Z,1606841666.805 [Default:CheckIn:Read_Iridium] Stopped 2020-12-01T16:54:26.806Z,1606841666.806 [Default:CheckIn:C.Wait] Running Loop=1 2020-12-01T16:54:26.806Z,1606841666.806 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-12-01T16:54:39.123Z,1606841679.123 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter 2020-12-01T16:54:44.470Z,1606841684.470 [Reporter](INFO): platform_buoyancy_position 195.244934 cc 2020-12-01T16:54:44.978Z,1606841684.978 [Reporter](INFO): platform_buoyancy_position 195.513519 cc 2020-12-01T16:54:45.158Z,1606841685.158 [Reporter](INFO): platform_buoyancy_position 195.379242 cc 2020-12-01T16:54:45.517Z,1606841685.517 [Reporter](INFO): platform_buoyancy_position 193.096130 cc 2020-12-01T16:54:46.250Z,1606841686.250 [Reporter](INFO): platform_buoyancy_position 191.215942 cc 2020-12-01T16:54:46.693Z,1606841686.693 [Reporter](INFO): platform_buoyancy_position 191.081635 cc 2020-12-01T16:54:47.097Z,1606841687.097 [Reporter](INFO): platform_buoyancy_position 188.932831 cc 2020-12-01T16:54:47.482Z,1606841687.482 [Reporter](INFO): platform_buoyancy_position 188.798523 cc 2020-12-01T16:54:47.870Z,1606841687.870 [Reporter](INFO): platform_buoyancy_position 186.784027 cc 2020-12-01T16:54:48.310Z,1606841688.310 [Reporter](INFO): platform_buoyancy_position 185.709625 cc 2020-12-01T16:54:48.707Z,1606841688.707 [Reporter](INFO): platform_buoyancy_position 184.500916 cc 2020-12-01T16:54:49.125Z,1606841689.125 [Reporter](INFO): platform_buoyancy_position 182.486420 cc 2020-12-01T16:54:49.498Z,1606841689.498 [Reporter](INFO): platform_buoyancy_position 182.352142 cc 2020-12-01T16:54:49.917Z,1606841689.917 [Reporter](INFO): platform_buoyancy_position 180.203339 cc 2020-12-01T16:54:50.322Z,1606841690.322 [Reporter](INFO): platform_buoyancy_position 179.263214 cc 2020-12-01T16:54:50.730Z,1606841690.730 [Reporter](INFO): platform_buoyancy_position 178.054535 cc 2020-12-01T16:54:51.106Z,1606841691.106 [Reporter](INFO): platform_buoyancy_position 176.442932 cc 2020-12-01T16:54:51.527Z,1606841691.527 [Reporter](INFO): platform_buoyancy_position 175.905731 cc 2020-12-01T16:54:51.946Z,1606841691.946 [Reporter](INFO): platform_buoyancy_position 173.756927 cc 2020-12-01T16:54:52.322Z,1606841692.322 [Reporter](INFO): platform_buoyancy_position 172.145325 cc 2020-12-01T16:54:52.737Z,1606841692.737 [Reporter](INFO): platform_buoyancy_position 171.608124 cc 2020-12-01T16:54:53.153Z,1606841693.153 [Reporter](INFO): platform_buoyancy_position 169.593628 cc 2020-12-01T16:54:53.562Z,1606841693.562 [Reporter](INFO): platform_buoyancy_position 167.444824 cc 2020-12-01T16:54:53.950Z,1606841693.950 [Reporter](INFO): platform_buoyancy_position 167.310516 cc 2020-12-01T16:54:54.368Z,1606841694.368 [Reporter](INFO): platform_buoyancy_position 165.430328 cc 2020-12-01T16:54:54.777Z,1606841694.777 [Reporter](INFO): platform_buoyancy_position 165.296021 cc 2020-12-01T16:54:55.142Z,1606841695.142 [Reporter](INFO): platform_buoyancy_position 163.147217 cc 2020-12-01T16:54:55.575Z,1606841695.575 [Reporter](INFO): platform_buoyancy_position 160.998413 cc 2020-12-01T16:54:56.374Z,1606841696.374 [Reporter](INFO): platform_buoyancy_position 158.849640 cc 2020-12-01T16:54:56.818Z,1606841696.818 [Reporter](INFO): platform_buoyancy_position 158.715332 cc 2020-12-01T16:54:57.186Z,1606841697.186 [Reporter](INFO): platform_buoyancy_position 156.700836 cc 2020-12-01T16:54:57.597Z,1606841697.597 [Reporter](INFO): platform_buoyancy_position 154.686340 cc 2020-12-01T16:54:58.077Z,1606841698.077 [Reporter](INFO): platform_buoyancy_position 154.149139 cc 2020-12-01T16:54:58.347Z,1606841698.347 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-12-01T16:54:58.350Z,1606841698.350 [BPC1](INFO): Received data from all battery sticks. 2020-12-01T16:54:58.426Z,1606841698.426 [Reporter](INFO): platform_buoyancy_position 152.671814 cc 2020-12-01T16:54:58.806Z,1606841698.806 [Reporter](INFO): platform_buoyancy_position 152.268921 cc 2020-12-01T16:54:59.213Z,1606841699.213 [Reporter](INFO): platform_buoyancy_position 150.254425 cc 2020-12-01T16:54:59.619Z,1606841699.619 [Reporter](INFO): platform_buoyancy_position 149.448639 cc 2020-12-01T16:54:59.986Z,1606841699.986 [Reporter](INFO): platform_buoyancy_position 147.702728 cc 2020-12-01T16:55:00.417Z,1606841700.417 [Reporter](INFO): platform_buoyancy_position 145.956818 cc 2020-12-01T16:55:00.862Z,1606841700.862 [Reporter](INFO): platform_buoyancy_position 143.942322 cc 2020-12-01T16:55:01.206Z,1606841701.206 [Reporter](INFO): platform_buoyancy_position 143.808014 cc 2020-12-01T16:55:01.630Z,1606841701.630 [Reporter](INFO): platform_buoyancy_position 141.659210 cc 2020-12-01T16:55:02.034Z,1606841702.034 [Reporter](INFO): platform_buoyancy_position 141.390625 cc 2020-12-01T16:55:02.414Z,1606841702.414 [Reporter](INFO): platform_buoyancy_position 139.376129 cc 2020-12-01T16:55:02.837Z,1606841702.837 [Reporter](INFO): platform_buoyancy_position 137.361633 cc 2020-12-01T16:55:03.666Z,1606841703.666 [Reporter](INFO): platform_buoyancy_position 135.078522 cc 2020-12-01T16:55:04.066Z,1606841704.066 [Reporter](INFO): platform_buoyancy_position 133.198334 cc 2020-12-01T16:55:04.458Z,1606841704.458 [Reporter](INFO): platform_buoyancy_position 132.929718 cc 2020-12-01T16:55:04.874Z,1606841704.874 [Reporter](INFO): platform_buoyancy_position 130.915222 cc 2020-12-01T16:55:05.295Z,1606841705.295 [Reporter](INFO): platform_buoyancy_position 130.378021 cc 2020-12-01T16:55:05.662Z,1606841705.662 [Reporter](INFO): platform_buoyancy_position 128.766418 cc 2020-12-01T16:55:06.090Z,1606841706.090 [Reporter](INFO): platform_buoyancy_position 127.020538 cc 2020-12-01T16:55:06.517Z,1606841706.517 [Reporter](INFO): platform_buoyancy_position 126.617615 cc 2020-12-01T16:55:06.883Z,1606841706.883 [Reporter](INFO): platform_buoyancy_position 124.468819 cc 2020-12-01T16:55:07.326Z,1606841707.326 [Reporter](INFO): platform_buoyancy_position 124.065918 cc 2020-12-01T16:55:07.710Z,1606841707.710 [Reporter](INFO): platform_buoyancy_position 122.185722 cc 2020-12-01T16:55:08.090Z,1606841708.090 [Reporter](INFO): platform_buoyancy_position 120.171234 cc 2020-12-01T16:55:08.506Z,1606841708.506 [Reporter](INFO): platform_buoyancy_position 120.036926 cc 2020-12-01T16:55:08.926Z,1606841708.926 [Reporter](INFO): platform_buoyancy_position 118.156738 cc 2020-12-01T16:55:09.286Z,1606841709.286 [Reporter](INFO): platform_buoyancy_position 117.619537 cc 2020-12-01T16:55:09.707Z,1606841709.707 [Reporter](INFO): platform_buoyancy_position 115.739319 cc 2020-12-01T16:55:10.117Z,1606841710.117 [Reporter](INFO): platform_buoyancy_position 113.590515 cc 2020-12-01T16:55:10.511Z,1606841710.511 [Reporter](INFO): platform_buoyancy_position 113.456238 cc 2020-12-01T16:55:10.906Z,1606841710.906 [Reporter](INFO): platform_buoyancy_position 111.576019 cc 2020-12-01T16:55:11.333Z,1606841711.333 [Reporter](INFO): platform_buoyancy_position 109.427216 cc 2020-12-01T16:58:27.794Z,1606841907.794 [CommandLine](IMPORTANT): got command maintain clear 2020-12-01T16:58:28.081Z,1606841908.081 [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-12-01T16:58:29.516Z,1606841909.516 [CommandLine](IMPORTANT): got command report clear 2020-12-01T16:59:27.430Z,1606841967.430 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-12-01T16:59:27.430Z,1606841967.430 [Default:CheckIn:C.Wait] Stopped 2020-12-01T16:59:27.430Z,1606841967.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-12-01T16:59:27.430Z,1606841967.430 [Default:CheckIn:D] Running Loop=1 2020-12-01T16:59:27.846Z,1606841967.846 [Default:CheckIn:D] Stopped 2020-12-01T16:59:27.846Z,1606841967.846 [Default:CheckIn:E] Running Loop=1 2020-12-01T16:59:28.273Z,1606841968.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.213739 min 2020-12-01T16:59:28.273Z,1606841968.273 [Default:CheckIn:E] Stopped 2020-12-01T16:59:28.273Z,1606841968.273 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-12-01T16:59:28.274Z,1606841968.274 [Default:CheckIn] Stopped 2020-12-01T16:59:28.274Z,1606841968.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-12-01T16:59:28.274Z,1606841968.274 [Default:CheckIn](INFO): Running loop #2 2020-12-01T16:59:28.274Z,1606841968.274 [Default:CheckIn] Running Loop=2 2020-12-01T16:59:28.274Z,1606841968.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-12-01T16:59:28.274Z,1606841968.274 [Default:CheckIn:Read_GPS] Running Loop=1 2020-12-01T16:59:30.255Z,1606841970.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165929.00,A,3648.16644,N,12147.28187,W,0.117,138.87,011220,,,A*73 2020-12-01T16:59:30.260Z,1606841970.260 [NAL9602](INFO): GPS fix at 20201201T165929: (36.802774, -121.788031) 2020-12-01T16:59:30.272Z,1606841970.272 [Default:CheckIn:Read_GPS] Stopped 2020-12-01T16:59:30.272Z,1606841970.272 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-12-01T16:59:46.432Z,1606841986.432 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-12-01T16:59:46.549Z,1606841986.549 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20201201T164721/Courier0007.lzma 2020-12-01T16:59:47.550Z,1606841987.550 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Courier0007.lzma.bak 2020-12-01T16:59:47.550Z,1606841987.550 [DataOverHttps](INFO): SBD MOMSN=13372388 2020-12-01T17:00:00.953Z,1606842000.953 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-12-01T17:00:01.028Z,1606842001.028 [NAL9602](FAULT): received: +CSQ:0 OK 2020-12-01T17:00:01.028Z,1606842001.028 [NAL9602] Data Fault, FailCount= 1 2020-12-01T17:00:01.028Z,1606842001.028 [NAL9602](ERROR): Data Fault 2020-12-01T17:00:01.151Z,1606842001.151 [CBIT](ERROR): Data Fault in component: NAL9602 2020-12-01T17:00:01.357Z,1606842001.357 [NAL9602](INFO): Powering down 2020-12-01T17:00:02.201Z,1606842002.201 [CBIT](INFO): Clearing failed state for component NAL9602 2020-12-01T17:00:02.201Z,1606842002.201 [NAL9602] No Fault, FailCount= 1 2020-12-01T17:00:31.359Z,1606842031.359 [NAL9602](INFO): Powering up NAL9602 2020-12-01T17:00:42.265Z,1606842042.265 [NAL9602](INFO): NAL9602 initialized 2020-12-01T17:01:13.371Z,1606842073.371 [NAL9602](INFO): Not Powering down - fast GPS 2020-12-01T17:02:06.747Z,1606842126.747 [CommandLine](IMPORTANT): got command failComponent 2020-12-01T17:02:06.747Z,1606842126.747 [CommandLine](IMPORTANT): Failed components: 2020-12-01T17:02:06.747Z,1606842126.747 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-12-01T17:02:11.046Z,1606842131.046 [CommandLine](IMPORTANT): got command failComponent none DropWeight 2020-12-01T17:02:11.046Z,1606842131.046 [DropWeight] No Fault, FailCount= 1 2020-12-01T17:02:11.046Z,1606842131.046 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault 2020-12-01T17:02:35.928Z,1606842155.928 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20201201T164721/Express0008.lzma 2020-12-01T17:02:36.930Z,1606842156.930 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Express0008.lzma.bak 2020-12-01T17:02:36.930Z,1606842156.930 [DataOverHttps](INFO): SBD MOMSN=13372435 2020-12-01T17:02:37.979Z,1606842157.979 [Default:CheckIn:Read_Iridium] Stopped 2020-12-01T17:02:37.979Z,1606842157.979 [Default:CheckIn:C.Wait] Running Loop=1 2020-12-01T17:02:37.979Z,1606842157.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-12-01T17:07:38.602Z,1606842458.602 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-12-01T17:07:38.602Z,1606842458.602 [Default:CheckIn:C.Wait] Stopped 2020-12-01T17:07:38.603Z,1606842458.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-12-01T17:07:38.603Z,1606842458.603 [Default:CheckIn:D] Running Loop=1 2020-12-01T17:07:39.005Z,1606842459.005 [Default:CheckIn:D] Stopped 2020-12-01T17:07:39.005Z,1606842459.005 [Default:CheckIn:E] Running Loop=1 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.399711 min 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn:E] Stopped 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn] Stopped 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn](INFO): Running loop #3 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn] Running Loop=3 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-12-01T17:07:39.418Z,1606842459.418 [Default:CheckIn:Read_GPS] Running Loop=1 2020-12-01T17:07:41.405Z,1606842461.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170739.00,A,3648.16387,N,12147.28533,W,0.136,311.10,011220,,,A*7D 2020-12-01T17:07:41.407Z,1606842461.407 [NAL9602](INFO): GPS fix at 20201201T170739: (36.802731, -121.788089) 2020-12-01T17:07:41.417Z,1606842461.417 [Default:CheckIn:Read_GPS] Stopped 2020-12-01T17:07:41.417Z,1606842461.417 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-12-01T17:07:43.050Z,1606842463.050 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-12-01T17:07:48.655Z,1606842468.655 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201201T164721/Courier0010.lzma 2020-12-01T17:07:49.663Z,1606842469.663 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Courier0010.lzma.bak 2020-12-01T17:07:49.663Z,1606842469.663 [DataOverHttps](INFO): SBD MOMSN=13372473 2020-12-01T17:08:05.916Z,1606842485.916 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20201201T164721/Express0011.lzma 2020-12-01T17:08:06.918Z,1606842486.918 [DataOverHttps](INFO): Moved sent file to Logs/20201201T164721/Express0011.lzma.bak 2020-12-01T17:08:06.918Z,1606842486.918 [DataOverHttps](INFO): SBD MOMSN=13372476 2020-12-01T17:08:08.109Z,1606842488.109 [Default:CheckIn:Read_Iridium] Stopped 2020-12-01T17:08:08.109Z,1606842488.109 [Default:CheckIn:C.Wait] Running Loop=1 2020-12-01T17:08:08.110Z,1606842488.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-12-01T17:08:13.730Z,1606842493.730 [NAL9602](INFO): Not Powering down - fast GPS 2020-12-01T17:08:22.873Z,1606842502.873 [CommandLine](IMPORTANT): got command failComponent 2020-12-01T17:08:22.873Z,1606842502.873 [CommandLine](IMPORTANT): Failed components: 2020-12-01T17:08:22.874Z,1606842502.874 [CommandLine](IMPORTANT): No failed Components. 2020-12-01T17:08:24.394Z,1606842504.394 [CommandLine](IMPORTANT): got command failComponent 2020-12-01T17:08:24.394Z,1606842504.394 [CommandLine](IMPORTANT): Failed components: 2020-12-01T17:08:24.394Z,1606842504.394 [CommandLine](IMPORTANT): No failed Components. 2020-12-01T17:08:25.648Z,1606842505.648 [CommandLine](IMPORTANT): got command failComponent 2020-12-01T17:08:25.648Z,1606842505.648 [CommandLine](IMPORTANT): Failed components: 2020-12-01T17:08:25.649Z,1606842505.649 [CommandLine](IMPORTANT): No failed Components. 2020-12-01T17:08:31.577Z,1606842511.577 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-12-01T17:08:51.425Z,1606842531.425 [DataOverHttps](IMPORTANT): SBD MTMSN=20201201T170850 2020-12-01T17:08:58.976Z,1606842538.976 [DataOverHttps](INFO): Received command:restart app 2020-12-01T17:08:59.069Z,1606842539.069 [CommandLine](IMPORTANT): got command restart application 2020-12-01T17:09:00.072Z,1606842540.072 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:00.072Z,1606842540.072 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.248Z,1606842540.248 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-12-01T17:09:00.248Z,1606842540.248 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.249Z,1606842540.249 [CommandLine](INFO): Join timeout helper Thread ID is 5861 2020-12-01T17:09:00.249Z,1606842540.249 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-12-01T17:09:00.249Z,1606842540.249 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.250Z,1606842540.250 [NavChartDb](INFO): Join timeout helper Thread ID is 5862 2020-12-01T17:09:00.468Z,1606842540.468 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:00.468Z,1606842540.468 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.488Z,1606842540.488 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2020-12-01T17:09:00.488Z,1606842540.488 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.488Z,1606842540.488 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 5863 2020-12-01T17:09:00.744Z,1606842540.744 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:00.744Z,1606842540.744 [WetLabsUBAT](INFO): Powering down 2020-12-01T17:09:00.745Z,1606842540.745 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.752Z,1606842540.752 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-12-01T17:09:00.752Z,1606842540.752 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.752Z,1606842540.752 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5864 2020-12-01T17:09:00.804Z,1606842540.804 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:00.805Z,1606842540.805 [WetLabsBB2FL](INFO): Powering down 2020-12-01T17:09:00.805Z,1606842540.805 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.816Z,1606842540.816 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-12-01T17:09:00.816Z,1606842540.816 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:00.816Z,1606842540.816 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5865 2020-12-01T17:09:01.264Z,1606842541.264 [CTD_Seabird](INFO): Powering down 2020-12-01T17:09:01.276Z,1606842541.276 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:01.276Z,1606842541.276 [CTD_Seabird](INFO): Powering down 2020-12-01T17:09:01.288Z,1606842541.288 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:01.297Z,1606842541.297 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2020-12-01T17:09:01.297Z,1606842541.297 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:01.297Z,1606842541.297 [BackSeatDriver](INFO): Join timeout helper Thread ID is 5866 2020-12-01T17:09:01.552Z,1606842541.552 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:01.552Z,1606842541.552 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:01.565Z,1606842541.565 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-12-01T17:09:01.565Z,1606842541.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:01.565Z,1606842541.565 [Radio_Surface](INFO): Join timeout helper Thread ID is 5867 2020-12-01T17:09:01.578Z,1606842541.578 [Radio_Surface](INFO): Powering down 2020-12-01T17:09:01.579Z,1606842541.579 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:01.579Z,1606842541.579 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:01.584Z,1606842541.584 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-12-01T17:09:01.585Z,1606842541.585 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:01.585Z,1606842541.585 [Onboard](INFO): Join timeout helper Thread ID is 5868 2020-12-01T17:09:01.984Z,1606842541.984 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-12-01T17:09:06.525Z,1606842546.525 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:06.525Z,1606842546.525 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:06.540Z,1606842546.540 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-12-01T17:09:06.541Z,1606842546.541 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:06.541Z,1606842546.541 [DataOverHttps](INFO): Join timeout helper Thread ID is 5869 2020-12-01T17:09:07.004Z,1606842547.004 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:07.004Z,1606842547.004 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.012Z,1606842547.012 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-12-01T17:09:07.013Z,1606842547.013 [logger ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.013Z,1606842547.013 [logger](INFO): Join timeout helper Thread ID is 5870 2020-12-01T17:09:07.048Z,1606842547.048 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:07.048Z,1606842547.048 [logger ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.053Z,1606842547.053 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-12-01T17:09:07.053Z,1606842547.053 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.053Z,1606842547.053 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-12-01T17:09:07.053Z,1606842547.053 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.054Z,1606842547.054 [controlThread](INFO): Join timeout helper Thread ID is 5871 2020-12-01T17:09:07.077Z,1606842547.077 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-12-01T17:09:07.077Z,1606842547.077 [controlThread](DEBUG): Uninitializing ControlThread 2020-12-01T17:09:07.077Z,1606842547.077 [AHRS_M2](INFO): Powering down 2020-12-01T17:09:07.149Z,1606842547.149 [NAL9602](INFO): Powering down 2020-12-01T17:09:07.220Z,1606842547.220 [RDI_Pathfinder](INFO): Powering down 2020-12-01T17:09:07.221Z,1606842547.221 [DAT](INFO): Powering down 2020-12-01T17:09:07.341Z,1606842547.341 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-12-01T17:09:07.342Z,1606842547.342 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-12-01T17:09:07.342Z,1606842547.342 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-12-01T17:09:07.343Z,1606842547.343 [MissionManager](INFO): Uninitializing Mission Default 2020-12-01T17:09:07.343Z,1606842547.343 [Default] Stopped 2020-12-01T17:09:07.343Z,1606842547.343 [Default](DEBUG): Aggregate::uninitialize Default 2020-12-01T17:09:07.343Z,1606842547.343 [Default:B.GoToSurface] Stopped 2020-12-01T17:09:07.343Z,1606842547.343 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-12-01T17:09:07.343Z,1606842547.343 [Default:CheckIn] Stopped 2020-12-01T17:09:07.343Z,1606842547.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-12-01T17:09:07.343Z,1606842547.343 [Default:CheckIn:C.Wait] Stopped 2020-12-01T17:09:07.343Z,1606842547.343 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-12-01T17:09:07.345Z,1606842547.345 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-12-01T17:09:07.346Z,1606842547.346 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-12-01T17:09:07.346Z,1606842547.346 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-12-01T17:09:07.346Z,1606842547.346 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-12-01T17:09:07.346Z,1606842547.346 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-12-01T17:09:07.347Z,1606842547.347 [BuoyancyServo](INFO): Powering down 2020-12-01T17:09:07.360Z,1606842547.360 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-12-01T17:09:07.360Z,1606842547.360 [ElevatorServo](INFO): Powering down 2020-12-01T17:09:07.361Z,1606842547.361 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-12-01T17:09:07.361Z,1606842547.361 [MassServo](INFO): Powering down 2020-12-01T17:09:07.362Z,1606842547.362 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-12-01T17:09:07.362Z,1606842547.362 [RudderServo](INFO): Powering down 2020-12-01T17:09:07.362Z,1606842547.362 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-12-01T17:09:07.363Z,1606842547.363 [ThrusterServo](INFO): Powering down 2020-12-01T17:09:07.363Z,1606842547.363 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-12-01T17:09:07.364Z,1606842547.364 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-12-01T17:09:07.364Z,1606842547.364 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-12-01T17:09:07.364Z,1606842547.364 [CBIT](DEBUG): Powering off loads. 2020-12-01T17:09:07.376Z,1606842547.376 [CBIT](DEBUG): Disabling WDT. 2020-12-01T17:09:07.388Z,1606842547.388 [CBIT](DEBUG): Opening all GF detection circuits. 2020-12-01T17:09:07.389Z,1606842547.389 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.478Z,1606842547.478 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.480Z,1606842547.480 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.485Z,1606842547.485 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.542Z,1606842547.542 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.543Z,1606842547.543 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open 2020-12-01T17:09:07.543Z,1606842547.543 [WetLabsUBAT](INFO): WetLabs destructor: done 2020-12-01T17:09:07.546Z,1606842547.546 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.550Z,1606842547.550 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.553Z,1606842547.553 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.562Z,1606842547.562 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-12-01T17:09:07.634Z,1606842547.634 [logger ThreadHandler](INFO): Thread cancelled.