2023-03-28T15:26:57.550Z,1680017217.550 [Supervisor](DEBUG): Initializing supervisor.
2023-03-28T15:26:57.554Z,1680017217.554 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-28T15:26:57.555Z,1680017217.555 [SyncHandler](INFO): Protected caller Thread ID is 2115
2023-03-28T15:26:57.555Z,1680017217.555 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-28T15:26:57.556Z,1680017217.556 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-28T15:26:57.556Z,1680017217.556 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2116
2023-03-28T15:26:57.560Z,1680017217.560 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-28T15:26:57.577Z,1680017217.577 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-28T15:26:57.578Z,1680017217.578 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-28T15:26:57.579Z,1680017217.579 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2117
2023-03-28T15:26:57.583Z,1680017217.583 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-28T15:26:57.584Z,1680017217.584 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-28T15:26:57.584Z,1680017217.584 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2118
2023-03-28T15:26:57.586Z,1680017217.586 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-28T15:26:57.587Z,1680017217.587 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-28T15:26:57.587Z,1680017217.587 [logger ThreadHandler](INFO): Protected caller Thread ID is 2119
2023-03-28T15:26:57.591Z,1680017217.591 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-28T15:26:57.591Z,1680017217.591 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-28T15:26:57.593Z,1680017217.593 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-28T15:26:57.846Z,1680017217.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-28T15:26:57.847Z,1680017217.847 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-28T15:26:57.924Z,1680017217.924 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-28T15:26:58.373Z,1680017218.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-28T15:26:58.374Z,1680017218.374 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-28T15:26:58.680Z,1680017218.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-28T15:26:58.681Z,1680017218.681 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-28T15:26:58.774Z,1680017218.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-28T15:26:58.775Z,1680017218.775 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-28T15:26:59.143Z,1680017219.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-28T15:26:59.144Z,1680017219.144 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-28T15:26:59.416Z,1680017219.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-28T15:26:59.417Z,1680017219.417 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-28T15:26:59.896Z,1680017219.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-28T15:26:59.897Z,1680017219.897 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-28T15:27:00.028Z,1680017220.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-28T15:27:00.029Z,1680017220.029 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-28T15:27:00.105Z,1680017220.105 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-28T15:27:00.817Z,1680017220.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-28T15:27:00.818Z,1680017220.818 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-28T15:27:01.425Z,1680017221.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-28T15:27:01.425Z,1680017221.425 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-28T15:27:01.600Z,1680017221.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-28T15:27:01.600Z,1680017221.600 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-28T15:27:01.707Z,1680017221.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-28T15:27:01.707Z,1680017221.707 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-28T15:27:01.919Z,1680017221.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-28T15:27:01.919Z,1680017221.919 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-28T15:27:02.144Z,1680017222.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-28T15:27:02.146Z,1680017222.146 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-03-28T15:27:02.147Z,1680017222.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-03-28T15:27:02.235Z,1680017222.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-03-28T15:27:02.317Z,1680017222.317 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-03-28T15:27:02.413Z,1680017222.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-03-28T15:27:02.494Z,1680017222.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-03-28T15:27:02.590Z,1680017222.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-03-28T15:27:02.687Z,1680017222.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-03-28T15:27:02.856Z,1680017222.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-03-28T15:27:02.931Z,1680017222.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-03-28T15:27:03.069Z,1680017223.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-03-28T15:27:03.199Z,1680017223.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-03-28T15:27:03.309Z,1680017223.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-03-28T15:27:03.564Z,1680017223.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-28T15:27:03.566Z,1680017223.566 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-03-28T15:27:03.566Z,1680017223.566 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-28T15:27:03.595Z,1680017223.595 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-28T15:27:03.645Z,1680017223.645 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-28T15:27:03.703Z,1680017223.703 [VerticalControl] Loaded
2023-03-28T15:27:03.703Z,1680017223.703 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-28T15:27:03.706Z,1680017223.706 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-28T15:27:03.746Z,1680017223.746 [HorizontalControl] Loaded
2023-03-28T15:27:03.746Z,1680017223.746 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-28T15:27:03.748Z,1680017223.748 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-28T15:27:03.751Z,1680017223.751 [SpeedControl] Loaded
2023-03-28T15:27:03.752Z,1680017223.752 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-28T15:27:03.754Z,1680017223.754 [LoopControl](DEBUG): Construct LoopControl.
2023-03-28T15:27:03.755Z,1680017223.755 [LoopControl] Loaded
2023-03-28T15:27:03.755Z,1680017223.755 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-28T15:27:03.755Z,1680017223.755 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-28T15:27:03.756Z,1680017223.756 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-28T15:27:03.766Z,1680017223.766 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-28T15:27:03.766Z,1680017223.766 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-28T15:27:03.912Z,1680017223.912 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-28T15:27:03.913Z,1680017223.913 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-28T15:27:04.040Z,1680017224.040 [BuoyancyServo] Loaded
2023-03-28T15:27:04.040Z,1680017224.040 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-28T15:27:04.060Z,1680017224.060 [ElevatorServo] Loaded
2023-03-28T15:27:04.060Z,1680017224.060 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-28T15:27:04.079Z,1680017224.079 [MassServo] Loaded
2023-03-28T15:27:04.079Z,1680017224.079 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-28T15:27:04.097Z,1680017224.097 [RudderServo] Loaded
2023-03-28T15:27:04.097Z,1680017224.097 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-28T15:27:04.114Z,1680017224.114 [ThrusterServo] Loaded
2023-03-28T15:27:04.114Z,1680017224.114 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-03-28T15:27:04.115Z,1680017224.115 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-28T15:27:04.115Z,1680017224.115 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-28T15:27:04.226Z,1680017224.226 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-28T15:27:04.226Z,1680017224.226 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-28T15:27:04.240Z,1680017224.240 [NavChart] Loaded
2023-03-28T15:27:04.240Z,1680017224.240 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-28T15:27:04.245Z,1680017224.245 [UniversalFixResidualReporter] Loaded
2023-03-28T15:27:04.245Z,1680017224.245 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-28T15:27:04.246Z,1680017224.246 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-28T15:27:04.247Z,1680017224.247 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-28T15:27:04.301Z,1680017224.301 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-28T15:27:04.302Z,1680017224.302 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-28T15:27:04.719Z,1680017224.719 [AHRS_M2] Loaded
2023-03-28T15:27:04.719Z,1680017224.719 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-28T15:27:05.524Z,1680017225.524 [BPC1] Loaded
2023-03-28T15:27:05.524Z,1680017225.524 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-03-28T15:27:05.596Z,1680017225.596 [DataOverHttps] Loaded
2023-03-28T15:27:05.596Z,1680017225.596 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-28T15:27:05.599Z,1680017225.599 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-03-28T15:27:05.599Z,1680017225.599 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2205
2023-03-28T15:27:05.620Z,1680017225.620 [Depth_Keller] Loaded
2023-03-28T15:27:05.620Z,1680017225.620 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-28T15:27:05.625Z,1680017225.625 [DropWeight] Loaded
2023-03-28T15:27:05.625Z,1680017225.625 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-03-28T15:27:05.644Z,1680017225.644 [MultiRay] Loaded
2023-03-28T15:27:05.644Z,1680017225.644 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-03-28T15:27:05.705Z,1680017225.705 [NAL9602] Loaded
2023-03-28T15:27:05.705Z,1680017225.705 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-28T15:27:05.735Z,1680017225.735 [Onboard] Loaded
2023-03-28T15:27:05.735Z,1680017225.735 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-28T15:27:05.736Z,1680017225.736 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-03-28T15:27:05.736Z,1680017225.736 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2206
2023-03-28T15:27:05.750Z,1680017225.750 [Power24vConverter] Loaded
2023-03-28T15:27:05.750Z,1680017225.750 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-28T15:27:05.763Z,1680017225.763 [Radio_Surface] Loaded
2023-03-28T15:27:05.764Z,1680017225.764 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-28T15:27:05.764Z,1680017225.764 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-03-28T15:27:05.765Z,1680017225.765 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2207
2023-03-28T15:27:05.766Z,1680017225.766 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-28T15:27:05.766Z,1680017225.766 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-28T15:27:05.816Z,1680017225.816 [DepthRateCalculator] Loaded
2023-03-28T15:27:05.816Z,1680017225.816 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-28T15:27:05.821Z,1680017225.821 [PitchRateCalculator] Loaded
2023-03-28T15:27:05.821Z,1680017225.821 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-28T15:27:05.830Z,1680017225.830 [SpeedCalculator] Loaded
2023-03-28T15:27:05.830Z,1680017225.830 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-28T15:27:05.846Z,1680017225.846 [VerticalHomogeneityIndexCalculator] Loaded
2023-03-28T15:27:05.846Z,1680017225.846 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-03-28T15:27:05.850Z,1680017225.850 [YawRateCalculator] Loaded
2023-03-28T15:27:05.850Z,1680017225.850 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-28T15:27:05.868Z,1680017225.868 [ElevatorOffsetCalculator] Loaded
2023-03-28T15:27:05.869Z,1680017225.869 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-28T15:27:05.885Z,1680017225.885 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-03-28T15:27:05.885Z,1680017225.885 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-03-28T15:27:05.885Z,1680017225.885 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-28T15:27:05.886Z,1680017225.886 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-28T15:27:06.317Z,1680017226.317 [CANONSampler] Loaded
2023-03-28T15:27:06.317Z,1680017226.317 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-03-28T15:27:06.475Z,1680017226.475 [CTD_Seabird] Loaded
2023-03-28T15:27:06.476Z,1680017226.476 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-28T15:27:06.484Z,1680017226.484 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2023-03-28T15:27:06.485Z,1680017226.485 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2208
2023-03-28T15:27:06.504Z,1680017226.504 [PAR_Licor] Loaded
2023-03-28T15:27:06.505Z,1680017226.505 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-28T15:27:06.535Z,1680017226.535 [WetLabsBB2FL] Loaded
2023-03-28T15:27:06.535Z,1680017226.535 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-28T15:27:06.536Z,1680017226.536 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2023-03-28T15:27:06.537Z,1680017226.537 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2209
2023-03-28T15:27:06.537Z,1680017226.537 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-28T15:27:06.538Z,1680017226.538 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-28T15:27:06.657Z,1680017226.657 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-28T15:27:06.658Z,1680017226.658 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-28T15:27:07.064Z,1680017227.064 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-28T15:27:07.064Z,1680017227.064 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-28T15:27:07.202Z,1680017227.202 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-28T15:27:07.212Z,1680017227.212 [SBIT] Loaded
2023-03-28T15:27:07.212Z,1680017227.212 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-28T15:27:07.215Z,1680017227.215 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-28T15:27:07.229Z,1680017227.229 [IBIT] Loaded
2023-03-28T15:27:07.229Z,1680017227.229 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-28T15:27:07.235Z,1680017227.235 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-28T15:27:07.336Z,1680017227.336 [CBIT] Loaded
2023-03-28T15:27:07.337Z,1680017227.337 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-28T15:27:07.337Z,1680017227.337 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-28T15:27:07.338Z,1680017227.338 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-28T15:27:07.436Z,1680017227.436 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-28T15:27:07.442Z,1680017227.442 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-28T15:27:07.445Z,1680017227.445 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-28T15:27:07.456Z,1680017227.456 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-28T15:27:07.457Z,1680017227.457 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-03-28T15:27:07.457Z,1680017227.457 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2210
2023-03-28T15:27:07.461Z,1680017227.461 [Supervisor](INFO): Main Thread ID is 2114
2023-03-28T15:27:07.461Z,1680017227.461 [Supervisor](DEBUG): Running supervisor.
2023-03-28T15:27:07.462Z,1680017227.462 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2211
2023-03-28T15:27:07.463Z,1680017227.463 [CommandExec](INFO): Initializing the command executive.
2023-03-28T15:27:07.464Z,1680017227.464 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2212
2023-03-28T15:27:07.467Z,1680017227.467 [controlThread ThreadHandler](INFO): Handler Thread ID is 2213
2023-03-28T15:27:07.468Z,1680017227.468 [controlThread](DEBUG): Initializing ControlThread
2023-03-28T15:27:07.469Z,1680017227.469 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-28T15:27:07.470Z,1680017227.470 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-28T15:27:07.471Z,1680017227.471 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-28T15:27:07.472Z,1680017227.472 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-28T15:27:07.473Z,1680017227.473 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-28T15:27:07.474Z,1680017227.474 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-28T15:27:07.481Z,1680017227.481 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-28T15:27:07.481Z,1680017227.481 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-28T15:27:07.481Z,1680017227.481 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-28T15:27:07.481Z,1680017227.481 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-03-28T15:27:07.482Z,1680017227.482 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-28T15:27:07.483Z,1680017227.483 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-28T15:27:07.483Z,1680017227.483 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-03-28T15:27:07.487Z,1680017227.487 [SBIT](INFO): Initialize SBIT Component.
2023-03-28T15:27:07.487Z,1680017227.487 [SBIT](IMPORTANT): git: 2023-03-17
2023-03-28T15:27:07.488Z,1680017227.488 [SBIT](INFO): git hash: 11ed9ebecafe2be57985d0df7ef5106c11760b30
2023-03-28T15:27:07.488Z,1680017227.488 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-28T15:27:07.489Z,1680017227.489 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-03-28T15:27:07.490Z,1680017227.490 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2023-03-28T15:27:07.491Z,1680017227.491 [IBIT](INFO): Initialize IBIT Component.
2023-03-28T15:27:07.492Z,1680017227.492 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-28T15:27:07.493Z,1680017227.493 [logger ThreadHandler](INFO): Handler Thread ID is 2214
2023-03-28T15:27:07.503Z,1680017227.503 [CBIT](DEBUG): Initialized mux pins.
2023-03-28T15:27:07.503Z,1680017227.503 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-28T15:27:07.511Z,1680017227.511 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2215
2023-03-28T15:27:07.512Z,1680017227.512 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-28T15:27:07.523Z,1680017227.523 [Onboard ThreadHandler](INFO): Handler Thread ID is 2216
2023-03-28T15:27:07.527Z,1680017227.527 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-03-28T15:27:07.528Z,1680017227.528 [CBIT](DEBUG): Initializing heartbeat.
2023-03-28T15:27:07.544Z,1680017227.544 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2217
2023-03-28T15:27:07.559Z,1680017227.559 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2218
2023-03-28T15:27:07.560Z,1680017227.560 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-28T15:27:07.566Z,1680017227.566 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2220
2023-03-28T15:27:07.571Z,1680017227.571 [WetLabsBB2FL](INFO): Powering up
2023-03-28T15:27:07.572Z,1680017227.572 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2222
2023-03-28T15:27:07.575Z,1680017227.575 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-28T15:27:07.575Z,1680017227.575 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-28T15:27:07.576Z,1680017227.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-28T15:27:07.576Z,1680017227.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-28T15:27:07.576Z,1680017227.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-28T15:27:07.576Z,1680017227.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-28T15:27:07.576Z,1680017227.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-28T15:27:07.576Z,1680017227.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-28T15:27:07.599Z,1680017227.599 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-28T15:27:07.599Z,1680017227.599 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-28T15:27:07.639Z,1680017227.639 [CBIT](DEBUG): Backplane powered.
2023-03-28T15:27:07.640Z,1680017227.640 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-28T15:27:07.652Z,1680017227.652 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-28T15:27:07.688Z,1680017227.688 [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-03-28T15:27:07.689Z,1680017227.689 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-28T15:27:07.767Z,1680017227.767 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-28T15:27:07.768Z,1680017227.768 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-28T15:27:07.770Z,1680017227.770 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-28T15:27:07.798Z,1680017227.798 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-28T15:27:07.831Z,1680017227.831 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-28T15:27:07.837Z,1680017227.837 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-28T15:27:07.861Z,1680017227.861 [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-03-28T15:27:07.865Z,1680017227.865 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-03-28T15:27:07.888Z,1680017227.888 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-28T15:27:07.950Z,1680017227.950 [MultiRay](INFO): Powering up MultiRay Lights
2023-03-28T15:27:07.951Z,1680017227.951 [Radio_Surface](INFO): Powering up
2023-03-28T15:27:07.983Z,1680017227.983 [Power24vConverter](INFO): Powering up.
2023-03-28T15:27:07.984Z,1680017227.984 [CANONSampler](INFO): Powering down
2023-03-28T15:27:08.017Z,1680017228.017 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-28T15:27:08.023Z,1680017228.023 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-28T15:27:08.024Z,1680017228.024 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-28T15:27:08.031Z,1680017228.031 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-28T15:27:08.032Z,1680017228.032 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-28T15:27:08.043Z,1680017228.043 [MassServo](DEBUG): Initializing MassServo.
2023-03-28T15:27:08.044Z,1680017228.044 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-28T15:27:08.051Z,1680017228.051 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-28T15:27:08.052Z,1680017228.052 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-03-28T15:27:08.059Z,1680017228.059 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-03-28T15:27:08.320Z,1680017228.320 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-28T15:27:08.320Z,1680017228.320 [DropWeight] Hardware Fault, FailCount= 1
2023-03-28T15:27:08.320Z,1680017228.320 [DropWeight](ERROR): Hardware Fault
2023-03-28T15:27:08.390Z,1680017228.390 [CommandExec](FAULT): Scheduling is paused
2023-03-28T15:27:08.398Z,1680017228.398 [CBIT](INFO): Critical error at 20230328T152708
2023-03-28T15:27:08.399Z,1680017228.399 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-28T15:27:08.401Z,1680017228.401 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-28T15:27:08.402Z,1680017228.402 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-28T15:27:08.995Z,1680017228.995 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-03-28T15:27:08.995Z,1680017228.995 [RudderServo](FAULT): Rudder failed to initialize
2023-03-28T15:27:08.995Z,1680017228.995 [RudderServo] Communications Fault, FailCount= 1
2023-03-28T15:27:08.995Z,1680017228.995 [RudderServo](ERROR): Communications Fault
2023-03-28T15:27:09.093Z,1680017229.093 [CBIT](INFO): Critical error at 20230328T152708
2023-03-28T15:27:09.095Z,1680017229.095 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-03-28T15:27:09.488Z,1680017229.488 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-28T15:27:09.488Z,1680017229.488 [RudderServo](INFO): Powering down
2023-03-28T15:27:09.964Z,1680017229.964 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-28T15:27:10.083Z,1680017230.083 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-28T15:27:10.088Z,1680017230.088 [CBIT](INFO): Clearing failed state for component RudderServo
2023-03-28T15:27:10.088Z,1680017230.088 [RudderServo] No Fault, FailCount= 1
2023-03-28T15:27:10.183Z,1680017230.183 [WetLabsBB2FL](INFO): Powering down
2023-03-28T15:27:14.627Z,1680017234.627 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-03-28T15:27:20.056Z,1680017240.056 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005460
2023-03-28T15:27:35.772Z,1680017255.772 [NAL9602](INFO): Powering up NAL9602
2023-03-28T15:27:46.680Z,1680017266.680 [NAL9602](INFO): NAL9602 initialized
2023-03-28T15:28:02.068Z,1680017282.068 [CommandExec](IMPORTANT): got command report mod NAL9602.sigQuality
2023-03-28T15:28:02.079Z,1680017282.079 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T15:28:23.759Z,1680017303.759 [CommandExec](IMPORTANT): got command strobe off
2023-03-28T15:28:23.759Z,1680017303.759 [CommandExec](IMPORTANT): Deactivating strobe
2023-03-28T15:28:23.895Z,1680017303.895 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-28T15:28:23.900Z,1680017303.900 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-28T15:28:34.836Z,1680017314.836 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-28T15:28:35.244Z,1680017315.244 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-28T15:28:40.155Z,1680017320.155 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T15:28:46.192Z,1680017326.192 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-28T15:28:46.584Z,1680017326.584 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-28T15:28:53.041Z,1680017333.041 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T15:28:57.496Z,1680017337.496 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-03-28T15:28:57.497Z,1680017337.497 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-03-28T15:29:18.047Z,1680017358.047 [SBIT](IMPORTANT): SBIT PASSED
2023-03-28T15:29:18.047Z,1680017358.047 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-28T15:29:18.048Z,1680017358.048 [SBIT](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2023-03-28T15:29:18.048Z,1680017358.048 [SBIT](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2023-03-28T15:29:18.048Z,1680017358.048 [SBIT](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2023-03-28T15:29:18.048Z,1680017358.048 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-03-28T15:29:18.048Z,1680017358.048 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;

2023-03-28T15:29:18.049Z,1680017358.049 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-03-28T15:29:18.050Z,1680017358.050 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_mean_sea_water_temperature;

2023-03-28T15:29:18.050Z,1680017358.050 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-03-28T15:29:18.050Z,1680017358.050 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2023-03-28T15:29:18.050Z,1680017358.050 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.100000 celsius;

2023-03-28T15:29:18.050Z,1680017358.050 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-03-28T15:29:18.050Z,1680017358.050 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=36.81 degree;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=36.81 degree;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=36.78 degree;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=36.78 degree;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2023-03-28T15:29:18.059Z,1680017358.059 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-121.87 degree;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-121.83 degree;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-121.83 degree;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-121.87 degree;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=336.456139 cubic_centimeter;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.157167 millimeter;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=15 meter;

2023-03-28T15:29:18.060Z,1680017358.060 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=20 meter;

2023-03-28T15:29:18.061Z,1680017358.061 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-03-28T15:29:18.406Z,1680017358.406 [MissionManager](IMPORTANT): Started mission Startup
2023-03-28T15:29:18.407Z,1680017358.407 [Startup] Running Loop=1
2023-03-28T15:29:18.407Z,1680017358.407 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-28T15:29:18.407Z,1680017358.407 [Startup:A.GoToSurface] Running Loop=1
2023-03-28T15:29:18.407Z,1680017358.407 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-28T15:29:18.408Z,1680017358.408 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-28T15:29:18.408Z,1680017358.408 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-28T15:29:18.408Z,1680017358.408 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-28T15:29:18.409Z,1680017358.409 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-28T15:29:18.409Z,1680017358.409 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-28T15:29:18.435Z,1680017358.435 [Startup:StartupSatComms] Running Loop=1
2023-03-28T15:29:18.435Z,1680017358.435 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-28T15:29:18.435Z,1680017358.435 [Startup:StartupSatComms:A] Running Loop=1
2023-03-28T15:29:18.823Z,1680017358.823 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-28T15:30:07.716Z,1680017407.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-28T15:30:07.716Z,1680017407.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-28T15:30:07.726Z,1680017407.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-28T15:30:08.118Z,1680017408.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-28T15:30:08.118Z,1680017408.118 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-28T15:30:18.601Z,1680017418.601 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-28T15:29:18.4Z
2023-03-28T15:30:18.601Z,1680017418.601 [Startup:StartupSatComms:A] Stopped
2023-03-28T15:30:18.601Z,1680017418.601 [Startup:StartupSatComms:B] Running Loop=1
2023-03-28T15:30:19.013Z,1680017419.013 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-28T15:30:27.704Z,1680017427.704 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230327T231936/Courier0033.lzma
2023-03-28T15:30:28.705Z,1680017428.705 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0033.lzma.bak
2023-03-28T15:30:28.705Z,1680017428.705 [DataOverHttps](INFO): SBD MOMSN=17724686
2023-03-28T15:30:44.294Z,1680017444.294 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20230328T152657/Courier0000.lzma
2023-03-28T15:30:45.296Z,1680017445.296 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0000.lzma.bak
2023-03-28T15:30:45.297Z,1680017445.297 [DataOverHttps](INFO): SBD MOMSN=17724688
2023-03-28T15:31:03.182Z,1680017463.182 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20230327T231936/Express0034.lzma
2023-03-28T15:31:04.184Z,1680017464.184 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0034.lzma.bak
2023-03-28T15:31:04.185Z,1680017464.185 [DataOverHttps](INFO): SBD MOMSN=17724693
2023-03-28T15:31:18.977Z,1680017478.977 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-28T15:30:18.6Z
2023-03-28T15:31:18.977Z,1680017478.977 [Startup:StartupSatComms:B] Stopped
2023-03-28T15:31:18.978Z,1680017478.978 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-28T15:31:18.978Z,1680017478.978 [Startup:StartupSatComms] Stopped
2023-03-28T15:31:18.978Z,1680017478.978 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-28T15:31:18.979Z,1680017478.979 [Startup](INFO): Completed Startup
2023-03-28T15:31:18.979Z,1680017478.979 [MissionManager](INFO): Startup is completed.
2023-03-28T15:31:18.979Z,1680017478.979 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-28T15:31:18.979Z,1680017478.979 [Startup] Stopped
2023-03-28T15:31:18.979Z,1680017478.979 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-28T15:31:18.979Z,1680017478.979 [Startup:A.GoToSurface] Stopped
2023-03-28T15:31:18.979Z,1680017478.979 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-28T15:31:19.378Z,1680017479.378 [MissionManager](IMPORTANT): Started mission Default
2023-03-28T15:31:19.378Z,1680017479.378 [Default] Running Loop=1
2023-03-28T15:31:19.378Z,1680017479.378 [Default](DEBUG): Aggregate::initialize Default
2023-03-28T15:31:19.378Z,1680017479.378 [Default:B.GoToSurface] Running Loop=1
2023-03-28T15:31:19.378Z,1680017479.378 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-28T15:31:19.379Z,1680017479.379 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-28T15:31:19.379Z,1680017479.379 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-28T15:31:19.379Z,1680017479.379 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-28T15:31:19.380Z,1680017479.380 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-28T15:31:19.380Z,1680017479.380 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-28T15:31:19.380Z,1680017479.380 [Default:A.Wait] Running Loop=1
2023-03-28T15:31:19.380Z,1680017479.380 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-28T15:31:19.700Z,1680017479.700 [DataOverHttps](INFO): Sending 1300 bytes from file Logs/20230328T152657/Express0001.lzma
2023-03-28T15:31:20.700Z,1680017480.700 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0001.lzma.bak
2023-03-28T15:31:20.700Z,1680017480.700 [DataOverHttps](INFO): SBD MOMSN=17724696
2023-03-28T15:31:32.737Z,1680017492.737 [Default:A.Wait](INFO): Done Waiting.
2023-03-28T15:31:32.737Z,1680017492.737 [Default:A.Wait] Stopped
2023-03-28T15:31:32.737Z,1680017492.737 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T15:31:33.149Z,1680017493.149 [Default:CheckIn] Running Loop=1
2023-03-28T15:31:33.149Z,1680017493.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T15:31:33.149Z,1680017493.149 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T15:31:33.535Z,1680017493.535 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-28T15:32:04.867Z,1680017524.867 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:32:06.940Z,1680017526.940 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T15:32:18.680Z,1680017538.680 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T15:32:20.625Z,1680017540.625 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:32:29.926Z,1680017549.926 [NAL9602](INFO): SBD MO Status=2, MOMSN=39542, MT Status=2, MTMSN=0
2023-03-28T15:32:29.926Z,1680017549.926 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T15:32:35.571Z,1680017555.571 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:32:48.512Z,1680017568.512 [NAL9602](INFO): SBD MO Status=0, MOMSN=39542, MT Status=0, MTMSN=0
2023-03-28T15:32:48.512Z,1680017568.512 [NAL9602](INFO): No messages in MT queue
2023-03-28T15:32:50.923Z,1680017570.923 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:33:02.250Z,1680017582.250 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153300.00,A,3648.17580,N,12147.28565,W,0.156,0.00,280323,,,A*7D
2023-03-28T15:33:02.253Z,1680017582.253 [NAL9602](INFO): GPS fix at 20230328T153300: (36.802930, -121.788094)
2023-03-28T15:33:02.265Z,1680017582.265 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T15:33:02.265Z,1680017582.265 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T15:33:02.677Z,1680017582.677 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-28T15:33:05.893Z,1680017585.893 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:33:11.530Z,1680017591.530 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230328T152657/Courier0004.lzma
2023-03-28T15:33:12.532Z,1680017592.532 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0004.lzma.bak
2023-03-28T15:33:12.533Z,1680017592.533 [DataOverHttps](INFO): SBD MOMSN=17724751
2023-03-28T15:33:20.841Z,1680017600.841 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:33:29.270Z,1680017609.270 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230328T152657/Express0005.lzma
2023-03-28T15:33:30.273Z,1680017610.273 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0005.lzma.bak
2023-03-28T15:33:30.273Z,1680017610.273 [DataOverHttps](INFO): SBD MOMSN=17724755
2023-03-28T15:33:31.871Z,1680017611.871 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T15:33:31.871Z,1680017611.871 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T15:33:31.871Z,1680017611.871 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T15:33:34.180Z,1680017614.180 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T15:33:34.265Z,1680017614.265 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T15:33:35.789Z,1680017615.789 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:33:51.141Z,1680017631.141 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:34:06.089Z,1680017646.089 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T15:34:06.852Z,1680017646.852 [CommandExec](IMPORTANT): got command report mod NAL9602.sigQuality
2023-03-28T15:34:20.706Z,1680017660.706 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-03-28T15:34:35.588Z,1680017675.588 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5.
2023-03-28T15:34:35.616Z,1680017675.616 [BPC1](INFO): Received data from all battery sticks.
2023-03-28T15:37:09.108Z,1680017829.108 [Power24vConverter](INFO): Powering down.
2023-03-28T15:38:32.339Z,1680017912.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T15:38:32.339Z,1680017912.339 [Default:CheckIn:C.Wait] Stopped
2023-03-28T15:38:32.340Z,1680017912.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T15:38:32.340Z,1680017912.340 [Default:CheckIn:D] Running Loop=1
2023-03-28T15:38:32.755Z,1680017912.755 [Default:CheckIn:D] Stopped
2023-03-28T15:38:32.755Z,1680017912.755 [Default:CheckIn:E] Running Loop=1
2023-03-28T15:38:33.155Z,1680017913.155 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.222953 min 
2023-03-28T15:38:33.155Z,1680017913.155 [Default:CheckIn:E] Stopped
2023-03-28T15:38:33.155Z,1680017913.155 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T15:38:33.155Z,1680017913.155 [Default:CheckIn] Stopped
2023-03-28T15:38:33.156Z,1680017913.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T15:38:33.156Z,1680017913.156 [Default:CheckIn](INFO): Running loop #2
2023-03-28T15:38:33.156Z,1680017913.156 [Default:CheckIn] Running Loop=2
2023-03-28T15:38:33.156Z,1680017913.156 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T15:38:33.156Z,1680017913.156 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T15:38:35.162Z,1680017915.162 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153833.00,A,3648.17485,N,12147.28650,W,0.078,51.72,280323,,,A*4B
2023-03-28T15:38:35.165Z,1680017915.165 [NAL9602](INFO): GPS fix at 20230328T153833: (36.802914, -121.788108)
2023-03-28T15:38:35.176Z,1680017915.176 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T15:38:35.176Z,1680017915.176 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T15:38:41.299Z,1680017921.299 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T15:38:42.502Z,1680017922.502 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230328T152657/Courier0007.lzma
2023-03-28T15:38:43.505Z,1680017923.505 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0007.lzma.bak
2023-03-28T15:38:43.505Z,1680017923.505 [DataOverHttps](INFO): SBD MOMSN=17725130
2023-03-28T15:38:43.645Z,1680017923.645 [NAL9602](INFO): SBD MO Status=0, MOMSN=39543, MT Status=0, MTMSN=0
2023-03-28T15:38:43.645Z,1680017923.645 [NAL9602](INFO): No messages in MT queue
2023-03-28T15:39:02.442Z,1680017942.442 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230328T152657/Express0008.lzma
2023-03-28T15:39:03.444Z,1680017943.444 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0008.lzma.bak
2023-03-28T15:39:03.445Z,1680017943.445 [DataOverHttps](INFO): SBD MOMSN=17725133
2023-03-28T15:39:04.710Z,1680017944.710 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T15:39:04.710Z,1680017944.710 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T15:39:04.710Z,1680017944.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T15:39:14.382Z,1680017954.382 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T15:39:14.442Z,1680017954.442 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T15:42:08.559Z,1680018128.559 [CBIT](INFO): Clearing failed state for component DropWeight
2023-03-28T15:42:08.559Z,1680018128.559 [DropWeight] No Fault, FailCount= 1
2023-03-28T15:44:05.289Z,1680018245.289 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T15:44:05.289Z,1680018245.289 [Default:CheckIn:C.Wait] Stopped
2023-03-28T15:44:05.290Z,1680018245.290 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T15:44:05.290Z,1680018245.290 [Default:CheckIn:D] Running Loop=1
2023-03-28T15:44:05.678Z,1680018245.678 [Default:CheckIn:D] Stopped
2023-03-28T15:44:05.678Z,1680018245.678 [Default:CheckIn:E] Running Loop=1
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.771662 min 
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn:E] Stopped
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn] Stopped
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn](INFO): Running loop #3
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn] Running Loop=3
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T15:44:06.088Z,1680018246.088 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T15:44:08.098Z,1680018248.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154407.00,A,3648.17575,N,12147.28667,W,0.019,51.72,280323,,,D*4F
2023-03-28T15:44:08.109Z,1680018248.109 [NAL9602](INFO): GPS fix at 20230328T154407: (36.802929, -121.788111)
2023-03-28T15:44:08.121Z,1680018248.121 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T15:44:08.121Z,1680018248.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T15:44:15.045Z,1680018255.045 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T152657/Courier0010.lzma
2023-03-28T15:44:16.045Z,1680018256.045 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0010.lzma.bak
2023-03-28T15:44:16.045Z,1680018256.045 [DataOverHttps](INFO): SBD MOMSN=17725138
2023-03-28T15:44:17.233Z,1680018257.233 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T15:44:19.522Z,1680018259.522 [NAL9602](INFO): SBD MO Status=0, MOMSN=39544, MT Status=0, MTMSN=0
2023-03-28T15:44:19.522Z,1680018259.522 [NAL9602](INFO): No messages in MT queue
2023-03-28T15:44:34.486Z,1680018274.486 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0011.lzma
2023-03-28T15:44:35.488Z,1680018275.488 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0011.lzma.bak
2023-03-28T15:44:35.488Z,1680018275.488 [DataOverHttps](INFO): SBD MOMSN=17725142
2023-03-28T15:44:36.963Z,1680018276.963 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T15:44:36.963Z,1680018276.963 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T15:44:36.964Z,1680018276.964 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T15:44:50.270Z,1680018290.270 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T15:44:50.344Z,1680018290.344 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T15:49:37.528Z,1680018577.528 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T15:49:37.528Z,1680018577.528 [Default:CheckIn:C.Wait] Stopped
2023-03-28T15:49:37.528Z,1680018577.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T15:49:37.529Z,1680018577.529 [Default:CheckIn:D] Running Loop=1
2023-03-28T15:49:37.938Z,1680018577.938 [Default:CheckIn:D] Stopped
2023-03-28T15:49:37.938Z,1680018577.938 [Default:CheckIn:E] Running Loop=1
2023-03-28T15:49:38.339Z,1680018578.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.309334 min 
2023-03-28T15:49:38.339Z,1680018578.339 [Default:CheckIn:E] Stopped
2023-03-28T15:49:38.339Z,1680018578.339 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T15:49:38.340Z,1680018578.340 [Default:CheckIn] Stopped
2023-03-28T15:49:38.340Z,1680018578.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T15:49:38.340Z,1680018578.340 [Default:CheckIn](INFO): Running loop #4
2023-03-28T15:49:38.340Z,1680018578.340 [Default:CheckIn] Running Loop=4
2023-03-28T15:49:38.340Z,1680018578.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T15:49:38.340Z,1680018578.340 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T15:49:40.345Z,1680018580.345 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154939.00,A,3648.17513,N,12147.28625,W,0.039,51.72,280323,,,D*4B
2023-03-28T15:49:40.348Z,1680018580.348 [NAL9602](INFO): GPS fix at 20230328T154939: (36.802919, -121.788104)
2023-03-28T15:49:40.359Z,1680018580.359 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T15:49:40.360Z,1680018580.360 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T15:49:48.066Z,1680018588.066 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T152657/Courier0013.lzma
2023-03-28T15:49:49.068Z,1680018589.068 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0013.lzma.bak
2023-03-28T15:49:49.068Z,1680018589.068 [DataOverHttps](INFO): SBD MOMSN=17725513
2023-03-28T15:50:05.030Z,1680018605.030 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230328T152657/Express0014.lzma
2023-03-28T15:50:06.032Z,1680018606.032 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0014.lzma.bak
2023-03-28T15:50:06.033Z,1680018606.033 [DataOverHttps](INFO): SBD MOMSN=17725516
2023-03-28T15:50:07.837Z,1680018607.837 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T15:50:07.837Z,1680018607.837 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T15:50:07.837Z,1680018607.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T15:51:06.031Z,1680018666.031 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T15:51:17.716Z,1680018677.716 [NAL9602](INFO): SBD MO Status=0, MOMSN=39545, MT Status=0, MTMSN=0
2023-03-28T15:51:17.716Z,1680018677.716 [NAL9602](INFO): No messages in MT queue
2023-03-28T15:51:48.412Z,1680018708.412 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T15:51:48.435Z,1680018708.435 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T15:55:08.441Z,1680018908.441 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T15:55:08.441Z,1680018908.441 [Default:CheckIn:C.Wait] Stopped
2023-03-28T15:55:08.441Z,1680018908.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T15:55:08.441Z,1680018908.441 [Default:CheckIn:D] Running Loop=1
2023-03-28T15:55:08.847Z,1680018908.847 [Default:CheckIn:D] Stopped
2023-03-28T15:55:08.847Z,1680018908.847 [Default:CheckIn:E] Running Loop=1
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.824487 min 
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn:E] Stopped
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn] Stopped
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn](INFO): Running loop #5
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn] Running Loop=5
2023-03-28T15:55:09.249Z,1680018909.249 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T15:55:09.250Z,1680018909.250 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T15:55:11.253Z,1680018911.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155510.00,A,3648.17555,N,12147.28541,W,0.039,51.72,280323,,,D*4E
2023-03-28T15:55:11.255Z,1680018911.255 [NAL9602](INFO): GPS fix at 20230328T155510: (36.802926, -121.788090)
2023-03-28T15:55:11.295Z,1680018911.295 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T15:55:11.295Z,1680018911.295 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T15:55:18.462Z,1680018918.462 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T152657/Courier0016.lzma
2023-03-28T15:55:19.464Z,1680018919.464 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0016.lzma.bak
2023-03-28T15:55:19.464Z,1680018919.464 [DataOverHttps](INFO): SBD MOMSN=17725522
2023-03-28T15:55:22.203Z,1680018922.203 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T15:55:27.413Z,1680018927.413 [NAL9602](INFO): SBD MO Status=0, MOMSN=39546, MT Status=0, MTMSN=0
2023-03-28T15:55:27.414Z,1680018927.414 [NAL9602](INFO): No messages in MT queue
2023-03-28T15:55:35.294Z,1680018935.294 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T152657/Express0017.lzma
2023-03-28T15:55:36.296Z,1680018936.296 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0017.lzma.bak
2023-03-28T15:55:36.296Z,1680018936.296 [DataOverHttps](INFO): SBD MOMSN=17725525
2023-03-28T15:55:37.524Z,1680018937.524 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T15:55:37.524Z,1680018937.524 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T15:55:37.525Z,1680018937.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T15:55:58.114Z,1680018958.114 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T15:55:58.165Z,1680018958.165 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:00:38.110Z,1680019238.110 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:00:38.110Z,1680019238.110 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:00:38.111Z,1680019238.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:00:38.111Z,1680019238.111 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:00:38.528Z,1680019238.528 [Default:CheckIn:D] Stopped
2023-03-28T16:00:38.528Z,1680019238.528 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:00:38.925Z,1680019238.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.319165 min 
2023-03-28T16:00:38.925Z,1680019238.925 [Default:CheckIn:E] Stopped
2023-03-28T16:00:38.925Z,1680019238.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:00:38.925Z,1680019238.925 [Default:CheckIn] Stopped
2023-03-28T16:00:38.925Z,1680019238.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:00:38.926Z,1680019238.926 [Default:CheckIn](INFO): Running loop #6
2023-03-28T16:00:38.926Z,1680019238.926 [Default:CheckIn] Running Loop=6
2023-03-28T16:00:38.926Z,1680019238.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:00:38.926Z,1680019238.926 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:00:40.929Z,1680019240.929 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160040.00,A,3648.17652,N,12147.28454,W,0.039,51.72,280323,,,D*49
2023-03-28T16:00:40.931Z,1680019240.931 [NAL9602](INFO): GPS fix at 20230328T160040: (36.802942, -121.788076)
2023-03-28T16:00:40.943Z,1680019240.943 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:00:40.943Z,1680019240.943 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:00:49.107Z,1680019249.107 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T16:00:49.950Z,1680019249.950 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T152657/Courier0019.lzma
2023-03-28T16:00:50.953Z,1680019250.953 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0019.lzma.bak
2023-03-28T16:00:50.953Z,1680019250.953 [DataOverHttps](INFO): SBD MOMSN=17725905
2023-03-28T16:01:04.821Z,1680019264.821 [NAL9602](INFO): SBD MO Status=2, MOMSN=39547, MT Status=2, MTMSN=0
2023-03-28T16:01:04.821Z,1680019264.821 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:01:09.170Z,1680019269.170 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0020.lzma
2023-03-28T16:01:09.318Z,1680019269.318 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T16:01:10.172Z,1680019270.172 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0020.lzma.bak
2023-03-28T16:01:10.173Z,1680019270.173 [DataOverHttps](INFO): SBD MOMSN=17725908
2023-03-28T16:01:11.716Z,1680019271.716 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:01:11.716Z,1680019271.716 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:01:11.717Z,1680019271.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:01:28.237Z,1680019288.237 [NAL9602](INFO): SBD MO Status=2, MOMSN=39547, MT Status=2, MTMSN=0
2023-03-28T16:01:28.237Z,1680019288.237 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:01:32.323Z,1680019292.323 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:01:39.987Z,1680019299.987 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T16:01:48.504Z,1680019308.504 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T16:02:01.369Z,1680019321.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=39547, MT Status=2, MTMSN=0
2023-03-28T16:02:01.370Z,1680019321.370 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:02:05.444Z,1680019325.444 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T16:02:09.891Z,1680019329.891 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T16:02:54.734Z,1680019374.734 [NAL9602](INFO): SBD MO Status=2, MOMSN=39547, MT Status=2, MTMSN=0
2023-03-28T16:02:54.734Z,1680019374.734 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:02:59.236Z,1680019379.236 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T16:03:02.010Z,1680019382.010 [NAL9602](INFO): SBD MO Status=0, MOMSN=39547, MT Status=0, MTMSN=0
2023-03-28T16:03:02.010Z,1680019382.010 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:03:32.706Z,1680019412.706 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:03:32.767Z,1680019412.767 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:06:12.385Z,1680019572.385 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:06:12.385Z,1680019572.385 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:06:12.385Z,1680019572.385 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:06:12.385Z,1680019572.385 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:06:12.797Z,1680019572.797 [Default:CheckIn:D] Stopped
2023-03-28T16:06:12.798Z,1680019572.798 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.890324 min 
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn:E] Stopped
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn] Stopped
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn](INFO): Running loop #7
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn] Running Loop=7
2023-03-28T16:06:13.183Z,1680019573.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:06:13.184Z,1680019573.184 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:06:15.202Z,1680019575.202 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160614.00,A,3648.17582,N,12147.28481,W,0.019,51.72,280323,,,D*4A
2023-03-28T16:06:15.204Z,1680019575.204 [NAL9602](INFO): GPS fix at 20230328T160614: (36.802930, -121.788080)
2023-03-28T16:06:15.215Z,1680019575.215 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:06:15.215Z,1680019575.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:06:23.056Z,1680019583.056 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T16:06:25.086Z,1680019585.086 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T152657/Courier0022.lzma
2023-03-28T16:06:26.088Z,1680019586.088 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0022.lzma.bak
2023-03-28T16:06:26.089Z,1680019586.089 [DataOverHttps](INFO): SBD MOMSN=17725913
2023-03-28T16:06:29.406Z,1680019589.406 [NAL9602](INFO): SBD MO Status=0, MOMSN=39548, MT Status=0, MTMSN=0
2023-03-28T16:06:29.406Z,1680019589.406 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:06:41.894Z,1680019601.894 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0023.lzma
2023-03-28T16:06:42.896Z,1680019602.896 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0023.lzma.bak
2023-03-28T16:06:42.897Z,1680019602.897 [DataOverHttps](INFO): SBD MOMSN=17725916
2023-03-28T16:06:44.365Z,1680019604.365 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:06:44.365Z,1680019604.365 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:06:44.365Z,1680019604.365 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:07:00.157Z,1680019620.157 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:07:00.181Z,1680019620.181 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:07:06.036Z,1680019626.036 [CommandLine](INFO): End of History
2023-03-28T16:07:06.896Z,1680019626.896 [CommandLine](INFO): End of History
2023-03-28T16:07:07.560Z,1680019627.560 [CommandLine](INFO): End of History
2023-03-28T16:07:08.165Z,1680019628.165 [CommandLine](INFO): End of History
2023-03-28T16:07:08.835Z,1680019628.835 [CommandLine](INFO): End of History
2023-03-28T16:07:09.500Z,1680019629.500 [CommandLine](INFO): End of History
2023-03-28T16:07:10.181Z,1680019630.181 [CommandLine](INFO): End of History
2023-03-28T16:11:44.848Z,1680019904.848 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:11:44.848Z,1680019904.848 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:11:44.848Z,1680019904.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:11:44.848Z,1680019904.848 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:11:45.217Z,1680019905.217 [Default:CheckIn:D] Stopped
2023-03-28T16:11:45.217Z,1680019905.217 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:11:45.638Z,1680019905.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.430648 min 
2023-03-28T16:11:45.638Z,1680019905.638 [Default:CheckIn:E] Stopped
2023-03-28T16:11:45.638Z,1680019905.638 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:11:45.638Z,1680019905.638 [Default:CheckIn] Stopped
2023-03-28T16:11:45.639Z,1680019905.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:11:45.639Z,1680019905.639 [Default:CheckIn](INFO): Running loop #8
2023-03-28T16:11:45.639Z,1680019905.639 [Default:CheckIn] Running Loop=8
2023-03-28T16:11:45.639Z,1680019905.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:11:45.639Z,1680019905.639 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:11:47.626Z,1680019907.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161146.00,A,3648.17633,N,12147.28550,W,0.019,51.72,280323,,,D*4F
2023-03-28T16:11:47.628Z,1680019907.628 [NAL9602](INFO): GPS fix at 20230328T161146: (36.802939, -121.788092)
2023-03-28T16:11:47.640Z,1680019907.640 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:11:47.640Z,1680019907.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:11:54.935Z,1680019914.935 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T16:11:54.974Z,1680019914.974 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T152657/Courier0025.lzma
2023-03-28T16:11:55.976Z,1680019915.976 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0025.lzma.bak
2023-03-28T16:11:55.976Z,1680019915.976 [DataOverHttps](INFO): SBD MOMSN=17726282
2023-03-28T16:11:57.322Z,1680019917.322 [NAL9602](INFO): SBD MO Status=0, MOMSN=39549, MT Status=0, MTMSN=0
2023-03-28T16:11:57.322Z,1680019917.322 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:12:09.843Z,1680019929.843 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-28T16:12:09.844Z,1680019929.844 [DropWeight] Hardware Fault, FailCount= 1
2023-03-28T16:12:09.844Z,1680019929.844 [DropWeight](ERROR): Hardware Fault
2023-03-28T16:12:09.952Z,1680019929.952 [CBIT](INFO): Critical error at 20230328T161209
2023-03-28T16:12:09.954Z,1680019929.954 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-28T16:12:09.963Z,1680019929.963 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-28T16:12:10.318Z,1680019930.318 [CBIT](INFO): Critical error at 20230328T161209
2023-03-28T16:12:12.014Z,1680019932.014 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0026.lzma
2023-03-28T16:12:13.016Z,1680019933.016 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0026.lzma.bak
2023-03-28T16:12:13.016Z,1680019933.016 [DataOverHttps](INFO): SBD MOMSN=17726285
2023-03-28T16:12:14.317Z,1680019934.317 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:12:14.317Z,1680019934.317 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:12:14.317Z,1680019934.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:12:28.057Z,1680019948.057 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:12:28.129Z,1680019948.129 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:17:14.766Z,1680020234.766 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:17:14.766Z,1680020234.766 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:17:14.766Z,1680020234.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:17:14.766Z,1680020234.766 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:17:15.171Z,1680020235.171 [Default:CheckIn:D] Stopped
2023-03-28T16:17:15.171Z,1680020235.171 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.929879 min 
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn:E] Stopped
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn] Stopped
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn](INFO): Running loop #9
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn] Running Loop=9
2023-03-28T16:17:15.582Z,1680020235.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:17:15.583Z,1680020235.583 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:17:17.589Z,1680020237.589 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161716.00,A,3648.17605,N,12147.28580,W,0.000,0.00,280323,,,D*7D
2023-03-28T16:17:17.592Z,1680020237.592 [NAL9602](INFO): GPS fix at 20230328T161716: (36.802934, -121.788097)
2023-03-28T16:17:17.627Z,1680020237.627 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:17:17.627Z,1680020237.627 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:17:23.727Z,1680020243.727 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T16:17:24.802Z,1680020244.802 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20230328T152657/Courier0028.lzma
2023-03-28T16:17:25.804Z,1680020245.804 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0028.lzma.bak
2023-03-28T16:17:25.804Z,1680020245.804 [DataOverHttps](INFO): SBD MOMSN=17726289
2023-03-28T16:17:35.764Z,1680020255.764 [NAL9602](INFO): SBD MO Status=2, MOMSN=39550, MT Status=2, MTMSN=0
2023-03-28T16:17:35.764Z,1680020255.764 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:17:39.895Z,1680020259.895 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T16:17:41.747Z,1680020261.747 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0029.lzma
2023-03-28T16:17:42.748Z,1680020262.748 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0029.lzma.bak
2023-03-28T16:17:42.748Z,1680020262.748 [DataOverHttps](INFO): SBD MOMSN=17726294
2023-03-28T16:17:44.277Z,1680020264.277 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:17:44.277Z,1680020264.277 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:17:44.277Z,1680020264.277 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:17:48.694Z,1680020268.694 [NAL9602](INFO): SBD MO Status=2, MOMSN=39550, MT Status=2, MTMSN=0
2023-03-28T16:17:48.694Z,1680020268.694 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:17:52.779Z,1680020272.779 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T16:18:06.515Z,1680020286.515 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:18:23.964Z,1680020303.964 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T16:18:28.329Z,1680020308.329 [NAL9602](INFO): SBD MO Status=0, MOMSN=39550, MT Status=0, MTMSN=0
2023-03-28T16:18:28.329Z,1680020308.329 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:18:59.078Z,1680020339.078 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:18:59.154Z,1680020339.154 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:22:44.708Z,1680020564.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:22:44.708Z,1680020564.708 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:22:44.708Z,1680020564.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:22:44.709Z,1680020564.709 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:22:45.115Z,1680020565.115 [Default:CheckIn:D] Stopped
2023-03-28T16:22:45.115Z,1680020565.115 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:22:45.511Z,1680020565.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.428743 min 
2023-03-28T16:22:45.511Z,1680020565.511 [Default:CheckIn:E] Stopped
2023-03-28T16:22:45.511Z,1680020565.511 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:22:45.511Z,1680020565.511 [Default:CheckIn] Stopped
2023-03-28T16:22:45.511Z,1680020565.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:22:45.512Z,1680020565.512 [Default:CheckIn](INFO): Running loop #10
2023-03-28T16:22:45.512Z,1680020565.512 [Default:CheckIn] Running Loop=10
2023-03-28T16:22:45.512Z,1680020565.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:22:45.512Z,1680020565.512 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:22:47.522Z,1680020567.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162246.00,A,3648.17470,N,12147.28595,W,0.019,0.00,280323,,,D*72
2023-03-28T16:22:47.524Z,1680020567.524 [NAL9602](INFO): GPS fix at 20230328T162246: (36.802912, -121.788099)
2023-03-28T16:22:47.535Z,1680020567.535 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:22:47.536Z,1680020567.536 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:22:53.899Z,1680020573.899 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T16:22:57.790Z,1680020577.790 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T152657/Courier0031.lzma
2023-03-28T16:22:58.793Z,1680020578.793 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0031.lzma.bak
2023-03-28T16:22:58.793Z,1680020578.793 [DataOverHttps](INFO): SBD MOMSN=17726669
2023-03-28T16:23:04.229Z,1680020584.229 [NAL9602](INFO): SBD MO Status=0, MOMSN=39551, MT Status=0, MTMSN=0
2023-03-28T16:23:04.229Z,1680020584.229 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:23:14.634Z,1680020594.634 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0032.lzma
2023-03-28T16:23:15.636Z,1680020595.636 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0032.lzma.bak
2023-03-28T16:23:15.637Z,1680020595.637 [DataOverHttps](INFO): SBD MOMSN=17726673
2023-03-28T16:23:17.176Z,1680020597.176 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:23:17.176Z,1680020597.176 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:23:17.176Z,1680020597.176 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:23:34.930Z,1680020614.930 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:23:34.953Z,1680020614.953 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:27:10.122Z,1680020830.122 [CBIT](INFO): Clearing failed state for component DropWeight
2023-03-28T16:27:10.122Z,1680020830.122 [DropWeight] No Fault, FailCount= 1
2023-03-28T16:28:17.963Z,1680020897.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:28:17.963Z,1680020897.963 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:28:17.963Z,1680020897.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:28:17.963Z,1680020897.963 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:28:18.380Z,1680020898.380 [Default:CheckIn:D] Stopped
2023-03-28T16:28:18.380Z,1680020898.380 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:28:18.770Z,1680020898.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.983374 min 
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn:E] Stopped
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn] Stopped
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn](INFO): Running loop #11
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn] Running Loop=11
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:28:18.787Z,1680020898.787 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:28:20.783Z,1680020900.783 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162820.00,A,3648.17598,N,12147.28556,W,0.019,0.00,280323,,,D*70
2023-03-28T16:28:20.786Z,1680020900.786 [NAL9602](INFO): GPS fix at 20230328T162820: (36.802933, -121.788093)
2023-03-28T16:28:20.806Z,1680020900.806 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:28:20.806Z,1680020900.806 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:28:28.547Z,1680020908.547 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T16:28:30.078Z,1680020910.078 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230328T152657/Courier0034.lzma
2023-03-28T16:28:31.080Z,1680020911.080 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0034.lzma.bak
2023-03-28T16:28:31.080Z,1680020911.080 [DataOverHttps](INFO): SBD MOMSN=17726677
2023-03-28T16:28:42.601Z,1680020922.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=39552, MT Status=0, MTMSN=0
2023-03-28T16:28:42.602Z,1680020922.602 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:28:47.330Z,1680020927.330 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T152657/Express0035.lzma
2023-03-28T16:28:48.333Z,1680020928.333 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0035.lzma.bak
2023-03-28T16:28:48.333Z,1680020928.333 [DataOverHttps](INFO): SBD MOMSN=17726680
2023-03-28T16:28:49.907Z,1680020929.907 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:28:49.907Z,1680020929.907 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:28:49.907Z,1680020929.907 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:29:13.343Z,1680020953.343 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:29:13.391Z,1680020953.391 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:33:50.561Z,1680021230.561 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:33:50.561Z,1680021230.561 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:33:50.561Z,1680021230.561 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:33:50.561Z,1680021230.561 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:33:50.985Z,1680021230.985 [Default:CheckIn:D] Stopped
2023-03-28T16:33:50.985Z,1680021230.985 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:33:51.370Z,1680021231.370 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.526790 min 
2023-03-28T16:33:51.371Z,1680021231.371 [Default:CheckIn:E] Stopped
2023-03-28T16:33:51.371Z,1680021231.371 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:33:51.371Z,1680021231.371 [Default:CheckIn] Stopped
2023-03-28T16:33:51.371Z,1680021231.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:33:51.371Z,1680021231.371 [Default:CheckIn](INFO): Running loop #12
2023-03-28T16:33:51.371Z,1680021231.371 [Default:CheckIn] Running Loop=12
2023-03-28T16:33:51.372Z,1680021231.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:33:51.372Z,1680021231.372 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:33:53.378Z,1680021233.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163352.00,A,3648.17561,N,12147.28619,W,0.000,0.00,280323,,,D*79
2023-03-28T16:33:53.380Z,1680021233.380 [NAL9602](INFO): GPS fix at 20230328T163352: (36.802927, -121.788103)
2023-03-28T16:33:53.392Z,1680021233.392 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:33:53.392Z,1680021233.392 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:34:00.626Z,1680021240.626 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T152657/Courier0037.lzma
2023-03-28T16:34:01.628Z,1680021241.628 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0037.lzma.bak
2023-03-28T16:34:01.628Z,1680021241.628 [DataOverHttps](INFO): SBD MOMSN=17726713
2023-03-28T16:34:04.351Z,1680021244.351 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T16:34:14.794Z,1680021254.794 [NAL9602](INFO): SBD MO Status=0, MOMSN=39553, MT Status=0, MTMSN=0
2023-03-28T16:34:14.794Z,1680021254.794 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:34:18.938Z,1680021258.938 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T152657/Express0038.lzma
2023-03-28T16:34:19.941Z,1680021259.941 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0038.lzma.bak
2023-03-28T16:34:19.941Z,1680021259.941 [DataOverHttps](INFO): SBD MOMSN=17726717
2023-03-28T16:34:21.296Z,1680021261.296 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:34:21.296Z,1680021261.296 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:34:21.297Z,1680021261.297 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:34:45.544Z,1680021285.544 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:34:45.568Z,1680021285.568 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:39:22.010Z,1680021562.010 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:39:22.010Z,1680021562.010 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:39:22.010Z,1680021562.010 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:39:22.010Z,1680021562.010 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:39:22.420Z,1680021562.420 [Default:CheckIn:D] Stopped
2023-03-28T16:39:22.420Z,1680021562.420 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:39:22.837Z,1680021562.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.050704 min 
2023-03-28T16:39:22.837Z,1680021562.837 [Default:CheckIn:E] Stopped
2023-03-28T16:39:22.837Z,1680021562.837 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:39:22.837Z,1680021562.837 [Default:CheckIn] Stopped
2023-03-28T16:39:22.837Z,1680021562.837 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:39:22.838Z,1680021562.838 [Default:CheckIn](INFO): Running loop #13
2023-03-28T16:39:22.838Z,1680021562.838 [Default:CheckIn] Running Loop=13
2023-03-28T16:39:22.838Z,1680021562.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:39:22.838Z,1680021562.838 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:39:24.834Z,1680021564.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163924.00,A,3648.17494,N,12147.28604,W,0.039,0.00,280323,,,D*7F
2023-03-28T16:39:24.836Z,1680021564.836 [NAL9602](INFO): GPS fix at 20230328T163924: (36.802916, -121.788101)
2023-03-28T16:39:24.872Z,1680021564.872 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:39:24.872Z,1680021564.872 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:39:32.258Z,1680021572.258 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230328T152657/Courier0040.lzma
2023-03-28T16:39:32.579Z,1680021572.579 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T16:39:33.260Z,1680021573.260 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0040.lzma.bak
2023-03-28T16:39:33.261Z,1680021573.261 [DataOverHttps](INFO): SBD MOMSN=17727059
2023-03-28T16:39:36.251Z,1680021576.251 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-03-28T16:39:36.541Z,1680021576.541 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:39:48.308Z,1680021588.308 [NAL9602](INFO): SBD MO Status=0, MOMSN=39554, MT Status=0, MTMSN=0
2023-03-28T16:39:48.308Z,1680021588.308 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:39:51.545Z,1680021591.545 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:39:52.498Z,1680021592.498 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T152657/Express0041.lzma
2023-03-28T16:39:53.501Z,1680021593.501 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0041.lzma.bak
2023-03-28T16:39:53.501Z,1680021593.501 [DataOverHttps](INFO): SBD MOMSN=17727062
2023-03-28T16:39:54.827Z,1680021594.827 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:39:54.827Z,1680021594.827 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:39:54.827Z,1680021594.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:40:06.897Z,1680021606.897 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:40:19.019Z,1680021619.019 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:40:19.083Z,1680021619.083 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:40:22.657Z,1680021622.657 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:40:38.011Z,1680021638.011 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:40:52.961Z,1680021652.961 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:41:08.313Z,1680021668.313 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:41:24.069Z,1680021684.069 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:41:38.617Z,1680021698.617 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-03-28T16:42:03.280Z,1680021723.280 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5.
2023-03-28T16:42:03.284Z,1680021723.284 [BPC1](INFO): Received data from all battery sticks.
2023-03-28T16:44:55.387Z,1680021895.387 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:44:55.387Z,1680021895.387 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:44:55.387Z,1680021895.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:44:55.387Z,1680021895.387 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:44:55.796Z,1680021895.796 [Default:CheckIn:D] Stopped
2023-03-28T16:44:55.796Z,1680021895.796 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:44:56.202Z,1680021896.202 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.606966 min 
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn:E] Stopped
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn] Stopped
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn](INFO): Running loop #14
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn] Running Loop=14
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:44:56.203Z,1680021896.203 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:44:58.210Z,1680021898.210 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164457.00,A,3648.17567,N,12147.28605,W,0.019,0.00,280323,,,D*7F
2023-03-28T16:44:58.212Z,1680021898.212 [NAL9602](INFO): GPS fix at 20230328T164457: (36.802928, -121.788101)
2023-03-28T16:44:58.225Z,1680021898.225 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:44:58.225Z,1680021898.225 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:45:03.802Z,1680021903.802 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230328T152657/Courier0043.lzma
2023-03-28T16:45:04.804Z,1680021904.804 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Courier0043.lzma.bak
2023-03-28T16:45:04.804Z,1680021904.804 [DataOverHttps](INFO): SBD MOMSN=17727091
2023-03-28T16:45:06.727Z,1680021906.727 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T16:45:13.153Z,1680021913.153 [NAL9602](INFO): SBD MO Status=0, MOMSN=39555, MT Status=0, MTMSN=0
2023-03-28T16:45:13.153Z,1680021913.153 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:45:19.922Z,1680021919.922 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T152657/Express0044.lzma
2023-03-28T16:45:20.924Z,1680021920.924 [DataOverHttps](INFO): Moved sent file to Logs/20230328T152657/Express0044.lzma.bak
2023-03-28T16:45:20.925Z,1680021920.925 [DataOverHttps](INFO): SBD MOMSN=17727093
2023-03-28T16:45:22.465Z,1680021922.465 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:45:22.465Z,1680021922.465 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:45:22.465Z,1680021922.465 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:45:43.855Z,1680021943.855 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:45:43.880Z,1680021943.880 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:50:23.085Z,1680022223.085 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:50:23.085Z,1680022223.085 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:50:23.085Z,1680022223.085 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:50:23.086Z,1680022223.086 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:50:23.505Z,1680022223.505 [Default:CheckIn:D] Stopped
2023-03-28T16:50:23.505Z,1680022223.505 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.068774 min 
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn:E] Stopped
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn] Stopped
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn](INFO): Running loop #15
2023-03-28T16:50:23.925Z,1680022223.925 [Default:CheckIn] Running Loop=15
2023-03-28T16:50:23.926Z,1680022223.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:50:23.926Z,1680022223.926 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:50:25.901Z,1680022225.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165025.00,A,3648.17494,N,12147.28545,W,0.019,0.00,280323,,,D*75
2023-03-28T16:50:25.903Z,1680022225.903 [NAL9602](INFO): GPS fix at 20230328T165025: (36.802916, -121.788091)
2023-03-28T16:50:25.916Z,1680022225.916 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:50:25.916Z,1680022225.916 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:50:27.148Z,1680022227.148 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:50:27.148Z,1680022227.148 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:50:27.148Z,1680022227.148 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:50:34.424Z,1680022234.424 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T16:50:52.981Z,1680022252.981 [NAL9602](INFO): SBD MO Status=2, MOMSN=39556, MT Status=2, MTMSN=0
2023-03-28T16:50:52.981Z,1680022252.981 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T16:50:57.464Z,1680022257.464 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T16:51:07.930Z,1680022267.930 [NAL9602](INFO): SBD MO Status=0, MOMSN=39556, MT Status=0, MTMSN=0
2023-03-28T16:51:07.930Z,1680022267.930 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:51:38.632Z,1680022298.632 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:51:38.667Z,1680022298.667 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:55:27.748Z,1680022527.748 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T16:55:27.748Z,1680022527.748 [Default:CheckIn:C.Wait] Stopped
2023-03-28T16:55:27.748Z,1680022527.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T16:55:27.748Z,1680022527.748 [Default:CheckIn:D] Running Loop=1
2023-03-28T16:55:28.147Z,1680022528.147 [Default:CheckIn:D] Stopped
2023-03-28T16:55:28.147Z,1680022528.147 [Default:CheckIn:E] Running Loop=1
2023-03-28T16:55:28.556Z,1680022528.556 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.146159 min 
2023-03-28T16:55:28.556Z,1680022528.556 [Default:CheckIn:E] Stopped
2023-03-28T16:55:28.556Z,1680022528.556 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T16:55:28.556Z,1680022528.556 [Default:CheckIn] Stopped
2023-03-28T16:55:28.556Z,1680022528.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T16:55:28.557Z,1680022528.557 [Default:CheckIn](INFO): Running loop #16
2023-03-28T16:55:28.557Z,1680022528.557 [Default:CheckIn] Running Loop=16
2023-03-28T16:55:28.557Z,1680022528.557 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T16:55:28.557Z,1680022528.557 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T16:55:30.561Z,1680022530.561 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165529.00,A,3648.17399,N,12147.28626,W,0.039,0.00,280323,,,D*72
2023-03-28T16:55:30.564Z,1680022530.564 [NAL9602](INFO): GPS fix at 20230328T165529: (36.802900, -121.788104)
2023-03-28T16:55:30.577Z,1680022530.577 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T16:55:30.577Z,1680022530.577 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T16:55:31.798Z,1680022531.798 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T16:55:31.798Z,1680022531.798 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T16:55:31.798Z,1680022531.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T16:55:37.476Z,1680022537.476 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-03-28T16:55:43.090Z,1680022543.090 [NAL9602](INFO): SBD MO Status=0, MOMSN=39557, MT Status=0, MTMSN=0
2023-03-28T16:55:43.090Z,1680022543.090 [NAL9602](INFO): No messages in MT queue
2023-03-28T16:56:13.792Z,1680022573.792 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T16:56:13.816Z,1680022573.816 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T16:57:11.563Z,1680022631.563 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-28T16:57:11.564Z,1680022631.564 [DropWeight] Hardware Fault, FailCount= 1
2023-03-28T16:57:11.564Z,1680022631.564 [DropWeight](ERROR): Hardware Fault
2023-03-28T16:57:11.583Z,1680022631.583 [CBIT](INFO): Critical error at 20230328T165711
2023-03-28T16:57:11.586Z,1680022631.586 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-28T16:57:11.586Z,1680022631.586 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-28T16:57:11.987Z,1680022631.987 [CBIT](INFO): Critical error at 20230328T165711
2023-03-28T17:00:32.364Z,1680022832.364 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:00:32.364Z,1680022832.364 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:00:32.364Z,1680022832.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:00:32.364Z,1680022832.364 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:00:32.772Z,1680022832.772 [Default:CheckIn:D] Stopped
2023-03-28T17:00:32.772Z,1680022832.772 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:00:33.177Z,1680022833.177 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.223234 min 
2023-03-28T17:00:33.177Z,1680022833.177 [Default:CheckIn:E] Stopped
2023-03-28T17:00:33.177Z,1680022833.177 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:00:33.177Z,1680022833.177 [Default:CheckIn] Stopped
2023-03-28T17:00:33.177Z,1680022833.177 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:00:33.177Z,1680022833.177 [Default:CheckIn](INFO): Running loop #17
2023-03-28T17:00:33.178Z,1680022833.178 [Default:CheckIn] Running Loop=17
2023-03-28T17:00:33.178Z,1680022833.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:00:33.178Z,1680022833.178 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:00:35.186Z,1680022835.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170034.00,A,3648.17518,N,12147.28361,W,1.788,0.00,280323,,,D*7A
2023-03-28T17:00:35.188Z,1680022835.188 [NAL9602](INFO): GPS fix at 20230328T170034: (36.802920, -121.788060)
2023-03-28T17:00:35.201Z,1680022835.201 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:00:35.201Z,1680022835.201 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:00:36.822Z,1680022836.822 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:00:36.822Z,1680022836.822 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:00:36.822Z,1680022836.822 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:01:04.371Z,1680022864.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:01:35.495Z,1680022895.495 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:02:06.619Z,1680022926.619 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:02:37.743Z,1680022957.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:03:08.867Z,1680022988.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:03:11.879Z,1680022991.879 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.719513
2023-03-28T17:04:10.111Z,1680023050.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:04:41.235Z,1680023081.235 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:05:12.359Z,1680023112.359 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:05:37.376Z,1680023137.376 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-28T17:05:37.388Z,1680023137.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:05:37.388Z,1680023137.388 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:05:37.388Z,1680023137.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:05:37.389Z,1680023137.389 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:05:37.792Z,1680023137.792 [Default:CheckIn:D] Stopped
2023-03-28T17:05:37.792Z,1680023137.792 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.306909 min 
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn:E] Stopped
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn] Stopped
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn](INFO): Running loop #18
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn] Running Loop=18
2023-03-28T17:05:38.197Z,1680023138.197 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:05:38.198Z,1680023138.198 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:05:43.483Z,1680023143.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:06:14.607Z,1680023174.607 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:06:45.731Z,1680023205.731 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:07:16.855Z,1680023236.855 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:07:47.979Z,1680023267.979 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:08:19.107Z,1680023299.107 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:08:50.231Z,1680023330.231 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:09:21.371Z,1680023361.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:09:52.495Z,1680023392.495 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:10:23.619Z,1680023423.619 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:10:38.365Z,1680023438.365 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-28T17:05:38.2Z
2023-03-28T17:10:38.365Z,1680023438.365 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:10:38.365Z,1680023438.365 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:10:54.743Z,1680023454.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:11:07.858Z,1680023467.858 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2023-03-28T17:11:07.931Z,1680023467.931 [NAL9602](ERROR): received: 
+CSQ:0

