2022-06-03T19:25:56.318Z,1654284356.318 [Supervisor](DEBUG): Initializing supervisor.
2022-06-03T19:25:56.323Z,1654284356.323 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-06-03T19:25:56.324Z,1654284356.324 [SyncHandler](INFO): Protected caller Thread ID is 6497
2022-06-03T19:25:56.324Z,1654284356.324 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-06-03T19:25:56.325Z,1654284356.325 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-06-03T19:25:56.325Z,1654284356.325 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6498
2022-06-03T19:25:56.330Z,1654284356.330 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-06-03T19:25:56.349Z,1654284356.349 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-06-03T19:25:56.350Z,1654284356.350 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-06-03T19:25:56.351Z,1654284356.351 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6499
2022-06-03T19:25:56.356Z,1654284356.356 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-06-03T19:25:56.357Z,1654284356.357 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-06-03T19:25:56.358Z,1654284356.358 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6500
2022-06-03T19:25:56.360Z,1654284356.360 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-06-03T19:25:56.361Z,1654284356.361 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-06-03T19:25:56.361Z,1654284356.361 [logger ThreadHandler](INFO): Protected caller Thread ID is 6501
2022-06-03T19:25:56.365Z,1654284356.365 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-06-03T19:25:56.365Z,1654284356.365 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-06-03T19:25:56.369Z,1654284356.369 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-06-03T19:25:56.685Z,1654284356.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-06-03T19:25:56.686Z,1654284356.686 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-06-03T19:25:56.767Z,1654284356.767 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-06-03T19:25:57.304Z,1654284357.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-06-03T19:25:57.305Z,1654284357.305 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-06-03T19:25:57.640Z,1654284357.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-06-03T19:25:57.640Z,1654284357.640 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-06-03T19:25:57.739Z,1654284357.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-06-03T19:25:57.740Z,1654284357.740 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-06-03T19:25:58.113Z,1654284358.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-06-03T19:25:58.114Z,1654284358.114 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-06-03T19:25:58.329Z,1654284358.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-06-03T19:25:58.330Z,1654284358.330 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-06-03T19:25:58.796Z,1654284358.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-06-03T19:25:58.797Z,1654284358.797 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-06-03T19:25:58.926Z,1654284358.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-06-03T19:25:58.927Z,1654284358.927 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-06-03T19:25:59.007Z,1654284359.007 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-06-03T19:25:59.709Z,1654284359.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-06-03T19:25:59.710Z,1654284359.710 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-06-03T19:26:00.084Z,1654284360.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-06-03T19:26:00.084Z,1654284360.084 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-06-03T19:26:00.328Z,1654284360.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-06-03T19:26:00.328Z,1654284360.328 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-06-03T19:26:00.974Z,1654284360.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-06-03T19:26:00.975Z,1654284360.975 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-06-03T19:26:01.200Z,1654284361.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-06-03T19:26:01.200Z,1654284361.200 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-06-03T19:26:01.432Z,1654284361.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-06-03T19:26:01.434Z,1654284361.434 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-06-03T19:26:01.436Z,1654284361.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-06-03T19:26:01.529Z,1654284361.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-06-03T19:26:01.615Z,1654284361.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-06-03T19:26:01.718Z,1654284361.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-06-03T19:26:01.803Z,1654284361.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-06-03T19:26:01.899Z,1654284361.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-06-03T19:26:02.003Z,1654284362.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-06-03T19:26:02.195Z,1654284362.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-06-03T19:26:02.270Z,1654284362.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-06-03T19:26:02.420Z,1654284362.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-06-03T19:26:02.558Z,1654284362.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-06-03T19:26:02.674Z,1654284362.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-06-03T19:26:02.939Z,1654284362.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-06-03T19:26:02.940Z,1654284362.940 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2022-06-03T19:26:02.941Z,1654284362.941 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-06-03T19:26:02.954Z,1654284362.954 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-06-03T19:26:03.027Z,1654284363.027 [VerticalControl](DEBUG): Construct VerticalControl.
2022-06-03T19:26:03.088Z,1654284363.088 [VerticalControl] Loaded
2022-06-03T19:26:03.088Z,1654284363.088 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-06-03T19:26:03.091Z,1654284363.091 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-06-03T19:26:03.130Z,1654284363.130 [HorizontalControl] Loaded
2022-06-03T19:26:03.130Z,1654284363.130 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-06-03T19:26:03.132Z,1654284363.132 [SpeedControl](DEBUG): Construct SpeedControl.
2022-06-03T19:26:03.135Z,1654284363.135 [SpeedControl] Loaded
2022-06-03T19:26:03.136Z,1654284363.136 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-06-03T19:26:03.138Z,1654284363.138 [LoopControl](DEBUG): Construct LoopControl.
2022-06-03T19:26:03.139Z,1654284363.139 [LoopControl] Loaded
2022-06-03T19:26:03.139Z,1654284363.139 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-06-03T19:26:03.139Z,1654284363.139 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-06-03T19:26:03.140Z,1654284363.140 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-06-03T19:26:03.157Z,1654284363.157 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-06-03T19:26:03.157Z,1654284363.157 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-06-03T19:26:03.303Z,1654284363.303 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-06-03T19:26:03.304Z,1654284363.304 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-06-03T19:26:03.457Z,1654284363.457 [BuoyancyServo] Loaded
2022-06-03T19:26:03.458Z,1654284363.458 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-06-03T19:26:03.477Z,1654284363.477 [ElevatorServo] Loaded
2022-06-03T19:26:03.478Z,1654284363.478 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-06-03T19:26:03.496Z,1654284363.496 [MassServo] Loaded
2022-06-03T19:26:03.497Z,1654284363.497 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-06-03T19:26:03.514Z,1654284363.514 [RudderServo] Loaded
2022-06-03T19:26:03.515Z,1654284363.515 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-06-03T19:26:03.532Z,1654284363.532 [ThrusterServo] Loaded
2022-06-03T19:26:03.533Z,1654284363.533 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-06-03T19:26:03.533Z,1654284363.533 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-06-03T19:26:03.534Z,1654284363.534 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-06-03T19:26:03.674Z,1654284363.674 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-06-03T19:26:03.674Z,1654284363.674 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-06-03T19:26:03.687Z,1654284363.687 [NavChart] Loaded
2022-06-03T19:26:03.688Z,1654284363.688 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-06-03T19:26:03.693Z,1654284363.693 [UniversalFixResidualReporter] Loaded
2022-06-03T19:26:03.693Z,1654284363.693 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-06-03T19:26:03.694Z,1654284363.694 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-06-03T19:26:03.695Z,1654284363.695 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-06-03T19:26:03.782Z,1654284363.782 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-06-03T19:26:03.782Z,1654284363.782 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-06-03T19:26:04.433Z,1654284364.433 [AHRS_M2] Loaded
2022-06-03T19:26:04.433Z,1654284364.433 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-06-03T19:26:05.249Z,1654284365.249 [BPC1] Loaded
2022-06-03T19:26:05.249Z,1654284365.249 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-06-03T19:26:05.541Z,1654284365.541 [DataOverHttps] Loaded
2022-06-03T19:26:05.541Z,1654284365.541 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-06-03T19:26:05.542Z,1654284365.542 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0
2022-06-03T19:26:05.543Z,1654284365.543 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6587
2022-06-03T19:26:05.563Z,1654284365.563 [Depth_Keller] Loaded
2022-06-03T19:26:05.563Z,1654284365.563 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-06-03T19:26:05.623Z,1654284365.623 [NAL9602] Loaded
2022-06-03T19:26:05.623Z,1654284365.623 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-06-03T19:26:05.655Z,1654284365.655 [Onboard] Loaded
2022-06-03T19:26:05.655Z,1654284365.655 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-06-03T19:26:05.656Z,1654284365.656 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409214E0
2022-06-03T19:26:05.657Z,1654284365.657 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6588
2022-06-03T19:26:05.670Z,1654284365.670 [Power24vConverter] Loaded
2022-06-03T19:26:05.670Z,1654284365.670 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-06-03T19:26:05.683Z,1654284365.683 [Radio_Surface] Loaded
2022-06-03T19:26:05.683Z,1654284365.683 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-06-03T19:26:05.684Z,1654284365.684 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409514E0
2022-06-03T19:26:05.685Z,1654284365.685 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6589
2022-06-03T19:26:05.686Z,1654284365.686 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-06-03T19:26:05.686Z,1654284365.686 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-06-03T19:26:05.906Z,1654284365.906 [DepthRateCalculator] Loaded
2022-06-03T19:26:05.906Z,1654284365.906 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-06-03T19:26:06.011Z,1654284366.011 [PitchRateCalculator] Loaded
2022-06-03T19:26:06.011Z,1654284366.011 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-06-03T19:26:06.056Z,1654284366.056 [SpeedCalculator] Loaded
2022-06-03T19:26:06.056Z,1654284366.056 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-06-03T19:26:06.060Z,1654284366.060 [YawRateCalculator] Loaded
2022-06-03T19:26:06.061Z,1654284366.061 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-06-03T19:26:06.101Z,1654284366.101 [ElevatorOffsetCalculator] Loaded
2022-06-03T19:26:06.102Z,1654284366.102 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-06-03T19:26:06.102Z,1654284366.102 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-06-03T19:26:06.103Z,1654284366.103 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-06-03T19:26:06.399Z,1654284366.399 [CANONSampler] Loaded
2022-06-03T19:26:06.399Z,1654284366.399 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-06-03T19:26:06.459Z,1654284366.459 [CTD_Seabird] Loaded
2022-06-03T19:26:06.459Z,1654284366.459 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-06-03T19:26:06.460Z,1654284366.460 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0
2022-06-03T19:26:06.461Z,1654284366.461 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6590
2022-06-03T19:26:06.482Z,1654284366.482 [PAR_Licor] Loaded
2022-06-03T19:26:06.482Z,1654284366.482 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-06-03T19:26:06.514Z,1654284366.514 [WetLabsBB2FL] Loaded
2022-06-03T19:26:06.514Z,1654284366.514 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-06-03T19:26:06.515Z,1654284366.515 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A394E0
2022-06-03T19:26:06.515Z,1654284366.515 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6591
2022-06-03T19:26:06.516Z,1654284366.516 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-06-03T19:26:06.517Z,1654284366.517 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-06-03T19:26:06.559Z,1654284366.559 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-06-03T19:26:06.560Z,1654284366.560 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-06-03T19:26:06.931Z,1654284366.931 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-06-03T19:26:06.933Z,1654284366.933 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-06-03T19:26:07.105Z,1654284367.105 [SBIT](DEBUG): Construct Startup Built In Test.
2022-06-03T19:26:07.114Z,1654284367.114 [SBIT] Loaded
2022-06-03T19:26:07.114Z,1654284367.114 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-06-03T19:26:07.117Z,1654284367.117 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-06-03T19:26:07.130Z,1654284367.130 [IBIT] Loaded
2022-06-03T19:26:07.131Z,1654284367.131 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-06-03T19:26:07.136Z,1654284367.136 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-06-03T19:26:07.243Z,1654284367.243 [CBIT] Loaded
2022-06-03T19:26:07.243Z,1654284367.243 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-06-03T19:26:07.243Z,1654284367.243 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-06-03T19:26:07.244Z,1654284367.244 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-06-03T19:26:07.363Z,1654284367.363 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-06-03T19:26:07.369Z,1654284367.369 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-06-03T19:26:07.372Z,1654284367.372 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-06-03T19:26:07.383Z,1654284367.383 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-06-03T19:26:07.384Z,1654284367.384 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B754E0
2022-06-03T19:26:07.385Z,1654284367.385 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6592
2022-06-03T19:26:07.389Z,1654284367.389 [Supervisor](INFO): Main Thread ID is 6496
2022-06-03T19:26:07.389Z,1654284367.389 [Supervisor](DEBUG): Running supervisor.
2022-06-03T19:26:07.390Z,1654284367.390 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6593
2022-06-03T19:26:07.390Z,1654284367.390 [CommandExec](INFO): Initializing the command executive.
2022-06-03T19:26:07.392Z,1654284367.392 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6594
2022-06-03T19:26:07.394Z,1654284367.394 [controlThread ThreadHandler](INFO): Handler Thread ID is 6595
2022-06-03T19:26:07.395Z,1654284367.395 [controlThread](DEBUG): Initializing ControlThread
2022-06-03T19:26:07.396Z,1654284367.396 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-06-03T19:26:07.397Z,1654284367.397 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-06-03T19:26:07.398Z,1654284367.398 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-06-03T19:26:07.398Z,1654284367.398 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-06-03T19:26:07.400Z,1654284367.400 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-06-03T19:26:07.401Z,1654284367.401 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-06-03T19:26:07.406Z,1654284367.406 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-06-03T19:26:07.406Z,1654284367.406 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-06-03T19:26:07.407Z,1654284367.407 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-06-03T19:26:07.407Z,1654284367.407 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-06-03T19:26:07.407Z,1654284367.407 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-06-03T19:26:07.410Z,1654284367.410 [SBIT](INFO): Initialize SBIT Component.
2022-06-03T19:26:07.410Z,1654284367.410 [SBIT](IMPORTANT): git: 2022-04-11
2022-06-03T19:26:07.410Z,1654284367.410 [SBIT](INFO): git hash: 059cb0c4bb1d2c42f72e645cf8ce57fde1aeb3d9
2022-06-03T19:26:07.411Z,1654284367.411 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-06-03T19:26:07.412Z,1654284367.412 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-06-03T19:26:07.413Z,1654284367.413 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2022-06-03T19:26:07.413Z,1654284367.413 [IBIT](INFO): Initialize IBIT Component.
2022-06-03T19:26:07.414Z,1654284367.414 [CBIT](DEBUG): Initialize CBIT Component.
2022-06-03T19:26:07.415Z,1654284367.415 [logger ThreadHandler](INFO): Handler Thread ID is 6596
2022-06-03T19:26:07.425Z,1654284367.425 [CBIT](DEBUG): Initialized mux pins.
2022-06-03T19:26:07.425Z,1654284367.425 [CBIT](DEBUG): Initializing the watchdog timer.
2022-06-03T19:26:07.434Z,1654284367.434 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6597
2022-06-03T19:26:07.435Z,1654284367.435 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-06-03T19:26:07.449Z,1654284367.449 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-06-03T19:26:07.450Z,1654284367.450 [CBIT](DEBUG): Initializing heartbeat.
2022-06-03T19:26:07.453Z,1654284367.453 [Onboard ThreadHandler](INFO): Handler Thread ID is 6598
2022-06-03T19:26:07.482Z,1654284367.482 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6599
2022-06-03T19:26:07.486Z,1654284367.486 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6600
2022-06-03T19:26:07.487Z,1654284367.487 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-06-03T19:26:07.491Z,1654284367.491 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6602
2022-06-03T19:26:07.494Z,1654284367.494 [WetLabsBB2FL](INFO): Powering up
2022-06-03T19:26:07.496Z,1654284367.496 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6604
2022-06-03T19:26:07.506Z,1654284367.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-06-03T19:26:07.506Z,1654284367.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-06-03T19:26:07.506Z,1654284367.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-06-03T19:26:07.506Z,1654284367.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-06-03T19:26:07.506Z,1654284367.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-06-03T19:26:07.506Z,1654284367.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-06-03T19:26:07.507Z,1654284367.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-06-03T19:26:07.507Z,1654284367.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-06-03T19:26:07.507Z,1654284367.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-06-03T19:26:07.507Z,1654284367.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-06-03T19:26:07.507Z,1654284367.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-06-03T19:26:07.507Z,1654284367.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-06-03T19:26:07.508Z,1654284367.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-06-03T19:26:07.508Z,1654284367.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-06-03T19:26:07.508Z,1654284367.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-06-03T19:26:07.508Z,1654284367.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-06-03T19:26:07.521Z,1654284367.521 [CBIT](DEBUG): Deactivating GF circuits.
2022-06-03T19:26:07.522Z,1654284367.522 [CBIT](DEBUG): Deactivating emergency mode.
2022-06-03T19:26:07.557Z,1654284367.557 [CBIT](DEBUG): Backplane powered.
2022-06-03T19:26:07.558Z,1654284367.558 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-06-03T19:26:07.589Z,1654284367.589 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-06-03T19:26:07.605Z,1654284367.605 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2022-06-03T19:26:07.634Z,1654284367.634 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-06-03T19:26:07.693Z,1654284367.693 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-06-03T19:26:07.706Z,1654284367.706 [Default:A.Wait](DEBUG): Construct Wait.
2022-06-03T19:26:07.708Z,1654284367.708 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-06-03T19:26:07.743Z,1654284367.743 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-06-03T19:26:07.754Z,1654284367.754 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-06-03T19:26:07.771Z,1654284367.771 [Default:E.Execute](DEBUG): Construct Execute.
2022-06-03T19:26:07.792Z,1654284367.792 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2022-06-03T19:26:07.810Z,1654284367.810 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-06-03T19:26:07.821Z,1654284367.821 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-06-03T19:26:07.885Z,1654284367.885 [Radio_Surface](INFO): Powering up
2022-06-03T19:26:07.888Z,1654284367.888 [Power24vConverter](INFO): Powering up.
2022-06-03T19:26:07.889Z,1654284367.889 [CANONSampler](INFO): Powering down
2022-06-03T19:26:07.939Z,1654284367.939 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-06-03T19:26:07.950Z,1654284367.950 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-06-03T19:26:07.951Z,1654284367.951 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-06-03T19:26:07.958Z,1654284367.958 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-06-03T19:26:07.959Z,1654284367.959 [MassServo](DEBUG): Initializing EZServoServo.
2022-06-03T19:26:07.966Z,1654284367.966 [MassServo](DEBUG): Initializing MassServo.
2022-06-03T19:26:07.966Z,1654284367.966 [RudderServo](DEBUG): Initializing EZServoServo.
2022-06-03T19:26:07.978Z,1654284367.978 [RudderServo](DEBUG): Initializing RudderServo.
2022-06-03T19:26:07.978Z,1654284367.978 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-06-03T19:26:07.986Z,1654284367.986 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-06-03T19:26:08.966Z,1654284368.966 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-06-03T19:26:08.966Z,1654284368.966 [RudderServo](FAULT): Rudder failed to initialize
2022-06-03T19:26:08.966Z,1654284368.966 [RudderServo] Communications Fault, FailCount= 1
2022-06-03T19:26:08.966Z,1654284368.966 [RudderServo](ERROR): Communications Fault
2022-06-03T19:26:09.065Z,1654284369.065 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-06-03T19:26:09.238Z,1654284369.238 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-03T19:26:09.238Z,1654284369.238 [RudderServo](INFO): Powering down
2022-06-03T19:26:09.601Z,1654284369.601 [WetLabsBB2FL](INFO): Powering down
2022-06-03T19:26:09.927Z,1654284369.927 [RudderServo](DEBUG): Initializing EZServoServo.
2022-06-03T19:26:10.046Z,1654284370.046 [RudderServo](DEBUG): Initializing RudderServo.
2022-06-03T19:26:10.050Z,1654284370.050 [CBIT](INFO): Clearing failed state for component RudderServo
2022-06-03T19:26:10.050Z,1654284370.050 [RudderServo] No Fault, FailCount= 1
2022-06-03T19:26:11.413Z,1654284371.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-06-03T19:26:11.841Z,1654284371.841 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-06-03T19:26:12.313Z,1654284372.313 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2022-06-03T19:26:21.384Z,1654284381.384 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004746
2022-06-03T19:26:36.019Z,1654284396.019 [NAL9602](INFO): Powering up NAL9602
2022-06-03T19:26:46.933Z,1654284406.933 [NAL9602](INFO): NAL9602 initialized
2022-06-03T19:27:41.001Z,1654284461.001 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-06-03T19:27:41.002Z,1654284461.002 [CommandExec](IMPORTANT): Onboard.Humidity 9.858381 %
2022-06-03T19:27:45.891Z,1654284465.891 [CommandExec](IMPORTANT): got command get
2022-06-03T19:27:45.891Z,1654284465.891 [CommandExec](FAULT): Incomplete syntax. Try: help get
2022-06-03T19:27:52.357Z,1654284472.357 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2022-06-03T19:27:52.358Z,1654284472.358 [CommandExec](IMPORTANT): Onboard.Pressure 69888.023438 Pa
2022-06-03T19:28:47.913Z,1654284527.913 [SBIT](IMPORTANT): Beginning Startup BIT
2022-06-03T19:28:47.918Z,1654284527.918 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-03T19:28:58.883Z,1654284538.883 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-03T19:28:59.279Z,1654284539.279 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-03T19:29:07.722Z,1654284547.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-06-03T19:29:07.722Z,1654284547.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:29:07.842Z,1654284547.842 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:29:08.236Z,1654284548.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:29:08.236Z,1654284548.236 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-06-03T19:29:10.222Z,1654284550.222 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-03T19:29:10.714Z,1654284550.714 [CBIT](IMPORTANT): Beginning ground fault scan
2022-06-03T19:29:21.510Z,1654284561.510 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-06-03T19:29:21.510Z,1654284561.510 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-06-03T19:29:42.052Z,1654284582.052 [SBIT](IMPORTANT): SBIT PASSED
2022-06-03T19:29:42.053Z,1654284582.053 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-06-03T19:29:42.053Z,1654284582.053 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=3 count;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=100 cubic_centimeter;

