2023-11-06T17:19:31.019Z,1699291171.019 [Supervisor](DEBUG): Initializing supervisor.
2023-11-06T17:19:31.023Z,1699291171.023 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-11-06T17:19:31.024Z,1699291171.024 [SyncHandler](INFO): Protected caller Thread ID is 4836
2023-11-06T17:19:31.024Z,1699291171.024 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-11-06T17:19:31.025Z,1699291171.025 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-11-06T17:19:31.025Z,1699291171.025 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4837
2023-11-06T17:19:31.029Z,1699291171.029 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-11-06T17:19:31.047Z,1699291171.047 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-11-06T17:19:31.048Z,1699291171.048 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-11-06T17:19:31.049Z,1699291171.049 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4838
2023-11-06T17:19:31.053Z,1699291171.053 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-11-06T17:19:31.054Z,1699291171.054 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-11-06T17:19:31.054Z,1699291171.054 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4839
2023-11-06T17:19:31.056Z,1699291171.056 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-11-06T17:19:31.057Z,1699291171.057 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-11-06T17:19:31.057Z,1699291171.057 [logger ThreadHandler](INFO): Protected caller Thread ID is 4840
2023-11-06T17:19:31.061Z,1699291171.061 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-11-06T17:19:31.061Z,1699291171.061 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-11-06T17:19:31.067Z,1699291171.067 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-11-06T17:19:31.229Z,1699291171.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-11-06T17:19:31.231Z,1699291171.231 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-11-06T17:19:31.905Z,1699291171.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-11-06T17:19:31.907Z,1699291171.907 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-11-06T17:19:32.120Z,1699291172.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-11-06T17:19:32.122Z,1699291172.122 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-11-06T17:19:32.204Z,1699291172.204 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-11-06T17:19:32.336Z,1699291172.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-11-06T17:19:32.337Z,1699291172.337 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-11-06T17:19:32.423Z,1699291172.423 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-11-06T17:19:32.533Z,1699291172.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-11-06T17:19:32.533Z,1699291172.533 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-11-06T17:19:32.975Z,1699291172.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-11-06T17:19:32.977Z,1699291172.977 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-11-06T17:19:33.445Z,1699291173.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-11-06T17:19:33.446Z,1699291173.446 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-11-06T17:19:33.656Z,1699291173.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-11-06T17:19:33.658Z,1699291173.658 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-11-06T17:19:33.840Z,1699291173.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-11-06T17:19:33.840Z,1699291173.840 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-11-06T17:19:34.335Z,1699291174.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-11-06T17:19:34.337Z,1699291174.337 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-11-06T17:19:34.658Z,1699291174.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-11-06T17:19:34.858Z,1699291174.858 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-11-06T17:19:35.193Z,1699291175.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-11-06T17:19:35.194Z,1699291175.194 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-11-06T17:19:35.651Z,1699291175.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-11-06T17:19:35.652Z,1699291175.652 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-11-06T17:19:35.974Z,1699291175.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-11-06T17:19:35.976Z,1699291175.976 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-11-06T17:19:35.979Z,1699291175.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-11-06T17:19:36.064Z,1699291176.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-11-06T17:19:36.228Z,1699291176.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-11-06T17:19:36.333Z,1699291176.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-11-06T17:19:36.422Z,1699291176.422 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-11-06T17:19:36.518Z,1699291176.518 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-11-06T17:19:36.633Z,1699291176.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-11-06T17:19:36.899Z,1699291176.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-11-06T17:19:36.899Z,1699291176.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-11-06T17:19:36.995Z,1699291176.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-11-06T17:19:37.095Z,1699291177.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-11-06T17:19:37.233Z,1699291177.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-11-06T17:19:37.333Z,1699291177.333 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-11-06T17:19:37.350Z,1699291177.350 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-11-06T17:19:37.472Z,1699291177.472 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-11-06T17:19:37.474Z,1699291177.474 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-11-06T17:19:37.541Z,1699291177.541 [VerticalControl](DEBUG): Construct VerticalControl.
2023-11-06T17:19:37.599Z,1699291177.599 [VerticalControl] Loaded
2023-11-06T17:19:37.599Z,1699291177.599 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-11-06T17:19:37.602Z,1699291177.602 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-11-06T17:19:37.642Z,1699291177.642 [HorizontalControl] Loaded
2023-11-06T17:19:37.642Z,1699291177.642 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-11-06T17:19:37.645Z,1699291177.645 [SpeedControl](DEBUG): Construct SpeedControl.
2023-11-06T17:19:37.648Z,1699291177.648 [SpeedControl] Loaded
2023-11-06T17:19:37.648Z,1699291177.648 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-11-06T17:19:37.650Z,1699291177.650 [LoopControl](DEBUG): Construct LoopControl.
2023-11-06T17:19:37.651Z,1699291177.651 [LoopControl] Loaded
2023-11-06T17:19:37.651Z,1699291177.651 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-11-06T17:19:37.651Z,1699291177.651 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-11-06T17:19:37.653Z,1699291177.653 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-11-06T17:19:37.723Z,1699291177.723 [DepthRateCalculator] Loaded
2023-11-06T17:19:37.723Z,1699291177.723 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-11-06T17:19:37.728Z,1699291177.728 [PitchRateCalculator] Loaded
2023-11-06T17:19:37.728Z,1699291177.728 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-11-06T17:19:37.738Z,1699291177.738 [SpeedCalculator] Loaded
2023-11-06T17:19:37.738Z,1699291177.738 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-11-06T17:19:37.742Z,1699291177.742 [YawRateCalculator] Loaded
2023-11-06T17:19:37.743Z,1699291177.743 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-11-06T17:19:37.762Z,1699291177.762 [ElevatorOffsetCalculator] Loaded
2023-11-06T17:19:37.762Z,1699291177.762 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-11-06T17:19:37.763Z,1699291177.763 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-11-06T17:19:37.763Z,1699291177.763 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-11-06T17:19:37.806Z,1699291177.806 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-11-06T17:19:37.806Z,1699291177.806 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-11-06T17:19:37.890Z,1699291177.890 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-11-06T17:19:37.891Z,1699291177.891 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-11-06T17:19:38.244Z,1699291178.244 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-11-06T17:19:38.244Z,1699291178.244 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-11-06T17:19:38.422Z,1699291178.422 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-11-06T17:19:38.423Z,1699291178.423 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-11-06T17:19:39.211Z,1699291179.211 [AHRS_M2] Loaded
2023-11-06T17:19:39.211Z,1699291179.211 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-11-06T17:19:39.460Z,1699291179.460 [BackseatComponent] Loaded
2023-11-06T17:19:39.461Z,1699291179.461 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-11-06T17:19:39.462Z,1699291179.462 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2023-11-06T17:19:39.462Z,1699291179.462 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4922
2023-11-06T17:19:39.465Z,1699291179.465 [LcmUniversalReporter] Loaded
2023-11-06T17:19:39.465Z,1699291179.465 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-11-06T17:19:40.274Z,1699291180.274 [BPC1] Loaded
2023-11-06T17:19:40.274Z,1699291180.274 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-11-06T17:19:40.582Z,1699291180.582 [DataOverHttps] Loaded
2023-11-06T17:19:40.582Z,1699291180.582 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-11-06T17:19:40.583Z,1699291180.583 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2023-11-06T17:19:40.583Z,1699291180.583 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4923
2023-11-06T17:19:40.604Z,1699291180.604 [Depth_Keller] Loaded
2023-11-06T17:19:40.604Z,1699291180.604 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-11-06T17:19:40.609Z,1699291180.609 [DropWeight] Loaded
2023-11-06T17:19:40.609Z,1699291180.609 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-11-06T17:19:40.671Z,1699291180.671 [NAL9602] Loaded
2023-11-06T17:19:40.671Z,1699291180.671 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-11-06T17:19:40.701Z,1699291180.701 [Onboard] Loaded
2023-11-06T17:19:40.701Z,1699291180.701 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-11-06T17:19:40.702Z,1699291180.702 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2023-11-06T17:19:40.702Z,1699291180.702 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4924
2023-11-06T17:19:40.708Z,1699291180.708 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-11-06T17:19:40.720Z,1699291180.720 [PowerOnly] Loaded
2023-11-06T17:19:40.720Z,1699291180.720 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-11-06T17:19:40.731Z,1699291180.731 [Power24vConverter] Loaded
2023-11-06T17:19:40.731Z,1699291180.731 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-11-06T17:19:40.744Z,1699291180.744 [Radio_Surface] Loaded
2023-11-06T17:19:40.745Z,1699291180.745 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-11-06T17:19:40.746Z,1699291180.746 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0
2023-11-06T17:19:40.746Z,1699291180.746 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4925
2023-11-06T17:19:40.790Z,1699291180.790 [RDI_Pathfinder] Loaded
2023-11-06T17:19:40.790Z,1699291180.790 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-11-06T17:19:40.882Z,1699291180.882 [DAT] Loaded
2023-11-06T17:19:40.883Z,1699291180.883 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-11-06T17:19:40.884Z,1699291180.884 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2023-11-06T17:19:40.884Z,1699291180.884 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4926
2023-11-06T17:19:40.885Z,1699291180.885 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-11-06T17:19:40.885Z,1699291180.885 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-11-06T17:19:41.308Z,1699291181.308 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-11-06T17:19:41.309Z,1699291181.309 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-11-06T17:19:41.346Z,1699291181.346 [NavChart] Loaded
2023-11-06T17:19:41.347Z,1699291181.347 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-11-06T17:19:41.352Z,1699291181.352 [UniversalFixResidualReporter] Loaded
2023-11-06T17:19:41.352Z,1699291181.352 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-11-06T17:19:41.353Z,1699291181.353 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-11-06T17:19:41.402Z,1699291181.402 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-11-06T17:19:41.818Z,1699291181.818 [SBIT](DEBUG): Construct Startup Built In Test.
2023-11-06T17:19:41.827Z,1699291181.827 [SBIT] Loaded
2023-11-06T17:19:41.827Z,1699291181.827 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-11-06T17:19:41.830Z,1699291181.830 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-11-06T17:19:41.843Z,1699291181.843 [IBIT] Loaded
2023-11-06T17:19:41.843Z,1699291181.843 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-11-06T17:19:41.849Z,1699291181.849 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-11-06T17:19:41.951Z,1699291181.951 [CBIT] Loaded
2023-11-06T17:19:41.951Z,1699291181.951 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-11-06T17:19:41.952Z,1699291181.952 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-11-06T17:19:41.952Z,1699291181.952 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-11-06T17:19:42.111Z,1699291182.111 [BuoyancyServo] Loaded
2023-11-06T17:19:42.111Z,1699291182.111 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-11-06T17:19:42.132Z,1699291182.132 [ElevatorServo] Loaded
2023-11-06T17:19:42.132Z,1699291182.132 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-11-06T17:19:42.151Z,1699291182.151 [MassServo] Loaded
2023-11-06T17:19:42.151Z,1699291182.151 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-11-06T17:19:42.171Z,1699291182.171 [RudderServo] Loaded
2023-11-06T17:19:42.171Z,1699291182.171 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-11-06T17:19:42.188Z,1699291182.188 [ThrusterServo] Loaded
2023-11-06T17:19:42.188Z,1699291182.188 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-11-06T17:19:42.189Z,1699291182.189 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-11-06T17:19:42.190Z,1699291182.190 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-11-06T17:19:42.205Z,1699291182.205 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-11-06T17:19:42.206Z,1699291182.206 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-11-06T17:19:42.427Z,1699291182.427 [CTD_Seabird] Loaded
2023-11-06T17:19:42.427Z,1699291182.427 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-11-06T17:19:42.428Z,1699291182.428 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B944E0
2023-11-06T17:19:42.428Z,1699291182.428 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4927
2023-11-06T17:19:42.448Z,1699291182.448 [PAR_Licor] Loaded
2023-11-06T17:19:42.449Z,1699291182.449 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-11-06T17:19:42.490Z,1699291182.490 [WetLabsBB2FL] Loaded
2023-11-06T17:19:42.490Z,1699291182.490 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-11-06T17:19:42.491Z,1699291182.491 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0
2023-11-06T17:19:42.491Z,1699291182.491 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4928
2023-11-06T17:19:42.510Z,1699291182.510 [WetLabsUBAT] Loaded
2023-11-06T17:19:42.511Z,1699291182.511 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-11-06T17:19:42.512Z,1699291182.512 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BF44E0
2023-11-06T17:19:42.512Z,1699291182.512 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 4929
2023-11-06T17:19:42.512Z,1699291182.512 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-11-06T17:19:42.519Z,1699291182.519 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-11-06T17:19:42.522Z,1699291182.522 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-11-06T17:19:42.533Z,1699291182.533 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-11-06T17:19:42.534Z,1699291182.534 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C244E0
2023-11-06T17:19:42.534Z,1699291182.534 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4930
2023-11-06T17:19:42.538Z,1699291182.538 [Supervisor](INFO): Main Thread ID is 4835
2023-11-06T17:19:42.539Z,1699291182.539 [Supervisor](DEBUG): Running supervisor.
2023-11-06T17:19:42.539Z,1699291182.539 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4931
2023-11-06T17:19:42.540Z,1699291182.540 [CommandExec](INFO): Initializing the command executive.
2023-11-06T17:19:42.541Z,1699291182.541 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4932
2023-11-06T17:19:42.544Z,1699291182.544 [controlThread ThreadHandler](INFO): Handler Thread ID is 4933
2023-11-06T17:19:42.544Z,1699291182.544 [controlThread](DEBUG): Initializing ControlThread
2023-11-06T17:19:42.545Z,1699291182.545 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-11-06T17:19:42.547Z,1699291182.547 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-11-06T17:19:42.547Z,1699291182.547 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-11-06T17:19:42.548Z,1699291182.548 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-11-06T17:19:42.548Z,1699291182.548 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-11-06T17:19:42.549Z,1699291182.549 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-11-06T17:19:42.549Z,1699291182.549 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-11-06T17:19:42.549Z,1699291182.549 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-11-06T17:19:42.550Z,1699291182.550 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-11-06T17:19:42.558Z,1699291182.558 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-11-06T17:19:42.559Z,1699291182.559 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-11-06T17:19:42.559Z,1699291182.559 [SBIT](INFO): Initialize SBIT Component.
2023-11-06T17:19:42.560Z,1699291182.560 [SBIT](IMPORTANT): git: 2023-10-12
2023-11-06T17:19:42.560Z,1699291182.560 [SBIT](INFO): git hash: f38038725fad7dfee583a1d6cb07fccd712c5d54
2023-11-06T17:19:42.560Z,1699291182.560 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-11-06T17:19:42.561Z,1699291182.561 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-11-06T17:19:42.562Z,1699291182.562 [SBIT](INFO): Beginning SBIT in 35.000000 seconds.
2023-11-06T17:19:42.563Z,1699291182.563 [IBIT](INFO): Initialize IBIT Component.
2023-11-06T17:19:42.564Z,1699291182.564 [CBIT](DEBUG): Initialize CBIT Component.
2023-11-06T17:19:42.565Z,1699291182.565 [logger ThreadHandler](INFO): Handler Thread ID is 4934
2023-11-06T17:19:42.574Z,1699291182.574 [CBIT](DEBUG): Initialized mux pins.
2023-11-06T17:19:42.575Z,1699291182.575 [CBIT](DEBUG): Initializing the watchdog timer.
2023-11-06T17:19:42.583Z,1699291182.583 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4935
2023-11-06T17:19:42.595Z,1699291182.595 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4936
2023-11-06T17:19:42.596Z,1699291182.596 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-11-06T17:19:42.599Z,1699291182.599 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-11-06T17:19:42.599Z,1699291182.599 [CBIT](DEBUG): Initializing heartbeat.
2023-11-06T17:19:42.607Z,1699291182.607 [Onboard ThreadHandler](INFO): Handler Thread ID is 4937
2023-11-06T17:19:42.624Z,1699291182.624 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4938
2023-11-06T17:19:42.643Z,1699291182.643 [DAT ThreadHandler](INFO): Handler Thread ID is 4939
2023-11-06T17:19:42.651Z,1699291182.651 [DAT](INFO): Powering up
2023-11-06T17:19:42.651Z,1699291182.651 [DAT](DEBUG): Initializing DAT.
2023-11-06T17:19:42.653Z,1699291182.653 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4941
2023-11-06T17:19:42.654Z,1699291182.654 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-11-06T17:19:42.663Z,1699291182.663 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4943
2023-11-06T17:19:42.667Z,1699291182.667 [WetLabsBB2FL](INFO): Powering up
2023-11-06T17:19:42.668Z,1699291182.668 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 4945
2023-11-06T17:19:42.671Z,1699291182.671 [CBIT](DEBUG): Deactivating GF circuits.
2023-11-06T17:19:42.671Z,1699291182.671 [CBIT](DEBUG): Deactivating emergency mode.
2023-11-06T17:19:42.672Z,1699291182.672 [WetLabsUBAT](INFO): Powering up
2023-11-06T17:19:42.673Z,1699291182.673 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4947
2023-11-06T17:19:42.683Z,1699291182.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-11-06T17:19:42.684Z,1699291182.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-11-06T17:19:42.684Z,1699291182.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-11-06T17:19:42.684Z,1699291182.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-11-06T17:19:42.684Z,1699291182.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-11-06T17:19:42.684Z,1699291182.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-11-06T17:19:42.685Z,1699291182.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-11-06T17:19:42.685Z,1699291182.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-11-06T17:19:42.710Z,1699291182.710 [CBIT](DEBUG): Backplane powered.
2023-11-06T17:19:42.715Z,1699291182.715 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-11-06T17:19:42.757Z,1699291182.757 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-06T17:19:42.789Z,1699291182.789 [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>


2023-11-06T17:19:42.790Z,1699291182.790 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-11-06T17:19:42.893Z,1699291182.893 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-11-06T17:19:42.896Z,1699291182.896 [Default:A.Wait](DEBUG): Construct Wait.
2023-11-06T17:19:42.898Z,1699291182.898 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-06T17:19:42.929Z,1699291182.929 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-11-06T17:19:42.932Z,1699291182.932 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-11-06T17:19:42.946Z,1699291182.946 [Default:E.Execute](DEBUG): Construct Execute.
2023-11-06T17:19:42.950Z,1699291182.950 [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="P4H">
                <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>


2023-11-06T17:19:42.960Z,1699291182.960 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-11-06T17:19:42.968Z,1699291182.968 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-11-06T17:19:43.039Z,1699291183.039 [Radio_Surface](INFO): Powering up
2023-11-06T17:19:43.081Z,1699291183.081 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-11-06T17:19:43.082Z,1699291183.082 [PowerOnly](INFO): Powering up loadControl
2023-11-06T17:19:43.099Z,1699291183.099 [Power24vConverter](INFO): Powering up.
2023-11-06T17:19:43.100Z,1699291183.100 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-11-06T17:19:43.117Z,1699291183.117 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-11-06T17:19:43.152Z,1699291183.152 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-11-06T17:19:43.159Z,1699291183.159 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-11-06T17:19:43.160Z,1699291183.160 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-11-06T17:19:43.167Z,1699291183.167 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-11-06T17:19:43.168Z,1699291183.168 [MassServo](DEBUG): Initializing EZServoServo.
2023-11-06T17:19:43.175Z,1699291183.175 [MassServo](DEBUG): Initializing MassServo.
2023-11-06T17:19:43.176Z,1699291183.176 [RudderServo](DEBUG): Initializing EZServoServo.
2023-11-06T17:19:43.183Z,1699291183.183 [RudderServo](DEBUG): Initializing RudderServo.
2023-11-06T17:19:43.184Z,1699291183.184 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-11-06T17:19:43.191Z,1699291183.191 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-11-06T17:19:43.415Z,1699291183.415 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-06T17:19:43.415Z,1699291183.415 [DropWeight] Hardware Fault, FailCount= 1
2023-11-06T17:19:43.415Z,1699291183.415 [DropWeight](ERROR): Hardware Fault
2023-11-06T17:19:43.423Z,1699291183.423 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-11-06T17:19:43.448Z,1699291183.448 [CommandExec](FAULT): Scheduling is paused
2023-11-06T17:19:43.448Z,1699291183.448 [CBIT](INFO): Critical error at 20231106T171943
2023-11-06T17:19:43.448Z,1699291183.448 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-11-06T17:19:43.459Z,1699291183.459 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-06T17:19:43.459Z,1699291183.459 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-06T17:19:43.800Z,1699291183.800 [CBIT](INFO): Critical error at 20231106T171943
2023-11-06T17:19:45.279Z,1699291185.279 [WetLabsBB2FL](INFO): Powering down
2023-11-06T17:19:49.683Z,1699291189.683 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-11-06T17:19:51.939Z,1699291191.939 [WetLabsUBAT](INFO): Powering down
2023-11-06T17:19:56.511Z,1699291196.511 [DAT](INFO): DAT read: 

2023-11-06T17:19:56.513Z,1699291196.513 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-11-06T17:19:56.633Z,1699291196.633 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.013838
2023-11-06T17:19:58.276Z,1699291198.276 [DAT](INFO): DAT read: MF Frequency Band

2023-11-06T17:19:58.277Z,1699291198.277 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-11-06T17:19:58.277Z,1699291198.277 [DAT](INFO): DAT read: Oct 24 2023 20:36:19 

2023-11-06T17:19:59.536Z,1699291199.536 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-11-06T17:19:59.537Z,1699291199.537 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-11-06T17:19:59.537Z,1699291199.537 [DAT](INFO): commRate: 800
2023-11-06T17:20:01.602Z,1699291201.602 [DAT](INFO): entering command mode
2023-11-06T17:20:01.803Z,1699291201.803 [DAT](INFO): DAT read: 

2023-11-06T17:20:01.804Z,1699291201.804 [DAT](INFO): DAT read: user:1>

2023-11-06T17:20:01.804Z,1699291201.804 [DAT](INFO): setting verbose to 3
2023-11-06T17:20:02.055Z,1699291202.055 [DAT](INFO): DAT read: user:1>

2023-11-06T17:20:02.056Z,1699291202.056 [DAT](INFO): DAT read: Verbose         | 3           

2023-11-06T17:20:02.057Z,1699291202.057 [DAT](INFO): set verbose to 3
2023-11-06T17:20:02.057Z,1699291202.057 [DAT](INFO): setting DatVerbose to 27440
2023-11-06T17:20:02.307Z,1699291202.307 [DAT](INFO): DAT read: user:2>

2023-11-06T17:20:02.308Z,1699291202.308 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-11-06T17:20:02.309Z,1699291202.309 [DAT](INFO): set DatVerbose to 27440
2023-11-06T17:20:02.309Z,1699291202.309 [DAT](INFO): setting transmit power to 8
2023-11-06T17:20:02.562Z,1699291202.562 [DAT](INFO): DAT read: user:3>

2023-11-06T17:20:02.564Z,1699291202.564 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-11-06T17:20:02.564Z,1699291202.564 [DAT](INFO): set transmit power to 8
2023-11-06T17:20:02.564Z,1699291202.564 [DAT](INFO): setting local address to 10
2023-11-06T17:20:02.811Z,1699291202.811 [DAT](INFO): DAT read: user:4>

2023-11-06T17:20:02.812Z,1699291202.812 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-11-06T17:20:02.813Z,1699291202.813 [DAT](INFO): set local address to 10
2023-11-06T17:20:02.814Z,1699291202.814 [DAT](INFO): Setting time to: 17:20:2 And date to:11/6/2023
2023-11-06T17:20:03.063Z,1699291203.063 [DAT](INFO): DAT read: user:5>

2023-11-06T17:20:03.064Z,1699291203.064 [DAT](INFO): DAT read: Mon Nov 6, 2023  17:20:02  

2023-11-06T17:20:03.065Z,1699291203.065 [DAT](INFO): Local DAT time set to Mon Nov 6, 2023  17:20:02  

2023-11-06T17:20:10.888Z,1699291210.888 [NAL9602](INFO): Powering up NAL9602
2023-11-06T17:20:18.209Z,1699291218.209 [SBIT](IMPORTANT): Beginning Startup BIT
2023-11-06T17:20:18.214Z,1699291218.214 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-06T17:20:21.796Z,1699291221.796 [NAL9602](INFO): NAL9602 initialized
2023-11-06T17:20:29.147Z,1699291229.147 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015805
CHAN A1 (24V): 0.027718
CHAN A2 (12V): 0.003991
CHAN A3 (5V): 0.002736
CHAN B0 (3.3V): 0.000569
CHAN B1 (3.15aV): 0.000602
CHAN B2 (3.15bV): 0.000882
CHAN B3 (GND): 0.000084
OPEN: 0.005186
Full Scale: +/- 1 mA
2023-11-06T17:21:11.996Z,1699291271.996 [SBIT](IMPORTANT): SBIT PASSED
2023-11-06T17:21:11.996Z,1699291271.996 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;

2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;

2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=1 ampere_hour;

2023-11-06T17:21:11.997Z,1699291271.997 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2023-11-06T17:21:11.998Z,1699291271.998 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=227.996383 cubic_centimeter;

2023-11-06T17:21:11.998Z,1699291271.998 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0 second;

2023-11-06T17:21:11.998Z,1699291271.998 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.0015 reciprocal_second;

2023-11-06T17:21:11.998Z,1699291271.998 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.01 none;

2023-11-06T17:21:11.998Z,1699291271.998 [SBIT](IMPORTANT): VerticalControl.massDeadband=0.1 millimeter;

2023-11-06T17:21:12.003Z,1699291272.003 [SBIT](IMPORTANT): VerticalControl.massDefault=3.881765 millimeter;

2023-11-06T17:21:12.361Z,1699291272.361 [MissionManager](IMPORTANT): Started mission Startup
2023-11-06T17:21:12.362Z,1699291272.362 [Startup] Running Loop=1
2023-11-06T17:21:12.362Z,1699291272.362 [Startup](DEBUG): Aggregate::initialize Startup
2023-11-06T17:21:12.362Z,1699291272.362 [Startup:A.GoToSurface] Running Loop=1
2023-11-06T17:21:12.362Z,1699291272.362 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-06T17:21:12.367Z,1699291272.367 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-06T17:21:12.367Z,1699291272.367 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-06T17:21:12.368Z,1699291272.368 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-06T17:21:12.368Z,1699291272.368 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-06T17:21:12.368Z,1699291272.368 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-06T17:21:12.370Z,1699291272.370 [Startup:StartupSatComms] Running Loop=1
2023-11-06T17:21:12.390Z,1699291272.390 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-11-06T17:21:12.391Z,1699291272.391 [Startup:StartupSatComms:A] Running Loop=1
2023-11-06T17:21:12.826Z,1699291272.826 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-11-06T17:22:12.555Z,1699291332.555 [Startup:StartupSatComms:A](INFO): Timed out from 2023-11-06T17:21:12.4Z
2023-11-06T17:22:12.555Z,1699291332.555 [Startup:StartupSatComms:A] Stopped
2023-11-06T17:22:12.555Z,1699291332.555 [Startup:StartupSatComms:B] Running Loop=1
2023-11-06T17:22:12.987Z,1699291332.987 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-06T17:22:19.420Z,1699291339.420 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20231024T194706/Courier0018.lzma
2023-11-06T17:22:20.420Z,1699291340.420 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0018.lzma.bak
2023-11-06T17:22:20.421Z,1699291340.421 [DataOverHttps](INFO): SBD MOMSN=19047218
2023-11-06T17:22:36.842Z,1699291356.842 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20231106T171930/Courier0000.lzma
2023-11-06T17:22:37.844Z,1699291357.844 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0000.lzma.bak
2023-11-06T17:22:37.844Z,1699291357.844 [DataOverHttps](INFO): SBD MOMSN=19047220
2023-11-06T17:22:42.901Z,1699291362.901 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-11-06T17:22:42.901Z,1699291362.901 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:22:42.936Z,1699291362.936 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:22:43.305Z,1699291363.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:22:43.305Z,1699291363.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-11-06T17:22:53.916Z,1699291373.916 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20231024T194706/Express0019.lzma
2023-11-06T17:22:54.912Z,1699291374.912 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0019.lzma.bak
2023-11-06T17:22:54.912Z,1699291374.912 [DataOverHttps](INFO): SBD MOMSN=19047225
2023-11-06T17:23:10.800Z,1699291390.800 [DataOverHttps](INFO): Sending 1090 bytes from file Logs/20231106T171930/Express0001.lzma
2023-11-06T17:23:11.800Z,1699291391.800 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0001.lzma.bak
2023-11-06T17:23:11.800Z,1699291391.800 [DataOverHttps](INFO): SBD MOMSN=19047230
2023-11-06T17:23:12.759Z,1699291392.759 [Startup:StartupSatComms:B](INFO): Timed out from 2023-11-06T17:22:12.6Z
2023-11-06T17:23:12.759Z,1699291392.759 [Startup:StartupSatComms:B] Stopped
2023-11-06T17:23:12.759Z,1699291392.759 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-11-06T17:23:12.759Z,1699291392.759 [Startup:StartupSatComms] Stopped
2023-11-06T17:23:12.759Z,1699291392.759 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-11-06T17:23:12.760Z,1699291392.760 [Startup](INFO): Completed Startup
2023-11-06T17:23:12.760Z,1699291392.760 [MissionManager](INFO): Startup is completed.
2023-11-06T17:23:12.760Z,1699291392.760 [MissionManager](INFO): Uninitializing Mission Startup
2023-11-06T17:23:12.760Z,1699291392.760 [Startup] Stopped
2023-11-06T17:23:12.760Z,1699291392.760 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-11-06T17:23:12.760Z,1699291392.760 [Startup:A.GoToSurface] Stopped
2023-11-06T17:23:12.760Z,1699291392.760 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-06T17:23:13.152Z,1699291393.152 [MissionManager](IMPORTANT): Started mission Default
2023-11-06T17:23:13.152Z,1699291393.152 [Default] Running Loop=1
2023-11-06T17:23:13.152Z,1699291393.152 [Default](DEBUG): Aggregate::initialize Default
2023-11-06T17:23:13.152Z,1699291393.152 [Default:B.GoToSurface] Running Loop=1
2023-11-06T17:23:13.152Z,1699291393.152 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-06T17:23:13.153Z,1699291393.153 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-06T17:23:13.153Z,1699291393.153 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-06T17:23:13.153Z,1699291393.153 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-06T17:23:13.153Z,1699291393.153 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-06T17:23:13.154Z,1699291393.154 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-06T17:23:13.154Z,1699291393.154 [Default:A.Wait] Running Loop=1
2023-11-06T17:23:13.154Z,1699291393.154 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-06T17:23:26.489Z,1699291406.489 [Default:A.Wait](INFO): Done Waiting.
2023-11-06T17:23:26.489Z,1699291406.489 [Default:A.Wait] Stopped
2023-11-06T17:23:26.489Z,1699291406.489 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T17:23:26.915Z,1699291406.915 [Default:CheckIn] Running Loop=1
2023-11-06T17:23:26.915Z,1699291406.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T17:23:26.915Z,1699291406.915 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T17:23:27.281Z,1699291407.281 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-11-06T17:24:36.751Z,1699291476.751 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:24:42.811Z,1699291482.811 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:24:54.175Z,1699291494.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T17:24:55.739Z,1699291495.739 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:24:56.183Z,1699291496.183 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267147
2023-11-06T17:25:06.711Z,1699291506.711 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002877
2023-11-06T17:25:08.686Z,1699291508.686 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:25:22.001Z,1699291522.001 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:25:25.236Z,1699291525.236 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-06T17:25:27.428Z,1699291527.428 [CommandExec](IMPORTANT): got command strobe off
2023-11-06T17:25:27.428Z,1699291527.428 [CommandExec](IMPORTANT): Deactivating strobe
2023-11-06T17:25:34.927Z,1699291534.927 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:25:43.856Z,1699291543.856 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-11-06T17:25:43.856Z,1699291543.856 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:25:43.891Z,1699291543.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:25:44.323Z,1699291544.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:25:44.323Z,1699291544.323 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-11-06T17:25:48.259Z,1699291548.259 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:26:01.187Z,1699291561.187 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:26:01.904Z,1699291561.904 [CommandExec](IMPORTANT): got command burn on
2023-11-06T17:26:01.904Z,1699291561.904 [CommandExec](IMPORTANT): Activating dropweight wire
2023-11-06T17:26:06.275Z,1699291566.275 [CommandExec](IMPORTANT): got command burn off
2023-11-06T17:26:06.276Z,1699291566.276 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-11-06T17:26:13.716Z,1699291573.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:26:26.656Z,1699291586.656 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:26:39.163Z,1699291599.163 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:26:52.495Z,1699291612.495 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:27:04.218Z,1699291624.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T17:27:10.700Z,1699291630.700 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2023-11-06T17:27:10.703Z,1699291630.703 [BPC1](INFO): Received data from all battery sticks.
2023-11-06T17:28:27.085Z,1699291707.085 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-06T17:23:26.9Z
2023-11-06T17:28:27.085Z,1699291707.085 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T17:28:27.085Z,1699291707.085 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T17:28:27.491Z,1699291707.491 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-06T17:28:34.238Z,1699291714.238 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20231106T171930/Courier0004.lzma
2023-11-06T17:28:35.240Z,1699291715.240 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0004.lzma.bak
2023-11-06T17:28:35.240Z,1699291715.240 [DataOverHttps](INFO): SBD MOMSN=19047256
2023-11-06T17:28:44.863Z,1699291724.863 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-11-06T17:28:44.863Z,1699291724.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:28:44.874Z,1699291724.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:28:45.273Z,1699291725.273 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:28:45.273Z,1699291725.273 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-11-06T17:28:51.339Z,1699291731.339 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20231106T171930/Express0005.lzma
2023-11-06T17:28:52.332Z,1699291732.332 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0005.lzma.bak
2023-11-06T17:28:52.332Z,1699291732.332 [DataOverHttps](INFO): SBD MOMSN=19047258
2023-11-06T17:28:53.741Z,1699291733.741 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T17:28:53.741Z,1699291733.741 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T17:28:53.742Z,1699291733.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T17:30:23.408Z,1699291823.408 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-06T17:30:23.408Z,1699291823.408 [NAL9602] Data Fault, FailCount= 1
2023-11-06T17:30:23.408Z,1699291823.408 [NAL9602](ERROR): Data Fault
2023-11-06T17:30:23.455Z,1699291823.455 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-06T17:30:23.812Z,1699291823.812 [NAL9602](INFO): Powering down
2023-11-06T17:30:24.697Z,1699291824.697 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-06T17:30:24.697Z,1699291824.697 [NAL9602] No Fault, FailCount= 1
2023-11-06T17:30:54.114Z,1699291854.114 [NAL9602](INFO): Powering up NAL9602
2023-11-06T17:31:05.016Z,1699291865.016 [NAL9602](INFO): NAL9602 initialized
2023-11-06T17:31:45.864Z,1699291905.864 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-11-06T17:31:45.864Z,1699291905.864 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:31:45.875Z,1699291905.875 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:31:46.268Z,1699291906.268 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:31:46.268Z,1699291906.268 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-11-06T17:33:54.315Z,1699292034.315 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T17:33:54.315Z,1699292034.315 [Default:CheckIn:C.Wait] Stopped
2023-11-06T17:33:54.315Z,1699292034.315 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T17:33:54.315Z,1699292034.315 [Default:CheckIn:D] Running Loop=1
2023-11-06T17:33:54.737Z,1699292034.737 [Default:CheckIn:D] Stopped
2023-11-06T17:33:54.737Z,1699292034.737 [Default:CheckIn:E] Running Loop=1
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.693077 min 
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn:E] Stopped
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn] Stopped
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn](INFO): Running loop #2
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn] Running Loop=2
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T17:33:55.117Z,1699292035.117 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T17:34:43.655Z,1699292083.655 [CBIT](INFO): Clearing failed state for component DropWeight
2023-11-06T17:34:43.655Z,1699292083.655 [DropWeight] No Fault, FailCount= 1
2023-11-06T17:34:46.913Z,1699292086.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-11-06T17:34:46.913Z,1699292086.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:34:46.924Z,1699292086.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:34:47.296Z,1699292087.296 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:34:47.296Z,1699292087.296 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-11-06T17:37:47.848Z,1699292267.848 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-11-06T17:37:47.848Z,1699292267.848 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:37:47.859Z,1699292267.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:37:48.277Z,1699292268.277 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:37:48.277Z,1699292268.277 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-11-06T17:38:55.357Z,1699292335.357 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-06T17:33:55.1Z
2023-11-06T17:38:55.357Z,1699292335.357 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T17:38:55.357Z,1699292335.357 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T17:39:02.669Z,1699292342.669 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231106T171930/Courier0007.lzma
2023-11-06T17:39:03.664Z,1699292343.664 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0007.lzma.bak
2023-11-06T17:39:03.664Z,1699292343.664 [DataOverHttps](INFO): SBD MOMSN=19047268
2023-11-06T17:39:19.487Z,1699292359.487 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20231106T171930/Express0008.lzma
2023-11-06T17:39:20.488Z,1699292360.488 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0008.lzma.bak
2023-11-06T17:39:20.488Z,1699292360.488 [DataOverHttps](INFO): SBD MOMSN=19047270
2023-11-06T17:39:22.017Z,1699292362.017 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T17:39:22.017Z,1699292362.017 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T17:39:22.017Z,1699292362.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T17:40:48.939Z,1699292448.939 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-11-06T17:40:48.939Z,1699292448.939 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:40:48.950Z,1699292448.950 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:40:49.316Z,1699292449.316 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:40:49.316Z,1699292449.316 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-11-06T17:41:06.658Z,1699292466.658 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-06T17:41:06.658Z,1699292466.658 [NAL9602] Data Fault, FailCount= 2
2023-11-06T17:41:06.658Z,1699292466.658 [NAL9602](ERROR): Data Fault
2023-11-06T17:41:06.723Z,1699292466.723 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-06T17:41:07.054Z,1699292467.054 [NAL9602](INFO): Powering down
2023-11-06T17:41:07.889Z,1699292467.889 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-06T17:41:07.889Z,1699292467.889 [NAL9602] No Fault, FailCount= 2
2023-11-06T17:41:37.352Z,1699292497.352 [NAL9602](INFO): Powering up NAL9602
2023-11-06T17:41:48.256Z,1699292508.256 [NAL9602](INFO): NAL9602 initialized
2023-11-06T17:43:49.921Z,1699292629.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-11-06T17:43:49.921Z,1699292629.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-06T17:43:49.932Z,1699292629.932 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-06T17:43:50.337Z,1699292630.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-06T17:43:50.337Z,1699292630.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-11-06T17:44:22.741Z,1699292662.741 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T17:44:22.741Z,1699292662.741 [Default:CheckIn:C.Wait] Stopped
2023-11-06T17:44:22.741Z,1699292662.741 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T17:44:22.741Z,1699292662.741 [Default:CheckIn:D] Running Loop=1
2023-11-06T17:44:23.054Z,1699292663.054 [Default:CheckIn:D] Stopped
2023-11-06T17:44:23.054Z,1699292663.054 [Default:CheckIn:E] Running Loop=1
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.165025 min 
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn:E] Stopped
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn] Stopped
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn](INFO): Running loop #3
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn] Running Loop=3
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T17:44:23.455Z,1699292663.455 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T17:45:36.970Z,1699292736.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174535.00,A,3648.15893,N,12147.27847,W,1.691,168.94,061123,,,A*77
2023-11-06T17:45:36.991Z,1699292736.991 [NAL9602](INFO): GPS fix at 20231106T174535: (36.802649, -121.787975)
2023-11-06T17:45:37.051Z,1699292737.051 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T17:45:37.051Z,1699292737.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T17:45:46.278Z,1699292746.278 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231106T171930/Courier0010.lzma
2023-11-06T17:45:47.282Z,1699292747.282 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0010.lzma.bak
2023-11-06T17:45:47.282Z,1699292747.282 [DataOverHttps](INFO): SBD MOMSN=19047274
2023-11-06T17:46:04.559Z,1699292764.559 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20231106T171930/Express0011.lzma
2023-11-06T17:46:05.560Z,1699292765.560 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0011.lzma.bak
2023-11-06T17:46:05.560Z,1699292765.560 [DataOverHttps](INFO): SBD MOMSN=19047277
2023-11-06T17:46:06.908Z,1699292766.908 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T17:46:06.908Z,1699292766.908 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T17:46:06.908Z,1699292766.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T17:46:09.288Z,1699292769.288 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T17:51:07.518Z,1699293067.518 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T17:51:07.518Z,1699293067.518 [Default:CheckIn:C.Wait] Stopped
2023-11-06T17:51:07.518Z,1699293067.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T17:51:07.518Z,1699293067.518 [Default:CheckIn:D] Running Loop=1
2023-11-06T17:51:07.927Z,1699293067.927 [Default:CheckIn:D] Stopped
2023-11-06T17:51:07.927Z,1699293067.927 [Default:CheckIn:E] Running Loop=1
2023-11-06T17:51:08.319Z,1699293068.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.912917 min 
2023-11-06T17:51:08.319Z,1699293068.319 [Default:CheckIn:E] Stopped
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn] Stopped
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn](INFO): Running loop #4
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn] Running Loop=4
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T17:51:08.320Z,1699293068.320 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T17:51:10.336Z,1699293070.336 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175109.00,A,3648.17281,N,12147.28310,W,0.875,168.94,061123,,,A*75
2023-11-06T17:51:10.339Z,1699293070.339 [NAL9602](INFO): GPS fix at 20231106T175109: (36.802880, -121.788052)
2023-11-06T17:51:10.350Z,1699293070.350 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T17:51:10.350Z,1699293070.350 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T17:51:18.202Z,1699293078.202 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0013.lzma
2023-11-06T17:51:19.208Z,1699293079.208 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0013.lzma.bak
2023-11-06T17:51:19.208Z,1699293079.208 [DataOverHttps](INFO): SBD MOMSN=19047281
2023-11-06T17:51:35.730Z,1699293095.730 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231106T171930/Express0014.lzma
2023-11-06T17:51:36.732Z,1699293096.732 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0014.lzma.bak
2023-11-06T17:51:36.733Z,1699293096.733 [DataOverHttps](INFO): SBD MOMSN=19047284
2023-11-06T17:51:38.220Z,1699293098.220 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T17:51:38.220Z,1699293098.220 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T17:51:38.220Z,1699293098.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T17:56:12.192Z,1699293372.192 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-06T17:56:38.881Z,1699293398.881 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T17:56:38.881Z,1699293398.881 [Default:CheckIn:C.Wait] Stopped
2023-11-06T17:56:38.881Z,1699293398.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T17:56:38.881Z,1699293398.881 [Default:CheckIn:D] Running Loop=1
2023-11-06T17:56:39.268Z,1699293399.268 [Default:CheckIn:D] Stopped
2023-11-06T17:56:39.268Z,1699293399.268 [Default:CheckIn:E] Running Loop=1
2023-11-06T17:56:39.685Z,1699293399.685 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.435260 min 
2023-11-06T17:56:39.685Z,1699293399.685 [Default:CheckIn:E] Stopped
2023-11-06T17:56:39.685Z,1699293399.685 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T17:56:39.685Z,1699293399.685 [Default:CheckIn] Stopped
2023-11-06T17:56:39.685Z,1699293399.685 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T17:56:39.686Z,1699293399.686 [Default:CheckIn](INFO): Running loop #5
2023-11-06T17:56:39.686Z,1699293399.686 [Default:CheckIn] Running Loop=5
2023-11-06T17:56:39.686Z,1699293399.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T17:56:39.686Z,1699293399.686 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T17:56:41.682Z,1699293401.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175640.00,A,3648.16447,N,12147.28118,W,0.447,168.94,061123,,,A*75
2023-11-06T17:56:41.684Z,1699293401.684 [NAL9602](INFO): GPS fix at 20231106T175640: (36.802741, -121.788020)
2023-11-06T17:56:41.719Z,1699293401.719 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T17:56:41.720Z,1699293401.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T17:56:49.318Z,1699293409.318 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231106T171930/Courier0016.lzma
2023-11-06T17:56:50.320Z,1699293410.320 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0016.lzma.bak
2023-11-06T17:56:50.320Z,1699293410.320 [DataOverHttps](INFO): SBD MOMSN=19047295
2023-11-06T17:57:06.688Z,1699293426.688 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20231106T171930/Express0017.lzma
2023-11-06T17:57:07.684Z,1699293427.684 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0017.lzma.bak
2023-11-06T17:57:07.684Z,1699293427.684 [DataOverHttps](INFO): SBD MOMSN=19047298
2023-11-06T17:57:09.175Z,1699293429.175 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T17:57:09.175Z,1699293429.175 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T17:57:09.175Z,1699293429.175 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T17:57:14.404Z,1699293434.404 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:02:09.782Z,1699293729.782 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:02:09.783Z,1699293729.783 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:02:09.783Z,1699293729.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:02:09.783Z,1699293729.783 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:02:10.154Z,1699293730.154 [Default:CheckIn:D] Stopped
2023-11-06T18:02:10.154Z,1699293730.154 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:02:10.562Z,1699293730.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.950033 min 
2023-11-06T18:02:10.562Z,1699293730.562 [Default:CheckIn:E] Stopped
2023-11-06T18:02:10.562Z,1699293730.562 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:02:10.562Z,1699293730.562 [Default:CheckIn] Stopped
2023-11-06T18:02:10.562Z,1699293730.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:02:10.563Z,1699293730.563 [Default:CheckIn](INFO): Running loop #6
2023-11-06T18:02:10.563Z,1699293730.563 [Default:CheckIn] Running Loop=6
2023-11-06T18:02:10.563Z,1699293730.563 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:02:10.563Z,1699293730.563 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:02:12.560Z,1699293732.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180211.00,A,3648.16619,N,12147.28041,W,0.097,0.00,061123,,,A*70
2023-11-06T18:02:12.562Z,1699293732.562 [NAL9602](INFO): GPS fix at 20231106T180211: (36.802770, -121.788007)
2023-11-06T18:02:12.574Z,1699293732.574 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:02:12.574Z,1699293732.574 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:02:23.323Z,1699293743.323 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0019.lzma
2023-11-06T18:02:24.321Z,1699293744.321 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0019.lzma.bak
2023-11-06T18:02:24.321Z,1699293744.321 [DataOverHttps](INFO): SBD MOMSN=19047302
2023-11-06T18:02:40.918Z,1699293760.918 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20231106T171930/Express0020.lzma
2023-11-06T18:02:41.920Z,1699293761.920 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0020.lzma.bak
2023-11-06T18:02:41.920Z,1699293761.920 [DataOverHttps](INFO): SBD MOMSN=19047305
2023-11-06T18:02:42.000Z,1699293763.000 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-06T18:02:43.079Z,1699293763.079 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-11-06T18:02:43.451Z,1699293763.451 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:02:43.451Z,1699293763.451 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:02:43.452Z,1699293763.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:04:45.007Z,1699293885.007 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-06T18:04:45.008Z,1699293885.008 [DropWeight] Hardware Fault, FailCount= 1
2023-11-06T18:04:45.008Z,1699293885.008 [DropWeight](ERROR): Hardware Fault
2023-11-06T18:04:45.082Z,1699293885.082 [CBIT](INFO): Critical error at 20231106T180445
2023-11-06T18:04:45.089Z,1699293885.089 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-06T18:04:45.089Z,1699293885.089 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-06T18:04:45.436Z,1699293885.436 [CBIT](INFO): Critical error at 20231106T180445
2023-11-06T18:07:14.548Z,1699294034.548 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-06T18:07:44.043Z,1699294064.043 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:07:44.043Z,1699294064.043 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:07:44.043Z,1699294064.043 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:07:44.043Z,1699294064.043 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:07:44.451Z,1699294064.451 [Default:CheckIn:D] Stopped
2023-11-06T18:07:44.451Z,1699294064.451 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.521647 min 
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn:E] Stopped
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn] Stopped
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn](INFO): Running loop #7
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn] Running Loop=7
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:07:44.928Z,1699294064.928 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:07:45.243Z,1699294065.243 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:07:46.860Z,1699294066.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180746.00,A,3648.16291,N,12147.28062,W,0.719,0.00,061123,,,A*73
2023-11-06T18:07:46.862Z,1699294066.862 [NAL9602](INFO): GPS fix at 20231106T180746: (36.802715, -121.788010)
2023-11-06T18:07:46.913Z,1699294066.913 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:07:46.913Z,1699294066.913 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:07:54.910Z,1699294074.910 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20231106T171930/Courier0022.lzma
2023-11-06T18:07:55.912Z,1699294075.912 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0022.lzma.bak
2023-11-06T18:07:55.912Z,1699294075.912 [DataOverHttps](INFO): SBD MOMSN=19047313
2023-11-06T18:08:12.318Z,1699294092.318 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20231106T171930/Express0023.lzma
2023-11-06T18:08:13.320Z,1699294093.320 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0023.lzma.bak
2023-11-06T18:08:13.320Z,1699294093.320 [DataOverHttps](INFO): SBD MOMSN=19047318
2023-11-06T18:08:14.816Z,1699294094.816 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:08:14.816Z,1699294094.816 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:08:14.816Z,1699294094.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:08:17.620Z,1699294097.620 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-06T18:08:17.694Z,1699294097.694 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-11-06T18:12:49.555Z,1699294369.555 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-06T18:13:15.423Z,1699294395.423 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:13:15.423Z,1699294395.423 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:13:15.423Z,1699294395.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:13:15.423Z,1699294395.423 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:13:15.839Z,1699294395.839 [Default:CheckIn:D] Stopped
2023-11-06T18:13:15.839Z,1699294395.839 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.044779 min 
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn:E] Stopped
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn] Stopped
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn](INFO): Running loop #8
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn] Running Loop=8
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:13:16.237Z,1699294396.237 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:13:18.246Z,1699294398.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181317.00,A,3648.17041,N,12147.28340,W,0.641,0.00,061123,,,A*73
2023-11-06T18:13:18.248Z,1699294398.248 [NAL9602](INFO): GPS fix at 20231106T181317: (36.802840, -121.788057)
2023-11-06T18:13:18.260Z,1699294398.260 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:13:18.260Z,1699294398.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:13:25.374Z,1699294405.374 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0025.lzma
2023-11-06T18:13:26.376Z,1699294406.376 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0025.lzma.bak
2023-11-06T18:13:26.376Z,1699294406.376 [DataOverHttps](INFO): SBD MOMSN=19047330
2023-11-06T18:13:42.755Z,1699294422.755 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20231106T171930/Express0026.lzma
2023-11-06T18:13:43.756Z,1699294423.756 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0026.lzma.bak
2023-11-06T18:13:43.756Z,1699294423.756 [DataOverHttps](INFO): SBD MOMSN=19047333
2023-11-06T18:13:45.058Z,1699294425.058 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:13:45.058Z,1699294425.058 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:13:45.058Z,1699294425.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:13:50.687Z,1699294430.687 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:18:45.619Z,1699294725.619 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:18:45.619Z,1699294725.619 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:18:45.619Z,1699294725.619 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:18:45.620Z,1699294725.620 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:18:46.002Z,1699294726.002 [Default:CheckIn:D] Stopped
2023-11-06T18:18:46.002Z,1699294726.002 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:18:46.401Z,1699294726.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.547506 min 
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn:E] Stopped
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn] Stopped
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn](INFO): Running loop #9
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn] Running Loop=9
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:18:46.402Z,1699294726.402 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:18:48.415Z,1699294728.415 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181847.00,A,3648.16050,N,12147.28198,W,0.156,0.00,061123,,,A*7A
2023-11-06T18:18:48.417Z,1699294728.417 [NAL9602](INFO): GPS fix at 20231106T181847: (36.802675, -121.788033)
2023-11-06T18:18:48.429Z,1699294728.429 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:18:48.429Z,1699294728.429 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:18:55.814Z,1699294735.814 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0028.lzma
2023-11-06T18:18:56.817Z,1699294736.817 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0028.lzma.bak
2023-11-06T18:18:56.817Z,1699294736.817 [DataOverHttps](INFO): SBD MOMSN=19047415
2023-11-06T18:19:13.339Z,1699294753.339 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20231106T171930/Express0029.lzma
2023-11-06T18:19:14.341Z,1699294754.341 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0029.lzma.bak
2023-11-06T18:19:14.341Z,1699294754.341 [DataOverHttps](INFO): SBD MOMSN=19047418
2023-11-06T18:19:15.898Z,1699294755.898 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:19:15.898Z,1699294755.898 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:19:15.899Z,1699294755.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:19:19.116Z,1699294759.116 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-06T18:19:19.198Z,1699294759.198 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-11-06T18:19:45.013Z,1699294785.013 [CBIT](INFO): Clearing failed state for component DropWeight
2023-11-06T18:19:45.013Z,1699294785.013 [DropWeight] No Fault, FailCount= 1
2023-11-06T18:23:50.279Z,1699295030.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-06T18:24:16.567Z,1699295056.567 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:24:16.568Z,1699295056.568 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:24:16.568Z,1699295056.568 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:24:16.568Z,1699295056.568 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:24:17.032Z,1699295057.032 [Default:CheckIn:D] Stopped
2023-11-06T18:24:17.032Z,1699295057.032 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:24:17.387Z,1699295057.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.064669 min 
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn:E] Stopped
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn] Stopped
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn](INFO): Running loop #10
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn] Running Loop=10
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:24:17.388Z,1699295057.388 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:24:19.369Z,1699295059.369 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182418.00,A,3648.16295,N,12147.28155,W,0.408,0.00,061123,,,A*7B
2023-11-06T18:24:19.371Z,1699295059.371 [NAL9602](INFO): GPS fix at 20231106T182418: (36.802716, -121.788026)
2023-11-06T18:24:19.419Z,1699295059.419 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:24:19.419Z,1699295059.419 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:24:29.062Z,1699295069.062 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0031.lzma
2023-11-06T18:24:30.064Z,1699295070.064 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0031.lzma.bak
2023-11-06T18:24:30.064Z,1699295070.064 [DataOverHttps](INFO): SBD MOMSN=19047671
2023-11-06T18:24:46.266Z,1699295086.266 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20231106T171930/Express0032.lzma
2023-11-06T18:24:47.268Z,1699295087.268 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0032.lzma.bak
2023-11-06T18:24:47.268Z,1699295087.268 [DataOverHttps](INFO): SBD MOMSN=19047677
2023-11-06T18:24:48.481Z,1699295088.481 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:24:48.481Z,1699295088.481 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:24:48.481Z,1699295088.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:24:51.700Z,1699295091.700 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:29:49.071Z,1699295389.071 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:29:49.071Z,1699295389.071 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:29:49.071Z,1699295389.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:29:49.071Z,1699295389.071 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:29:49.463Z,1699295389.463 [Default:CheckIn:D] Stopped
2023-11-06T18:29:49.463Z,1699295389.463 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.605184 min 
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn:E] Stopped
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn] Stopped
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn](INFO): Running loop #11
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn] Running Loop=11
2023-11-06T18:29:49.859Z,1699295389.859 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:29:49.860Z,1699295389.860 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:29:51.869Z,1699295391.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182951.00,A,3648.17080,N,12147.28187,W,0.156,314.54,061123,,,A*7A
2023-11-06T18:29:51.872Z,1699295391.872 [NAL9602](INFO): GPS fix at 20231106T182951: (36.802847, -121.788031)
2023-11-06T18:29:51.883Z,1699295391.883 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:29:51.883Z,1699295391.883 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:30:00.264Z,1699295400.264 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0034.lzma
2023-11-06T18:30:00.941Z,1699295400.941 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0034.lzma.bak
2023-11-06T18:30:00.941Z,1699295400.941 [DataOverHttps](INFO): SBD MOMSN=19047765
2023-11-06T18:30:19.714Z,1699295419.714 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231106T171930/Express0035.lzma
2023-11-06T18:30:20.717Z,1699295420.717 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0035.lzma.bak
2023-11-06T18:30:20.717Z,1699295420.717 [DataOverHttps](INFO): SBD MOMSN=19047768
2023-11-06T18:30:22.219Z,1699295422.219 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:30:22.219Z,1699295422.219 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:30:22.219Z,1699295422.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:30:22.617Z,1699295422.617 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-06T18:30:22.698Z,1699295422.698 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-11-06T18:32:08.893Z,1699295528.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:32:22.225Z,1699295542.225 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:32:35.153Z,1699295555.153 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:32:48.081Z,1699295568.081 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:33:01.011Z,1699295581.011 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:33:13.937Z,1699295593.937 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:33:27.269Z,1699295607.269 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:33:40.197Z,1699295620.197 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:33:53.125Z,1699295633.125 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:34:05.653Z,1699295645.653 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:34:18.981Z,1699295658.981 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:34:32.314Z,1699295672.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T18:34:38.799Z,1699295678.799 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2023-11-06T18:34:38.806Z,1699295678.806 [BPC1](INFO): Received data from all battery sticks.
2023-11-06T18:34:54.537Z,1699295694.537 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-06T18:35:22.866Z,1699295722.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:35:22.866Z,1699295722.866 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:35:22.867Z,1699295722.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:35:22.867Z,1699295722.867 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:35:23.238Z,1699295723.238 [Default:CheckIn:D] Stopped
2023-11-06T18:35:23.238Z,1699295723.238 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:35:23.634Z,1699295723.634 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.168091 min 
2023-11-06T18:35:23.634Z,1699295723.634 [Default:CheckIn:E] Stopped
2023-11-06T18:35:23.634Z,1699295723.634 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:35:23.634Z,1699295723.634 [Default:CheckIn] Stopped
2023-11-06T18:35:23.634Z,1699295723.634 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:35:23.635Z,1699295723.635 [Default:CheckIn](INFO): Running loop #12
2023-11-06T18:35:23.635Z,1699295723.635 [Default:CheckIn] Running Loop=12
2023-11-06T18:35:23.635Z,1699295723.635 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:35:23.635Z,1699295723.635 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:35:25.651Z,1699295725.651 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183524.00,A,3648.16831,N,12147.27965,W,0.253,314.54,061123,,,A*7B
2023-11-06T18:35:25.654Z,1699295725.654 [NAL9602](INFO): GPS fix at 20231106T183524: (36.802805, -121.787994)
2023-11-06T18:35:25.665Z,1699295725.665 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:35:25.665Z,1699295725.665 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:35:33.086Z,1699295733.086 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20231106T171930/Courier0037.lzma
2023-11-06T18:35:34.088Z,1699295734.088 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0037.lzma.bak
2023-11-06T18:35:34.089Z,1699295734.089 [DataOverHttps](INFO): SBD MOMSN=19047773
2023-11-06T18:35:50.290Z,1699295750.290 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20231106T171930/Express0038.lzma
2023-11-06T18:35:51.292Z,1699295751.292 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0038.lzma.bak
2023-11-06T18:35:51.293Z,1699295751.293 [DataOverHttps](INFO): SBD MOMSN=19047778
2023-11-06T18:35:52.750Z,1699295752.750 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:35:52.750Z,1699295752.750 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:35:52.750Z,1699295752.750 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:35:58.366Z,1699295758.366 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:39:37.033Z,1699295977.033 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for beam range. Device response is::RA,  0.008,-32768,V
2023-11-06T18:40:53.425Z,1699296053.425 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:40:53.425Z,1699296053.425 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:40:53.425Z,1699296053.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:40:53.425Z,1699296053.425 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:40:53.803Z,1699296053.803 [Default:CheckIn:D] Stopped
2023-11-06T18:40:53.803Z,1699296053.803 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.677515 min 
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn:E] Stopped
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn] Stopped
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn](INFO): Running loop #13
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn] Running Loop=13
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:40:54.204Z,1699296054.204 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:40:56.213Z,1699296056.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184055.00,A,3648.17141,N,12147.28391,W,0.078,314.54,061123,,,A*75
2023-11-06T18:40:56.216Z,1699296056.216 [NAL9602](INFO): GPS fix at 20231106T184055: (36.802857, -121.788065)
2023-11-06T18:40:56.252Z,1699296056.252 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:40:56.252Z,1699296056.252 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:41:04.302Z,1699296064.302 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231106T171930/Courier0040.lzma
2023-11-06T18:41:05.304Z,1699296065.304 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0040.lzma.bak
2023-11-06T18:41:05.304Z,1699296065.304 [DataOverHttps](INFO): SBD MOMSN=19047815
2023-11-06T18:41:22.206Z,1699296082.206 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20231106T171930/Express0041.lzma
2023-11-06T18:41:23.208Z,1699296083.208 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0041.lzma.bak
2023-11-06T18:41:23.209Z,1699296083.209 [DataOverHttps](INFO): SBD MOMSN=19047818
2023-11-06T18:41:24.531Z,1699296084.531 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:41:24.531Z,1699296084.531 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:41:24.531Z,1699296084.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:41:26.948Z,1699296086.948 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-06T18:41:27.023Z,1699296087.023 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-11-06T18:43:54.809Z,1699296234.809 [NAL9602](INFO): SBD MO Status=0, MOMSN=32157, MT Status=0, MTMSN=0
2023-11-06T18:43:54.809Z,1699296234.809 [NAL9602](INFO): No messages in MT queue
2023-11-06T18:44:25.513Z,1699296265.513 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:46:25.115Z,1699296385.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:46:25.115Z,1699296385.115 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:46:25.115Z,1699296385.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:46:25.116Z,1699296385.116 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:46:25.523Z,1699296385.523 [Default:CheckIn:D] Stopped
2023-11-06T18:46:25.523Z,1699296385.523 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:46:25.915Z,1699296385.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.206185 min 
2023-11-06T18:46:25.915Z,1699296385.915 [Default:CheckIn:E] Stopped
2023-11-06T18:46:25.915Z,1699296385.915 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:46:25.915Z,1699296385.915 [Default:CheckIn] Stopped
2023-11-06T18:46:25.916Z,1699296385.916 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:46:25.916Z,1699296385.916 [Default:CheckIn](INFO): Running loop #14
2023-11-06T18:46:25.916Z,1699296385.916 [Default:CheckIn] Running Loop=14
2023-11-06T18:46:25.916Z,1699296385.916 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:46:25.916Z,1699296385.916 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:46:27.930Z,1699296387.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184627.00,A,3648.16758,N,12147.27356,W,2.488,117.53,061123,,,A*72
2023-11-06T18:46:27.932Z,1699296387.932 [NAL9602](INFO): GPS fix at 20231106T184627: (36.802793, -121.787893)
2023-11-06T18:46:27.943Z,1699296387.943 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:46:27.944Z,1699296387.944 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:46:35.206Z,1699296395.206 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0043.lzma
2023-11-06T18:46:36.208Z,1699296396.208 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0043.lzma.bak
2023-11-06T18:46:36.209Z,1699296396.209 [DataOverHttps](INFO): SBD MOMSN=19047828
2023-11-06T18:46:39.342Z,1699296399.342 [NAL9602](INFO): SBD MO Status=0, MOMSN=32158, MT Status=0, MTMSN=0
2023-11-06T18:46:39.342Z,1699296399.342 [NAL9602](INFO): No messages in MT queue
2023-11-06T18:46:52.673Z,1699296412.673 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20231106T171930/Express0044.lzma
2023-11-06T18:46:53.668Z,1699296413.668 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0044.lzma.bak
2023-11-06T18:46:53.669Z,1699296413.669 [DataOverHttps](INFO): SBD MOMSN=19047831
2023-11-06T18:46:55.107Z,1699296415.107 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:46:55.107Z,1699296415.107 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:46:55.107Z,1699296415.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:47:10.035Z,1699296430.035 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:49:46.395Z,1699296586.395 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-06T18:49:46.395Z,1699296586.395 [DropWeight] Hardware Fault, FailCount= 1
2023-11-06T18:49:46.395Z,1699296586.395 [DropWeight](ERROR): Hardware Fault
2023-11-06T18:49:46.414Z,1699296586.414 [CBIT](INFO): Critical error at 20231106T184946
2023-11-06T18:49:46.417Z,1699296586.417 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-06T18:49:46.417Z,1699296586.417 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-06T18:49:46.838Z,1699296586.838 [CBIT](INFO): Critical error at 20231106T184946
2023-11-06T18:51:55.731Z,1699296715.731 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:51:55.731Z,1699296715.731 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:51:55.731Z,1699296715.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:51:55.731Z,1699296715.731 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:51:56.106Z,1699296716.106 [Default:CheckIn:D] Stopped
2023-11-06T18:51:56.106Z,1699296716.106 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:51:56.510Z,1699296716.510 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.715902 min 
2023-11-06T18:51:56.510Z,1699296716.510 [Default:CheckIn:E] Stopped
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn] Stopped
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn](INFO): Running loop #15
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn] Running Loop=15
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:51:56.511Z,1699296716.511 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:51:58.516Z,1699296718.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185157.00,A,3648.13907,N,12147.23345,W,0.836,56.11,061123,,,A*4D
2023-11-06T18:51:58.518Z,1699296718.518 [NAL9602](INFO): GPS fix at 20231106T185157: (36.802318, -121.787224)
2023-11-06T18:51:58.530Z,1699296718.530 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:51:58.530Z,1699296718.530 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:52:05.914Z,1699296725.914 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20231106T171930/Courier0046.lzma
2023-11-06T18:52:06.916Z,1699296726.916 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0046.lzma.bak
2023-11-06T18:52:06.916Z,1699296726.916 [DataOverHttps](INFO): SBD MOMSN=19047836
2023-11-06T18:52:16.717Z,1699296736.717 [NAL9602](INFO): SBD MO Status=0, MOMSN=32159, MT Status=0, MTMSN=0
2023-11-06T18:52:16.717Z,1699296736.717 [NAL9602](INFO): No messages in MT queue
2023-11-06T18:52:26.506Z,1699296746.506 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231106T171930/Express0047.lzma
2023-11-06T18:52:27.509Z,1699296747.509 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0047.lzma.bak
2023-11-06T18:52:27.509Z,1699296747.509 [DataOverHttps](INFO): SBD MOMSN=19047841
2023-11-06T18:52:28.916Z,1699296748.916 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:52:28.916Z,1699296748.916 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:52:28.916Z,1699296748.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:52:47.467Z,1699296767.467 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T18:57:29.559Z,1699297049.559 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T18:57:29.559Z,1699297049.559 [Default:CheckIn:C.Wait] Stopped
2023-11-06T18:57:29.559Z,1699297049.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T18:57:29.559Z,1699297049.559 [Default:CheckIn:D] Running Loop=1
2023-11-06T18:57:29.955Z,1699297049.955 [Default:CheckIn:D] Stopped
2023-11-06T18:57:29.955Z,1699297049.955 [Default:CheckIn:E] Running Loop=1
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.280046 min 
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn:E] Stopped
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn] Stopped
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn](INFO): Running loop #16
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn] Running Loop=16
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T18:57:30.359Z,1699297050.359 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T18:57:32.365Z,1699297052.365 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185731.00,A,3648.14754,N,12147.22305,W,0.078,28.23,061123,,,A*4B
2023-11-06T18:57:32.377Z,1699297052.377 [NAL9602](INFO): GPS fix at 20231106T185731: (36.802459, -121.787051)
2023-11-06T18:57:32.389Z,1699297052.389 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T18:57:32.389Z,1699297052.389 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T18:57:39.602Z,1699297059.602 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0049.lzma
2023-11-06T18:57:40.606Z,1699297060.606 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0049.lzma.bak
2023-11-06T18:57:40.606Z,1699297060.606 [DataOverHttps](INFO): SBD MOMSN=19047853
2023-11-06T18:57:52.566Z,1699297072.566 [NAL9602](INFO): SBD MO Status=0, MOMSN=32160, MT Status=0, MTMSN=0
2023-11-06T18:57:52.567Z,1699297072.567 [NAL9602](INFO): No messages in MT queue
2023-11-06T18:57:57.054Z,1699297077.054 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231106T171930/Express0050.lzma
2023-11-06T18:57:58.057Z,1699297078.057 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0050.lzma.bak
2023-11-06T18:57:58.057Z,1699297078.057 [DataOverHttps](INFO): SBD MOMSN=19047857
2023-11-06T18:57:59.455Z,1699297079.455 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T18:57:59.455Z,1699297079.455 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T18:57:59.455Z,1699297079.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T18:58:23.271Z,1699297103.271 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:03:00.031Z,1699297380.031 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:03:00.031Z,1699297380.031 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:03:00.032Z,1699297380.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:03:00.032Z,1699297380.032 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:03:00.448Z,1699297380.448 [Default:CheckIn:D] Stopped
2023-11-06T19:03:00.449Z,1699297380.449 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:03:00.869Z,1699297380.869 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.788273 min 
2023-11-06T19:03:00.869Z,1699297380.869 [Default:CheckIn:E] Stopped
2023-11-06T19:03:00.869Z,1699297380.869 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:03:00.869Z,1699297380.869 [Default:CheckIn] Stopped
2023-11-06T19:03:00.870Z,1699297380.870 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:03:00.870Z,1699297380.870 [Default:CheckIn](INFO): Running loop #17
2023-11-06T19:03:00.870Z,1699297380.870 [Default:CheckIn] Running Loop=17
2023-11-06T19:03:00.870Z,1699297380.870 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:03:00.870Z,1699297380.870 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:03:02.849Z,1699297382.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190302.00,A,3648.14752,N,12147.22280,W,0.058,321.80,061123,,,A*70
2023-11-06T19:03:02.852Z,1699297382.852 [NAL9602](INFO): GPS fix at 20231106T190302: (36.802459, -121.787047)
2023-11-06T19:03:02.934Z,1699297382.934 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:03:02.934Z,1699297382.934 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:03:11.578Z,1699297391.578 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231106T171930/Courier0052.lzma
2023-11-06T19:03:12.580Z,1699297392.580 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0052.lzma.bak
2023-11-06T19:03:12.580Z,1699297392.580 [DataOverHttps](INFO): SBD MOMSN=19047861
2023-11-06T19:03:19.009Z,1699297399.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=32161, MT Status=0, MTMSN=0
2023-11-06T19:03:19.009Z,1699297399.009 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:03:29.074Z,1699297409.074 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231106T171930/Express0053.lzma
2023-11-06T19:03:30.077Z,1699297410.077 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0053.lzma.bak
2023-11-06T19:03:30.077Z,1699297410.077 [DataOverHttps](INFO): SBD MOMSN=19047864
2023-11-06T19:03:31.551Z,1699297411.551 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:03:31.551Z,1699297411.551 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:03:31.551Z,1699297411.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:03:49.706Z,1699297429.706 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:04:46.830Z,1699297486.830 [CBIT](INFO): Clearing failed state for component DropWeight
2023-11-06T19:04:46.830Z,1699297486.830 [DropWeight] No Fault, FailCount= 1
2023-11-06T19:08:32.203Z,1699297712.203 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:08:32.203Z,1699297712.203 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:08:32.203Z,1699297712.203 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:08:32.203Z,1699297712.203 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:08:32.615Z,1699297712.615 [Default:CheckIn:D] Stopped
2023-11-06T19:08:32.615Z,1699297712.615 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.324382 min 
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn:E] Stopped
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn] Stopped
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn](INFO): Running loop #18
2023-11-06T19:08:33.049Z,1699297713.049 [Default:CheckIn] Running Loop=18
2023-11-06T19:08:33.050Z,1699297713.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:08:33.050Z,1699297713.050 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:08:35.021Z,1699297715.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190834.00,A,3648.15005,N,12147.22316,W,0.078,321.80,061123,,,D*73
2023-11-06T19:08:35.023Z,1699297715.023 [NAL9602](INFO): GPS fix at 20231106T190834: (36.802501, -121.787053)
2023-11-06T19:08:35.077Z,1699297715.077 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:08:35.077Z,1699297715.077 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:08:43.374Z,1699297723.374 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0055.lzma
2023-11-06T19:08:44.376Z,1699297724.376 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0055.lzma.bak
2023-11-06T19:08:44.376Z,1699297724.376 [DataOverHttps](INFO): SBD MOMSN=19047874
2023-11-06T19:08:49.581Z,1699297729.581 [NAL9602](INFO): SBD MO Status=0, MOMSN=32162, MT Status=0, MTMSN=0
2023-11-06T19:08:49.582Z,1699297729.582 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:09:00.830Z,1699297740.830 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231106T171930/Express0056.lzma
2023-11-06T19:09:01.832Z,1699297741.832 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0056.lzma.bak
2023-11-06T19:09:01.833Z,1699297741.833 [DataOverHttps](INFO): SBD MOMSN=19047877
2023-11-06T19:09:03.322Z,1699297743.322 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:09:03.322Z,1699297743.322 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:09:03.322Z,1699297743.322 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:09:20.272Z,1699297760.272 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:14:03.939Z,1699298043.939 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:14:03.939Z,1699298043.939 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:14:03.939Z,1699298043.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:14:03.939Z,1699298043.939 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:14:04.347Z,1699298044.347 [Default:CheckIn:D] Stopped
2023-11-06T19:14:04.347Z,1699298044.347 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.853247 min 
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn:E] Stopped
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn] Stopped
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn](INFO): Running loop #19
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn] Running Loop=19
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:14:04.769Z,1699298044.769 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:14:06.761Z,1699298046.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191406.00,A,3648.14980,N,12147.22224,W,0.078,321.80,061123,,,D*7A
2023-11-06T19:14:06.772Z,1699298046.772 [NAL9602](INFO): GPS fix at 20231106T191406: (36.802497, -121.787037)
2023-11-06T19:14:06.803Z,1699298046.803 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:14:06.803Z,1699298046.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:14:14.994Z,1699298054.994 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0058.lzma
2023-11-06T19:14:15.996Z,1699298055.996 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0058.lzma.bak
2023-11-06T19:14:15.997Z,1699298055.997 [DataOverHttps](INFO): SBD MOMSN=19047881
2023-11-06T19:14:18.891Z,1699298058.891 [NAL9602](INFO): SBD MO Status=0, MOMSN=32163, MT Status=0, MTMSN=0
2023-11-06T19:14:18.891Z,1699298058.891 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:14:35.379Z,1699298075.379 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231106T171930/Express0059.lzma
2023-11-06T19:14:36.380Z,1699298076.380 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0059.lzma.bak
2023-11-06T19:14:36.380Z,1699298076.381 [DataOverHttps](INFO): SBD MOMSN=19047884
2023-11-06T19:14:37.959Z,1699298077.959 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:14:37.959Z,1699298077.959 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:14:37.959Z,1699298077.959 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:14:49.655Z,1699298089.655 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:15:08.715Z,1699298108.715 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2023-11-06T19:15:08.715Z,1699298108.715 [RDI_Pathfinder] Communications Fault, FailCount= 1
2023-11-06T19:15:08.715Z,1699298108.715 [RDI_Pathfinder](ERROR): Communications Fault
2023-11-06T19:15:08.762Z,1699298108.762 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2023-11-06T19:15:09.123Z,1699298109.123 [RDI_Pathfinder](INFO): Powering down
2023-11-06T19:15:09.896Z,1699298109.896 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2023-11-06T19:15:09.896Z,1699298109.896 [RDI_Pathfinder] No Fault, FailCount= 1
2023-11-06T19:15:10.265Z,1699298110.265 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-11-06T19:19:38.562Z,1699298378.562 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:19:38.562Z,1699298378.562 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:19:38.562Z,1699298378.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:19:38.562Z,1699298378.562 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:19:38.972Z,1699298378.972 [Default:CheckIn:D] Stopped
2023-11-06T19:19:38.973Z,1699298378.973 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:19:39.384Z,1699298379.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.430339 min 
2023-11-06T19:19:39.384Z,1699298379.384 [Default:CheckIn:E] Stopped
2023-11-06T19:19:39.384Z,1699298379.384 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:19:39.384Z,1699298379.384 [Default:CheckIn] Stopped
2023-11-06T19:19:39.385Z,1699298379.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:19:39.385Z,1699298379.385 [Default:CheckIn](INFO): Running loop #20
2023-11-06T19:19:39.385Z,1699298379.385 [Default:CheckIn] Running Loop=20
2023-11-06T19:19:39.385Z,1699298379.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:19:39.385Z,1699298379.385 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:19:41.385Z,1699298381.385 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191940.00,A,3648.14960,N,12147.22219,W,0.019,321.80,061123,,,D*72
2023-11-06T19:19:41.388Z,1699298381.388 [NAL9602](INFO): GPS fix at 20231106T191940: (36.802493, -121.787036)
2023-11-06T19:19:41.400Z,1699298381.400 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:19:41.400Z,1699298381.400 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:19:48.486Z,1699298388.486 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231106T171930/Courier0061.lzma
2023-11-06T19:19:49.488Z,1699298389.488 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0061.lzma.bak
2023-11-06T19:19:49.488Z,1699298389.488 [DataOverHttps](INFO): SBD MOMSN=19047895
2023-11-06T19:20:05.994Z,1699298405.994 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20231106T171930/Express0062.lzma
2023-11-06T19:20:06.997Z,1699298406.997 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0062.lzma.bak
2023-11-06T19:20:06.997Z,1699298406.997 [DataOverHttps](INFO): SBD MOMSN=19047898
2023-11-06T19:20:08.535Z,1699298408.535 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:20:08.535Z,1699298408.535 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:20:08.535Z,1699298408.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:20:09.727Z,1699298409.727 [NAL9602](INFO): SBD MO Status=0, MOMSN=32164, MT Status=0, MTMSN=0
2023-11-06T19:20:09.728Z,1699298409.728 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:20:40.437Z,1699298440.437 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:25:09.134Z,1699298709.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:25:09.134Z,1699298709.134 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:25:09.134Z,1699298709.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:25:09.135Z,1699298709.135 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:25:09.539Z,1699298709.539 [Default:CheckIn:D] Stopped
2023-11-06T19:25:09.539Z,1699298709.539 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.939787 min 
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn:E] Stopped
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn] Stopped
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn](INFO): Running loop #21
2023-11-06T19:25:09.969Z,1699298709.969 [Default:CheckIn] Running Loop=21
2023-11-06T19:25:09.970Z,1699298709.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:25:09.970Z,1699298709.970 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:25:11.949Z,1699298711.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192511.00,A,3648.14925,N,12147.22137,W,0.097,79.09,061123,,,D*4E
2023-11-06T19:25:11.952Z,1699298711.952 [NAL9602](INFO): GPS fix at 20231106T192511: (36.802487, -121.787023)
2023-11-06T19:25:12.006Z,1699298712.006 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:25:12.006Z,1699298712.006 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:25:19.774Z,1699298719.774 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0064.lzma
2023-11-06T19:25:20.777Z,1699298720.777 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0064.lzma.bak
2023-11-06T19:25:20.777Z,1699298720.777 [DataOverHttps](INFO): SBD MOMSN=19047903
2023-11-06T19:25:23.265Z,1699298723.265 [NAL9602](INFO): SBD MO Status=0, MOMSN=32165, MT Status=0, MTMSN=0
2023-11-06T19:25:23.265Z,1699298723.265 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:25:37.138Z,1699298737.138 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231106T171930/Express0065.lzma
2023-11-06T19:25:38.141Z,1699298738.141 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0065.lzma.bak
2023-11-06T19:25:38.141Z,1699298738.141 [DataOverHttps](INFO): SBD MOMSN=19047906
2023-11-06T19:25:39.444Z,1699298739.444 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:25:39.444Z,1699298739.444 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:25:39.444Z,1699298739.444 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:25:53.971Z,1699298753.971 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:30:40.096Z,1699299040.096 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:30:40.096Z,1699299040.096 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:30:40.096Z,1699299040.096 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:30:40.096Z,1699299040.096 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:30:40.503Z,1699299040.503 [Default:CheckIn:D] Stopped
2023-11-06T19:30:40.503Z,1699299040.503 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:30:40.907Z,1699299040.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.455851 min 
2023-11-06T19:30:40.907Z,1699299040.907 [Default:CheckIn:E] Stopped
2023-11-06T19:30:40.907Z,1699299040.907 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:30:40.907Z,1699299040.907 [Default:CheckIn] Stopped
2023-11-06T19:30:40.907Z,1699299040.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:30:40.908Z,1699299040.908 [Default:CheckIn](INFO): Running loop #22
2023-11-06T19:30:40.908Z,1699299040.908 [Default:CheckIn] Running Loop=22
2023-11-06T19:30:40.908Z,1699299040.908 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:30:40.908Z,1699299040.908 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:30:42.913Z,1699299042.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193042.00,A,3648.14962,N,12147.22263,W,0.058,79.09,061123,,,D*4E
2023-11-06T19:30:42.933Z,1699299042.933 [NAL9602](INFO): GPS fix at 20231106T193042: (36.802494, -121.787044)
2023-11-06T19:30:42.962Z,1699299042.962 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:30:42.962Z,1699299042.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:30:50.431Z,1699299050.431 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0067.lzma
2023-11-06T19:30:51.432Z,1699299051.432 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0067.lzma.bak
2023-11-06T19:30:51.433Z,1699299051.433 [DataOverHttps](INFO): SBD MOMSN=19047916
2023-11-06T19:30:59.477Z,1699299059.477 [NAL9602](INFO): SBD MO Status=0, MOMSN=32166, MT Status=0, MTMSN=0
2023-11-06T19:30:59.477Z,1699299059.477 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:31:07.870Z,1699299067.870 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20231106T171930/Express0068.lzma
2023-11-06T19:31:08.872Z,1699299068.872 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0068.lzma.bak
2023-11-06T19:31:08.873Z,1699299068.873 [DataOverHttps](INFO): SBD MOMSN=19047919
2023-11-06T19:31:10.408Z,1699299070.408 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:31:10.408Z,1699299070.408 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:31:10.408Z,1699299070.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:31:30.183Z,1699299090.183 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:34:48.155Z,1699299288.155 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-06T19:34:48.155Z,1699299288.155 [DropWeight] Hardware Fault, FailCount= 1
2023-11-06T19:34:48.155Z,1699299288.155 [DropWeight](ERROR): Hardware Fault
2023-11-06T19:34:48.187Z,1699299288.187 [CBIT](INFO): Critical error at 20231106T193448
2023-11-06T19:34:48.189Z,1699299288.189 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-06T19:34:48.190Z,1699299288.190 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-06T19:34:48.599Z,1699299288.599 [CBIT](INFO): Critical error at 20231106T193448
2023-11-06T19:36:10.978Z,1699299370.978 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:36:10.978Z,1699299370.978 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:36:10.978Z,1699299370.978 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:36:10.978Z,1699299370.978 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:36:11.382Z,1699299371.382 [Default:CheckIn:D] Stopped
2023-11-06T19:36:11.383Z,1699299371.383 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:36:11.814Z,1699299371.814 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.970500 min 
2023-11-06T19:36:11.814Z,1699299371.814 [Default:CheckIn:E] Stopped
2023-11-06T19:36:11.815Z,1699299371.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:36:11.815Z,1699299371.815 [Default:CheckIn] Stopped
2023-11-06T19:36:11.815Z,1699299371.815 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:36:11.815Z,1699299371.815 [Default:CheckIn](INFO): Running loop #23
2023-11-06T19:36:11.815Z,1699299371.815 [Default:CheckIn] Running Loop=23
2023-11-06T19:36:11.815Z,1699299371.815 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:36:11.816Z,1699299371.816 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:36:13.830Z,1699299373.830 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193613.00,A,3648.14948,N,12147.22287,W,0.058,79.09,061123,,,D*4E
2023-11-06T19:36:13.832Z,1699299373.832 [NAL9602](INFO): GPS fix at 20231106T193613: (36.802491, -121.787048)
2023-11-06T19:36:13.899Z,1699299373.899 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:36:13.899Z,1699299373.899 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:36:24.370Z,1699299384.370 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20231106T171930/Courier0070.lzma
2023-11-06T19:36:25.373Z,1699299385.373 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0070.lzma.bak
2023-11-06T19:36:25.373Z,1699299385.373 [DataOverHttps](INFO): SBD MOMSN=19047923
2023-11-06T19:36:38.537Z,1699299398.537 [NAL9602](INFO): SBD MO Status=0, MOMSN=32167, MT Status=0, MTMSN=0
2023-11-06T19:36:38.537Z,1699299398.537 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:36:42.574Z,1699299402.574 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231106T171930/Express0071.lzma
2023-11-06T19:36:43.577Z,1699299403.577 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0071.lzma.bak
2023-11-06T19:36:43.577Z,1699299403.577 [DataOverHttps](INFO): SBD MOMSN=19047928
2023-11-06T19:36:45.010Z,1699299405.010 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:36:45.010Z,1699299405.010 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:36:45.011Z,1699299405.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:37:09.245Z,1699299429.245 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:39:34.341Z,1699299574.341 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:39:40.749Z,1699299580.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:39:53.682Z,1699299593.682 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:40:06.609Z,1699299606.609 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:40:19.537Z,1699299619.537 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:40:32.095Z,1699299632.095 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:40:45.426Z,1699299645.426 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:40:58.757Z,1699299658.757 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:41:12.089Z,1699299672.089 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:41:25.084Z,1699299685.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:41:37.945Z,1699299697.945 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:41:45.683Z,1699299705.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:41:45.683Z,1699299705.683 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:41:45.683Z,1699299705.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:41:45.683Z,1699299705.683 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:41:46.064Z,1699299706.064 [Default:CheckIn:D] Stopped
2023-11-06T19:41:46.064Z,1699299706.064 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:41:46.459Z,1699299706.459 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.548535 min 
2023-11-06T19:41:46.459Z,1699299706.459 [Default:CheckIn:E] Stopped
2023-11-06T19:41:46.459Z,1699299706.459 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:41:46.460Z,1699299706.460 [Default:CheckIn] Stopped
2023-11-06T19:41:46.460Z,1699299706.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:41:46.460Z,1699299706.460 [Default:CheckIn](INFO): Running loop #24
2023-11-06T19:41:46.460Z,1699299706.460 [Default:CheckIn] Running Loop=24
2023-11-06T19:41:46.460Z,1699299706.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:41:46.460Z,1699299706.460 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:41:48.453Z,1699299708.453 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194147.00,A,3648.15577,N,12147.21338,W,0.330,67.89,061123,,,D*42
2023-11-06T19:41:48.456Z,1699299708.456 [NAL9602](INFO): GPS fix at 20231106T194147: (36.802596, -121.786890)
2023-11-06T19:41:48.468Z,1699299708.468 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:41:48.468Z,1699299708.468 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:41:50.873Z,1699299710.873 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-06T19:41:56.158Z,1699299716.158 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0073.lzma
2023-11-06T19:41:57.161Z,1699299717.161 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0073.lzma.bak
2023-11-06T19:41:57.161Z,1699299717.161 [DataOverHttps](INFO): SBD MOMSN=19047939
2023-11-06T19:42:09.072Z,1699299729.072 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2023-11-06T19:42:09.079Z,1699299729.079 [BPC1](INFO): Received data from all battery sticks.
2023-11-06T19:42:13.502Z,1699299733.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=32168, MT Status=0, MTMSN=0
2023-11-06T19:42:13.502Z,1699299733.502 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:42:13.854Z,1699299733.854 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231106T171930/Express0074.lzma
2023-11-06T19:42:14.856Z,1699299734.856 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0074.lzma.bak
2023-11-06T19:42:14.857Z,1699299734.857 [DataOverHttps](INFO): SBD MOMSN=19047942
2023-11-06T19:42:16.364Z,1699299736.364 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:42:16.364Z,1699299736.364 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:42:16.364Z,1699299736.364 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:42:44.300Z,1699299764.300 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:47:17.009Z,1699300037.009 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:47:17.009Z,1699300037.009 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:47:17.010Z,1699300037.010 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:47:17.010Z,1699300037.010 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:47:17.424Z,1699300037.424 [Default:CheckIn:D] Stopped
2023-11-06T19:47:17.424Z,1699300037.424 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:47:17.857Z,1699300037.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.071208 min 
2023-11-06T19:47:17.857Z,1699300037.857 [Default:CheckIn:E] Stopped
2023-11-06T19:47:17.857Z,1699300037.857 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:47:17.857Z,1699300037.857 [Default:CheckIn] Stopped
2023-11-06T19:47:17.857Z,1699300037.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:47:17.857Z,1699300037.857 [Default:CheckIn](INFO): Running loop #25
2023-11-06T19:47:17.858Z,1699300037.858 [Default:CheckIn] Running Loop=25
2023-11-06T19:47:17.858Z,1699300037.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:47:17.858Z,1699300037.858 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:47:19.833Z,1699300039.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194719.00,A,3648.15359,N,12147.22018,W,0.544,242.11,061123,,,D*76
2023-11-06T19:47:19.836Z,1699300039.836 [NAL9602](INFO): GPS fix at 20231106T194719: (36.802560, -121.787003)
2023-11-06T19:47:19.890Z,1699300039.890 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:47:19.891Z,1699300039.891 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:47:28.278Z,1699300048.278 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20231106T171930/Courier0076.lzma
2023-11-06T19:47:29.280Z,1699300049.280 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0076.lzma.bak
2023-11-06T19:47:29.281Z,1699300049.281 [DataOverHttps](INFO): SBD MOMSN=19047946
2023-11-06T19:47:45.647Z,1699300065.647 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231106T171930/Express0077.lzma
2023-11-06T19:47:46.500Z,1699300066.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=32169, MT Status=0, MTMSN=0
2023-11-06T19:47:46.500Z,1699300066.500 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:47:46.641Z,1699300066.641 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0077.lzma.bak
2023-11-06T19:47:46.641Z,1699300066.641 [DataOverHttps](INFO): SBD MOMSN=19047949
2023-11-06T19:47:48.128Z,1699300068.128 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:47:48.128Z,1699300068.128 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:47:48.128Z,1699300068.128 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:48:17.196Z,1699300097.196 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:49:48.164Z,1699300188.164 [CBIT](INFO): Clearing failed state for component DropWeight
2023-11-06T19:49:48.164Z,1699300188.164 [DropWeight] No Fault, FailCount= 1
2023-11-06T19:52:48.735Z,1699300368.735 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:52:48.736Z,1699300368.736 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:52:48.736Z,1699300368.736 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:52:48.736Z,1699300368.736 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:52:49.150Z,1699300369.150 [Default:CheckIn:D] Stopped
2023-11-06T19:52:49.151Z,1699300369.151 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.599707 min 
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn:E] Stopped
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn] Stopped
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn](INFO): Running loop #26
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn] Running Loop=26
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:52:49.535Z,1699300369.535 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:52:51.544Z,1699300371.544 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195250.00,A,3648.47678,N,12147.10702,W,4.996,350.38,061123,,,D*7D
2023-11-06T19:52:51.547Z,1699300371.547 [NAL9602](INFO): GPS fix at 20231106T195250: (36.807946, -121.785117)
2023-11-06T19:52:51.558Z,1699300371.558 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:52:51.558Z,1699300371.558 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:52:58.970Z,1699300378.970 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0079.lzma
2023-11-06T19:52:59.973Z,1699300379.973 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0079.lzma.bak
2023-11-06T19:52:59.973Z,1699300379.973 [DataOverHttps](INFO): SBD MOMSN=19047959
2023-11-06T19:53:11.437Z,1699300391.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=32170, MT Status=0, MTMSN=0
2023-11-06T19:53:11.438Z,1699300391.438 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:53:16.302Z,1699300396.302 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231106T171930/Express0080.lzma
2023-11-06T19:53:17.305Z,1699300397.305 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0080.lzma.bak
2023-11-06T19:53:17.305Z,1699300397.305 [DataOverHttps](INFO): SBD MOMSN=19047962
2023-11-06T19:53:18.715Z,1699300398.715 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:53:18.715Z,1699300398.715 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:53:18.715Z,1699300398.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:53:42.140Z,1699300422.140 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T19:54:55.730Z,1699300495.730 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3278,V
2023-11-06T19:58:19.371Z,1699300699.371 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T19:58:19.371Z,1699300699.371 [Default:CheckIn:C.Wait] Stopped
2023-11-06T19:58:19.371Z,1699300699.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T19:58:19.371Z,1699300699.371 [Default:CheckIn:D] Running Loop=1
2023-11-06T19:58:19.861Z,1699300699.861 [Default:CheckIn:D] Stopped
2023-11-06T19:58:19.861Z,1699300699.861 [Default:CheckIn:E] Running Loop=1
2023-11-06T19:58:20.192Z,1699300700.192 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.111816 min 
2023-11-06T19:58:20.192Z,1699300700.192 [Default:CheckIn:E] Stopped
2023-11-06T19:58:20.192Z,1699300700.192 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T19:58:20.192Z,1699300700.192 [Default:CheckIn] Stopped
2023-11-06T19:58:20.192Z,1699300700.192 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T19:58:20.192Z,1699300700.192 [Default:CheckIn](INFO): Running loop #27
2023-11-06T19:58:20.193Z,1699300700.193 [Default:CheckIn] Running Loop=27
2023-11-06T19:58:20.193Z,1699300700.193 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T19:58:20.193Z,1699300700.193 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T19:58:22.174Z,1699300702.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195821.00,A,3648.18692,N,12148.51044,W,22.957,258.78,061123,,,D*44
2023-11-06T19:58:22.176Z,1699300702.176 [NAL9602](INFO): GPS fix at 20231106T195821: (36.803115, -121.808507)
2023-11-06T19:58:22.232Z,1699300702.232 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T19:58:22.232Z,1699300702.232 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T19:58:29.926Z,1699300709.926 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231106T171930/Courier0082.lzma
2023-11-06T19:58:30.928Z,1699300710.928 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Courier0082.lzma.bak
2023-11-06T19:58:30.929Z,1699300710.929 [DataOverHttps](INFO): SBD MOMSN=19047967
2023-11-06T19:58:36.309Z,1699300716.309 [NAL9602](INFO): SBD MO Status=0, MOMSN=32171, MT Status=0, MTMSN=0
2023-11-06T19:58:36.309Z,1699300716.309 [NAL9602](INFO): No messages in MT queue
2023-11-06T19:58:47.270Z,1699300727.270 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231106T171930/Express0083.lzma
2023-11-06T19:58:48.272Z,1699300728.272 [DataOverHttps](INFO): Moved sent file to Logs/20231106T171930/Express0083.lzma.bak
2023-11-06T19:58:48.273Z,1699300728.273 [DataOverHttps](INFO): SBD MOMSN=19047970
2023-11-06T19:58:49.719Z,1699300729.719 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T19:58:49.719Z,1699300729.719 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T19:58:49.719Z,1699300729.719 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T19:59:07.011Z,1699300747.011 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T20:02:56.367Z,1699300976.367 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239433
2023-11-06T20:03:50.235Z,1699301030.235 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T20:03:50.235Z,1699301030.235 [Default:CheckIn:C.Wait] Stopped
2023-11-06T20:03:50.235Z,1699301030.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T20:03:50.235Z,1699301030.235 [Default:CheckIn:D] Running Loop=1
2023-11-06T20:03:50.639Z,1699301030.639 [Default:CheckIn:D] Stopped
2023-11-06T20:03:50.639Z,1699301030.639 [Default:CheckIn:E] Running Loop=1
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.624772 min 
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn:E] Stopped
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn] Stopped
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn](INFO): Running loop #28
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn] Running Loop=28
2023-11-06T20:03:51.047Z,1699301031.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T20:03:51.048Z,1699301031.048 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T20:03:53.053Z,1699301033.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200352.00,A,3647.91308,N,12150.97436,W,22.238,259.96,061123,,,D*4D
2023-11-06T20:03:53.056Z,1699301033.056 [NAL9602](INFO): GPS fix at 20231106T200352: (36.798551, -121.849573)
2023-11-06T20:03:53.100Z,1699301033.100 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T20:03:53.100Z,1699301033.100 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T20:04:19.015Z,1699301059.015 [NAL9602](INFO): SBD MO Status=1, MOMSN=32172, MT Status=0, MTMSN=0
2023-11-06T20:04:19.063Z,1699301059.063 [NAL9602](INFO): Sent 72 bytes from file Logs/20231106T171930/Courier0085.lzma
2023-11-06T20:04:19.063Z,1699301059.063 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:04:21.175Z,1699301061.175 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002790
2023-11-06T20:04:34.951Z,1699301074.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=32173, MT Status=2, MTMSN=0
2023-11-06T20:04:34.951Z,1699301074.951 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:05:05.665Z,1699301105.665 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T20:05:21.328Z,1699301121.328 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.156048
2023-11-06T20:05:47.346Z,1699301147.346 [NAL9602](INFO): SBD MO Status=1, MOMSN=32173, MT Status=0, MTMSN=0
2023-11-06T20:05:47.399Z,1699301147.399 [NAL9602](INFO): Sent 150 bytes from file Logs/20231106T171930/Express0086.lzma
2023-11-06T20:05:47.400Z,1699301147.400 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:05:52.467Z,1699301152.467 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:05:57.037Z,1699301157.037 [NAL9602](INFO): SBD MO Status=0, MOMSN=32174, MT Status=0, MTMSN=0
2023-11-06T20:05:57.135Z,1699301157.135 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T20:05:57.135Z,1699301157.135 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T20:05:57.135Z,1699301157.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T20:06:27.616Z,1699301187.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:06:27.739Z,1699301187.739 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T20:07:37.879Z,1699301257.879 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:08:13.031Z,1699301293.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:08:48.171Z,1699301328.171 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:09:23.311Z,1699301363.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:09:43.687Z,1699301383.687 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23110612104603,35.0, +2559.77
2023-11-06T20:09:58.451Z,1699301398.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:10:33.591Z,1699301433.591 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:10:57.671Z,1699301457.671 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T20:10:57.671Z,1699301457.671 [Default:CheckIn:C.Wait] Stopped
2023-11-06T20:10:57.671Z,1699301457.671 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T20:10:57.671Z,1699301457.671 [Default:CheckIn:D] Running Loop=1
2023-11-06T20:10:58.056Z,1699301458.056 [Default:CheckIn:D] Stopped
2023-11-06T20:10:58.056Z,1699301458.056 [Default:CheckIn:E] Running Loop=1
2023-11-06T20:10:58.428Z,1699301458.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.748405 min 
2023-11-06T20:10:58.428Z,1699301458.428 [Default:CheckIn:E] Stopped
2023-11-06T20:10:58.428Z,1699301458.428 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T20:10:58.428Z,1699301458.428 [Default:CheckIn] Stopped
2023-11-06T20:10:58.429Z,1699301458.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T20:10:58.429Z,1699301458.429 [Default:CheckIn](INFO): Running loop #29
2023-11-06T20:10:58.429Z,1699301458.429 [Default:CheckIn] Running Loop=29
2023-11-06T20:10:58.429Z,1699301458.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T20:10:58.429Z,1699301458.429 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T20:11:00.442Z,1699301460.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201059.00,A,3647.82990,N,12151.66544,W,0.603,34.40,061123,,,A*4D
2023-11-06T20:11:00.444Z,1699301460.444 [NAL9602](INFO): GPS fix at 20231106T201059: (36.797165, -121.861091)
2023-11-06T20:11:00.456Z,1699301460.456 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T20:11:00.456Z,1699301460.456 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T20:11:08.731Z,1699301468.731 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:11:24.366Z,1699301484.366 [NAL9602](INFO): SBD MO Status=2, MOMSN=32175, MT Status=2, MTMSN=0
2023-11-06T20:11:24.366Z,1699301484.366 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:11:35.357Z,1699301495.357 [NAL9602](INFO): SBD MO Status=1, MOMSN=32175, MT Status=0, MTMSN=0
2023-11-06T20:11:35.407Z,1699301495.407 [NAL9602](INFO): Sent 73 bytes from file Logs/20231106T171930/Courier0088.lzma
2023-11-06T20:11:35.407Z,1699301495.407 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:11:43.876Z,1699301503.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:11:44.321Z,1699301504.321 [NAL9602](INFO): SBD MO Status=1, MOMSN=32176, MT Status=0, MTMSN=0
2023-11-06T20:11:44.379Z,1699301504.379 [NAL9602](INFO): Sent 150 bytes from file Logs/20231106T171930/Express0089.lzma
2023-11-06T20:11:44.379Z,1699301504.379 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:11:56.846Z,1699301516.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=32177, MT Status=2, MTMSN=0
2023-11-06T20:11:56.846Z,1699301516.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:12:01.870Z,1699301521.870 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-11-06T20:12:15.835Z,1699301535.835 [NAL9602](INFO): SBD MO Status=0, MOMSN=32177, MT Status=0, MTMSN=0
2023-11-06T20:12:15.948Z,1699301535.948 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T20:12:15.948Z,1699301535.948 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T20:12:15.948Z,1699301535.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T20:12:17.547Z,1699301537.547 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003444
2023-11-06T20:12:46.604Z,1699301566.604 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T20:13:30.599Z,1699301610.599 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-11-06T20:13:37.630Z,1699301617.630 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.913700
2023-11-06T20:14:20.236Z,1699301660.236 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002846
2023-11-06T20:16:41.662Z,1699301801.662 [DataOverHttps](IMPORTANT): SBD MTMSN=20231106T201640
2023-11-06T20:16:49.391Z,1699301809.391 [DataOverHttps](INFO): Received command: ibit
2023-11-06T20:16:49.403Z,1699301809.403 [CommandExec](IMPORTANT): got command ibit
2023-11-06T20:16:49.469Z,1699301809.469 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-11-06T20:16:49.469Z,1699301809.469 [IBIT](IMPORTANT): Beginning control surface checks.
2023-11-06T20:16:49.473Z,1699301809.473 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-06T20:16:51.052Z,1699301811.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201650.00,A,3647.86672,N,12151.61415,W,0.544,53.16,061123,,,A*45
2023-11-06T20:16:51.055Z,1699301811.055 [NAL9602](INFO): GPS fix at 20231106T201650: (36.797779, -121.860236)
2023-11-06T20:17:00.780Z,1699301820.780 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006277
CHAN A1 (24V): 0.027478
CHAN A2 (12V): 0.005010
CHAN A3 (5V): -0.000233
CHAN B0 (3.3V): 0.000890
CHAN B1 (3.15aV): 0.000461
CHAN B2 (3.15bV): 0.000934
CHAN B3 (GND): -0.000333
OPEN: 0.005342
Full Scale: +/- 1 mA
2023-11-06T20:17:03.985Z,1699301823.985 [NAL9602](INFO): SBD MO Status=0, MOMSN=32178, MT Status=0, MTMSN=0
2023-11-06T20:17:03.985Z,1699301823.985 [NAL9602](INFO): No messages in MT queue
2023-11-06T20:17:05.214Z,1699301825.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201704.00,A,3647.86850,N,12151.61336,W,0.486,15.36,061123,,,A*42
2023-11-06T20:17:05.256Z,1699301825.256 [NAL9602](INFO): GPS fix at 20231106T201704: (36.797808, -121.860223)
2023-11-06T20:17:08.030Z,1699301828.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201707.00,A,3647.86911,N,12151.61298,W,0.855,34.10,061123,,,A*45
2023-11-06T20:17:08.032Z,1699301828.032 [NAL9602](INFO): GPS fix at 20231106T201707: (36.797818, -121.860216)
2023-11-06T20:17:10.849Z,1699301830.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201710.00,A,3647.86933,N,12151.61214,W,0.797,50.09,061123,,,A*4C
2023-11-06T20:17:10.851Z,1699301830.851 [NAL9602](INFO): GPS fix at 20231106T201710: (36.797822, -121.860202)
2023-11-06T20:17:14.080Z,1699301834.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201713.00,A,3647.86976,N,12151.61207,W,0.505,37.71,061123,,,A*4B
2023-11-06T20:17:14.094Z,1699301834.094 [NAL9602](INFO): GPS fix at 20231106T201713: (36.797829, -121.860201)
2023-11-06T20:17:16.909Z,1699301836.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201716.00,A,3647.87003,N,12151.61207,W,0.855,37.71,061123,,,A*4C
2023-11-06T20:17:16.912Z,1699301836.912 [NAL9602](INFO): GPS fix at 20231106T201716: (36.797834, -121.860201)
2023-11-06T20:17:20.140Z,1699301840.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201719.00,A,3647.87050,N,12151.61109,W,0.914,50.42,061123,,,A*4D
2023-11-06T20:17:20.143Z,1699301840.143 [NAL9602](INFO): GPS fix at 20231106T201719: (36.797842, -121.860185)
2023-11-06T20:17:22.970Z,1699301842.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201722.00,A,3647.87084,N,12151.61108,W,0.253,50.42,061123,,,A*45
2023-11-06T20:17:22.972Z,1699301842.972 [NAL9602](INFO): GPS fix at 20231106T201722: (36.797847, -121.860185)
2023-11-06T20:17:25.798Z,1699301845.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201725.00,A,3647.87094,N,12151.61085,W,0.428,50.42,061123,,,A*4D
2023-11-06T20:17:25.800Z,1699301845.800 [NAL9602](INFO): GPS fix at 20231106T201725: (36.797849, -121.860181)
2023-11-06T20:17:29.030Z,1699301849.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201728.00,A,3647.87126,N,12151.61057,W,0.719,18.18,061123,,,A*45
2023-11-06T20:17:29.033Z,1699301849.033 [NAL9602](INFO): GPS fix at 20231106T201728: (36.797854, -121.860176)
2023-11-06T20:17:31.861Z,1699301851.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201731.00,A,3647.87195,N,12151.61028,W,0.544,18.29,061123,,,A*45
2023-11-06T20:17:31.864Z,1699301851.864 [NAL9602](INFO): GPS fix at 20231106T201731: (36.797866, -121.860171)
2023-11-06T20:17:35.089Z,1699301855.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201734.00,A,3647.87194,N,12151.60962,W,0.622,18.29,061123,,,A*44
2023-11-06T20:17:35.092Z,1699301855.092 [NAL9602](INFO): GPS fix at 20231106T201734: (36.797866, -121.860160)
2023-11-06T20:17:35.947Z,1699301855.947 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.797867 Longitude: -121.860161
2023-11-06T20:17:36.321Z,1699301856.321 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.580000
2023-11-06T20:17:36.321Z,1699301856.321 [IBIT](IMPORTANT): batteryCapacityThreshold: 1.000000 Ah
2023-11-06T20:17:36.321Z,1699301856.321 [IBIT](IMPORTANT): batteryVoltageThreshold: 11.000000 V
2023-11-06T20:17:36.733Z,1699301856.733 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-11-06T20:17:36.733Z,1699301856.733 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-11-06T20:17:36.733Z,1699301856.733 [IBIT](IMPORTANT): Pressure:7.895235 PSI
2023-11-06T20:17:36.734Z,1699301856.734 [IBIT](IMPORTANT): Humidity:23.654011 %
2023-11-06T20:17:37.149Z,1699301857.149 [IBIT](IMPORTANT): Vehicle Pitch:1.911046 degrees
2023-11-06T20:17:37.149Z,1699301857.149 [IBIT](IMPORTANT): Vehicle  Roll:8.823522 degrees
2023-11-06T20:17:37.149Z,1699301857.149 [IBIT](IMPORTANT): Vehicle  Heading:86.732613 degrees
2023-11-06T20:17:37.537Z,1699301857.537 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-11-06T20:17:37.537Z,1699301857.537 [IBIT](IMPORTANT): buoyancyNeutral: 227.996384 cc
2023-11-06T20:17:37.537Z,1699301857.537 [IBIT](IMPORTANT): massDefault: 0.388176 cm
2023-11-06T20:17:37.538Z,1699301857.538 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-11-06T20:17:37.538Z,1699301857.538 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-11-06T20:17:37.538Z,1699301857.538 [IBIT](IMPORTANT): IBIT PASSED
2023-11-06T20:17:37.958Z,1699301857.958 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T20:17:37.959Z,1699301857.959 [Default:CheckIn:C.Wait] Stopped
2023-11-06T20:17:37.959Z,1699301857.959 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T20:17:37.959Z,1699301857.959 [Default:CheckIn:D] Running Loop=1
2023-11-06T20:17:38.335Z,1699301858.335 [Default:CheckIn:D] Stopped
2023-11-06T20:17:38.335Z,1699301858.335 [Default:CheckIn:E] Running Loop=1
2023-11-06T20:17:38.739Z,1699301858.739 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.419710 min 
2023-11-06T20:17:38.739Z,1699301858.739 [Default:CheckIn:E] Stopped
2023-11-06T20:17:38.739Z,1699301858.739 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T20:17:38.739Z,1699301858.739 [Default:CheckIn] Stopped
2023-11-06T20:17:38.739Z,1699301858.739 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T20:17:38.739Z,1699301858.739 [Default:CheckIn](INFO): Running loop #30
2023-11-06T20:17:38.740Z,1699301858.740 [Default:CheckIn] Running Loop=30
2023-11-06T20:17:38.740Z,1699301858.740 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T20:17:38.740Z,1699301858.740 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T20:17:40.746Z,1699301860.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201740.00,A,3647.87276,N,12151.60928,W,0.544,6.66,061123,,,A*71
2023-11-06T20:17:40.749Z,1699301860.749 [NAL9602](INFO): GPS fix at 20231106T201740: (36.797879, -121.860155)
2023-11-06T20:17:40.761Z,1699301860.761 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T20:17:40.761Z,1699301860.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T20:17:48.631Z,1699301868.631 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:17:50.639Z,1699301870.639 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251480
2023-11-06T20:18:11.513Z,1699301891.513 [NAL9602](INFO): SBD MO Status=2, MOMSN=32179, MT Status=2, MTMSN=0
2023-11-06T20:18:11.513Z,1699301891.513 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:18:21.767Z,1699301901.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:18:30.149Z,1699301910.149 [NAL9602](INFO): SBD MO Status=1, MOMSN=32179, MT Status=0, MTMSN=0
2023-11-06T20:18:30.215Z,1699301910.215 [NAL9602](INFO): Sent 71 bytes from file Logs/20231106T171930/Courier0091.lzma
2023-11-06T20:18:30.215Z,1699301910.215 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:18:46.802Z,1699301926.802 [NAL9602](INFO): SBD MO Status=2, MOMSN=32180, MT Status=2, MTMSN=0
2023-11-06T20:18:46.802Z,1699301926.802 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:18:56.923Z,1699301936.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:19:07.066Z,1699301947.066 [NAL9602](INFO): SBD MO Status=1, MOMSN=32180, MT Status=0, MTMSN=0
2023-11-06T20:19:07.124Z,1699301947.124 [NAL9602](INFO): Sent 332 bytes from file Logs/20231106T171930/Express0092.lzma
2023-11-06T20:19:07.124Z,1699301947.124 [NAL9602](INFO): Packets left to send: 2
2023-11-06T20:19:19.240Z,1699301959.240 [NAL9602](INFO): SBD MO Status=2, MOMSN=32181, MT Status=2, MTMSN=0
2023-11-06T20:19:19.240Z,1699301959.240 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:19:32.075Z,1699301972.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:19:49.629Z,1699301989.629 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-06T20:19:49.629Z,1699301989.629 [DropWeight] Hardware Fault, FailCount= 1
2023-11-06T20:19:49.629Z,1699301989.629 [DropWeight](ERROR): Hardware Fault
2023-11-06T20:19:49.723Z,1699301989.723 [CBIT](INFO): Critical error at 20231106T201949
2023-11-06T20:19:49.725Z,1699301989.725 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-06T20:19:49.726Z,1699301989.726 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-06T20:19:50.096Z,1699301990.096 [CBIT](INFO): Critical error at 20231106T201949
2023-11-06T20:19:51.228Z,1699301991.228 [NAL9602](INFO): SBD MO Status=2, MOMSN=32181, MT Status=2, MTMSN=0
2023-11-06T20:19:51.228Z,1699301991.228 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-06T20:20:07.217Z,1699302007.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:20:10.706Z,1699302010.706 [NAL9602](INFO): SBD MO Status=1, MOMSN=32181, MT Status=0, MTMSN=0
2023-11-06T20:20:10.771Z,1699302010.771 [NAL9602](INFO): Sent 332 bytes from file Logs/20231106T171930/Express0092.lzma
2023-11-06T20:20:10.771Z,1699302010.771 [NAL9602](INFO): Packets left to send: 1
2023-11-06T20:20:22.118Z,1699302022.118 [NAL9602](INFO): SBD MO Status=1, MOMSN=32182, MT Status=0, MTMSN=0
2023-11-06T20:20:22.184Z,1699302022.184 [NAL9602](INFO): Sent 143 bytes from file Logs/20231106T171930/Express0092.lzma
2023-11-06T20:20:22.184Z,1699302022.184 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:20:27.859Z,1699302027.859 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23110612213203,35.0, +24.8,   0.,  0
2023-11-06T20:20:31.904Z,1699302031.904 [NAL9602](INFO): SBD MO Status=1, MOMSN=32183, MT Status=0, MTMSN=0
2023-11-06T20:20:31.983Z,1699302031.983 [NAL9602](INFO): Sent 61 bytes from file Logs/20231106T171930/Express0095.lzma
2023-11-06T20:20:31.983Z,1699302031.983 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:20:42.030Z,1699302042.030 [NAL9602](INFO): SBD MO Status=0, MOMSN=32184, MT Status=0, MTMSN=0
2023-11-06T20:20:42.162Z,1699302042.162 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T20:20:42.163Z,1699302042.163 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-06T20:20:42.163Z,1699302042.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-06T20:20:42.391Z,1699302042.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:21:12.697Z,1699302072.697 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-06T20:21:17.551Z,1699302077.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:21:52.691Z,1699302112.691 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:22:27.843Z,1699302147.843 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:23:02.999Z,1699302182.999 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:23:38.140Z,1699302218.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:24:13.291Z,1699302253.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:24:48.451Z,1699302288.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:25:23.608Z,1699302323.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:25:39.750Z,1699302339.750 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23110612264203,35.0, +24 0.00,4215.64
2023-11-06T20:25:42.584Z,1699302342.584 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-06T20:25:42.584Z,1699302342.584 [Default:CheckIn:C.Wait] Stopped
2023-11-06T20:25:42.584Z,1699302342.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-06T20:25:42.584Z,1699302342.584 [Default:CheckIn:D] Running Loop=1
2023-11-06T20:25:42.986Z,1699302342.986 [Default:CheckIn:D] Stopped
2023-11-06T20:25:42.986Z,1699302342.986 [Default:CheckIn:E] Running Loop=1
2023-11-06T20:25:43.396Z,1699302343.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.497233 min 
2023-11-06T20:25:43.396Z,1699302343.396 [Default:CheckIn:E] Stopped
2023-11-06T20:25:43.396Z,1699302343.396 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-06T20:25:43.396Z,1699302343.396 [Default:CheckIn] Stopped
2023-11-06T20:25:43.396Z,1699302343.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T20:25:43.397Z,1699302343.397 [Default:CheckIn](INFO): Running loop #31
2023-11-06T20:25:43.397Z,1699302343.397 [Default:CheckIn] Running Loop=31
2023-11-06T20:25:43.397Z,1699302343.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-06T20:25:43.397Z,1699302343.397 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-06T20:25:45.396Z,1699302345.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202544.00,A,3647.91660,N,12151.55269,W,0.641,32.95,061123,,,D*40
2023-11-06T20:25:45.399Z,1699302345.399 [NAL9602](INFO): GPS fix at 20231106T202544: (36.798610, -121.859212)
2023-11-06T20:25:45.410Z,1699302345.410 [Default:CheckIn:Read_GPS] Stopped
2023-11-06T20:25:45.410Z,1699302345.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-06T20:25:58.747Z,1699302358.747 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-06T20:26:00.033Z,1699302360.033 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=32185, MT Status=1, MTMSN=1818
2023-11-06T20:26:00.087Z,1699302360.087 [NAL9602](INFO): Sent 146 bytes from file Logs/20231106T171930/Courier0097.lzma
2023-11-06T20:26:00.087Z,1699302360.087 [NAL9602](INFO): Packets left to send: 0
2023-11-06T20:26:00.519Z,1699302360.519 [NAL9602](INFO): Received command: restart sys
2023-11-06T20:26:00.596Z,1699302360.596 [CommandExec](IMPORTANT): got command restart system
2023-11-06T20:26:02.743Z,1699302362.743 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:02.743Z,1699302362.743 [CommandExec](INFO): Uninitializing the command executive.
2023-11-06T20:26:02.743Z,1699302362.743 [CommandExec](INFO): Uninitializing the command scheduler.
2023-11-06T20:26:02.743Z,1699302362.743 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:02.787Z,1699302362.787 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-11-06T20:26:02.788Z,1699302362.788 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-11-06T20:26:02.788Z,1699302362.788 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:02.789Z,1699302362.789 [NavChartDb](INFO): Join timeout helper Thread ID is 5389
2023-11-06T20:26:02.807Z,1699302362.807 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:02.807Z,1699302362.807 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:02.807Z,1699302362.807 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-11-06T20:26:02.808Z,1699302362.808 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:02.808Z,1699302362.808 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 5390
2023-11-06T20:26:03.063Z,1699302363.063 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:03.063Z,1699302363.063 [WetLabsUBAT](INFO): Powering down
2023-11-06T20:26:03.064Z,1699302363.064 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:03.067Z,1699302363.067 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-11-06T20:26:03.067Z,1699302363.067 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:03.067Z,1699302363.067 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5391
2023-11-06T20:26:03.315Z,1699302363.315 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:03.315Z,1699302363.315 [WetLabsBB2FL](INFO): Powering down
2023-11-06T20:26:03.316Z,1699302363.316 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:03.323Z,1699302363.323 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-11-06T20:26:03.323Z,1699302363.323 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:03.323Z,1699302363.323 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5392
2023-11-06T20:26:04.215Z,1699302364.215 [CTD_Seabird](INFO): Powering down
2023-11-06T20:26:04.226Z,1699302364.226 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:04.227Z,1699302364.227 [CTD_Seabird](INFO): Powering down
2023-11-06T20:26:04.240Z,1699302364.240 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:04.259Z,1699302364.259 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-11-06T20:26:04.259Z,1699302364.259 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:04.259Z,1699302364.259 [DAT](INFO): Join timeout helper Thread ID is 5393
2023-11-06T20:26:04.271Z,1699302364.271 [DAT](INFO): Powering down
2023-11-06T20:26:04.344Z,1699302364.344 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:04.344Z,1699302364.344 [DAT](INFO): Powering down
2023-11-06T20:26:04.345Z,1699302364.345 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:04.356Z,1699302364.356 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-11-06T20:26:04.356Z,1699302364.356 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:04.356Z,1699302364.356 [Radio_Surface](INFO): Join timeout helper Thread ID is 5394
2023-11-06T20:26:04.687Z,1699302364.687 [Radio_Surface](INFO): Powering down
2023-11-06T20:26:04.688Z,1699302364.688 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:04.688Z,1699302364.688 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:04.708Z,1699302364.708 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-11-06T20:26:04.708Z,1699302364.708 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:04.709Z,1699302364.709 [Onboard](INFO): Join timeout helper Thread ID is 5395
2023-11-06T20:26:07.199Z,1699302367.199 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:07.199Z,1699302367.199 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.219Z,1699302367.219 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-11-06T20:26:07.219Z,1699302367.219 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.220Z,1699302367.220 [DataOverHttps](INFO): Join timeout helper Thread ID is 5396
2023-11-06T20:26:07.783Z,1699302367.783 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:07.783Z,1699302367.783 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.799Z,1699302367.799 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-11-06T20:26:07.799Z,1699302367.799 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.800Z,1699302367.800 [BackseatComponent](INFO): Join timeout helper Thread ID is 5397
2023-11-06T20:26:07.819Z,1699302367.819 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:07.819Z,1699302367.819 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.838Z,1699302367.838 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-11-06T20:26:07.838Z,1699302367.838 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.851Z,1699302367.851 [logger](INFO): Join timeout helper Thread ID is 5398
2023-11-06T20:26:07.876Z,1699302367.876 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:07.876Z,1699302367.876 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.891Z,1699302367.891 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-11-06T20:26:07.891Z,1699302367.891 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.892Z,1699302367.892 [CommandLine](INFO): Join timeout helper Thread ID is 5399
2023-11-06T20:26:07.979Z,1699302367.979 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:07.979Z,1699302367.979 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.991Z,1699302367.991 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-11-06T20:26:07.991Z,1699302367.991 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.992Z,1699302367.992 [CommandExec](INFO): Join timeout helper Thread ID is 5400
2023-11-06T20:26:07.993Z,1699302367.993 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-11-06T20:26:07.993Z,1699302367.993 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:07.993Z,1699302367.993 [controlThread](INFO): Join timeout helper Thread ID is 5401
2023-11-06T20:26:08.171Z,1699302368.171 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-06T20:26:08.171Z,1699302368.171 [controlThread](DEBUG): Uninitializing ControlThread
2023-11-06T20:26:08.171Z,1699302368.171 [AHRS_M2](INFO): Powering down
2023-11-06T20:26:08.243Z,1699302368.243 [NAL9602](INFO): Powering down
2023-11-06T20:26:08.315Z,1699302368.315 [RDI_Pathfinder](INFO): Powering down
2023-11-06T20:26:08.316Z,1699302368.316 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-11-06T20:26:08.317Z,1699302368.317 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-11-06T20:26:08.317Z,1699302368.317 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-11-06T20:26:08.318Z,1699302368.318 [MissionManager](INFO): Uninitializing Mission Default
2023-11-06T20:26:08.318Z,1699302368.318 [Default] Stopped
2023-11-06T20:26:08.318Z,1699302368.318 [Default](DEBUG): Aggregate::uninitialize Default
2023-11-06T20:26:08.318Z,1699302368.318 [Default:B.GoToSurface] Stopped
2023-11-06T20:26:08.318Z,1699302368.318 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-06T20:26:08.318Z,1699302368.318 [Default:CheckIn] Stopped
2023-11-06T20:26:08.319Z,1699302368.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-06T20:26:08.319Z,1699302368.319 [Default:CheckIn:Read_Iridium] Stopped
2023-11-06T20:26:08.321Z,1699302368.321 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-11-06T20:26:08.322Z,1699302368.322 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-11-06T20:26:08.322Z,1699302368.322 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-11-06T20:26:08.322Z,1699302368.322 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-11-06T20:26:08.323Z,1699302368.323 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-11-06T20:26:08.323Z,1699302368.323 [BuoyancyServo](INFO): Powering down
2023-11-06T20:26:08.335Z,1699302368.335 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-11-06T20:26:08.335Z,1699302368.335 [ElevatorServo](INFO): Powering down
2023-11-06T20:26:08.336Z,1699302368.336 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-11-06T20:26:08.336Z,1699302368.336 [MassServo](INFO): Powering down
2023-11-06T20:26:08.337Z,1699302368.337 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-11-06T20:26:08.337Z,1699302368.337 [RudderServo](INFO): Powering down
2023-11-06T20:26:08.338Z,1699302368.338 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-11-06T20:26:08.338Z,1699302368.338 [ThrusterServo](INFO): Powering down
2023-11-06T20:26:08.339Z,1699302368.339 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-11-06T20:26:08.339Z,1699302368.339 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-11-06T20:26:08.340Z,1699302368.340 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-11-06T20:26:08.340Z,1699302368.340 [CBIT](DEBUG): Powering off loads.
2023-11-06T20:26:08.350Z,1699302368.350 [CBIT](DEBUG): Disabling WDT.
2023-11-06T20:26:08.362Z,1699302368.362 [CBIT](DEBUG): Opening all GF detection circuits.
2023-11-06T20:26:08.363Z,1699302368.363 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.418Z,1699302368.418 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.426Z,1699302368.426 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.429Z,1699302368.429 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.435Z,1699302368.435 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.482Z,1699302368.482 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.543Z,1699302368.543 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.545Z,1699302368.545 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.549Z,1699302368.549 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.557Z,1699302368.557 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-06T20:26:08.636Z,1699302368.636 [logger ThreadHandler](INFO): Thread cancelled.