OK557, 0, 0, 0, 0

OK
2023-03-28T17:11:10.058Z,1680023470.058 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:14.493Z,1680023474.493 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:16.514Z,1680023476.514 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:20.962Z,1680023480.962 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:23.002Z,1680023483.002 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:25.867Z,1680023485.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:11:27.443Z,1680023487.443 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:29.446Z,1680023489.446 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:33.887Z,1680023493.887 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:35.911Z,1680023495.911 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:40.350Z,1680023500.350 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:42.366Z,1680023502.366 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:46.816Z,1680023506.816 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:48.832Z,1680023508.832 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:53.279Z,1680023513.279 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:55.304Z,1680023515.304 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:11:56.991Z,1680023516.991 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:11:59.748Z,1680023519.748 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:01.760Z,1680023521.760 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:06.225Z,1680023526.225 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:08.229Z,1680023528.229 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:11.697Z,1680023531.697 [CBIT](INFO): Clearing failed state for component DropWeight
2023-03-28T17:12:11.698Z,1680023531.698 [DropWeight] No Fault, FailCount= 1
2023-03-28T17:12:12.671Z,1680023532.671 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:14.695Z,1680023534.695 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:19.137Z,1680023539.137 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:21.153Z,1680023541.153 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:25.600Z,1680023545.600 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:27.620Z,1680023547.620 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:28.119Z,1680023548.119 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:12:32.080Z,1680023552.080 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:34.086Z,1680023554.086 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:38.526Z,1680023558.526 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:40.550Z,1680023560.550 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:44.986Z,1680023564.986 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:47.012Z,1680023567.012 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:51.460Z,1680023571.460 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:55.901Z,1680023575.901 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:57.947Z,1680023577.947 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:12:59.243Z,1680023579.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-03-28T17:13:02.371Z,1680023582.371 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:04.398Z,1680023584.398 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:08.831Z,1680023588.831 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:10.856Z,1680023590.856 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:15.288Z,1680023595.288 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:17.312Z,1680023597.312 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:21.763Z,1680023601.763 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:23.953Z,1680023603.953 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:27.624Z,1680023607.624 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003696
2023-03-28T17:13:28.261Z,1680023608.261 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2023-03-28T17:13:28.889Z,1680023608.889 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:13:28.890Z,1680023608.890 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:13:28.890Z,1680023608.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:13:58.776Z,1680023638.776 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:18:29.499Z,1680023909.499 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:18:29.500Z,1680023909.500 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:18:29.500Z,1680023909.500 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:18:29.500Z,1680023909.500 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:18:29.900Z,1680023909.900 [Default:CheckIn:D] Stopped
2023-03-28T17:18:29.900Z,1680023909.900 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:18:30.308Z,1680023910.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.175366 min 
2023-03-28T17:18:30.308Z,1680023910.308 [Default:CheckIn:E] Stopped
2023-03-28T17:18:30.308Z,1680023910.308 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:18:30.308Z,1680023910.308 [Default:CheckIn] Stopped
2023-03-28T17:18:30.309Z,1680023910.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:18:30.309Z,1680023910.309 [Default:CheckIn](INFO): Running loop #19
2023-03-28T17:18:30.309Z,1680023910.309 [Default:CheckIn] Running Loop=19
2023-03-28T17:18:30.309Z,1680023910.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:18:30.309Z,1680023910.309 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:18:31.912Z,1680023911.912 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-28T17:18:31.912Z,1680023911.912 [NAL9602] Data Fault, FailCount= 1
2023-03-28T17:18:31.912Z,1680023911.912 [NAL9602](ERROR): Data Fault
2023-03-28T17:18:31.938Z,1680023911.938 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-28T17:18:31.939Z,1680023911.939 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:18:32.311Z,1680023912.311 [NAL9602](INFO): Powering down
2023-03-28T17:18:33.157Z,1680023913.157 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-28T17:18:33.157Z,1680023913.157 [NAL9602] No Fault, FailCount= 1
2023-03-28T17:19:02.620Z,1680023942.620 [NAL9602](INFO): Powering up NAL9602
2023-03-28T17:19:12.739Z,1680023952.739 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:19:13.536Z,1680023953.536 [NAL9602](INFO): NAL9602 initialized
2023-03-28T17:23:30.560Z,1680024210.560 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-28T17:18:30.3Z
2023-03-28T17:23:30.560Z,1680024210.560 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:23:30.560Z,1680024210.560 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:23:31.778Z,1680024211.778 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:23:31.778Z,1680024211.778 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:23:31.778Z,1680024211.778 [Default:CheckIn:C.Wait](DEBUG): In