2022-06-03T19:29:42.054Z,1654284582.054 [SBIT](IMPORTANT): VerticalControl.massDefault=-35 millimeter;

2022-06-03T19:29:42.455Z,1654284582.455 [MissionManager](IMPORTANT): Started mission Startup
2022-06-03T19:29:42.455Z,1654284582.455 [Startup] Running Loop=1
2022-06-03T19:29:42.455Z,1654284582.455 [Startup](DEBUG): Aggregate::initialize Startup
2022-06-03T19:29:42.455Z,1654284582.455 [Startup:A.GoToSurface] Running Loop=1
2022-06-03T19:29:42.456Z,1654284582.456 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-03T19:29:42.456Z,1654284582.456 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-03T19:29:42.457Z,1654284582.457 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-03T19:29:42.457Z,1654284582.457 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-03T19:29:42.458Z,1654284582.458 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-03T19:29:42.458Z,1654284582.458 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-03T19:29:42.459Z,1654284582.459 [Startup:StartupSatComms] Running Loop=1
2022-06-03T19:29:42.459Z,1654284582.459 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-06-03T19:29:42.459Z,1654284582.459 [Startup:StartupSatComms:A] Running Loop=1
2022-06-03T19:29:42.875Z,1654284582.875 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-06-03T19:30:42.807Z,1654284642.807 [Startup:StartupSatComms:A](INFO): Timed out from 2022-06-03T19:29:42.5Z
2022-06-03T19:30:42.807Z,1654284642.807 [Startup:StartupSatComms:A] Stopped
2022-06-03T19:30:42.807Z,1654284642.807 [Startup:StartupSatComms:B] Running Loop=1
2022-06-03T19:30:43.176Z,1654284643.176 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-06-03T19:30:47.694Z,1654284647.694 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220519T144934/Courier0192.lzma
2022-06-03T19:30:48.692Z,1654284648.692 [DataOverHttps](INFO): Moved sent file to Logs/20220519T144934/Courier0192.lzma.bak
2022-06-03T19:30:48.692Z,1654284648.692 [DataOverHttps](INFO): SBD MOMSN=16853088
2022-06-03T19:31:04.146Z,1654284664.146 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220524T161330/Courier0000.lzma
2022-06-03T19:31:05.147Z,1654284665.147 [DataOverHttps](INFO): Moved sent file to Logs/20220524T161330/Courier0000.lzma.bak
2022-06-03T19:31:05.147Z,1654284665.147 [DataOverHttps](INFO): SBD MOMSN=16853090
2022-06-03T19:31:05.429Z,1654284665.429 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:31:20.377Z,1654284680.377 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:31:20.889Z,1654284680.889 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220603T192556/Courier0000.lzma
2022-06-03T19:31:21.891Z,1654284681.891 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0000.lzma.bak
2022-06-03T19:31:21.891Z,1654284681.891 [DataOverHttps](INFO): SBD MOMSN=16853093
2022-06-03T19:31:35.327Z,1654284695.327 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:31:37.311Z,1654284697.311 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20220519T144934/Express0193.lzma
2022-06-03T19:31:38.312Z,1654284698.312 [DataOverHttps](INFO): Moved sent file to Logs/20220519T144934/Express0193.lzma.bak
2022-06-03T19:31:38.312Z,1654284698.312 [DataOverHttps](INFO): SBD MOMSN=16853096
2022-06-03T19:31:43.054Z,1654284703.054 [Startup:StartupSatComms:B](INFO): Timed out from 2022-06-03T19:30:42.8Z
2022-06-03T19:31:43.055Z,1654284703.055 [Startup:StartupSatComms:B] Stopped
2022-06-03T19:31:43.055Z,1654284703.055 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-06-03T19:31:43.055Z,1654284703.055 [Startup:StartupSatComms] Stopped
2022-06-03T19:31:43.055Z,1654284703.055 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-06-03T19:31:43.056Z,1654284703.056 [Startup](INFO): Completed Startup
2022-06-03T19:31:43.056Z,1654284703.056 [MissionManager](INFO): Startup is completed.
2022-06-03T19:31:43.056Z,1654284703.056 [MissionManager](INFO): Uninitializing Mission Startup
2022-06-03T19:31:43.056Z,1654284703.056 [Startup] Stopped
2022-06-03T19:31:43.056Z,1654284703.056 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-06-03T19:31:43.056Z,1654284703.056 [Startup:A.GoToSurface] Stopped
2022-06-03T19:31:43.056Z,1654284703.056 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-03T19:31:43.434Z,1654284703.434 [MissionManager](IMPORTANT): Started mission Default
2022-06-03T19:31:43.435Z,1654284703.435 [Default] Running Loop=1
2022-06-03T19:31:43.435Z,1654284703.435 [Default](DEBUG): Aggregate::initialize Default
2022-06-03T19:31:43.435Z,1654284703.435 [Default:B.GoToSurface] Running Loop=1
2022-06-03T19:31:43.435Z,1654284703.435 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-03T19:31:43.435Z,1654284703.435 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-03T19:31:43.435Z,1654284703.435 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-03T19:31:43.436Z,1654284703.436 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-03T19:31:43.436Z,1654284703.436 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-03T19:31:43.436Z,1654284703.436 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-03T19:31:43.437Z,1654284703.437 [Default:A.Wait] Running Loop=1
2022-06-03T19:31:43.437Z,1654284703.437 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-06-03T19:31:50.359Z,1654284710.359 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-03T19:31:50.678Z,1654284710.678 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:31:54.175Z,1654284714.175 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20220524T161330/Express0001.lzma
2022-06-03T19:31:55.175Z,1654284715.175 [DataOverHttps](INFO): Moved sent file to Logs/20220524T161330/Express0001.lzma.bak
2022-06-03T19:31:55.175Z,1654284715.175 [DataOverHttps](INFO): SBD MOMSN=16853099
2022-06-03T19:31:56.755Z,1654284716.755 [Default:A.Wait](INFO): Done Waiting.
2022-06-03T19:31:56.755Z,1654284716.755 [Default:A.Wait] Stopped
2022-06-03T19:31:56.755Z,1654284716.755 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T19:31:57.585Z,1654284717.585 [Default:CheckIn] Running Loop=1
2022-06-03T19:31:57.585Z,1654284717.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T19:31:57.585Z,1654284717.585 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T19:31:57.704Z,1654284717.704 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-06-03T19:32:05.686Z,1654284725.686 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:32:08.527Z,1654284728.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-06-03T19:32:08.527Z,1654284728.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:32:08.537Z,1654284728.537 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:32:08.948Z,1654284728.948 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:32:08.948Z,1654284728.948 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-06-03T19:32:21.034Z,1654284741.034 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:32:35.981Z,1654284755.981 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:32:51.334Z,1654284771.334 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:33:06.281Z,1654284786.281 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T19:33:20.869Z,1654284800.869 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-06-03T19:33:31.336Z,1654284811.336 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2022-06-03T19:33:31.339Z,1654284811.339 [BPC1](INFO): Received data from all battery sticks.
2022-06-03T19:35:09.528Z,1654284909.528 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-06-03T19:35:09.528Z,1654284909.528 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:35:09.538Z,1654284909.538 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:35:09.935Z,1654284909.935 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:35:09.935Z,1654284909.935 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-06-03T19:36:08.895Z,1654284968.895 [Power24vConverter](INFO): Powering down.
2022-06-03T19:36:49.771Z,1654285009.771 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-03T19:36:49.771Z,1654285009.771 [NAL9602] Data Fault, FailCount= 1
2022-06-03T19:36:49.771Z,1654285009.771 [NAL9602](ERROR): Data Fault
2022-06-03T19:36:49.788Z,1654285009.788 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-03T19:36:50.171Z,1654285010.171 [NAL9602](INFO): Powering down
2022-06-03T19:36:51.011Z,1654285011.011 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-03T19:36:51.011Z,1654285011.011 [NAL9602] No Fault, FailCount= 1
2022-06-03T19:36:57.854Z,1654285017.854 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T19:31:57.6Z
2022-06-03T19:36:57.854Z,1654285017.854 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T19:36:57.854Z,1654285017.854 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T19:36:58.268Z,1654285018.268 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-06-03T19:37:05.958Z,1654285025.958 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220603T192556/Courier0004.lzma
2022-06-03T19:37:06.959Z,1654285026.959 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0004.lzma.bak
2022-06-03T19:37:06.959Z,1654285026.959 [DataOverHttps](INFO): SBD MOMSN=16853115
2022-06-03T19:37:20.471Z,1654285040.471 [NAL9602](INFO): Powering up NAL9602
2022-06-03T19:37:22.543Z,1654285042.543 [DataOverHttps](INFO): Sending 866 bytes from file Logs/20220603T192556/Express0001.lzma
2022-06-03T19:37:23.543Z,1654285043.543 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0001.lzma.bak
2022-06-03T19:37:23.543Z,1654285043.543 [DataOverHttps](INFO): SBD MOMSN=16853119
2022-06-03T19:37:31.379Z,1654285051.379 [NAL9602](INFO): NAL9602 initialized
2022-06-03T19:37:38.749Z,1654285058.749 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20220603T192556/Express0005.lzma
2022-06-03T19:37:39.751Z,1654285059.751 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0005.lzma.bak
2022-06-03T19:37:39.752Z,1654285059.752 [DataOverHttps](INFO): SBD MOMSN=16853147
2022-06-03T19:37:41.100Z,1654285061.100 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T19:37:41.100Z,1654285061.100 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T19:37:41.100Z,1654285061.100 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T19:38:10.599Z,1654285090.599 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-06-03T19:38:10.599Z,1654285090.599 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:38:10.613Z,1654285090.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:38:11.007Z,1654285091.007 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:38:11.007Z,1654285091.007 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-06-03T19:41:11.642Z,1654285271.642 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-06-03T19:41:11.642Z,1654285271.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:41:11.652Z,1654285271.652 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:41:12.063Z,1654285272.063 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:41:12.063Z,1654285272.063 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-06-03T19:42:41.774Z,1654285361.774 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T19:42:41.774Z,1654285361.774 [Default:CheckIn:C.Wait] Stopped
2022-06-03T19:42:41.774Z,1654285361.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T19:42:41.774Z,1654285361.774 [Default:CheckIn:D] Running Loop=1
2022-06-03T19:42:42.166Z,1654285362.166 [Default:CheckIn:D] Stopped
2022-06-03T19:42:42.166Z,1654285362.166 [Default:CheckIn:E] Running Loop=1
2022-06-03T19:42:42.588Z,1654285362.588 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.978859 min 
2022-06-03T19:42:42.588Z,1654285362.588 [Default:CheckIn:E] Stopped
2022-06-03T19:42:42.588Z,1654285362.588 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T19:42:42.588Z,1654285362.588 [Default:CheckIn] Stopped
2022-06-03T19:42:42.588Z,1654285362.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T19:42:42.589Z,1654285362.589 [Default:CheckIn](INFO): Running loop #2
2022-06-03T19:42:42.589Z,1654285362.589 [Default:CheckIn] Running Loop=2
2022-06-03T19:42:42.589Z,1654285362.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T19:42:42.589Z,1654285362.589 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T19:44:12.666Z,1654285452.666 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-06-03T19:44:12.666Z,1654285452.666 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:44:12.676Z,1654285452.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:44:13.087Z,1654285453.087 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:44:13.087Z,1654285453.087 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-06-03T19:47:13.810Z,1654285633.810 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-06-03T19:47:13.810Z,1654285633.810 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:47:13.820Z,1654285633.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:47:14.229Z,1654285634.229 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:47:14.229Z,1654285634.229 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-06-03T19:47:33.571Z,1654285653.571 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-03T19:47:33.571Z,1654285653.571 [NAL9602] Data Fault, FailCount= 2
2022-06-03T19:47:33.571Z,1654285653.571 [NAL9602](ERROR): Data Fault
2022-06-03T19:47:33.592Z,1654285653.592 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-03T19:47:33.987Z,1654285653.987 [NAL9602](INFO): Powering down
2022-06-03T19:47:34.801Z,1654285654.801 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-03T19:47:34.801Z,1654285654.801 [NAL9602] No Fault, FailCount= 2
2022-06-03T19:47:42.872Z,1654285662.872 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T19:42:42.6Z
2022-06-03T19:47:42.872Z,1654285662.872 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T19:47:42.872Z,1654285662.872 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T19:47:50.295Z,1654285670.295 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220603T192556/Courier0007.lzma
2022-06-03T19:47:51.297Z,1654285671.297 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0007.lzma.bak
2022-06-03T19:47:51.297Z,1654285671.297 [DataOverHttps](INFO): SBD MOMSN=16853177
2022-06-03T19:48:04.281Z,1654285684.281 [NAL9602](INFO): Powering up NAL9602
2022-06-03T19:48:06.583Z,1654285686.583 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220603T192556/Express0008.lzma
2022-06-03T19:48:07.585Z,1654285687.585 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0008.lzma.bak
2022-06-03T19:48:07.585Z,1654285687.585 [DataOverHttps](INFO): SBD MOMSN=16853179
2022-06-03T19:48:08.747Z,1654285688.747 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T19:48:08.747Z,1654285688.747 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T19:48:08.748Z,1654285688.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T19:48:15.197Z,1654285695.197 [NAL9602](INFO): NAL9602 initialized
2022-06-03T19:50:14.795Z,1654285814.795 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-06-03T19:50:14.795Z,1654285814.795 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:50:14.805Z,1654285814.805 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:50:15.205Z,1654285815.205 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:50:15.205Z,1654285815.205 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-06-03T19:53:09.349Z,1654285989.349 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T19:53:09.349Z,1654285989.349 [Default:CheckIn:C.Wait] Stopped
2022-06-03T19:53:09.350Z,1654285989.350 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T19:53:09.350Z,1654285989.350 [Default:CheckIn:D] Running Loop=1
2022-06-03T19:53:09.748Z,1654285989.748 [Default:CheckIn:D] Stopped
2022-06-03T19:53:09.748Z,1654285989.748 [Default:CheckIn:E] Running Loop=1
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.438554 min 
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn:E] Stopped
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn] Stopped
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn](INFO): Running loop #3
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn] Running Loop=3
2022-06-03T19:53:10.138Z,1654285990.138 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T19:53:10.139Z,1654285990.139 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T19:53:15.804Z,1654285995.804 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-06-03T19:53:15.804Z,1654285995.804 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:53:15.815Z,1654285995.815 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:53:16.225Z,1654285996.225 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:53:16.225Z,1654285996.225 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-06-03T19:56:16.782Z,1654286176.782 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-06-03T19:56:16.782Z,1654286176.782 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:56:16.797Z,1654286176.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:56:17.199Z,1654286177.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:56:17.199Z,1654286177.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-06-03T19:58:10.317Z,1654286290.317 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T19:53:10.1Z
2022-06-03T19:58:10.317Z,1654286290.317 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T19:58:10.318Z,1654286290.318 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T19:58:16.355Z,1654286296.355 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-03T19:58:16.356Z,1654286296.356 [NAL9602] Data Fault, FailCount= 3
2022-06-03T19:58:16.356Z,1654286296.356 [NAL9602](ERROR): Data Fault
2022-06-03T19:58:16.391Z,1654286296.391 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-03T19:58:16.759Z,1654286296.759 [NAL9602](INFO): Powering down
2022-06-03T19:58:17.342Z,1654286297.342 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220603T192556/Courier0010.lzma
2022-06-03T19:58:17.579Z,1654286297.579 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-03T19:58:17.579Z,1654286297.579 [NAL9602] No Fault, FailCount= 3
2022-06-03T19:58:18.344Z,1654286298.344 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0010.lzma.bak
2022-06-03T19:58:18.344Z,1654286298.344 [DataOverHttps](INFO): SBD MOMSN=16853196
2022-06-03T19:58:38.682Z,1654286318.682 [DataOverHttps](INFO): Sending 108 bytes from file Logs/20220603T192556/Express0011.lzma
2022-06-03T19:58:39.684Z,1654286319.684 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0011.lzma.bak
2022-06-03T19:58:39.684Z,1654286319.684 [DataOverHttps](INFO): SBD MOMSN=16853198
2022-06-03T19:58:41.135Z,1654286321.135 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T19:58:41.135Z,1654286321.135 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T19:58:41.135Z,1654286321.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T19:58:46.786Z,1654286326.786 [NAL9602](INFO): Powering up NAL9602
2022-06-03T19:58:57.687Z,1654286337.687 [NAL9602](INFO): NAL9602 initialized
2022-06-03T19:59:17.907Z,1654286357.907 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-06-03T19:59:17.908Z,1654286357.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T19:59:17.918Z,1654286357.918 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T19:59:18.324Z,1654286358.324 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T19:59:18.324Z,1654286358.324 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-06-03T20:02:18.911Z,1654286538.911 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-06-03T20:02:18.911Z,1654286538.911 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-06-03T20:02:18.921Z,1654286538.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-06-03T20:02:19.333Z,1654286539.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-06-03T20:02:19.333Z,1654286539.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-06-03T20:03:41.723Z,1654286621.723 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T20:03:41.723Z,1654286621.723 [Default:CheckIn:C.Wait] Stopped
2022-06-03T20:03:41.724Z,1654286621.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T20:03:41.724Z,1654286621.724 [Default:CheckIn:D] Running Loop=1
2022-06-03T20:03:42.136Z,1654286622.136 [Default:CheckIn:D] Stopped
2022-06-03T20:03:42.136Z,1654286622.136 [Default:CheckIn:E] Running Loop=1
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.978361 min 
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn:E] Stopped
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn] Stopped
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn](INFO): Running loop #4
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn] Running Loop=4
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T20:03:42.555Z,1654286622.555 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T20:04:34.959Z,1654286674.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200433.00,A,3648.16319,N,12147.28039,W,0.350,0.00,030622,,,A*7D
2022-06-03T20:04:34.972Z,1654286674.972 [NAL9602](INFO): GPS fix at 20220603T200433: (36.802720, -121.788006)
2022-06-03T20:04:34.983Z,1654286674.983 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T20:04:34.983Z,1654286674.983 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T20:04:43.820Z,1654286683.820 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20220603T192556/Courier0013.lzma
2022-06-03T20:04:44.823Z,1654286684.823 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0013.lzma.bak
2022-06-03T20:04:44.823Z,1654286684.823 [DataOverHttps](INFO): SBD MOMSN=16853208
2022-06-03T20:05:01.580Z,1654286701.580 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20220603T192556/Express0014.lzma
2022-06-03T20:05:02.582Z,1654286702.582 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0014.lzma.bak
2022-06-03T20:05:02.582Z,1654286702.582 [DataOverHttps](INFO): SBD MOMSN=16853211
2022-06-03T20:05:04.069Z,1654286704.069 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T20:05:04.069Z,1654286704.069 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T20:05:04.070Z,1654286704.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T20:05:07.289Z,1654286707.289 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-03T20:05:41.538Z,1654286741.538 [DataOverHttps](IMPORTANT): SBD MTMSN=20220603T200540
2022-06-03T20:05:48.960Z,1654286748.960 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2022-06-03T20:05:49.055Z,1654286749.055 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-06-03T20:05:49.055Z,1654286749.055 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-06-03T20:05:49.121Z,1654286749.121 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2022-06-03T20:05:49.140Z,1654286749.140 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2022-06-03T20:05:49.143Z,1654286749.143 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2022-06-03T20:05:49.146Z,1654286749.146 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2022-06-03T20:05:49.148Z,1654286749.148 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2022-06-03T20:05:49.151Z,1654286749.151 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2022-06-03T20:05:49.154Z,1654286749.154 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2022-06-03T20:05:49.157Z,1654286749.157 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2022-06-03T20:05:49.160Z,1654286749.160 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2022-06-03T20:05:49.163Z,1654286749.163 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2022-06-03T20:05:49.166Z,1654286749.166 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2022-06-03T20:05:49.169Z,1654286749.169 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2022-06-03T20:05:49.171Z,1654286749.171 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2022-06-03T20:05:49.174Z,1654286749.174 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2022-06-03T20:05:49.175Z,1654286749.175 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2022-06-03T20:05:49.571Z,1654286749.571 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2022-06-03T20:05:49.574Z,1654286749.574 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2022-06-03T20:05:49.593Z,1654286749.593 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2022-06-03T20:05:49.601Z,1654286749.601 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2022-06-03T20:05:49.604Z,1654286749.604 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2022-06-03T20:05:49.610Z,1654286749.610 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2022-06-03T20:05:49.615Z,1654286749.615 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2022-06-03T20:05:49.626Z,1654286749.626 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2022-06-03T20:05:49.640Z,1654286749.640 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2022-06-03T20:05:49.643Z,1654286749.643 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2022-06-03T20:05:49.657Z,1654286749.657 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2022-06-03T20:05:49.660Z,1654286749.660 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2022-06-03T20:05:49.663Z,1654286749.663 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2022-06-03T20:05:49.676Z,1654286749.676 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2022-06-03T20:05:49.678Z,1654286749.678 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-06-03T20:05:49.682Z,1654286749.682 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2022-06-03T20:05:49.733Z,1654286749.733 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2022-06-03T20:05:49.736Z,1654286749.736 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2022-06-03T20:05:49.753Z,1654286749.753 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2022-06-03T20:05:49.757Z,1654286749.757 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2022-06-03T20:05:49.760Z,1654286749.760 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2022-06-03T20:05:49.763Z,1654286749.763 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2022-06-03T20:05:49.781Z,1654286749.781 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2022-06-03T20:05:49.784Z,1654286749.784 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2022-06-03T20:05:49.786Z,1654286749.786 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2022-06-03T20:05:49.809Z,1654286749.809 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-06-03T20:05:49.823Z,1654286749.823 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2022-06-03T20:05:49.830Z,1654286749.830 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2022-06-03T20:05:49.833Z,1654286749.833 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2022-06-03T20:05:49.856Z,1654286749.856 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2022-06-03T20:05:49.859Z,1654286749.859 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2022-06-03T20:05:49.862Z,1654286749.862 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2022-06-03T20:05:49.884Z,1654286749.884 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2022-06-03T20:05:49.888Z,1654286749.888 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2022-06-03T20:05:49.891Z,1654286749.891 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2022-06-03T20:05:49.894Z,1654286749.894 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2022-06-03T20:05:49.897Z,1654286749.897 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2022-06-03T20:05:49.912Z,1654286749.912 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2022-06-03T20:05:49.915Z,1654286749.915 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2022-06-03T20:05:49.918Z,1654286749.918 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2022-06-03T20:05:49.921Z,1654286749.921 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2022-06-03T20:05:49.936Z,1654286749.936 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2022-06-03T20:05:49.939Z,1654286749.939 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2022-06-03T20:05:49.942Z,1654286749.942 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2022-06-03T20:05:49.946Z,1654286749.946 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2022-06-03T20:05:49.957Z,1654286749.957 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2022-06-03T20:05:49.966Z,1654286749.966 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2022-06-03T20:05:49.969Z,1654286749.969 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2022-06-03T20:05:49.972Z,1654286749.972 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2022-06-03T20:05:49.975Z,1654286749.975 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2022-06-03T20:05:49.988Z,1654286749.988 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2022-06-03T20:05:49.991Z,1654286749.991 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2022-06-03T20:05:49.994Z,1654286749.994 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2022-06-03T20:05:50.014Z,1654286750.014 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2022-06-03T20:05:50.017Z,1654286750.017 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2022-06-03T20:05:50.021Z,1654286750.021 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2022-06-03T20:05:50.024Z,1654286750.024 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2022-06-03T20:05:50.039Z,1654286750.039 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2022-06-03T20:05:50.042Z,1654286750.042 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2022-06-03T20:05:50.046Z,1654286750.046 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2022-06-03T20:05:50.062Z,1654286750.062 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2022-06-03T20:05:50.065Z,1654286750.065 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2022-06-03T20:05:50.069Z,1654286750.069 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2022-06-03T20:05:50.116Z,1654286750.116 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2022-06-03T20:05:50.119Z,1654286750.119 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2022-06-03T20:05:50.211Z,1654286750.211 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-06-03T20:05:50.236Z,1654286750.236 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-06-03T20:05:50.270Z,1654286750.270 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-06-03T20:05:50.299Z,1654286750.299 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-06-03T20:05:50.320Z,1654286750.320 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-06-03T20:05:50.364Z,1654286750.364 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-06-03T20:05:50.375Z,1654286750.375 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-06-03T20:05:50.420Z,1654286750.420 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-06-03T20:05:50.456Z,1654286750.456 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2022-06-03T20:05:50.681Z,1654286750.681 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2022-06-03T20:05:50.688Z,1654286750.688 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2022-06-03T20:05:50.691Z,1654286750.691 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2022-06-03T20:05:50.702Z,1654286750.702 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2022-06-03T20:05:50.705Z,1654286750.705 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2022-06-03T20:05:50.713Z,1654286750.713 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2022-06-03T20:05:50.720Z,1654286750.720 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2022-06-03T20:05:50.726Z,1654286750.726 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2022-06-03T20:05:50.730Z,1654286750.730 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2022-06-03T20:05:50.733Z,1654286750.733 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2022-06-03T20:05:50.748Z,1654286750.748 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2022-06-03T20:05:50.751Z,1654286750.751 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2022-06-03T20:05:50.758Z,1654286750.758 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2022-06-03T20:05:50.773Z,1654286750.773 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2022-06-03T20:05:50.811Z,1654286750.811 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2022-06-03T20:05:50.823Z,1654286750.823 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2022-06-03T20:05:50.835Z,1654286750.835 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2022-06-03T20:05:50.853Z,1654286750.853 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2022-06-03T20:05:50.861Z,1654286750.861 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2022-06-03T20:05:50.868Z,1654286750.868 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2022-06-03T20:05:50.884Z,1654286750.884 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2022-06-03T20:05:50.888Z,1654286750.888 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2022-06-03T20:05:50.891Z,1654286750.891 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2022-06-03T20:05:50.958Z,1654286750.958 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2022-06-03T20:05:50.972Z,1654286750.972 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2022-06-03T20:05:51.016Z,1654286751.016 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2022-06-03T20:05:51.018Z,1654286751.018 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2022-06-03T20:05:51.019Z,1654286751.019 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2022-06-03T20:05:51.021Z,1654286751.021 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2022-06-03T20:05:51.023Z,1654286751.023 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2022-06-03T20:05:51.048Z,1654286751.048 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2022-06-03T20:05:51.050Z,1654286751.050 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2022-06-03T20:05:51.053Z,1654286751.053 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2022-06-03T20:05:51.076Z,1654286751.076 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2022-06-03T20:05:51.080Z,1654286751.080 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2022-06-03T20:05:51.136Z,1654286751.136 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="ballast_and_trim">

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed durnig the
        mission.
    </Description><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="SurfaceThreshold"><Description>
        Surface threshold depth.
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>20</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="WaitToFloat"><Units:minute/><Value>5</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml"/>

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Insert Filename="Insert/BallastAndTrim.xml">
            <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
            <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
            <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
            <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
            <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
        </Insert>

        <Aggregate Id="Depth1">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth1"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

        <Aggregate Id="Depth2">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth2"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="Float_Up">

        <Description>
            Float to surface instead of driving.
        </Description>

        <Sequence/>

        <Break>
            <Universal:depth/>
            <Lt><Control:VerticalControl.surfaceThreshold/></Lt>
        </Break>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyDefault/></Setting>
        </Guidance:Buoyancy>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="WaitToFloat"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2022-06-03T20:05:51.140Z,1654286751.140 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-06-03T20:05:51.325Z,1654286751.325 [Default] Stopped
