2022-08-05T19:16:07.987Z,1659726967.987 [Supervisor](DEBUG): Initializing supervisor.
2022-08-05T19:16:07.991Z,1659726967.991 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-08-05T19:16:07.992Z,1659726967.992 [SyncHandler](INFO): Protected caller Thread ID is 835
2022-08-05T19:16:07.992Z,1659726967.992 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-08-05T19:16:07.993Z,1659726967.993 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-08-05T19:16:07.994Z,1659726967.994 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2022-08-05T19:16:07.997Z,1659726967.997 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-08-05T19:16:08.015Z,1659726968.015 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-08-05T19:16:08.016Z,1659726968.016 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-08-05T19:16:08.016Z,1659726968.016 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2022-08-05T19:16:08.020Z,1659726968.020 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-08-05T19:16:08.021Z,1659726968.021 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-08-05T19:16:08.021Z,1659726968.021 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2022-08-05T19:16:08.023Z,1659726968.023 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-08-05T19:16:08.024Z,1659726968.024 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-08-05T19:16:08.025Z,1659726968.025 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2022-08-05T19:16:08.028Z,1659726968.028 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-08-05T19:16:08.029Z,1659726968.029 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-08-05T19:16:08.033Z,1659726968.033 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-08-05T19:16:08.438Z,1659726968.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-08-05T19:16:08.440Z,1659726968.440 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-08-05T19:16:08.650Z,1659726968.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-08-05T19:16:08.652Z,1659726968.652 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-08-05T19:16:08.733Z,1659726968.733 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-08-05T19:16:08.944Z,1659726968.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-08-05T19:16:08.946Z,1659726968.946 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-08-05T19:16:09.025Z,1659726969.025 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-08-05T19:16:09.119Z,1659726969.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-08-05T19:16:09.121Z,1659726969.121 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-08-05T19:16:09.446Z,1659726969.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-08-05T19:16:09.448Z,1659726969.448 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-08-05T19:16:09.576Z,1659726969.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-08-05T19:16:09.576Z,1659726969.576 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-08-05T19:16:10.069Z,1659726970.069 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-08-05T19:16:10.071Z,1659726970.071 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-08-05T19:16:10.251Z,1659726970.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-08-05T19:16:10.252Z,1659726970.252 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-08-05T19:16:10.614Z,1659726970.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-08-05T19:16:10.614Z,1659726970.614 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-08-05T19:16:10.884Z,1659726970.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-08-05T19:16:10.886Z,1659726970.886 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-08-05T19:16:10.994Z,1659726970.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-08-05T19:16:10.996Z,1659726970.996 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-08-05T19:16:11.883Z,1659726971.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-08-05T19:16:11.884Z,1659726971.884 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-08-05T19:16:12.684Z,1659726972.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-08-05T19:16:12.686Z,1659726972.686 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-08-05T19:16:12.904Z,1659726972.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-08-05T19:16:12.906Z,1659726972.906 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-08-05T19:16:12.909Z,1659726972.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-08-05T19:16:13.098Z,1659726973.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-08-05T19:16:13.181Z,1659726973.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-08-05T19:16:13.317Z,1659726973.317 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-08-05T19:16:13.403Z,1659726973.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-08-05T19:16:13.663Z,1659726973.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-08-05T19:16:13.664Z,1659726973.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-08-05T19:16:13.765Z,1659726973.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-08-05T19:16:13.874Z,1659726973.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-08-05T19:16:13.980Z,1659726973.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-08-05T19:16:14.151Z,1659726974.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-08-05T19:16:14.262Z,1659726974.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-08-05T19:16:14.353Z,1659726974.353 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-08-05T19:16:14.392Z,1659726974.392 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-08-05T19:16:14.408Z,1659726974.408 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-08-05T19:16:14.409Z,1659726974.409 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-08-05T19:16:14.525Z,1659726974.525 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-08-05T19:16:14.527Z,1659726974.527 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-08-05T19:16:14.594Z,1659726974.594 [VerticalControl](DEBUG): Construct VerticalControl.
2022-08-05T19:16:14.652Z,1659726974.652 [VerticalControl] Loaded
2022-08-05T19:16:14.653Z,1659726974.653 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-08-05T19:16:14.655Z,1659726974.655 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-08-05T19:16:14.696Z,1659726974.696 [HorizontalControl] Loaded
2022-08-05T19:16:14.696Z,1659726974.696 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-08-05T19:16:14.699Z,1659726974.699 [SpeedControl](DEBUG): Construct SpeedControl.
2022-08-05T19:16:14.702Z,1659726974.702 [SpeedControl] Loaded
2022-08-05T19:16:14.702Z,1659726974.702 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-08-05T19:16:14.704Z,1659726974.704 [LoopControl](DEBUG): Construct LoopControl.
2022-08-05T19:16:14.705Z,1659726974.705 [LoopControl] Loaded
2022-08-05T19:16:14.705Z,1659726974.705 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-08-05T19:16:14.706Z,1659726974.706 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-08-05T19:16:14.706Z,1659726974.706 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-08-05T19:16:14.879Z,1659726974.879 [BuoyancyServo] Loaded
2022-08-05T19:16:14.879Z,1659726974.879 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-08-05T19:16:14.899Z,1659726974.899 [ElevatorServo] Loaded
2022-08-05T19:16:14.899Z,1659726974.899 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-08-05T19:16:14.917Z,1659726974.917 [MassServo] Loaded
2022-08-05T19:16:14.918Z,1659726974.918 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-08-05T19:16:14.936Z,1659726974.936 [RudderServo] Loaded
2022-08-05T19:16:14.936Z,1659726974.936 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-08-05T19:16:14.950Z,1659726974.950 [ThrusterHE] Loaded
2022-08-05T19:16:14.951Z,1659726974.951 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-08-05T19:16:14.951Z,1659726974.951 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-08-05T19:16:14.952Z,1659726974.952 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-08-05T19:16:15.021Z,1659726975.021 [DepthRateCalculator] Loaded
2022-08-05T19:16:15.022Z,1659726975.022 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-08-05T19:16:15.026Z,1659726975.026 [PitchRateCalculator] Loaded
2022-08-05T19:16:15.027Z,1659726975.027 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-08-05T19:16:15.036Z,1659726975.036 [SpeedCalculator] Loaded
2022-08-05T19:16:15.036Z,1659726975.036 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-08-05T19:16:15.041Z,1659726975.041 [YawRateCalculator] Loaded
2022-08-05T19:16:15.041Z,1659726975.041 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-08-05T19:16:15.059Z,1659726975.059 [ElevatorOffsetCalculator] Loaded
2022-08-05T19:16:15.059Z,1659726975.059 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-08-05T19:16:15.060Z,1659726975.060 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-08-05T19:16:15.060Z,1659726975.060 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-08-05T19:16:15.195Z,1659726975.195 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-08-05T19:16:15.195Z,1659726975.195 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-08-05T19:16:15.209Z,1659726975.209 [NavChart] Loaded
2022-08-05T19:16:15.209Z,1659726975.209 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-08-05T19:16:15.215Z,1659726975.215 [UniversalFixResidualReporter] Loaded
2022-08-05T19:16:15.215Z,1659726975.215 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-08-05T19:16:15.216Z,1659726975.216 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-08-05T19:16:15.216Z,1659726975.216 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-08-05T19:16:15.912Z,1659726975.912 [AHRS_M2] Loaded
2022-08-05T19:16:15.912Z,1659726975.912 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-08-05T19:16:16.162Z,1659726976.162 [BackseatComponent] Loaded
2022-08-05T19:16:16.162Z,1659726976.162 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-08-05T19:16:16.163Z,1659726976.163 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2022-08-05T19:16:16.163Z,1659726976.163 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2022-08-05T19:16:16.166Z,1659726976.166 [LcmUniversalReporter] Loaded
2022-08-05T19:16:16.166Z,1659726976.166 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-08-05T19:16:16.957Z,1659726976.957 [BPC1] Loaded
2022-08-05T19:16:16.957Z,1659726976.957 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-08-05T19:16:17.097Z,1659726977.097 [DataOverHttps] Loaded
2022-08-05T19:16:17.097Z,1659726977.097 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-08-05T19:16:17.098Z,1659726977.098 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2022-08-05T19:16:17.099Z,1659726977.099 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2022-08-05T19:16:17.119Z,1659726977.119 [Depth_Keller] Loaded
2022-08-05T19:16:17.119Z,1659726977.119 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-08-05T19:16:17.125Z,1659726977.125 [DropWeight] Loaded
2022-08-05T19:16:17.125Z,1659726977.125 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-08-05T19:16:17.186Z,1659726977.186 [NAL9602] Loaded
2022-08-05T19:16:17.186Z,1659726977.186 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-08-05T19:16:17.215Z,1659726977.215 [Onboard] Loaded
2022-08-05T19:16:17.216Z,1659726977.216 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-08-05T19:16:17.217Z,1659726977.217 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2022-08-05T19:16:17.217Z,1659726977.217 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2022-08-05T19:16:17.229Z,1659726977.229 [Power24vConverter] Loaded
2022-08-05T19:16:17.229Z,1659726977.229 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-08-05T19:16:17.242Z,1659726977.242 [Radio_Surface] Loaded
2022-08-05T19:16:17.242Z,1659726977.242 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-08-05T19:16:17.243Z,1659726977.243 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2022-08-05T19:16:17.244Z,1659726977.244 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2022-08-05T19:16:17.334Z,1659726977.334 [DAT] Loaded
2022-08-05T19:16:17.335Z,1659726977.335 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-08-05T19:16:17.336Z,1659726977.336 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2022-08-05T19:16:17.336Z,1659726977.336 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2022-08-05T19:16:17.337Z,1659726977.337 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-08-05T19:16:17.337Z,1659726977.337 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-08-05T19:16:17.554Z,1659726977.554 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-08-05T19:16:17.555Z,1659726977.555 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-08-05T19:16:18.161Z,1659726978.161 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-08-05T19:16:18.162Z,1659726978.162 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-08-05T19:16:18.204Z,1659726978.204 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-08-05T19:16:18.205Z,1659726978.205 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-08-05T19:16:18.420Z,1659726978.420 [CTD_Seabird] Loaded
2022-08-05T19:16:18.420Z,1659726978.420 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-08-05T19:16:18.421Z,1659726978.421 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0
2022-08-05T19:16:18.422Z,1659726978.422 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2022-08-05T19:16:18.442Z,1659726978.442 [PAR_Licor] Loaded
2022-08-05T19:16:18.442Z,1659726978.442 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-08-05T19:16:18.472Z,1659726978.472 [WetLabsBB2FL] Loaded
2022-08-05T19:16:18.473Z,1659726978.473 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-08-05T19:16:18.474Z,1659726978.474 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0
2022-08-05T19:16:18.474Z,1659726978.474 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2022-08-05T19:16:18.475Z,1659726978.475 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-08-05T19:16:18.475Z,1659726978.475 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-08-05T19:16:18.829Z,1659726978.829 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-08-05T19:16:18.830Z,1659726978.830 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-08-05T19:16:18.997Z,1659726978.997 [SBIT](DEBUG): Construct Startup Built In Test.
2022-08-05T19:16:19.006Z,1659726979.006 [SBIT] Loaded
2022-08-05T19:16:19.006Z,1659726979.006 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-08-05T19:16:19.009Z,1659726979.009 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-08-05T19:16:19.022Z,1659726979.022 [IBIT] Loaded
2022-08-05T19:16:19.022Z,1659726979.022 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-08-05T19:16:19.028Z,1659726979.028 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-08-05T19:16:19.133Z,1659726979.133 [CBIT] Loaded
2022-08-05T19:16:19.133Z,1659726979.133 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-08-05T19:16:19.133Z,1659726979.133 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-08-05T19:16:19.139Z,1659726979.139 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-08-05T19:16:19.142Z,1659726979.142 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-08-05T19:16:19.153Z,1659726979.153 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-08-05T19:16:19.154Z,1659726979.154 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0
2022-08-05T19:16:19.154Z,1659726979.154 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2022-08-05T19:16:19.159Z,1659726979.159 [Supervisor](INFO): Main Thread ID is 830
2022-08-05T19:16:19.159Z,1659726979.159 [Supervisor](DEBUG): Running supervisor.
2022-08-05T19:16:19.160Z,1659726979.160 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2022-08-05T19:16:19.160Z,1659726979.160 [CommandExec](INFO): Initializing the command executive.
2022-08-05T19:16:19.162Z,1659726979.162 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2022-08-05T19:16:19.164Z,1659726979.164 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2022-08-05T19:16:19.164Z,1659726979.164 [controlThread](DEBUG): Initializing ControlThread
2022-08-05T19:16:19.165Z,1659726979.165 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-08-05T19:16:19.167Z,1659726979.167 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-08-05T19:16:19.167Z,1659726979.167 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-08-05T19:16:19.168Z,1659726979.168 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-08-05T19:16:19.169Z,1659726979.169 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-08-05T19:16:19.170Z,1659726979.170 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-08-05T19:16:19.170Z,1659726979.170 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-08-05T19:16:19.170Z,1659726979.170 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-08-05T19:16:19.171Z,1659726979.171 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-08-05T19:16:19.172Z,1659726979.172 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-08-05T19:16:19.172Z,1659726979.172 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-08-05T19:16:19.180Z,1659726979.180 [SBIT](INFO): Initialize SBIT Component.
2022-08-05T19:16:19.181Z,1659726979.181 [SBIT](IMPORTANT): git: 2022-08-02
2022-08-05T19:16:19.181Z,1659726979.181 [SBIT](INFO): git hash: f84e5919489b70ff94ab72b61eefa777bac519a0
2022-08-05T19:16:19.181Z,1659726979.181 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-08-05T19:16:19.182Z,1659726979.182 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-08-05T19:16:19.183Z,1659726979.183 [SBIT](INFO): Beginning SBIT in 109.000000 seconds.
2022-08-05T19:16:19.184Z,1659726979.184 [IBIT](INFO): Initialize IBIT Component.
2022-08-05T19:16:19.185Z,1659726979.185 [CBIT](DEBUG): Initialize CBIT Component.
2022-08-05T19:16:19.186Z,1659726979.186 [logger ThreadHandler](INFO): Handler Thread ID is 933
2022-08-05T19:16:19.196Z,1659726979.196 [CBIT](DEBUG): Initialized mux pins.
2022-08-05T19:16:19.196Z,1659726979.196 [CBIT](DEBUG): Initializing the watchdog timer.
2022-08-05T19:16:19.204Z,1659726979.204 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2022-08-05T19:16:19.216Z,1659726979.216 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2022-08-05T19:16:19.217Z,1659726979.217 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-08-05T19:16:19.220Z,1659726979.220 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-08-05T19:16:19.220Z,1659726979.220 [CBIT](DEBUG): Initializing heartbeat.
2022-08-05T19:16:19.228Z,1659726979.228 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2022-08-05T19:16:19.246Z,1659726979.246 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2022-08-05T19:16:19.268Z,1659726979.268 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2022-08-05T19:16:19.269Z,1659726979.269 [DAT](INFO): Powering up
2022-08-05T19:16:19.269Z,1659726979.269 [DAT](DEBUG): Initializing DAT.
2022-08-05T19:16:19.273Z,1659726979.273 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2022-08-05T19:16:19.274Z,1659726979.274 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-08-05T19:16:19.277Z,1659726979.277 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2022-08-05T19:16:19.280Z,1659726979.280 [WetLabsBB2FL](INFO): Powering up
2022-08-05T19:16:19.282Z,1659726979.282 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2022-08-05T19:16:19.292Z,1659726979.292 [CBIT](DEBUG): Deactivating GF circuits.
2022-08-05T19:16:19.292Z,1659726979.292 [CBIT](DEBUG): Deactivating emergency mode.
2022-08-05T19:16:19.293Z,1659726979.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-08-05T19:16:19.293Z,1659726979.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-08-05T19:16:19.294Z,1659726979.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2022-08-05T19:16:19.294Z,1659726979.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2022-08-05T19:16:19.294Z,1659726979.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2022-08-05T19:16:19.294Z,1659726979.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2022-08-05T19:16:19.294Z,1659726979.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2022-08-05T19:16:19.294Z,1659726979.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2022-08-05T19:16:19.295Z,1659726979.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2022-08-05T19:16:19.295Z,1659726979.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2022-08-05T19:16:19.295Z,1659726979.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-08-05T19:16:19.295Z,1659726979.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-08-05T19:16:19.295Z,1659726979.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2022-08-05T19:16:19.295Z,1659726979.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2022-08-05T19:16:19.296Z,1659726979.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DD.000
2022-08-05T19:16:19.296Z,1659726979.296 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DD.000
2022-08-05T19:16:19.296Z,1659726979.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DE.000
2022-08-05T19:16:19.296Z,1659726979.296 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DE.000
2022-08-05T19:16:19.296Z,1659726979.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DF.000
2022-08-05T19:16:19.297Z,1659726979.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DF.000
2022-08-05T19:16:19.297Z,1659726979.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DG.000
2022-08-05T19:16:19.297Z,1659726979.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DG.000
2022-08-05T19:16:19.297Z,1659726979.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DH.000
2022-08-05T19:16:19.297Z,1659726979.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DH.000
2022-08-05T19:16:19.297Z,1659726979.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CC.000
2022-08-05T19:16:19.298Z,1659726979.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CC.000
2022-08-05T19:16:19.298Z,1659726979.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CD.000
2022-08-05T19:16:19.298Z,1659726979.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CD.000
2022-08-05T19:16:19.298Z,1659726979.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CE.000
2022-08-05T19:16:19.298Z,1659726979.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CE.000
2022-08-05T19:16:19.298Z,1659726979.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QE.000
2022-08-05T19:16:19.299Z,1659726979.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QE.000
2022-08-05T19:16:19.299Z,1659726979.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QF.000
2022-08-05T19:16:19.299Z,1659726979.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QF.000
2022-08-05T19:16:19.299Z,1659726979.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QG.000
2022-08-05T19:16:19.299Z,1659726979.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QG.000
2022-08-05T19:16:19.300Z,1659726979.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PB.000
2022-08-05T19:16:19.300Z,1659726979.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PB.000
2022-08-05T19:16:19.300Z,1659726979.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PC.000
2022-08-05T19:16:19.300Z,1659726979.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PC.000
2022-08-05T19:16:19.301Z,1659726979.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PD.000
2022-08-05T19:16:19.301Z,1659726979.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PD.000
2022-08-05T19:16:19.301Z,1659726979.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PE.000
2022-08-05T19:16:19.301Z,1659726979.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PE.000
2022-08-05T19:16:19.301Z,1659726979.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PF.000
2022-08-05T19:16:19.301Z,1659726979.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PF.000
2022-08-05T19:16:19.302Z,1659726979.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PG.000
2022-08-05T19:16:19.302Z,1659726979.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PG.000
2022-08-05T19:16:19.302Z,1659726979.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5WI22M.000
2022-08-05T19:16:19.302Z,1659726979.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5WI22M.000
2022-08-05T19:16:19.328Z,1659726979.328 [CBIT](DEBUG): Backplane powered.
2022-08-05T19:16:19.329Z,1659726979.329 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-08-05T19:16:19.368Z,1659726979.368 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-05T19:16:19.379Z,1659726979.379 [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-08-05T19:16:19.380Z,1659726979.380 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-08-05T19:16:19.457Z,1659726979.457 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-08-05T19:16:19.458Z,1659726979.458 [Default:A.Wait](DEBUG): Construct Wait.
2022-08-05T19:16:19.469Z,1659726979.469 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-05T19:16:19.491Z,1659726979.491 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-08-05T19:16:19.493Z,1659726979.493 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-08-05T19:16:19.508Z,1659726979.508 [Default:E.Execute](DEBUG): Construct Execute.
2022-08-05T19:16:19.511Z,1659726979.511 [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-08-05T19:16:19.525Z,1659726979.525 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-08-05T19:16:19.544Z,1659726979.544 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-08-05T19:16:19.652Z,1659726979.652 [Radio_Surface](INFO): Powering up
2022-08-05T19:16:19.703Z,1659726979.703 [Power24vConverter](INFO): Powering up.
2022-08-05T19:16:19.746Z,1659726979.746 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-08-05T19:16:19.752Z,1659726979.752 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-08-05T19:16:19.753Z,1659726979.753 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-08-05T19:16:19.760Z,1659726979.760 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-08-05T19:16:19.761Z,1659726979.761 [MassServo](DEBUG): Initializing EZServoServo.
2022-08-05T19:16:19.768Z,1659726979.768 [MassServo](DEBUG): Initializing MassServo.
2022-08-05T19:16:19.769Z,1659726979.769 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-05T19:16:19.776Z,1659726979.776 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-05T19:16:19.777Z,1659726979.777 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-08-05T19:16:19.785Z,1659726979.785 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-08-05T19:16:20.676Z,1659726980.676 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-08-05T19:16:20.676Z,1659726980.676 [RudderServo](FAULT): Rudder failed to initialize
2022-08-05T19:16:20.676Z,1659726980.676 [RudderServo] Communications Fault, FailCount= 1
2022-08-05T19:16:20.676Z,1659726980.676 [RudderServo](ERROR): Communications Fault
2022-08-05T19:16:20.680Z,1659726980.680 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-08-05T19:16:20.869Z,1659726980.869 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-05T19:16:20.869Z,1659726980.869 [RudderServo](INFO): Powering down
2022-08-05T19:16:21.388Z,1659726981.388 [WetLabsBB2FL](INFO): Powering down
2022-08-05T19:16:21.565Z,1659726981.565 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-05T19:16:21.685Z,1659726981.685 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-05T19:16:21.689Z,1659726981.689 [CBIT](INFO): Clearing failed state for component RudderServo
2022-08-05T19:16:21.689Z,1659726981.689 [RudderServo] No Fault, FailCount= 1
2022-08-05T19:16:25.369Z,1659726985.369 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-08-05T19:16:26.304Z,1659726986.304 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-08-05T19:16:34.140Z,1659726994.140 [DAT](INFO): commRate: 800
2022-08-05T19:16:36.204Z,1659726996.204 [DAT](INFO): entering command mode
2022-08-05T19:16:36.408Z,1659726996.408 [DAT](INFO): setting verbose to 3
2022-08-05T19:16:36.658Z,1659726996.658 [DAT](INFO): set verbose to 3
2022-08-05T19:16:36.658Z,1659726996.658 [DAT](INFO): setting DatVerbose to 27440
2022-08-05T19:16:36.909Z,1659726996.909 [DAT](INFO): set DatVerbose to 27440
2022-08-05T19:16:36.910Z,1659726996.910 [DAT](INFO): setting transmit power to 8
2022-08-05T19:16:37.161Z,1659726997.161 [DAT](INFO): set transmit power to 8
2022-08-05T19:16:37.162Z,1659726997.162 [DAT](INFO): setting local address to 5
2022-08-05T19:16:37.413Z,1659726997.413 [DAT](INFO): set local address to 5
2022-08-05T19:16:37.414Z,1659726997.414 [DAT](INFO): Setting time to: 19:16:37 And date to:8/5/2022
2022-08-05T19:16:37.665Z,1659726997.665 [DAT](INFO): Local DAT time set to Fri Aug 5, 2022  19:16:37  

2022-08-05T19:16:47.429Z,1659727007.429 [NAL9602](INFO): Powering up NAL9602
2022-08-05T19:16:58.338Z,1659727018.338 [NAL9602](INFO): NAL9602 initialized
2022-08-05T19:17:26.655Z,1659727046.655 [NAL9602](INFO): SBD MO Status=2, MOMSN=7794, MT Status=2, MTMSN=0
2022-08-05T19:17:26.655Z,1659727046.655 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-05T19:17:40.359Z,1659727060.359 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:17:53.686Z,1659727073.686 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:18:06.607Z,1659727086.607 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:18:08.647Z,1659727088.647 [SBIT](IMPORTANT): Beginning Startup BIT
2022-08-05T19:18:08.652Z,1659727088.652 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-05T19:18:19.745Z,1659727099.745 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006712
CHAN A1 (24V): -0.007707
CHAN A2 (12V): -0.004419
CHAN A3 (5V): -0.002808
CHAN B0 (3.3V): -0.000567
CHAN B1 (3.15aV): -0.000920
CHAN B2 (3.15bV): -0.001796
CHAN B3 (GND): -0.000232
OPEN: -0.004161
Full Scale: +/- 1 mA
2022-08-05T19:18:19.938Z,1659727099.938 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:18:33.270Z,1659727113.270 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:18:47.006Z,1659727127.006 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:19:00.338Z,1659727140.338 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:19:02.789Z,1659727142.789 [SBIT](IMPORTANT): SBIT PASSED
2022-08-05T19:19:02.789Z,1659727142.789 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-08-05T19:19:02.790Z,1659727142.790 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2022-08-05T19:19:02.790Z,1659727142.790 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-08-05T19:19:02.790Z,1659727142.790 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-08-05T19:19:02.790Z,1659727142.790 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=236.483517 cubic_centimeter;

2022-08-05T19:19:02.790Z,1659727142.790 [SBIT](IMPORTANT): VerticalControl.massDefault=22.391415 millimeter;

2022-08-05T19:19:03.181Z,1659727143.181 [MissionManager](IMPORTANT): Started mission Startup
2022-08-05T19:19:03.181Z,1659727143.181 [Startup] Running Loop=1
2022-08-05T19:19:03.181Z,1659727143.181 [Startup](DEBUG): Aggregate::initialize Startup
2022-08-05T19:19:03.181Z,1659727143.181 [Startup:A.GoToSurface] Running Loop=1
2022-08-05T19:19:03.181Z,1659727143.181 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-05T19:19:03.182Z,1659727143.182 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-05T19:19:03.182Z,1659727143.182 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-05T19:19:03.183Z,1659727143.183 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-05T19:19:03.183Z,1659727143.183 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-05T19:19:03.183Z,1659727143.183 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-05T19:19:03.205Z,1659727143.205 [Startup:StartupSatComms] Running Loop=1
2022-08-05T19:19:03.205Z,1659727143.205 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-08-05T19:19:03.205Z,1659727143.205 [Startup:StartupSatComms:A] Running Loop=1
2022-08-05T19:19:03.587Z,1659727143.587 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-08-05T19:19:13.670Z,1659727153.670 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:19:14.887Z,1659727154.887 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-08-05T19:19:19.339Z,1659727159.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-08-05T19:19:19.339Z,1659727159.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-05T19:19:19.350Z,1659727159.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-05T19:19:19.765Z,1659727159.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-05T19:19:19.765Z,1659727159.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-08-05T19:19:26.598Z,1659727166.598 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:19:39.935Z,1659727179.935 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:19:52.414Z,1659727192.414 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004605
2022-08-05T19:19:53.273Z,1659727193.273 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:20:03.382Z,1659727203.382 [Startup:StartupSatComms:A](INFO): Timed out from 2022-08-05T19:19:03.2Z
2022-08-05T19:20:03.382Z,1659727203.382 [Startup:StartupSatComms:A] Stopped
2022-08-05T19:20:03.382Z,1659727203.382 [Startup:StartupSatComms:B] Running Loop=1
2022-08-05T19:20:03.787Z,1659727203.787 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-05T19:20:11.097Z,1659727211.097 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T133613/Courier0166.lzma
2022-08-05T19:20:12.098Z,1659727212.098 [DataOverHttps](INFO): Moved sent file to Logs/20220805T133613/Courier0166.lzma.bak
2022-08-05T19:20:12.098Z,1659727212.098 [DataOverHttps](INFO): SBD MOMSN=17090372
2022-08-05T19:20:30.539Z,1659727230.539 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220805T133613/Courier0171.lzma
2022-08-05T19:20:31.542Z,1659727231.542 [DataOverHttps](INFO): Moved sent file to Logs/20220805T133613/Courier0171.lzma.bak
2022-08-05T19:20:31.542Z,1659727231.542 [DataOverHttps](INFO): SBD MOMSN=17090375
2022-08-05T19:20:47.275Z,1659727247.275 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220805T191607/Courier0000.lzma
2022-08-05T19:20:48.278Z,1659727248.278 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0000.lzma.bak
2022-08-05T19:20:48.278Z,1659727248.278 [DataOverHttps](INFO): SBD MOMSN=17090377
2022-08-05T19:20:54.735Z,1659727254.735 [NAL9602](INFO): SBD MO Status=0, MOMSN=7794, MT Status=0, MTMSN=0
2022-08-05T19:20:54.736Z,1659727254.736 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:20:55.935Z,1659727255.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192054.00,A,4651.46645,N,09106.22669,W,0.117,0.00,050822,,,D*7C
2022-08-05T19:20:55.939Z,1659727255.939 [NAL9602](INFO): GPS fix at 20220805T192054: (46.857774, -91.103778)
2022-08-05T19:21:03.656Z,1659727263.656 [Startup:StartupSatComms:B](INFO): Timed out from 2022-08-05T19:20:03.4Z
2022-08-05T19:21:03.656Z,1659727263.656 [Startup:StartupSatComms:B] Stopped
2022-08-05T19:21:03.656Z,1659727263.656 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-08-05T19:21:03.656Z,1659727263.656 [Startup:StartupSatComms] Stopped
2022-08-05T19:21:03.656Z,1659727263.656 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-08-05T19:21:03.657Z,1659727263.657 [Startup](INFO): Completed Startup
2022-08-05T19:21:03.657Z,1659727263.657 [MissionManager](INFO): Startup is completed.
2022-08-05T19:21:03.657Z,1659727263.657 [MissionManager](INFO): Uninitializing Mission Startup
2022-08-05T19:21:03.657Z,1659727263.657 [Startup] Stopped
2022-08-05T19:21:03.658Z,1659727263.658 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-08-05T19:21:03.658Z,1659727263.658 [Startup:A.GoToSurface] Stopped
2022-08-05T19:21:03.658Z,1659727263.658 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-05T19:21:04.067Z,1659727264.067 [MissionManager](IMPORTANT): Started mission Default
2022-08-05T19:21:04.067Z,1659727264.067 [Default] Running Loop=1
2022-08-05T19:21:04.067Z,1659727264.067 [Default](DEBUG): Aggregate::initialize Default
2022-08-05T19:21:04.067Z,1659727264.067 [Default:B.GoToSurface] Running Loop=1
2022-08-05T19:21:04.068Z,1659727264.068 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-05T19:21:04.076Z,1659727264.076 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-05T19:21:04.076Z,1659727264.076 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-05T19:21:04.077Z,1659727264.077 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-05T19:21:04.077Z,1659727264.077 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-05T19:21:04.077Z,1659727264.077 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-05T19:21:04.078Z,1659727264.078 [Default:A.Wait] Running Loop=1
2022-08-05T19:21:04.078Z,1659727264.078 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:21:09.689Z,1659727269.689 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20220805T133613/Express0167.lzma
2022-08-05T19:21:10.690Z,1659727270.690 [DataOverHttps](INFO): Moved sent file to Logs/20220805T133613/Express0167.lzma.bak
2022-08-05T19:21:10.691Z,1659727270.691 [DataOverHttps](INFO): SBD MOMSN=17090380
2022-08-05T19:21:17.407Z,1659727277.407 [Default:A.Wait](INFO): Done Waiting.
2022-08-05T19:21:17.407Z,1659727277.407 [Default:A.Wait] Stopped
2022-08-05T19:21:17.407Z,1659727277.407 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:21:17.820Z,1659727277.820 [Default:CheckIn] Running Loop=1
2022-08-05T19:21:17.820Z,1659727277.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:21:17.820Z,1659727277.820 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:21:18.242Z,1659727278.242 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-08-05T19:21:19.815Z,1659727279.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192118.00,A,4651.46637,N,09106.22685,W,0.078,0.00,050822,,,A*7F
2022-08-05T19:21:19.817Z,1659727279.817 [NAL9602](INFO): GPS fix at 20220805T192118: (46.857773, -91.103781)
2022-08-05T19:21:19.827Z,1659727279.827 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:21:19.827Z,1659727279.827 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:21:20.263Z,1659727280.263 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-05T19:21:27.784Z,1659727287.784 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20220805T191607/Courier0004.lzma
2022-08-05T19:21:28.785Z,1659727288.786 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0004.lzma.bak
2022-08-05T19:21:28.786Z,1659727288.786 [DataOverHttps](INFO): SBD MOMSN=17090385
2022-08-05T19:21:44.965Z,1659727304.965 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20220805T133613/Express0168.lzma
2022-08-05T19:21:45.966Z,1659727305.966 [DataOverHttps](INFO): Moved sent file to Logs/20220805T133613/Express0168.lzma.bak
2022-08-05T19:21:45.966Z,1659727305.966 [DataOverHttps](INFO): SBD MOMSN=17090388
2022-08-05T19:21:52.181Z,1659727312.181 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:22:01.903Z,1659727321.903 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220805T133613/Express0172.lzma
2022-08-05T19:22:02.906Z,1659727322.906 [DataOverHttps](INFO): Moved sent file to Logs/20220805T133613/Express0172.lzma.bak
2022-08-05T19:22:02.906Z,1659727322.906 [DataOverHttps](INFO): SBD MOMSN=17090390
2022-08-05T19:22:19.537Z,1659727339.537 [DataOverHttps](INFO): Sending 858 bytes from file Logs/20220805T191607/Express0001.lzma
2022-08-05T19:22:20.538Z,1659727340.538 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0001.lzma.bak
2022-08-05T19:22:20.538Z,1659727340.538 [DataOverHttps](INFO): SBD MOMSN=17090392
2022-08-05T19:22:39.488Z,1659727359.488 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20220805T191607/Express0005.lzma
2022-08-05T19:22:40.490Z,1659727360.490 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0005.lzma.bak
2022-08-05T19:22:40.490Z,1659727360.490 [DataOverHttps](INFO): SBD MOMSN=17090409
2022-08-05T19:22:41.963Z,1659727361.963 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:22:41.963Z,1659727361.963 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:22:41.963Z,1659727361.963 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:27:08.634Z,1659727628.634 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:27:21.960Z,1659727641.960 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:27:35.292Z,1659727655.292 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:27:42.578Z,1659727662.578 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T19:27:42.579Z,1659727662.579 [Default:CheckIn:C.Wait] Stopped
2022-08-05T19:27:42.579Z,1659727662.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:27:42.579Z,1659727662.579 [Default:CheckIn:D] Running Loop=1
2022-08-05T19:27:42.987Z,1659727662.987 [Default:CheckIn:D] Stopped
2022-08-05T19:27:42.987Z,1659727662.987 [Default:CheckIn:E] Running Loop=1
2022-08-05T19:27:43.395Z,1659727663.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.648658 min 
2022-08-05T19:27:43.395Z,1659727663.395 [Default:CheckIn:E] Stopped
2022-08-05T19:27:43.395Z,1659727663.395 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T19:27:43.395Z,1659727663.395 [Default:CheckIn] Stopped
2022-08-05T19:27:43.395Z,1659727663.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T19:27:43.395Z,1659727663.395 [Default:CheckIn](INFO): Running loop #2
2022-08-05T19:27:43.396Z,1659727663.396 [Default:CheckIn] Running Loop=2
2022-08-05T19:27:43.396Z,1659727663.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:27:43.396Z,1659727663.396 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:27:45.399Z,1659727665.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192744.00,A,4651.46680,N,09106.22703,W,0.097,0.00,050822,,,D*77
2022-08-05T19:27:45.401Z,1659727665.401 [NAL9602](INFO): GPS fix at 20220805T192744: (46.857780, -91.103784)
2022-08-05T19:27:45.412Z,1659727665.412 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:27:45.412Z,1659727665.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:27:49.030Z,1659727669.030 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:27:53.371Z,1659727673.371 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0007.lzma
2022-08-05T19:27:54.374Z,1659727674.374 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0007.lzma.bak
2022-08-05T19:27:54.374Z,1659727674.374 [DataOverHttps](INFO): SBD MOMSN=17090413
2022-08-05T19:28:02.362Z,1659727682.362 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:28:10.315Z,1659727690.315 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20220805T191607/Express0008.lzma
2022-08-05T19:28:11.318Z,1659727691.318 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0008.lzma.bak
2022-08-05T19:28:11.318Z,1659727691.318 [DataOverHttps](INFO): SBD MOMSN=17090416
2022-08-05T19:28:12.887Z,1659727692.887 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:28:12.887Z,1659727692.887 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:28:12.887Z,1659727692.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:28:16.103Z,1659727696.103 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:28:29.428Z,1659727709.428 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:28:43.164Z,1659727723.164 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:28:56.495Z,1659727736.495 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:29:09.828Z,1659727749.828 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:29:23.160Z,1659727763.160 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:30:03.158Z,1659727803.158 [NAL9602](INFO): SBD MO Status=0, MOMSN=7795, MT Status=0, MTMSN=0
2022-08-05T19:30:03.158Z,1659727803.158 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:30:33.871Z,1659727833.871 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:33:13.563Z,1659727993.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T19:33:13.563Z,1659727993.563 [Default:CheckIn:C.Wait] Stopped
2022-08-05T19:33:13.563Z,1659727993.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:33:13.563Z,1659727993.563 [Default:CheckIn:D] Running Loop=1
2022-08-05T19:33:13.962Z,1659727993.962 [Default:CheckIn:D] Stopped
2022-08-05T19:33:13.962Z,1659727993.962 [Default:CheckIn:E] Running Loop=1
2022-08-05T19:33:14.357Z,1659727994.357 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.164918 min 
2022-08-05T19:33:14.357Z,1659727994.357 [Default:CheckIn:E] Stopped
2022-08-05T19:33:14.357Z,1659727994.357 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T19:33:14.357Z,1659727994.357 [Default:CheckIn] Stopped
2022-08-05T19:33:14.358Z,1659727994.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T19:33:14.358Z,1659727994.358 [Default:CheckIn](INFO): Running loop #3
2022-08-05T19:33:14.358Z,1659727994.358 [Default:CheckIn] Running Loop=3
2022-08-05T19:33:14.358Z,1659727994.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:33:14.358Z,1659727994.358 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:33:16.363Z,1659727996.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193314.00,A,4651.46712,N,09106.22798,W,0.097,0.00,050822,,,D*7F
2022-08-05T19:33:16.365Z,1659727996.365 [NAL9602](INFO): GPS fix at 20220805T193314: (46.857785, -91.103800)
2022-08-05T19:33:16.401Z,1659727996.401 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:33:16.401Z,1659727996.401 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:33:24.059Z,1659728004.059 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0010.lzma
2022-08-05T19:33:25.062Z,1659728005.062 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0010.lzma.bak
2022-08-05T19:33:25.062Z,1659728005.062 [DataOverHttps](INFO): SBD MOMSN=17090422
2022-08-05T19:33:32.523Z,1659728012.523 [NAL9602](INFO): SBD MO Status=0, MOMSN=7796, MT Status=0, MTMSN=0
2022-08-05T19:33:32.523Z,1659728012.523 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:33:41.059Z,1659728021.059 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20220805T191607/Express0011.lzma
2022-08-05T19:33:42.062Z,1659728022.062 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0011.lzma.bak
2022-08-05T19:33:42.062Z,1659728022.062 [DataOverHttps](INFO): SBD MOMSN=17090425
2022-08-05T19:33:43.460Z,1659728023.460 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:33:43.460Z,1659728023.460 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:33:43.460Z,1659728023.460 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:34:03.229Z,1659728043.229 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:36:46.101Z,1659728206.101 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:36:59.427Z,1659728219.427 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:37:13.165Z,1659728233.165 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:37:26.495Z,1659728246.495 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:37:39.828Z,1659728259.828 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:37:53.565Z,1659728273.565 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:38:06.897Z,1659728286.897 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:38:20.229Z,1659728300.229 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:38:33.560Z,1659728313.560 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:38:44.097Z,1659728324.097 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T19:38:44.097Z,1659728324.097 [Default:CheckIn:C.Wait] Stopped
2022-08-05T19:38:44.097Z,1659728324.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:38:44.097Z,1659728324.097 [Default:CheckIn:D] Running Loop=1
2022-08-05T19:38:44.478Z,1659728324.478 [Default:CheckIn:D] Stopped
2022-08-05T19:38:44.478Z,1659728324.478 [Default:CheckIn:E] Running Loop=1
2022-08-05T19:38:44.888Z,1659728324.888 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.673505 min 
2022-08-05T19:38:44.888Z,1659728324.888 [Default:CheckIn:E] Stopped
2022-08-05T19:38:44.888Z,1659728324.888 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T19:38:44.889Z,1659728324.889 [Default:CheckIn] Stopped
2022-08-05T19:38:44.889Z,1659728324.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T19:38:44.889Z,1659728324.889 [Default:CheckIn](INFO): Running loop #4
2022-08-05T19:38:44.889Z,1659728324.889 [Default:CheckIn] Running Loop=4
2022-08-05T19:38:44.889Z,1659728324.889 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:38:44.889Z,1659728324.889 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:38:46.962Z,1659728326.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193845.00,A,4651.46711,N,09106.22738,W,0.078,0.00,050822,,,D*78
2022-08-05T19:38:46.965Z,1659728326.965 [NAL9602](INFO): GPS fix at 20220805T193845: (46.857785, -91.103790)
2022-08-05T19:38:46.975Z,1659728326.975 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:38:46.975Z,1659728326.975 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:38:47.141Z,1659728327.141 [CommandExec](IMPORTANT): got command strobe off
2022-08-05T19:38:47.141Z,1659728327.141 [CommandExec](IMPORTANT): Deactivating strobe
2022-08-05T19:38:47.295Z,1659728327.295 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:38:54.587Z,1659728334.587 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0013.lzma
2022-08-05T19:38:55.590Z,1659728335.590 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0013.lzma.bak
2022-08-05T19:38:55.590Z,1659728335.590 [DataOverHttps](INFO): SBD MOMSN=17090429
2022-08-05T19:39:00.630Z,1659728340.630 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:39:14.648Z,1659728354.648 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20220805T191607/Express0014.lzma
2022-08-05T19:39:15.650Z,1659728355.650 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0014.lzma.bak
2022-08-05T19:39:15.650Z,1659728355.650 [DataOverHttps](INFO): SBD MOMSN=17090432
2022-08-05T19:39:17.265Z,1659728357.265 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:39:17.265Z,1659728357.265 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:39:17.265Z,1659728357.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:39:19.267Z,1659728359.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=7797, MT Status=2, MTMSN=0
2022-08-05T19:39:19.267Z,1659728359.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-05T19:39:39.058Z,1659728379.058 [NAL9602](INFO): SBD MO Status=0, MOMSN=7797, MT Status=0, MTMSN=0
2022-08-05T19:39:39.059Z,1659728379.059 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:40:09.773Z,1659728409.773 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:44:17.937Z,1659728657.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T19:44:17.937Z,1659728657.937 [Default:CheckIn:C.Wait] Stopped
2022-08-05T19:44:17.938Z,1659728657.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:44:17.938Z,1659728657.938 [Default:CheckIn:D] Running Loop=1
2022-08-05T19:44:18.365Z,1659728658.365 [Default:CheckIn:D] Stopped
2022-08-05T19:44:18.365Z,1659728658.365 [Default:CheckIn:E] Running Loop=1
2022-08-05T19:44:18.743Z,1659728658.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.238291 min 
2022-08-05T19:44:18.743Z,1659728658.743 [Default:CheckIn:E] Stopped
2022-08-05T19:44:18.743Z,1659728658.743 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T19:44:18.743Z,1659728658.743 [Default:CheckIn] Stopped
2022-08-05T19:44:18.743Z,1659728658.743 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T19:44:18.744Z,1659728658.744 [Default:CheckIn](INFO): Running loop #5
2022-08-05T19:44:18.744Z,1659728658.744 [Default:CheckIn] Running Loop=5
2022-08-05T19:44:18.744Z,1659728658.744 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:44:18.744Z,1659728658.744 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:44:20.803Z,1659728660.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194419.00,A,4651.46756,N,09106.22837,W,0.078,0.00,050822,,,D*79
2022-08-05T19:44:20.805Z,1659728660.805 [NAL9602](INFO): GPS fix at 20220805T194419: (46.857793, -91.103806)
2022-08-05T19:44:20.856Z,1659728660.856 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:44:20.856Z,1659728660.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:44:30.719Z,1659728670.719 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0016.lzma
2022-08-05T19:44:31.722Z,1659728671.722 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0016.lzma.bak
2022-08-05T19:44:31.722Z,1659728671.722 [DataOverHttps](INFO): SBD MOMSN=17090438
2022-08-05T19:44:33.158Z,1659728673.158 [NAL9602](INFO): SBD MO Status=0, MOMSN=7798, MT Status=0, MTMSN=0
2022-08-05T19:44:33.159Z,1659728673.159 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:44:55.075Z,1659728695.075 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20220805T191607/Express0017.lzma
2022-08-05T19:44:56.078Z,1659728696.078 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0017.lzma.bak
2022-08-05T19:44:56.078Z,1659728696.078 [DataOverHttps](INFO): SBD MOMSN=17090441
2022-08-05T19:44:57.430Z,1659728697.430 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:44:57.430Z,1659728697.430 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:44:57.430Z,1659728697.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:45:03.862Z,1659728703.862 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:46:09.720Z,1659728769.720 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-08-05T19:46:36.034Z,1659728796.034 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:46:49.300Z,1659728809.300 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:47:02.229Z,1659728822.229 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:47:15.967Z,1659728835.967 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T19:47:20.003Z,1659728840.003 [BPC1](ERROR): Battery stick #19 (s/n: 00F6) reported OVER_TEMP_ALARM. Status code: 0x1717.
2022-08-05T19:47:20.004Z,1659728840.004 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2022-08-05T19:47:20.007Z,1659728840.007 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2022-08-05T19:49:58.041Z,1659728998.041 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T19:49:58.041Z,1659728998.041 [Default:CheckIn:C.Wait] Stopped
2022-08-05T19:49:58.041Z,1659728998.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:49:58.042Z,1659728998.042 [Default:CheckIn:D] Running Loop=1
2022-08-05T19:49:58.451Z,1659728998.451 [Default:CheckIn:D] Stopped
2022-08-05T19:49:58.451Z,1659728998.451 [Default:CheckIn:E] Running Loop=1
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.906388 min 
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn:E] Stopped
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn] Stopped
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn](INFO): Running loop #6
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn] Running Loop=6
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:49:58.853Z,1659728998.853 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:50:00.872Z,1659729000.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194959.00,A,4651.46726,N,09106.22723,W,0.058,0.00,050822,,,D*7F
2022-08-05T19:50:00.875Z,1659729000.875 [NAL9602](INFO): GPS fix at 20220805T194959: (46.857788, -91.103787)
2022-08-05T19:50:00.885Z,1659729000.885 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:50:00.885Z,1659729000.885 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:50:08.020Z,1659729008.020 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20220805T191607/Courier0019.lzma
2022-08-05T19:50:09.022Z,1659729009.022 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0019.lzma.bak
2022-08-05T19:50:09.022Z,1659729009.022 [DataOverHttps](INFO): SBD MOMSN=17090446
2022-08-05T19:50:17.032Z,1659729017.032 [NAL9602](INFO): SBD MO Status=0, MOMSN=7799, MT Status=0, MTMSN=0
2022-08-05T19:50:17.032Z,1659729017.032 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:50:27.975Z,1659729027.975 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220805T191607/Express0020.lzma
2022-08-05T19:50:28.978Z,1659729028.978 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0020.lzma.bak
2022-08-05T19:50:28.978Z,1659729028.978 [DataOverHttps](INFO): SBD MOMSN=17090449
2022-08-05T19:50:30.445Z,1659729030.445 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:50:30.445Z,1659729030.445 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:50:30.445Z,1659729030.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:50:47.797Z,1659729047.797 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:55:31.110Z,1659729331.110 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T19:55:31.110Z,1659729331.110 [Default:CheckIn:C.Wait] Stopped
2022-08-05T19:55:31.110Z,1659729331.110 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T19:55:31.110Z,1659729331.110 [Default:CheckIn:D] Running Loop=1
2022-08-05T19:55:31.515Z,1659729331.515 [Default:CheckIn:D] Stopped
2022-08-05T19:55:31.515Z,1659729331.515 [Default:CheckIn:E] Running Loop=1
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.457458 min 
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn:E] Stopped
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn] Stopped
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn](INFO): Running loop #7
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn] Running Loop=7
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T19:55:31.915Z,1659729331.915 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T19:55:33.936Z,1659729333.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195532.00,A,4651.46667,N,09106.22703,W,0.078,0.00,050822,,,D*7B
2022-08-05T19:55:33.939Z,1659729333.939 [NAL9602](INFO): GPS fix at 20220805T195532: (46.857778, -91.103784)
2022-08-05T19:55:33.949Z,1659729333.949 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T19:55:33.949Z,1659729333.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T19:55:40.887Z,1659729340.887 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0022.lzma
2022-08-05T19:55:41.890Z,1659729341.890 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0022.lzma.bak
2022-08-05T19:55:41.890Z,1659729341.890 [DataOverHttps](INFO): SBD MOMSN=17090453
2022-08-05T19:55:55.374Z,1659729355.374 [NAL9602](INFO): SBD MO Status=0, MOMSN=7800, MT Status=0, MTMSN=0
2022-08-05T19:55:55.375Z,1659729355.375 [NAL9602](INFO): No messages in MT queue
2022-08-05T19:55:57.796Z,1659729357.796 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20220805T191607/Express0023.lzma
2022-08-05T19:55:58.798Z,1659729358.798 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0023.lzma.bak
2022-08-05T19:55:58.798Z,1659729358.798 [DataOverHttps](INFO): SBD MOMSN=17090456
2022-08-05T19:56:00.244Z,1659729360.244 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T19:56:00.244Z,1659729360.244 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T19:56:00.244Z,1659729360.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T19:56:26.095Z,1659729386.095 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T19:59:40.993Z,1659729580.993 [DataOverHttps](IMPORTANT): SBD MTMSN=20220805T195939
2022-08-05T19:59:48.436Z,1659729588.436 [DataOverHttps](INFO): Received command: configSet verticalControl.buoyancyNeutral 200 cc persist;configSet verticalControl.massDefault 1.75 cm persist
2022-08-05T19:59:49.214Z,1659729589.214 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 200 cubic_centimeter persist
2022-08-05T19:59:49.220Z,1659729589.220 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.75 centimeter persist
2022-08-05T20:01:00.926Z,1659729660.926 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:01:00.926Z,1659729660.926 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:01:00.926Z,1659729660.926 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:01:00.926Z,1659729660.926 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:01:01.331Z,1659729661.331 [Default:CheckIn:D] Stopped
2022-08-05T20:01:01.331Z,1659729661.331 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:01:01.747Z,1659729661.747 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.954399 min 
2022-08-05T20:01:01.747Z,1659729661.747 [Default:CheckIn:E] Stopped
2022-08-05T20:01:01.747Z,1659729661.747 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:01:01.747Z,1659729661.747 [Default:CheckIn] Stopped
2022-08-05T20:01:01.747Z,1659729661.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:01:01.752Z,1659729661.752 [Default:CheckIn](INFO): Running loop #8
2022-08-05T20:01:01.752Z,1659729661.752 [Default:CheckIn] Running Loop=8
2022-08-05T20:01:01.752Z,1659729661.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:01:01.752Z,1659729661.752 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:01:03.751Z,1659729663.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200102.00,A,4651.46648,N,09106.22714,W,0.058,0.00,050822,,,D*7A
2022-08-05T20:01:03.753Z,1659729663.753 [NAL9602](INFO): GPS fix at 20220805T200102: (46.857775, -91.103786)
2022-08-05T20:01:03.797Z,1659729663.797 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:01:03.797Z,1659729663.797 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:01:13.608Z,1659729673.608 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0025.lzma
2022-08-05T20:01:14.611Z,1659729674.611 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0025.lzma.bak
2022-08-05T20:01:14.611Z,1659729674.611 [DataOverHttps](INFO): SBD MOMSN=17090463
2022-08-05T20:01:15.098Z,1659729675.098 [NAL9602](INFO): SBD MO Status=0, MOMSN=7801, MT Status=0, MTMSN=0
2022-08-05T20:01:15.099Z,1659729675.099 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:01:30.452Z,1659729690.452 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20220805T191607/Express0026.lzma
2022-08-05T20:01:31.454Z,1659729691.454 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0026.lzma.bak
2022-08-05T20:01:31.454Z,1659729691.454 [DataOverHttps](INFO): SBD MOMSN=17090467
2022-08-05T20:01:32.893Z,1659729692.893 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:01:32.893Z,1659729692.893 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:01:32.894Z,1659729692.894 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:01:45.800Z,1659729705.800 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:06:33.527Z,1659729993.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:06:33.527Z,1659729993.527 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:06:33.527Z,1659729993.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:06:33.527Z,1659729993.527 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:06:33.938Z,1659729993.938 [Default:CheckIn:D] Stopped
2022-08-05T20:06:33.938Z,1659729993.938 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:06:34.334Z,1659729994.334 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.497847 min 
2022-08-05T20:06:34.334Z,1659729994.334 [Default:CheckIn:E] Stopped
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn] Stopped
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn](INFO): Running loop #9
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn] Running Loop=9
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:06:34.335Z,1659729994.335 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:06:36.347Z,1659729996.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200635.00,A,4651.46675,N,09106.22822,W,0.175,0.00,050822,,,D*73
2022-08-05T20:06:36.349Z,1659729996.349 [NAL9602](INFO): GPS fix at 20220805T200635: (46.857779, -91.103804)
2022-08-05T20:06:36.359Z,1659729996.359 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:06:36.359Z,1659729996.359 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:06:43.739Z,1659730003.739 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0028.lzma
2022-08-05T20:06:44.742Z,1659730004.742 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0028.lzma.bak
2022-08-05T20:06:44.742Z,1659730004.742 [DataOverHttps](INFO): SBD MOMSN=17090475
2022-08-05T20:06:52.102Z,1659730012.102 [NAL9602](INFO): SBD MO Status=0, MOMSN=7802, MT Status=0, MTMSN=0
2022-08-05T20:06:52.102Z,1659730012.102 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:07:01.467Z,1659730021.467 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20220805T191607/Express0030.lzma
2022-08-05T20:07:02.470Z,1659730022.470 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0030.lzma.bak
2022-08-05T20:07:02.470Z,1659730022.470 [DataOverHttps](INFO): SBD MOMSN=17090479
2022-08-05T20:07:03.845Z,1659730023.845 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:07:03.845Z,1659730023.845 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:07:03.845Z,1659730023.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:07:22.873Z,1659730042.873 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:10:12.036Z,1659730212.036 [CommandExec](IMPORTANT): got command get DAT.loadAtStartup
2022-08-05T20:10:12.037Z,1659730212.037 [CommandExec](IMPORTANT): DAT.loadAtStartup 1 bool
2022-08-05T20:12:04.515Z,1659730324.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:12:04.515Z,1659730324.515 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:12:04.515Z,1659730324.515 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:12:04.515Z,1659730324.515 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:12:04.926Z,1659730324.926 [Default:CheckIn:D] Stopped
2022-08-05T20:12:04.926Z,1659730324.926 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:12:05.322Z,1659730325.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.014315 min 
2022-08-05T20:12:05.322Z,1659730325.322 [Default:CheckIn:E] Stopped
2022-08-05T20:12:05.322Z,1659730325.322 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:12:05.323Z,1659730325.323 [Default:CheckIn] Stopped
2022-08-05T20:12:05.323Z,1659730325.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:12:05.323Z,1659730325.323 [Default:CheckIn](INFO): Running loop #10
2022-08-05T20:12:05.323Z,1659730325.323 [Default:CheckIn] Running Loop=10
2022-08-05T20:12:05.323Z,1659730325.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:12:05.323Z,1659730325.323 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:12:07.335Z,1659730327.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201206.00,A,4651.46606,N,09106.22728,W,0.039,0.00,050822,,,D*7E
2022-08-05T20:12:07.337Z,1659730327.337 [NAL9602](INFO): GPS fix at 20220805T201206: (46.857768, -91.103788)
2022-08-05T20:12:07.347Z,1659730327.347 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:12:07.347Z,1659730327.347 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:12:14.915Z,1659730334.915 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0034.lzma
2022-08-05T20:12:15.918Z,1659730335.918 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0034.lzma.bak
2022-08-05T20:12:15.918Z,1659730335.918 [DataOverHttps](INFO): SBD MOMSN=17090485
2022-08-05T20:12:20.690Z,1659730340.690 [NAL9602](INFO): SBD MO Status=0, MOMSN=7803, MT Status=0, MTMSN=0
2022-08-05T20:12:20.691Z,1659730340.691 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:12:35.155Z,1659730355.155 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20220805T191607/Express0035.lzma
2022-08-05T20:12:36.567Z,1659730356.567 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0035.lzma.bak
2022-08-05T20:12:36.567Z,1659730356.567 [DataOverHttps](INFO): SBD MOMSN=17090488
2022-08-05T20:12:37.714Z,1659730357.714 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:12:37.714Z,1659730357.714 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:12:37.714Z,1659730357.714 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:12:51.421Z,1659730371.421 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:17:38.325Z,1659730658.325 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:17:38.325Z,1659730658.325 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:17:38.326Z,1659730658.326 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:17:38.326Z,1659730658.326 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:17:38.727Z,1659730658.727 [Default:CheckIn:D] Stopped
2022-08-05T20:17:38.727Z,1659730658.727 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:17:39.132Z,1659730659.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.577665 min 
2022-08-05T20:17:39.132Z,1659730659.132 [Default:CheckIn:E] Stopped
2022-08-05T20:17:39.132Z,1659730659.132 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:17:39.132Z,1659730659.132 [Default:CheckIn] Stopped
2022-08-05T20:17:39.133Z,1659730659.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:17:39.133Z,1659730659.133 [Default:CheckIn](INFO): Running loop #11
2022-08-05T20:17:39.133Z,1659730659.133 [Default:CheckIn] Running Loop=11
2022-08-05T20:17:39.133Z,1659730659.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:17:39.133Z,1659730659.133 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:17:41.161Z,1659730661.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201739.00,A,4651.46662,N,09106.22782,W,0.039,0.00,050822,,,D*75
2022-08-05T20:17:41.163Z,1659730661.163 [NAL9602](INFO): GPS fix at 20220805T201739: (46.857777, -91.103797)
2022-08-05T20:17:41.198Z,1659730661.198 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:17:41.198Z,1659730661.198 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:17:48.167Z,1659730668.167 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0037.lzma
2022-08-05T20:17:49.170Z,1659730669.170 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0037.lzma.bak
2022-08-05T20:17:49.170Z,1659730669.170 [DataOverHttps](INFO): SBD MOMSN=17090495
2022-08-05T20:17:57.722Z,1659730677.722 [NAL9602](INFO): SBD MO Status=0, MOMSN=7804, MT Status=0, MTMSN=0
2022-08-05T20:17:57.723Z,1659730677.723 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:18:04.971Z,1659730684.971 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220805T191607/Express0038.lzma
2022-08-05T20:18:05.974Z,1659730685.974 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0038.lzma.bak
2022-08-05T20:18:05.974Z,1659730685.974 [DataOverHttps](INFO): SBD MOMSN=17090498
2022-08-05T20:18:07.433Z,1659730687.433 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:18:07.433Z,1659730687.433 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:18:07.433Z,1659730687.433 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:18:28.421Z,1659730708.421 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:23:08.099Z,1659730988.099 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:23:08.099Z,1659730988.099 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:23:08.099Z,1659730988.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:23:08.099Z,1659730988.099 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:23:08.490Z,1659730988.490 [Default:CheckIn:D] Stopped
2022-08-05T20:23:08.490Z,1659730988.490 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.073714 min 
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn:E] Stopped
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn] Stopped
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn](INFO): Running loop #12
2022-08-05T20:23:08.909Z,1659730988.909 [Default:CheckIn] Running Loop=12
2022-08-05T20:23:08.910Z,1659730988.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:23:08.910Z,1659730988.910 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:23:10.907Z,1659730990.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202309.00,A,4651.46714,N,09106.22787,W,0.019,0.00,050822,,,D*76
2022-08-05T20:23:10.909Z,1659730990.909 [NAL9602](INFO): GPS fix at 20220805T202309: (46.857786, -91.103798)
2022-08-05T20:23:10.919Z,1659730990.919 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:23:10.919Z,1659730990.919 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:23:18.447Z,1659730998.447 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0040.lzma
2022-08-05T20:23:19.450Z,1659730999.450 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0040.lzma.bak
2022-08-05T20:23:19.450Z,1659730999.450 [DataOverHttps](INFO): SBD MOMSN=17090504
2022-08-05T20:23:34.338Z,1659731014.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=7805, MT Status=2, MTMSN=0
2022-08-05T20:23:34.339Z,1659731014.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-05T20:23:35.307Z,1659731015.307 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20220805T191607/Express0041.lzma
2022-08-05T20:23:36.310Z,1659731016.310 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0041.lzma.bak
2022-08-05T20:23:36.310Z,1659731016.310 [DataOverHttps](INFO): SBD MOMSN=17090507
2022-08-05T20:23:37.995Z,1659731017.995 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:23:37.995Z,1659731017.995 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:23:37.995Z,1659731017.995 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:23:50.099Z,1659731030.099 [NAL9602](INFO): SBD MO Status=0, MOMSN=7805, MT Status=0, MTMSN=0
2022-08-05T20:23:50.099Z,1659731030.099 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:24:20.805Z,1659731060.805 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:28:38.631Z,1659731318.631 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:28:38.631Z,1659731318.631 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:28:38.631Z,1659731318.631 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:28:38.631Z,1659731318.631 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:28:39.039Z,1659731319.039 [Default:CheckIn:D] Stopped
2022-08-05T20:28:39.039Z,1659731319.039 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:28:39.438Z,1659731319.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.582865 min 
2022-08-05T20:28:39.438Z,1659731319.438 [Default:CheckIn:E] Stopped
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn] Stopped
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn](INFO): Running loop #13
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn] Running Loop=13
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:28:39.439Z,1659731319.439 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:28:41.451Z,1659731321.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202840.00,A,4651.46756,N,09106.22774,W,0.058,0.00,050822,,,D*7F
2022-08-05T20:28:41.453Z,1659731321.453 [NAL9602](INFO): GPS fix at 20220805T202840: (46.857793, -91.103796)
2022-08-05T20:28:41.463Z,1659731321.463 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:28:41.463Z,1659731321.463 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:28:48.851Z,1659731328.851 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0043.lzma
2022-08-05T20:28:49.854Z,1659731329.854 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0043.lzma.bak
2022-08-05T20:28:49.854Z,1659731329.854 [DataOverHttps](INFO): SBD MOMSN=17090511
2022-08-05T20:28:55.586Z,1659731335.586 [NAL9602](INFO): SBD MO Status=0, MOMSN=7806, MT Status=0, MTMSN=0
2022-08-05T20:28:55.587Z,1659731335.587 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:29:06.875Z,1659731346.875 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20220805T191607/Express0044.lzma
2022-08-05T20:29:07.878Z,1659731347.878 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0044.lzma.bak
2022-08-05T20:29:07.878Z,1659731347.878 [DataOverHttps](INFO): SBD MOMSN=17090514
2022-08-05T20:29:09.788Z,1659731349.788 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:29:09.788Z,1659731349.788 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:29:09.788Z,1659731349.788 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:29:26.377Z,1659731366.377 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:34:10.486Z,1659731650.486 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:34:10.486Z,1659731650.486 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:34:10.486Z,1659731650.486 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:34:10.486Z,1659731650.486 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:34:10.887Z,1659731650.887 [Default:CheckIn:D] Stopped
2022-08-05T20:34:10.887Z,1659731650.887 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.113656 min 
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn:E] Stopped
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn] Stopped
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn](INFO): Running loop #14
2022-08-05T20:34:11.299Z,1659731651.299 [Default:CheckIn] Running Loop=14
2022-08-05T20:34:11.300Z,1659731651.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:34:11.300Z,1659731651.300 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:34:13.302Z,1659731653.302 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203412.00,A,4651.47069,N,09106.23051,W,0.039,341.57,050822,,,D*7D
2022-08-05T20:34:13.304Z,1659731653.304 [NAL9602](INFO): GPS fix at 20220805T203412: (46.857845, -91.103842)
2022-08-05T20:34:13.315Z,1659731653.315 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:34:13.315Z,1659731653.315 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:34:21.647Z,1659731661.647 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220805T191607/Courier0046.lzma
2022-08-05T20:34:22.658Z,1659731662.658 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0046.lzma.bak
2022-08-05T20:34:22.658Z,1659731662.658 [DataOverHttps](INFO): SBD MOMSN=17090518
2022-08-05T20:34:37.579Z,1659731677.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=7807, MT Status=0, MTMSN=0
2022-08-05T20:34:37.579Z,1659731677.579 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:34:40.932Z,1659731680.932 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20220805T191607/Express0047.lzma
2022-08-05T20:34:41.934Z,1659731681.934 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0047.lzma.bak
2022-08-05T20:34:41.934Z,1659731681.934 [DataOverHttps](INFO): SBD MOMSN=17090521
2022-08-05T20:34:43.252Z,1659731683.252 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:34:43.253Z,1659731683.253 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:34:43.253Z,1659731683.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:35:08.281Z,1659731708.281 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:39:43.959Z,1659731983.959 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:39:43.959Z,1659731983.959 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:39:43.959Z,1659731983.959 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:39:43.959Z,1659731983.959 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:39:44.355Z,1659731984.355 [Default:CheckIn:D] Stopped
2022-08-05T20:39:44.355Z,1659731984.355 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.671460 min 
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn:E] Stopped
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn] Stopped
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn](INFO): Running loop #15
2022-08-05T20:39:44.759Z,1659731984.759 [Default:CheckIn] Running Loop=15
2022-08-05T20:39:44.760Z,1659731984.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:39:44.760Z,1659731984.760 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:39:46.767Z,1659731986.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203945.00,A,4651.46988,N,09106.23118,W,0.097,341.57,050822,,,D*7D
2022-08-05T20:39:46.769Z,1659731986.769 [NAL9602](INFO): GPS fix at 20220805T203945: (46.857831, -91.103853)
2022-08-05T20:39:46.780Z,1659731986.780 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:39:46.780Z,1659731986.780 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:39:54.589Z,1659731994.589 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0049.lzma
2022-08-05T20:39:55.590Z,1659731995.590 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0049.lzma.bak
2022-08-05T20:39:55.590Z,1659731995.590 [DataOverHttps](INFO): SBD MOMSN=17090525
2022-08-05T20:39:59.290Z,1659731999.290 [NAL9602](INFO): SBD MO Status=0, MOMSN=7808, MT Status=0, MTMSN=0
2022-08-05T20:39:59.291Z,1659731999.291 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:40:11.415Z,1659732011.415 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20220805T191607/Express0050.lzma
2022-08-05T20:40:12.419Z,1659732012.419 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0050.lzma.bak
2022-08-05T20:40:12.419Z,1659732012.419 [DataOverHttps](INFO): SBD MOMSN=17090528
2022-08-05T20:40:13.858Z,1659732013.858 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:40:13.858Z,1659732013.858 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:40:13.859Z,1659732013.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:40:30.001Z,1659732030.001 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:45:14.443Z,1659732314.443 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:45:14.443Z,1659732314.443 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:45:14.444Z,1659732314.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:45:14.444Z,1659732314.444 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:45:14.846Z,1659732314.846 [Default:CheckIn:D] Stopped
2022-08-05T20:45:14.846Z,1659732314.846 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:45:15.251Z,1659732315.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.179647 min 
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn:E] Stopped
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn] Stopped
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn](INFO): Running loop #16
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn] Running Loop=16
2022-08-05T20:45:15.252Z,1659732315.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:45:15.253Z,1659732315.253 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:45:17.259Z,1659732317.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204516.00,A,4651.47086,N,09106.23123,W,0.019,341.57,050822,,,D*78
2022-08-05T20:45:17.262Z,1659732317.262 [NAL9602](INFO): GPS fix at 20220805T204516: (46.857848, -91.103854)
2022-08-05T20:45:17.288Z,1659732317.288 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:45:17.288Z,1659732317.288 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:45:24.559Z,1659732324.559 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0052.lzma
2022-08-05T20:45:25.562Z,1659732325.562 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0052.lzma.bak
2022-08-05T20:45:25.562Z,1659732325.562 [DataOverHttps](INFO): SBD MOMSN=17090532
2022-08-05T20:45:41.927Z,1659732341.927 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220805T191607/Express0053.lzma
2022-08-05T20:45:42.930Z,1659732342.930 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0053.lzma.bak
2022-08-05T20:45:42.930Z,1659732342.930 [DataOverHttps](INFO): SBD MOMSN=17090535
2022-08-05T20:45:44.344Z,1659732344.344 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:45:44.344Z,1659732344.344 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:45:44.344Z,1659732344.344 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:45:45.543Z,1659732345.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=7809, MT Status=0, MTMSN=0
2022-08-05T20:45:45.543Z,1659732345.543 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:46:16.245Z,1659732376.245 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:49:47.667Z,1659732587.667 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:50:00.924Z,1659732600.924 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:50:14.329Z,1659732614.329 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:50:27.999Z,1659732627.999 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:50:40.922Z,1659732640.922 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:50:44.977Z,1659732644.977 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:50:44.977Z,1659732644.977 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:50:44.977Z,1659732644.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:50:44.977Z,1659732644.977 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:50:45.378Z,1659732645.378 [Default:CheckIn:D] Stopped
2022-08-05T20:50:45.379Z,1659732645.379 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:50:45.788Z,1659732645.788 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.688517 min 
2022-08-05T20:50:45.788Z,1659732645.788 [Default:CheckIn:E] Stopped
2022-08-05T20:50:45.788Z,1659732645.788 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:50:45.789Z,1659732645.789 [Default:CheckIn] Stopped
2022-08-05T20:50:45.789Z,1659732645.789 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:50:45.789Z,1659732645.789 [Default:CheckIn](INFO): Running loop #17
2022-08-05T20:50:45.789Z,1659732645.789 [Default:CheckIn] Running Loop=17
2022-08-05T20:50:45.789Z,1659732645.789 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:50:45.789Z,1659732645.789 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:50:47.796Z,1659732647.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205046.00,A,4651.47068,N,09106.23173,W,0.039,341.57,050822,,,D*7E
2022-08-05T20:50:47.798Z,1659732647.798 [NAL9602](INFO): GPS fix at 20220805T205046: (46.857845, -91.103862)
2022-08-05T20:50:47.824Z,1659732647.824 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:50:47.824Z,1659732647.824 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:50:54.773Z,1659732654.773 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:50:55.795Z,1659732655.795 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220805T191607/Courier0055.lzma
2022-08-05T20:50:56.798Z,1659732656.798 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0055.lzma.bak
2022-08-05T20:50:56.798Z,1659732656.798 [DataOverHttps](INFO): SBD MOMSN=17090541
2022-08-05T20:51:08.087Z,1659732668.087 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:51:08.831Z,1659732668.831 [NAL9602](INFO): SBD MO Status=2, MOMSN=7810, MT Status=2, MTMSN=0
2022-08-05T20:51:08.831Z,1659732668.831 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-05T20:51:16.040Z,1659732676.040 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20220805T191607/Express0056.lzma
2022-08-05T20:51:17.042Z,1659732677.042 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0056.lzma.bak
2022-08-05T20:51:17.042Z,1659732677.042 [DataOverHttps](INFO): SBD MOMSN=17090544
2022-08-05T20:51:18.551Z,1659732678.551 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:51:18.551Z,1659732678.551 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:51:18.552Z,1659732678.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:51:18.947Z,1659732678.947 [NAL9602](INFO): SBD MO Status=0, MOMSN=7810, MT Status=0, MTMSN=0
2022-08-05T20:51:18.947Z,1659732678.947 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:51:21.775Z,1659732681.775 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:51:35.107Z,1659732695.107 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:51:48.446Z,1659732708.446 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:51:49.649Z,1659732709.649 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-05T20:52:01.770Z,1659732721.770 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:52:15.105Z,1659732735.105 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-08-05T20:56:19.277Z,1659732979.277 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-05T20:56:19.278Z,1659732979.278 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:56:19.278Z,1659732979.278 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:56:19.278Z,1659732979.278 [Default:CheckIn:D] Running Loop=1
2022-08-05T20:56:19.688Z,1659732979.688 [Default:CheckIn:D] Stopped
2022-08-05T20:56:19.688Z,1659732979.688 [Default:CheckIn:E] Running Loop=1
2022-08-05T20:56:20.099Z,1659732980.099 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.260343 min 
2022-08-05T20:56:20.099Z,1659732980.099 [Default:CheckIn:E] Stopped
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn] Stopped
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn](INFO): Running loop #18
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn] Running Loop=18
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-05T20:56:20.109Z,1659732980.109 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-05T20:56:22.090Z,1659732982.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205620.00,A,4651.47021,N,09106.23099,W,0.039,341.57,050822,,,D*70
2022-08-05T20:56:22.093Z,1659732982.093 [NAL9602](INFO): GPS fix at 20220805T205620: (46.857837, -91.103850)
2022-08-05T20:56:22.111Z,1659732982.111 [Default:CheckIn:Read_GPS] Stopped
2022-08-05T20:56:22.111Z,1659732982.111 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-05T20:56:31.666Z,1659732991.666 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220805T191607/Courier0058.lzma
2022-08-05T20:56:32.666Z,1659732992.666 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Courier0058.lzma.bak
2022-08-05T20:56:32.666Z,1659732992.666 [DataOverHttps](INFO): SBD MOMSN=17090549
2022-08-05T20:56:48.552Z,1659733008.552 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20220805T191607/Express0059.lzma
2022-08-05T20:56:49.546Z,1659733009.546 [DataOverHttps](INFO): Moved sent file to Logs/20220805T191607/Express0059.lzma.bak
2022-08-05T20:56:49.546Z,1659733009.546 [DataOverHttps](INFO): SBD MOMSN=17090552
2022-08-05T20:56:51.061Z,1659733011.061 [Default:CheckIn:Read_Iridium] Stopped
2022-08-05T20:56:51.062Z,1659733011.062 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-05T20:56:51.062Z,1659733011.062 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-05T20:58:11.338Z,1659733091.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=7811, MT Status=2, MTMSN=0
2022-08-05T20:58:11.338Z,1659733091.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-05T20:59:08.300Z,1659733148.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=7811, MT Status=0, MTMSN=0
2022-08-05T20:59:08.300Z,1659733148.300 [NAL9602](INFO): No messages in MT queue
2022-08-05T20:59:20.923Z,1659733160.923 [DataOverHttps](IMPORTANT): SBD MTMSN=20220805T205920
2022-08-05T20:59:28.390Z,1659733168.390 [DataOverHttps](INFO): Received command: restart app
2022-08-05T20:59:28.392Z,1659733168.392 [CommandExec](IMPORTANT): got command restart application
2022-08-05T20:59:29.394Z,1659733169.394 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-08-05T20:59:29.395Z,1659733169.395 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:29.395Z,1659733169.395 [CommandExec](INFO): Uninitializing the command executive.
2022-08-05T20:59:29.395Z,1659733169.395 [CommandExec](INFO): Uninitializing the command scheduler.
2022-08-05T20:59:29.395Z,1659733169.395 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:29.462Z,1659733169.462 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-08-05T20:59:29.462Z,1659733169.462 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-08-05T20:59:29.462Z,1659733169.462 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:29.463Z,1659733169.463 [NavChartDb](INFO): Join timeout helper Thread ID is 1548
2022-08-05T20:59:29.542Z,1659733169.542 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:29.543Z,1659733169.543 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:29.562Z,1659733169.562 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-08-05T20:59:29.562Z,1659733169.562 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:29.563Z,1659733169.563 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1549
2022-08-05T20:59:30.062Z,1659733170.062 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:30.063Z,1659733170.063 [WetLabsBB2FL](INFO): Powering down
2022-08-05T20:59:30.063Z,1659733170.063 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.074Z,1659733170.074 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-08-05T20:59:30.074Z,1659733170.074 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.075Z,1659733170.075 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1550
2022-08-05T20:59:30.314Z,1659733170.314 [CTD_Seabird](INFO): Powering down
2022-08-05T20:59:30.326Z,1659733170.326 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:30.326Z,1659733170.326 [CTD_Seabird](INFO): Powering down
2022-08-05T20:59:30.338Z,1659733170.338 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.342Z,1659733170.342 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-08-05T20:59:30.342Z,1659733170.342 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.343Z,1659733170.343 [DAT](INFO): Join timeout helper Thread ID is 1551
2022-08-05T20:59:30.386Z,1659733170.386 [DAT](INFO): Powering down
2022-08-05T20:59:30.459Z,1659733170.459 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:30.459Z,1659733170.459 [DAT](INFO): Powering down
2022-08-05T20:59:30.460Z,1659733170.460 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.471Z,1659733170.471 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-08-05T20:59:30.471Z,1659733170.471 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.471Z,1659733170.471 [Radio_Surface](INFO): Join timeout helper Thread ID is 1552
2022-08-05T20:59:30.758Z,1659733170.758 [Radio_Surface](INFO): Powering down
2022-08-05T20:59:30.759Z,1659733170.759 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:30.759Z,1659733170.759 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.771Z,1659733170.771 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-08-05T20:59:30.771Z,1659733170.771 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:30.772Z,1659733170.772 [Onboard](INFO): Join timeout helper Thread ID is 1553
2022-08-05T20:59:31.398Z,1659733171.398 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-08-05T20:59:34.054Z,1659733174.054 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:34.055Z,1659733174.055 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.063Z,1659733174.063 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-08-05T20:59:34.063Z,1659733174.063 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.063Z,1659733174.063 [DataOverHttps](INFO): Join timeout helper Thread ID is 1554
2022-08-05T20:59:34.410Z,1659733174.410 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:34.411Z,1659733174.411 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.431Z,1659733174.431 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-08-05T20:59:34.431Z,1659733174.431 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.431Z,1659733174.431 [BackseatComponent](INFO): Join timeout helper Thread ID is 1555
2022-08-05T20:59:34.466Z,1659733174.466 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:34.467Z,1659733174.467 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.471Z,1659733174.471 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-08-05T20:59:34.471Z,1659733174.471 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.472Z,1659733174.472 [logger](INFO): Join timeout helper Thread ID is 1556
2022-08-05T20:59:34.486Z,1659733174.486 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:34.487Z,1659733174.487 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.491Z,1659733174.491 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-08-05T20:59:34.491Z,1659733174.491 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.491Z,1659733174.491 [CommandLine](INFO): Join timeout helper Thread ID is 1557
2022-08-05T20:59:34.570Z,1659733174.570 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:34.571Z,1659733174.571 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.575Z,1659733174.575 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-08-05T20:59:34.575Z,1659733174.575 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.576Z,1659733174.576 [CommandExec](INFO): Join timeout helper Thread ID is 1558
2022-08-05T20:59:34.579Z,1659733174.579 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-08-05T20:59:34.579Z,1659733174.579 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.579Z,1659733174.579 [controlThread](INFO): Join timeout helper Thread ID is 1559
2022-08-05T20:59:34.600Z,1659733174.600 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-05T20:59:34.600Z,1659733174.600 [controlThread](DEBUG): Uninitializing ControlThread
2022-08-05T20:59:34.600Z,1659733174.600 [AHRS_M2](INFO): Powering down
2022-08-05T20:59:34.671Z,1659733174.671 [NAL9602](INFO): Powering down
2022-08-05T20:59:34.673Z,1659733174.673 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-08-05T20:59:34.674Z,1659733174.674 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-08-05T20:59:34.674Z,1659733174.674 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-08-05T20:59:34.675Z,1659733174.675 [MissionManager](INFO): Uninitializing Mission Default
2022-08-05T20:59:34.675Z,1659733174.675 [Default] Stopped
2022-08-05T20:59:34.675Z,1659733174.675 [Default](DEBUG): Aggregate::uninitialize Default
2022-08-05T20:59:34.675Z,1659733174.675 [Default:B.GoToSurface] Stopped
2022-08-05T20:59:34.675Z,1659733174.675 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-05T20:59:34.675Z,1659733174.675 [Default:CheckIn] Stopped
2022-08-05T20:59:34.675Z,1659733174.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-05T20:59:34.676Z,1659733174.676 [Default:CheckIn:C.Wait] Stopped
2022-08-05T20:59:34.676Z,1659733174.676 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-05T20:59:34.678Z,1659733174.678 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-08-05T20:59:34.679Z,1659733174.679 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-08-05T20:59:34.679Z,1659733174.679 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-08-05T20:59:34.679Z,1659733174.679 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-08-05T20:59:34.680Z,1659733174.680 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-08-05T20:59:34.680Z,1659733174.680 [BuoyancyServo](INFO): Powering down
2022-08-05T20:59:34.694Z,1659733174.694 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-08-05T20:59:34.694Z,1659733174.694 [ElevatorServo](INFO): Powering down
2022-08-05T20:59:34.695Z,1659733174.695 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-08-05T20:59:34.695Z,1659733174.695 [MassServo](INFO): Powering down
2022-08-05T20:59:34.696Z,1659733174.696 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-05T20:59:34.696Z,1659733174.696 [RudderServo](INFO): Powering down
2022-08-05T20:59:34.697Z,1659733174.697 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-08-05T20:59:34.697Z,1659733174.697 [ThrusterHE](INFO): Powering down
2022-08-05T20:59:34.698Z,1659733174.698 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-08-05T20:59:34.698Z,1659733174.698 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-08-05T20:59:34.699Z,1659733174.699 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-08-05T20:59:34.699Z,1659733174.699 [CBIT](DEBUG): Powering off loads.
2022-08-05T20:59:34.710Z,1659733174.710 [CBIT](DEBUG): Disabling WDT.
2022-08-05T20:59:34.722Z,1659733174.722 [CBIT](DEBUG): Opening all GF detection circuits.
2022-08-05T20:59:34.723Z,1659733174.723 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.781Z,1659733174.781 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.786Z,1659733174.786 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.788Z,1659733174.788 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.794Z,1659733174.794 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.837Z,1659733174.837 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.873Z,1659733174.873 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.876Z,1659733174.876 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.908Z,1659733174.908 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-05T20:59:34.967Z,1659733174.967 [logger ThreadHandler](INFO): Thread cancelled.