2022-06-03T20:05:51.325Z,1654286751.325 [Default](DEBUG): Aggregate::uninitialize Default
2022-06-03T20:05:51.326Z,1654286751.326 [Default:B.GoToSurface] Stopped
2022-06-03T20:05:51.326Z,1654286751.326 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-03T20:05:51.326Z,1654286751.326 [Default:CheckIn] Stopped
2022-06-03T20:05:51.326Z,1654286751.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T20:05:51.326Z,1654286751.326 [Default:CheckIn:C.Wait] Stopped
2022-06-03T20:05:51.326Z,1654286751.326 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T20:05:51.326Z,1654286751.326 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2022-06-03T20:05:51.326Z,1654286751.326 [ballast_and_trim] Running Loop=1
2022-06-03T20:05:51.326Z,1654286751.326 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:A] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:B] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:C] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:D] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:E] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:F] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2022-06-03T20:05:51.327Z,1654286751.327 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-06-03T20:05:51.328Z,1654286751.328 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-06-03T20:05:51.330Z,1654286751.330 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2022-06-03T20:05:51.330Z,1654286751.330 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2022-06-03T20:05:51.330Z,1654286751.330 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-06-03T20:05:51.330Z,1654286751.330 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-06-03T20:05:51.330Z,1654286751.330 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2022-06-03T20:05:51.331Z,1654286751.331 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2022-06-03T20:05:51.331Z,1654286751.331 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-06-03T20:05:51.331Z,1654286751.331 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-06-03T20:05:51.333Z,1654286751.333 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2022-06-03T20:05:51.333Z,1654286751.333 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2022-06-03T20:05:51.333Z,1654286751.333 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-06-03T20:05:51.333Z,1654286751.333 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-06-03T20:05:51.335Z,1654286751.335 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2022-06-03T20:05:51.335Z,1654286751.335 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2022-06-03T20:05:51.335Z,1654286751.335 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-06-03T20:05:51.335Z,1654286751.335 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-06-03T20:05:51.336Z,1654286751.336 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2022-06-03T20:05:51.336Z,1654286751.336 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2022-06-03T20:05:51.336Z,1654286751.336 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-06-03T20:05:51.336Z,1654286751.336 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-06-03T20:05:51.338Z,1654286751.338 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2022-06-03T20:05:51.338Z,1654286751.338 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2022-06-03T20:05:51.338Z,1654286751.338 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-06-03T20:05:51.338Z,1654286751.338 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-06-03T20:05:51.340Z,1654286751.340 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2022-06-03T20:05:51.346Z,1654286751.346 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2022-06-03T20:05:51.346Z,1654286751.346 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-06-03T20:05:51.346Z,1654286751.346 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-06-03T20:05:51.347Z,1654286751.347 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2022-06-03T20:05:51.347Z,1654286751.347 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2022-06-03T20:05:51.347Z,1654286751.347 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-06-03T20:05:51.347Z,1654286751.347 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-06-03T20:05:51.347Z,1654286751.347 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2022-06-03T20:05:51.347Z,1654286751.347 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2022-06-03T20:05:51.348Z,1654286751.348 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2022-06-03T20:05:51.348Z,1654286751.348 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-06-03T20:05:51.348Z,1654286751.348 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2022-06-03T20:05:51.349Z,1654286751.349 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2022-06-03T20:05:51.349Z,1654286751.349 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2022-06-03T20:05:51.349Z,1654286751.349 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-06-03T20:05:51.349Z,1654286751.349 [ballast_and_trim:Science] Running Loop=1
2022-06-03T20:05:51.350Z,1654286751.350 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2022-06-03T20:05:51.350Z,1654286751.350 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2022-06-03T20:05:51.350Z,1654286751.350 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-06-03T20:05:51.350Z,1654286751.350 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-06-03T20:05:51.350Z,1654286751.350 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-06-03T20:05:51.351Z,1654286751.351 [ballast_and_trim:Science:PeakDetectOil] Stopped
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-06-03T20:05:51.352Z,1654286751.352 [ballast_and_trim:Science:PeakDetectChl] Stopped
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:Read_Oil] Stopped
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:F] Stopped
2022-06-03T20:05:51.353Z,1654286751.353 [ballast_and_trim:Science:E] Running Loop=1
2022-06-03T20:05:51.354Z,1654286751.354 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-06-03T20:05:51.354Z,1654286751.354 [ballast_and_trim:Science:D] Stopped
2022-06-03T20:05:51.354Z,1654286751.354 [ballast_and_trim:Science:C] Running Loop=1
2022-06-03T20:05:51.355Z,1654286751.355 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-06-03T20:05:51.355Z,1654286751.355 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-06-03T20:05:51.355Z,1654286751.355 [ballast_and_trim:Science:B] Running Loop=1
2022-06-03T20:05:51.375Z,1654286751.375 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-06-03T20:05:51.376Z,1654286751.376 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-06-03T20:05:51.376Z,1654286751.376 [ballast_and_trim:Science:A] Stopped
2022-06-03T20:05:51.403Z,1654286751.403 [WetLabsBB2FL](INFO): Powering up
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-06-03T20:05:51.749Z,1654286751.749 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-06-03T20:05:51.750Z,1654286751.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2022-06-03T20:05:51.751Z,1654286751.751 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2022-06-03T20:05:51.751Z,1654286751.751 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2022-06-03T20:05:51.756Z,1654286751.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2022-06-03T20:05:51.757Z,1654286751.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-06-03T20:05:51.758Z,1654286751.758 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-06-03T20:05:51.758Z,1654286751.758 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-06-03T20:05:51.758Z,1654286751.758 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-06-03T20:05:51.758Z,1654286751.758 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2022-06-03T20:05:52.150Z,1654286752.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-06-03T20:05:52.549Z,1654286752.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2022-06-03T20:05:52.549Z,1654286752.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2022-06-03T20:05:52.549Z,1654286752.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2022-06-03T20:05:52.550Z,1654286752.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2022-06-03T20:05:52.944Z,1654286752.944 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-06-03T20:05:52.945Z,1654286752.945 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2022-06-03T20:05:52.945Z,1654286752.945 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2022-06-03T20:05:52.945Z,1654286752.945 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2022-06-03T20:05:52.945Z,1654286752.945 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2022-06-03T20:05:54.413Z,1654286754.413 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-06-03T20:05:55.419Z,1654286755.419 [CTD_Seabird](ERROR): Salinity reading out of range: 24.225292 psu
2022-06-03T20:05:56.422Z,1654286756.422 [CTD_Seabird](ERROR): Salinity reading out of range: 26.650402 psu
2022-06-03T20:07:44.712Z,1654286864.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:08:15.840Z,1654286895.840 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:08:46.964Z,1654286926.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:09:18.092Z,1654286958.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:09:24.117Z,1654286964.117 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.775009
2022-06-03T20:09:57.156Z,1654286997.156 [Radio_Surface](INFO): Powering down
2022-06-03T20:10:19.336Z,1654287019.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:10:20.340Z,1654287020.340 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-06-03T20:12:56.936Z,1654287176.936 [Radio_Surface](INFO): Powering up
2022-06-03T20:13:03.072Z,1654287183.072 [DataOverHttps](INFO): Radio surface powered ON.
2022-06-03T20:13:33.188Z,1654287213.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:14:04.312Z,1654287244.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:14:35.436Z,1654287275.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:15:06.568Z,1654287306.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:15:37.700Z,1654287337.700 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:16:08.825Z,1654287368.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:16:38.452Z,1654287398.452 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003256
2022-06-03T20:18:42.784Z,1654287522.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:19:09.468Z,1654287549.468 [Radio_Surface](INFO): Powering down
2022-06-03T20:19:13.920Z,1654287553.920 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:19:14.924Z,1654287554.924 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-06-03T20:19:53.369Z,1654287593.369 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2022-06-03T20:19:53.369Z,1654287593.369 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-06-03T20:19:53.369Z,1654287593.369 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2022-06-03T20:19:53.370Z,1654287593.370 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2022-06-03T20:19:53.371Z,1654287593.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-06-03T20:19:53.371Z,1654287593.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-06-03T20:19:53.371Z,1654287593.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2022-06-03T20:19:53.371Z,1654287593.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-06-03T20:19:53.371Z,1654287593.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2022-06-03T20:19:53.371Z,1654287593.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2022-06-03T20:19:53.763Z,1654287593.763 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.040721 m 
2022-06-03T20:19:53.763Z,1654287593.763 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2022-06-03T20:19:53.764Z,1654287593.764 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2022-06-03T20:19:53.764Z,1654287593.764 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2022-06-03T20:19:53.764Z,1654287593.764 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-06-03T20:19:53.764Z,1654287593.764 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-06-03T20:21:02.362Z,1654287662.362 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.740524
2022-06-03T20:29:54.541Z,1654288194.541 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2022-06-03T20:29:54.542Z,1654288194.542 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2022-06-03T20:29:54.542Z,1654288194.542 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T20:29:54.542Z,1654288194.542 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2022-06-03T20:29:54.941Z,1654288194.941 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 7.051120 m 
2022-06-03T20:29:54.941Z,1654288194.941 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2022-06-03T20:29:54.942Z,1654288194.942 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2022-06-03T20:29:54.942Z,1654288194.942 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2022-06-03T20:38:27.610Z,1654288707.610 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:38:40.137Z,1654288720.137 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:38:52.658Z,1654288732.658 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:39:05.181Z,1654288745.181 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:39:18.518Z,1654288758.518 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:39:31.045Z,1654288771.045 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:39:43.977Z,1654288783.977 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:39:56.097Z,1654288796.097 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:40:09.021Z,1654288809.021 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:40:21.949Z,1654288821.949 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:40:34.473Z,1654288834.473 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-06-03T20:40:46.997Z,1654288846.997 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-06-03T20:40:54.289Z,1654288854.289 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2022-06-03T20:40:54.291Z,1654288854.291 [BPC1](INFO): Received data from all battery sticks.
2022-06-03T20:41:07.237Z,1654288867.237 [CTD_Seabird](ERROR): Failed to parse device response: 3,4.36077,4649.71
2022-06-03T20:51:03.556Z,1654289463.556 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 21.14 minutes.
2022-06-03T20:51:03.557Z,1654289463.557 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 347.211455 +/- 14.998254 cc (conf. level 99.90%, sigma: 25.838653 cc).
2022-06-03T20:51:03.559Z,1654289463.559 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: -13.148004 +/- 0.195463 mm (conf. level 99.90%, sigma: 0.336740 mm).
2022-06-03T20:51:03.560Z,1654289463.560 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 347.211455 cc buoyancy neutral and -13.148004 mm mass default
2022-06-03T20:51:03.568Z,1654289463.568 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-06-03T20:51:03.569Z,1654289463.569 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-06-03T20:51:03.569Z,1654289463.569 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryMissingStickThreshold=3 count;

2022-06-03T20:51:03.569Z,1654289463.569 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=347.211455 cubic_centimeter;

2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=-13.148004 millimeter;

2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2022-06-03T20:51:03.570Z,1654289463.570 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2022-06-03T20:51:03.572Z,1654289463.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-06-03T20:51:03.572Z,1654289463.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2022-06-03T20:51:03.572Z,1654289463.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-06-03T20:51:03.572Z,1654289463.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2022-06-03T20:51:03.572Z,1654289463.572 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2022-06-03T20:51:03.573Z,1654289463.573 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2022-06-03T20:51:03.574Z,1654289463.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2022-06-03T20:51:03.923Z,1654289463.923 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2022-06-03T20:51:03.924Z,1654289463.924 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2022-06-03T20:51:04.330Z,1654289464.330 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:RunBallastAndTrim] Stopped
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:Float_Up] Running Loop=1
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2022-06-03T20:51:04.331Z,1654289464.331 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-06-03T20:51:04.332Z,1654289464.332 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2022-06-03T20:51:04.332Z,1654289464.332 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2022-06-03T20:51:04.757Z,1654289464.757 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2022-06-03T20:52:46.148Z,1654289566.148 [ballast_and_trim:Float_Up] Stopped
2022-06-03T20:52:46.148Z,1654289566.148 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2022-06-03T20:52:46.148Z,1654289566.148 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2022-06-03T20:52:46.148Z,1654289566.148 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-06-03T20:52:46.148Z,1654289566.148 [ballast_and_trim:Float_Up:B.Wait] Stopped
2022-06-03T20:52:46.148Z,1654289566.148 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim](INFO): Completed ballast_and_trim
2022-06-03T20:52:46.165Z,1654289566.165 [MissionManager](INFO): ballast_and_trim is completed.
2022-06-03T20:52:46.165Z,1654289566.165 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim] Stopped
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim:Science] Stopped
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim:Science:B] Stopped
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim:Science:C] Stopped
2022-06-03T20:52:46.165Z,1654289566.165 [ballast_and_trim:Science:E] Stopped
2022-06-03T20:52:46.166Z,1654289566.166 [ballast_and_trim:Science:OceanCurrent] Stopped
2022-06-03T20:52:46.166Z,1654289566.166 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2022-06-03T20:52:46.166Z,1654289566.166 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2022-06-03T20:52:46.424Z,1654289566.424 [WetLabsBB2FL](INFO): Powering down
2022-06-03T20:52:46.552Z,1654289566.552 [MissionManager](IMPORTANT): Started mission Default
2022-06-03T20:52:46.552Z,1654289566.552 [Default] Running Loop=1
2022-06-03T20:52:46.552Z,1654289566.552 [Default](DEBUG): Aggregate::initialize Default
2022-06-03T20:52:46.553Z,1654289566.553 [Default:B.GoToSurface] Running Loop=1
2022-06-03T20:52:46.553Z,1654289566.553 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-06-03T20:52:46.553Z,1654289566.553 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-06-03T20:52:46.553Z,1654289566.553 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-06-03T20:52:46.553Z,1654289566.553 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-06-03T20:52:46.554Z,1654289566.554 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-06-03T20:52:46.554Z,1654289566.554 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-06-03T20:52:46.555Z,1654289566.555 [Default:A.Wait] Running Loop=1
2022-06-03T20:52:46.555Z,1654289566.555 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-06-03T20:52:46.716Z,1654289566.716 [Radio_Surface](INFO): Powering up
2022-06-03T20:52:52.284Z,1654289572.284 [DataOverHttps](INFO): Radio surface powered ON.
2022-06-03T20:52:59.886Z,1654289579.886 [Default:A.Wait](INFO): Done Waiting.
2022-06-03T20:52:59.887Z,1654289579.887 [Default:A.Wait] Stopped
2022-06-03T20:52:59.887Z,1654289579.887 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T20:53:00.296Z,1654289580.296 [Default:CheckIn] Running Loop=1
2022-06-03T20:53:00.296Z,1654289580.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T20:53:00.296Z,1654289580.296 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T20:53:22.404Z,1654289602.404 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:53:53.528Z,1654289633.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:54:24.664Z,1654289664.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:54:55.788Z,1654289695.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:55:26.936Z,1654289726.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:55:58.077Z,1654289758.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-03T20:56:40.352Z,1654289800.352 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003166
2022-06-03T20:58:00.589Z,1654289880.589 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T20:53:00.3Z
2022-06-03T20:58:00.589Z,1654289880.589 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T20:58:00.589Z,1654289880.589 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T20:58:05.001Z,1654289885.001 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-03T20:58:07.681Z,1654289887.681 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20220603T192556/Courier0016.lzma
2022-06-03T20:58:08.682Z,1654289888.682 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0016.lzma.bak
2022-06-03T20:58:08.682Z,1654289888.682 [DataOverHttps](INFO): SBD MOMSN=16853295
2022-06-03T20:58:30.122Z,1654289910.122 [DataOverHttps](INFO): Sending 1219 bytes from file Logs/20220603T192556/Express0017.lzma
2022-06-03T20:58:31.122Z,1654289911.122 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0017.lzma.bak
2022-06-03T20:58:31.122Z,1654289911.122 [DataOverHttps](INFO): SBD MOMSN=16853297
2022-06-03T20:58:32.588Z,1654289912.588 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T20:58:32.588Z,1654289912.588 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T20:58:32.588Z,1654289912.588 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T20:58:35.777Z,1654289915.777 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-03T21:03:33.186Z,1654290213.186 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T21:03:33.186Z,1654290213.186 [Default:CheckIn:C.Wait] Stopped
2022-06-03T21:03:33.186Z,1654290213.186 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T21:03:33.186Z,1654290213.186 [Default:CheckIn:D] Running Loop=1
2022-06-03T21:03:33.585Z,1654290213.585 [Default:CheckIn:D] Stopped
2022-06-03T21:03:33.585Z,1654290213.585 [Default:CheckIn:E] Running Loop=1
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.783876 min 
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn:E] Stopped
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn] Stopped
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn](INFO): Running loop #2
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn] Running Loop=2
2022-06-03T21:03:34.001Z,1654290214.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T21:03:34.002Z,1654290214.002 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T21:03:35.609Z,1654290215.609 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-03T21:03:35.609Z,1654290215.609 [NAL9602] Data Fault, FailCount= 4
2022-06-03T21:03:35.609Z,1654290215.609 [NAL9602](ERROR): Data Fault
2022-06-03T21:03:35.628Z,1654290215.628 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-03T21:03:36.001Z,1654290216.001 [NAL9602](INFO): Powering down
2022-06-03T21:03:36.830Z,1654290216.830 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-03T21:03:36.830Z,1654290216.830 [NAL9602] No Fault, FailCount= 4
2022-06-03T21:04:06.301Z,1654290246.301 [NAL9602](INFO): Powering up NAL9602
2022-06-03T21:04:17.207Z,1654290257.207 [NAL9602](INFO): NAL9602 initialized
2022-06-03T21:07:40.022Z,1654290460.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-03T21:07:40.023Z,1654290460.023 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-03T21:08:34.219Z,1654290514.219 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T21:03:34.0Z
2022-06-03T21:08:34.220Z,1654290514.220 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T21:08:34.236Z,1654290514.236 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T21:08:41.411Z,1654290521.411 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220603T192556/Courier0022.lzma
2022-06-03T21:08:42.414Z,1654290522.414 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0022.lzma.bak
2022-06-03T21:08:42.414Z,1654290522.414 [DataOverHttps](INFO): SBD MOMSN=16853334
2022-06-03T21:08:58.344Z,1654290538.344 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20220603T192556/Express0023.lzma
2022-06-03T21:08:59.346Z,1654290539.346 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0023.lzma.bak
2022-06-03T21:08:59.346Z,1654290539.346 [DataOverHttps](INFO): SBD MOMSN=16853336
2022-06-03T21:09:00.903Z,1654290540.903 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T21:09:00.903Z,1654290540.903 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T21:09:00.903Z,1654290540.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T21:09:20.669Z,1654290560.669 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-03T21:09:51.373Z,1654290591.373 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-03T21:14:01.480Z,1654290841.480 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T21:14:01.480Z,1654290841.480 [Default:CheckIn:C.Wait] Stopped
2022-06-03T21:14:01.481Z,1654290841.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T21:14:01.481Z,1654290841.481 [Default:CheckIn:D] Running Loop=1
2022-06-03T21:14:01.892Z,1654290841.892 [Default:CheckIn:D] Stopped
2022-06-03T21:14:01.892Z,1654290841.892 [Default:CheckIn:E] Running Loop=1
2022-06-03T21:14:02.313Z,1654290842.313 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.255656 min 
2022-06-03T21:14:02.313Z,1654290842.313 [Default:CheckIn:E] Stopped
2022-06-03T21:14:02.313Z,1654290842.313 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T21:14:02.313Z,1654290842.313 [Default:CheckIn] Stopped
2022-06-03T21:14:02.314Z,1654290842.314 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T21:14:02.314Z,1654290842.314 [Default:CheckIn](INFO): Running loop #3
2022-06-03T21:14:02.314Z,1654290842.314 [Default:CheckIn] Running Loop=3
2022-06-03T21:14:02.314Z,1654290842.314 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T21:14:02.314Z,1654290842.314 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T21:16:14.394Z,1654290974.394 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-03T21:16:14.394Z,1654290974.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-03T21:18:17.237Z,1654291097.237 [NAL9602](INFO): SBD MO Status=2, MOMSN=33059, MT Status=2, MTMSN=0
2022-06-03T21:18:17.237Z,1654291097.237 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-03T21:19:02.526Z,1654291142.526 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T21:14:02.3Z
2022-06-03T21:19:02.526Z,1654291142.526 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T21:19:02.527Z,1654291142.527 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T21:19:06.121Z,1654291146.121 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-03T21:19:09.491Z,1654291149.491 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220603T192556/Courier0025.lzma
2022-06-03T21:19:10.494Z,1654291150.494 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0025.lzma.bak
2022-06-03T21:19:10.494Z,1654291150.494 [DataOverHttps](INFO): SBD MOMSN=16853354
2022-06-03T21:19:26.332Z,1654291166.332 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220603T192556/Express0026.lzma
2022-06-03T21:19:27.334Z,1654291167.334 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0026.lzma.bak
2022-06-03T21:19:27.334Z,1654291167.334 [DataOverHttps](INFO): SBD MOMSN=16853360
2022-06-03T21:19:28.757Z,1654291168.757 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T21:19:28.757Z,1654291168.757 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T21:19:28.757Z,1654291168.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T21:19:36.849Z,1654291176.849 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-03T21:24:29.388Z,1654291469.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T21:24:29.388Z,1654291469.388 [Default:CheckIn:C.Wait] Stopped
2022-06-03T21:24:29.389Z,1654291469.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T21:24:29.389Z,1654291469.389 [Default:CheckIn:D] Running Loop=1
2022-06-03T21:24:29.804Z,1654291469.804 [Default:CheckIn:D] Stopped
2022-06-03T21:24:29.805Z,1654291469.805 [Default:CheckIn:E] Running Loop=1
2022-06-03T21:24:30.199Z,1654291470.199 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.720866 min 
2022-06-03T21:24:30.199Z,1654291470.199 [Default:CheckIn:E] Stopped
2022-06-03T21:24:30.199Z,1654291470.199 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T21:24:30.199Z,1654291470.199 [Default:CheckIn] Stopped
2022-06-03T21:24:30.200Z,1654291470.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T21:24:30.225Z,1654291470.225 [Default:CheckIn](INFO): Running loop #4
2022-06-03T21:24:30.225Z,1654291470.225 [Default:CheckIn] Running Loop=4
2022-06-03T21:24:30.225Z,1654291470.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T21:24:30.225Z,1654291470.225 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T21:24:31.809Z,1654291471.809 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-06-03T21:24:31.809Z,1654291471.809 [NAL9602] Data Fault, FailCount= 5
2022-06-03T21:24:31.809Z,1654291471.809 [NAL9602](ERROR): Data Fault
2022-06-03T21:24:31.849Z,1654291471.849 [CBIT](ERROR): Data Fault in component: NAL9602
2022-06-03T21:24:31.849Z,1654291471.849 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-06-03T21:24:32.213Z,1654291472.213 [NAL9602](INFO): Powering down
2022-06-03T21:24:32.230Z,1654291472.230 [CommandExec](FAULT): Scheduling is paused
2022-06-03T21:24:32.231Z,1654291472.231 [CBIT](INFO): Critical error at 20220603T212431
2022-06-03T21:24:32.231Z,1654291472.231 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-06-03T21:26:31.816Z,1654291591.816 [CBIT](INFO): Clearing failed state for component NAL9602
2022-06-03T21:26:31.816Z,1654291591.816 [NAL9602] No Fault, FailCount= 5
2022-06-03T21:26:32.205Z,1654291592.205 [NAL9602](INFO): Powering up NAL9602
2022-06-03T21:26:43.113Z,1654291603.113 [NAL9602](INFO): NAL9602 initialized
2022-06-03T21:29:30.506Z,1654291770.506 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T21:24:30.2Z
2022-06-03T21:29:30.506Z,1654291770.506 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T21:29:30.506Z,1654291770.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T21:29:36.883Z,1654291776.883 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20220603T192556/Courier0028.lzma
2022-06-03T21:29:37.886Z,1654291777.886 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0028.lzma.bak
2022-06-03T21:29:37.886Z,1654291777.886 [DataOverHttps](INFO): SBD MOMSN=16853375
2022-06-03T21:29:53.836Z,1654291793.836 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20220603T192556/Express0029.lzma
2022-06-03T21:29:54.838Z,1654291794.838 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0029.lzma.bak
2022-06-03T21:29:54.838Z,1654291794.838 [DataOverHttps](INFO): SBD MOMSN=16853380
2022-06-03T21:29:56.269Z,1654291796.269 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T21:29:56.269Z,1654291796.269 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T21:29:56.270Z,1654291796.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T21:31:46.521Z,1654291906.521 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-03T21:32:17.229Z,1654291937.229 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-03T21:34:56.814Z,1654292096.814 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-03T21:34:56.814Z,1654292096.814 [Default:CheckIn:C.Wait] Stopped
2022-06-03T21:34:56.814Z,1654292096.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T21:34:56.814Z,1654292096.814 [Default:CheckIn:D] Running Loop=1
2022-06-03T21:34:57.223Z,1654292097.223 [Default:CheckIn:D] Stopped
2022-06-03T21:34:57.223Z,1654292097.223 [Default:CheckIn:E] Running Loop=1
2022-06-03T21:34:57.632Z,1654292097.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.177832 min 
2022-06-03T21:34:57.632Z,1654292097.632 [Default:CheckIn:E] Stopped
2022-06-03T21:34:57.632Z,1654292097.632 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-03T21:34:57.632Z,1654292097.632 [Default:CheckIn] Stopped
2022-06-03T21:34:57.632Z,1654292097.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T21:34:57.633Z,1654292097.633 [Default:CheckIn](INFO): Running loop #5
2022-06-03T21:34:57.633Z,1654292097.633 [Default:CheckIn] Running Loop=5
2022-06-03T21:34:57.633Z,1654292097.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-03T21:34:57.633Z,1654292097.633 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-03T21:39:57.790Z,1654292397.790 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-06-03T21:34:57.6Z
2022-06-03T21:39:57.790Z,1654292397.790 [Default:CheckIn:Read_GPS] Stopped
2022-06-03T21:39:57.790Z,1654292397.790 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-03T21:40:01.417Z,1654292401.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-03T21:40:05.947Z,1654292405.947 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220603T192556/Courier0031.lzma
2022-06-03T21:40:06.942Z,1654292406.942 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Courier0031.lzma.bak
2022-06-03T21:40:06.942Z,1654292406.942 [DataOverHttps](INFO): SBD MOMSN=16853502
2022-06-03T21:40:25.600Z,1654292425.600 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220603T192556/Express0032.lzma
2022-06-03T21:40:26.602Z,1654292426.602 [DataOverHttps](INFO): Moved sent file to Logs/20220603T192556/Express0032.lzma.bak
2022-06-03T21:40:26.602Z,1654292426.602 [DataOverHttps](INFO): SBD MOMSN=16853504
2022-06-03T21:40:28.142Z,1654292428.142 [Default:CheckIn:Read_Iridium] Stopped
2022-06-03T21:40:28.143Z,1654292428.143 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-03T21:40:28.143Z,1654292428.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-03T21:40:32.173Z,1654292432.173 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-03T21:42:03.701Z,1654292523.701 [CommandExec](IMPORTANT): got command quit
2022-06-03T21:42:04.704Z,1654292524.704 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:04.704Z,1654292524.704 [CommandExec](INFO): Uninitializing the command executive.
2022-06-03T21:42:04.704Z,1654292524.704 [CommandExec](INFO): Uninitializing the command scheduler.
2022-06-03T21:42:04.705Z,1654292524.705 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:04.812Z,1654292524.812 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-06-03T21:42:04.812Z,1654292524.812 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-06-03T21:42:04.812Z,1654292524.812 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:04.813Z,1654292524.813 [NavChartDb](INFO): Join timeout helper Thread ID is 7245
2022-06-03T21:42:04.984Z,1654292524.984 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:04.984Z,1654292524.984 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:05.004Z,1654292525.004 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-06-03T21:42:05.004Z,1654292525.004 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:05.005Z,1654292525.005 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7246
2022-06-03T21:42:05.148Z,1654292525.148 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:05.148Z,1654292525.148 [WetLabsBB2FL](INFO): Powering down
2022-06-03T21:42:05.149Z,1654292525.149 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:05.156Z,1654292525.156 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-06-03T21:42:05.156Z,1654292525.156 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:05.156Z,1654292525.156 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7247
2022-06-03T21:42:05.856Z,1654292525.856 [CTD_Seabird](INFO): Powering down
2022-06-03T21:42:05.868Z,1654292525.868 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:05.868Z,1654292525.868 [CTD_Seabird](INFO): Powering down
2022-06-03T21:42:05.880Z,1654292525.880 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:05.880Z,1654292525.880 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-06-03T21:42:05.880Z,1654292525.880 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:05.881Z,1654292525.881 [Radio_Surface](INFO): Join timeout helper Thread ID is 7248
2022-06-03T21:42:06.126Z,1654292526.126 [Radio_Surface](INFO): Powering down
2022-06-03T21:42:06.127Z,1654292526.127 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:06.129Z,1654292526.129 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.133Z,1654292526.133 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-06-03T21:42:06.133Z,1654292526.133 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.133Z,1654292526.133 [Onboard](INFO): Join timeout helper Thread ID is 7249
2022-06-03T21:42:06.136Z,1654292526.136 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:06.136Z,1654292526.136 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.153Z,1654292526.153 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-06-03T21:42:06.153Z,1654292526.153 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.154Z,1654292526.154 [DataOverHttps](INFO): Join timeout helper Thread ID is 7250
2022-06-03T21:42:06.920Z,1654292526.920 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:06.923Z,1654292526.923 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.949Z,1654292526.949 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-06-03T21:42:06.949Z,1654292526.949 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.949Z,1654292526.949 [logger](INFO): Join timeout helper Thread ID is 7251
2022-06-03T21:42:06.968Z,1654292526.968 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:06.968Z,1654292526.968 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.981Z,1654292526.981 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-06-03T21:42:06.981Z,1654292526.981 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:06.981Z,1654292526.981 [CommandLine](INFO): Join timeout helper Thread ID is 7252
2022-06-03T21:42:07.056Z,1654292527.056 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:07.056Z,1654292527.056 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.061Z,1654292527.061 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-06-03T21:42:07.061Z,1654292527.061 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.061Z,1654292527.061 [CommandExec](INFO): Join timeout helper Thread ID is 7253
2022-06-03T21:42:07.062Z,1654292527.062 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-06-03T21:42:07.062Z,1654292527.062 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.063Z,1654292527.063 [controlThread](INFO): Join timeout helper Thread ID is 7254
2022-06-03T21:42:07.096Z,1654292527.096 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-03T21:42:07.096Z,1654292527.096 [controlThread](DEBUG): Uninitializing ControlThread
2022-06-03T21:42:07.097Z,1654292527.097 [AHRS_M2](INFO): Powering down
2022-06-03T21:42:07.169Z,1654292527.169 [NAL9602](INFO): Powering down
2022-06-03T21:42:07.171Z,1654292527.171 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-06-03T21:42:07.172Z,1654292527.172 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-06-03T21:42:07.173Z,1654292527.173 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-06-03T21:42:07.173Z,1654292527.173 [MissionManager](INFO): Uninitializing Mission Default
2022-06-03T21:42:07.173Z,1654292527.173 [Default] Stopped
2022-06-03T21:42:07.173Z,1654292527.173 [Default](DEBUG): Aggregate::uninitialize Default
2022-06-03T21:42:07.173Z,1654292527.173 [Default:B.GoToSurface] Stopped
2022-06-03T21:42:07.174Z,1654292527.174 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-03T21:42:07.174Z,1654292527.174 [Default:CheckIn] Stopped
2022-06-03T21:42:07.174Z,1654292527.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-03T21:42:07.174Z,1654292527.174 [Default:CheckIn:C.Wait] Stopped
2022-06-03T21:42:07.174Z,1654292527.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-03T21:42:07.177Z,1654292527.177 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-06-03T21:42:07.177Z,1654292527.177 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-06-03T21:42:07.177Z,1654292527.177 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-06-03T21:42:07.178Z,1654292527.178 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-06-03T21:42:07.178Z,1654292527.178 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-06-03T21:42:07.178Z,1654292527.178 [BuoyancyServo](INFO): Powering down
2022-06-03T21:42:07.192Z,1654292527.192 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-06-03T21:42:07.192Z,1654292527.192 [ElevatorServo](INFO): Powering down
2022-06-03T21:42:07.193Z,1654292527.193 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-06-03T21:42:07.193Z,1654292527.193 [MassServo](INFO): Powering down
2022-06-03T21:42:07.194Z,1654292527.194 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-03T21:42:07.194Z,1654292527.194 [RudderServo](INFO): Powering down
2022-06-03T21:42:07.195Z,1654292527.195 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-06-03T21:42:07.195Z,1654292527.195 [ThrusterServo](INFO): Powering down
2022-06-03T21:42:07.196Z,1654292527.196 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-06-03T21:42:07.196Z,1654292527.196 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-06-03T21:42:07.196Z,1654292527.196 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-06-03T21:42:07.197Z,1654292527.197 [CBIT](DEBUG): Powering off loads.
2022-06-03T21:42:07.208Z,1654292527.208 [CBIT](DEBUG): Disabling WDT.
2022-06-03T21:42:07.220Z,1654292527.220 [CBIT](DEBUG): Opening all GF detection circuits.
2022-06-03T21:42:07.221Z,1654292527.221 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.276Z,1654292527.276 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.279Z,1654292527.279 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.285Z,1654292527.285 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.354Z,1654292527.354 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.358Z,1654292527.358 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.397Z,1654292527.397 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-03T21:42:07.471Z,1654292527.471 [logger ThreadHandler](INFO): Thread cancelled.