2024-08-12T16:27:00.323Z,1723480020.323 [Supervisor](DEBUG): Initializing supervisor.
2024-08-12T16:27:00.328Z,1723480020.328 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-08-12T16:27:00.329Z,1723480020.329 [SyncHandler](INFO): Protected caller Thread ID is 4519
2024-08-12T16:27:00.329Z,1723480020.329 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-08-12T16:27:00.330Z,1723480020.330 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-08-12T16:27:00.330Z,1723480020.330 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4520
2024-08-12T16:27:00.334Z,1723480020.334 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-08-12T16:27:00.352Z,1723480020.352 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-08-12T16:27:00.353Z,1723480020.353 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-08-12T16:27:00.353Z,1723480020.353 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4521
2024-08-12T16:27:00.357Z,1723480020.357 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-08-12T16:27:00.358Z,1723480020.358 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-08-12T16:27:00.359Z,1723480020.359 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4522
2024-08-12T16:27:00.361Z,1723480020.361 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-08-12T16:27:00.362Z,1723480020.362 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-08-12T16:27:00.362Z,1723480020.362 [logger ThreadHandler](INFO): Protected caller Thread ID is 4523
2024-08-12T16:27:00.365Z,1723480020.365 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-08-12T16:27:00.366Z,1723480020.366 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-08-12T16:27:00.368Z,1723480020.368 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-08-12T16:27:00.857Z,1723480020.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-08-12T16:27:00.857Z,1723480020.857 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-08-12T16:27:00.953Z,1723480020.953 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-08-12T16:27:01.514Z,1723480021.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-08-12T16:27:01.515Z,1723480021.515 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-08-12T16:27:01.882Z,1723480021.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-08-12T16:27:01.883Z,1723480021.883 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-08-12T16:27:01.992Z,1723480021.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-08-12T16:27:01.993Z,1723480021.993 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-08-12T16:27:02.420Z,1723480022.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-08-12T16:27:02.421Z,1723480022.421 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-08-12T16:27:03.095Z,1723480023.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-08-12T16:27:03.095Z,1723480023.095 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-08-12T16:27:03.647Z,1723480023.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-08-12T16:27:03.647Z,1723480023.647 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-08-12T16:27:03.797Z,1723480023.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-08-12T16:27:03.798Z,1723480023.798 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-08-12T16:27:03.890Z,1723480023.890 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-08-12T16:27:04.759Z,1723480024.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-08-12T16:27:04.759Z,1723480024.759 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-08-12T16:27:05.165Z,1723480025.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-08-12T16:27:05.166Z,1723480025.166 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-08-12T16:27:05.376Z,1723480025.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-08-12T16:27:05.377Z,1723480025.377 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-08-12T16:27:05.504Z,1723480025.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-08-12T16:27:05.504Z,1723480025.504 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-08-12T16:27:05.748Z,1723480025.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-08-12T16:27:05.749Z,1723480025.749 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-08-12T16:27:06.043Z,1723480026.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-08-12T16:27:06.045Z,1723480026.045 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-08-12T16:27:06.046Z,1723480026.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-08-12T16:27:06.377Z,1723480026.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-08-12T16:27:06.591Z,1723480026.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-08-12T16:27:06.784Z,1723480026.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-08-12T16:27:06.993Z,1723480026.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-08-12T16:27:07.139Z,1723480027.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-08-12T16:27:07.250Z,1723480027.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-08-12T16:27:07.382Z,1723480027.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-08-12T16:27:07.551Z,1723480027.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-08-12T16:27:07.717Z,1723480027.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-08-12T16:27:07.841Z,1723480027.841 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-08-12T16:27:08.120Z,1723480028.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-08-12T16:27:08.121Z,1723480028.121 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-08-12T16:27:08.126Z,1723480028.126 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-08-12T16:27:08.179Z,1723480028.179 [VerticalControl](DEBUG): Construct VerticalControl.
2024-08-12T16:27:08.237Z,1723480028.237 [VerticalControl] Loaded
2024-08-12T16:27:08.237Z,1723480028.237 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-08-12T16:27:08.240Z,1723480028.240 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-08-12T16:27:08.286Z,1723480028.286 [HorizontalControl] Loaded
2024-08-12T16:27:08.286Z,1723480028.286 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-08-12T16:27:08.288Z,1723480028.288 [SpeedControl](DEBUG): Construct SpeedControl.
2024-08-12T16:27:08.291Z,1723480028.291 [SpeedControl] Loaded
2024-08-12T16:27:08.291Z,1723480028.291 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-08-12T16:27:08.294Z,1723480028.294 [LoopControl](DEBUG): Construct LoopControl.
2024-08-12T16:27:08.294Z,1723480028.294 [LoopControl] Loaded
2024-08-12T16:27:08.295Z,1723480028.295 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-08-12T16:27:08.295Z,1723480028.295 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-08-12T16:27:08.296Z,1723480028.296 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-08-12T16:27:08.306Z,1723480028.306 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-08-12T16:27:08.307Z,1723480028.307 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-08-12T16:27:08.479Z,1723480028.479 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-08-12T16:27:08.479Z,1723480028.479 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-08-12T16:27:08.645Z,1723480028.645 [BuoyancyServo] Loaded
2024-08-12T16:27:08.645Z,1723480028.645 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-08-12T16:27:08.667Z,1723480028.667 [ElevatorServo] Loaded
2024-08-12T16:27:08.667Z,1723480028.667 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-08-12T16:27:08.687Z,1723480028.687 [MassServo] Loaded
2024-08-12T16:27:08.688Z,1723480028.688 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-08-12T16:27:08.708Z,1723480028.708 [RudderServo] Loaded
2024-08-12T16:27:08.708Z,1723480028.708 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-08-12T16:27:08.724Z,1723480028.724 [ThrusterHE] Loaded
2024-08-12T16:27:08.724Z,1723480028.724 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-08-12T16:27:08.724Z,1723480028.724 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-08-12T16:27:08.725Z,1723480028.725 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-08-12T16:27:08.836Z,1723480028.836 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-08-12T16:27:08.837Z,1723480028.837 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-08-12T16:27:08.850Z,1723480028.850 [NavChart] Loaded
2024-08-12T16:27:08.850Z,1723480028.850 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-08-12T16:27:08.855Z,1723480028.855 [UniversalFixResidualReporter] Loaded
2024-08-12T16:27:08.856Z,1723480028.856 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-08-12T16:27:08.856Z,1723480028.856 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-08-12T16:27:08.857Z,1723480028.857 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-08-12T16:27:08.914Z,1723480028.914 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-08-12T16:27:08.914Z,1723480028.914 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-08-12T16:27:09.358Z,1723480029.358 [AHRS_M2] Loaded
2024-08-12T16:27:09.358Z,1723480029.358 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-08-12T16:27:10.179Z,1723480030.179 [BPC1] Loaded
2024-08-12T16:27:10.179Z,1723480030.179 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-08-12T16:27:10.251Z,1723480030.251 [DataOverHttps] Loaded
2024-08-12T16:27:10.252Z,1723480030.252 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-08-12T16:27:10.253Z,1723480030.253 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-08-12T16:27:10.253Z,1723480030.253 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4605
2024-08-12T16:27:10.276Z,1723480030.276 [Depth_Keller] Loaded
2024-08-12T16:27:10.276Z,1723480030.276 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-08-12T16:27:10.281Z,1723480030.281 [DropWeight] Loaded
2024-08-12T16:27:10.282Z,1723480030.282 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-08-12T16:27:10.345Z,1723480030.345 [NAL9602] Loaded
2024-08-12T16:27:10.345Z,1723480030.345 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-08-12T16:27:10.375Z,1723480030.375 [Onboard] Loaded
2024-08-12T16:27:10.375Z,1723480030.375 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-08-12T16:27:10.376Z,1723480030.376 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-08-12T16:27:10.376Z,1723480030.376 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4606
2024-08-12T16:27:10.391Z,1723480030.391 [Power24vConverter] Loaded
2024-08-12T16:27:10.392Z,1723480030.392 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-08-12T16:27:10.406Z,1723480030.406 [Radio_Surface] Loaded
2024-08-12T16:27:10.407Z,1723480030.407 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-08-12T16:27:10.408Z,1723480030.408 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-08-12T16:27:10.408Z,1723480030.408 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4607
2024-08-12T16:27:10.503Z,1723480030.503 [DAT] Loaded
2024-08-12T16:27:10.504Z,1723480030.504 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-08-12T16:27:10.504Z,1723480030.504 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2024-08-12T16:27:10.505Z,1723480030.505 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4608
2024-08-12T16:27:10.505Z,1723480030.505 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-08-12T16:27:10.506Z,1723480030.506 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-08-12T16:27:10.556Z,1723480030.556 [DepthRateCalculator] Loaded
2024-08-12T16:27:10.556Z,1723480030.556 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-08-12T16:27:10.561Z,1723480030.561 [PitchRateCalculator] Loaded
2024-08-12T16:27:10.561Z,1723480030.561 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-08-12T16:27:10.570Z,1723480030.570 [SpeedCalculator] Loaded
2024-08-12T16:27:10.570Z,1723480030.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-08-12T16:27:10.574Z,1723480030.574 [YawRateCalculator] Loaded
2024-08-12T16:27:10.574Z,1723480030.574 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-08-12T16:27:10.593Z,1723480030.593 [ElevatorOffsetCalculator] Loaded
2024-08-12T16:27:10.593Z,1723480030.593 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-08-12T16:27:10.594Z,1723480030.594 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-08-12T16:27:10.594Z,1723480030.594 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-08-12T16:27:10.729Z,1723480030.729 [CANONSampler] Loaded
2024-08-12T16:27:10.729Z,1723480030.729 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-08-12T16:27:10.792Z,1723480030.792 [CTD_Seabird] Loaded
2024-08-12T16:27:10.792Z,1723480030.792 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-08-12T16:27:10.793Z,1723480030.793 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-08-12T16:27:10.793Z,1723480030.793 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4609
2024-08-12T16:27:10.816Z,1723480030.816 [PAR_Licor] Loaded
2024-08-12T16:27:10.816Z,1723480030.816 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-08-12T16:27:10.858Z,1723480030.858 [WetLabsBB2FL] Loaded
2024-08-12T16:27:10.858Z,1723480030.858 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-08-12T16:27:10.859Z,1723480030.859 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2024-08-12T16:27:10.860Z,1723480030.860 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4610
2024-08-12T16:27:10.860Z,1723480030.860 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-08-12T16:27:10.861Z,1723480030.861 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-08-12T16:27:10.888Z,1723480030.888 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-08-12T16:27:10.889Z,1723480030.889 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-08-12T16:27:11.222Z,1723480031.222 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-08-12T16:27:11.223Z,1723480031.223 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-08-12T16:27:11.621Z,1723480031.621 [SBIT](DEBUG): Construct Startup Built In Test.
2024-08-12T16:27:11.630Z,1723480031.630 [SBIT] Loaded
2024-08-12T16:27:11.630Z,1723480031.630 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-08-12T16:27:11.633Z,1723480031.633 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-08-12T16:27:11.646Z,1723480031.646 [IBIT] Loaded
2024-08-12T16:27:11.646Z,1723480031.646 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-08-12T16:27:11.652Z,1723480031.652 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-08-12T16:27:11.817Z,1723480031.817 [CBIT] Loaded
2024-08-12T16:27:11.817Z,1723480031.817 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-08-12T16:27:11.817Z,1723480031.817 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-08-12T16:27:11.818Z,1723480031.818 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-08-12T16:27:11.927Z,1723480031.927 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-08-12T16:27:11.934Z,1723480031.934 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-08-12T16:27:11.936Z,1723480031.936 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-08-12T16:27:11.948Z,1723480031.948 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-08-12T16:27:11.949Z,1723480031.949 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-08-12T16:27:11.949Z,1723480031.949 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4611
2024-08-12T16:27:11.954Z,1723480031.954 [Supervisor](INFO): Main Thread ID is 3900
2024-08-12T16:27:11.954Z,1723480031.954 [Supervisor](DEBUG): Running supervisor.
2024-08-12T16:27:11.955Z,1723480031.955 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4612
2024-08-12T16:27:11.955Z,1723480031.955 [CommandExec](INFO): Initializing the command executive.
2024-08-12T16:27:11.957Z,1723480031.957 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4613
2024-08-12T16:27:11.959Z,1723480031.959 [controlThread ThreadHandler](INFO): Handler Thread ID is 4614
2024-08-12T16:27:11.959Z,1723480031.959 [controlThread](DEBUG): Initializing ControlThread
2024-08-12T16:27:11.960Z,1723480031.960 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-08-12T16:27:11.962Z,1723480031.962 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-08-12T16:27:11.962Z,1723480031.962 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-08-12T16:27:11.963Z,1723480031.963 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-08-12T16:27:11.965Z,1723480031.965 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-08-12T16:27:11.965Z,1723480031.965 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-08-12T16:27:11.971Z,1723480031.971 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-08-12T16:27:11.972Z,1723480031.972 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-08-12T16:27:11.972Z,1723480031.972 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-08-12T16:27:11.972Z,1723480031.972 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-08-12T16:27:11.972Z,1723480031.972 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-08-12T16:27:11.978Z,1723480031.978 [SBIT](INFO): Initialize SBIT Component.
2024-08-12T16:27:11.978Z,1723480031.978 [SBIT](IMPORTANT): git: 2024-08-12
2024-08-12T16:27:11.978Z,1723480031.978 [SBIT](INFO): git hash: 514a2155e4c471e50d5c939abb69db7fb725fa43
2024-08-12T16:27:11.979Z,1723480031.979 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-08-12T16:27:11.980Z,1723480031.980 [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
2024-08-12T16:27:11.981Z,1723480031.981 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2024-08-12T16:27:11.982Z,1723480031.982 [IBIT](INFO): Initialize IBIT Component.
2024-08-12T16:27:11.982Z,1723480031.982 [CBIT](DEBUG): Initialize CBIT Component.
2024-08-12T16:27:11.983Z,1723480031.983 [logger ThreadHandler](INFO): Handler Thread ID is 4615
2024-08-12T16:27:11.993Z,1723480031.993 [CBIT](DEBUG): Initialized mux pins.
2024-08-12T16:27:11.994Z,1723480031.994 [CBIT](DEBUG): Initializing the watchdog timer.
2024-08-12T16:27:12.002Z,1723480032.002 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4616
2024-08-12T16:27:12.004Z,1723480032.004 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-08-12T16:27:12.014Z,1723480032.014 [Onboard ThreadHandler](INFO): Handler Thread ID is 4617
2024-08-12T16:27:12.018Z,1723480032.018 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-08-12T16:27:12.019Z,1723480032.019 [CBIT](DEBUG): Initializing heartbeat.
2024-08-12T16:27:12.035Z,1723480032.035 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4618
2024-08-12T16:27:12.046Z,1723480032.046 [DAT ThreadHandler](INFO): Handler Thread ID is 4619
2024-08-12T16:27:12.047Z,1723480032.047 [DAT](INFO): Powering up
2024-08-12T16:27:12.047Z,1723480032.047 [DAT](DEBUG): Initializing DAT.
2024-08-12T16:27:12.051Z,1723480032.051 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4621
2024-08-12T16:27:12.051Z,1723480032.051 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-08-12T16:27:12.055Z,1723480032.055 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4623
2024-08-12T16:27:12.059Z,1723480032.059 [WetLabsBB2FL](INFO): Powering up
2024-08-12T16:27:12.060Z,1723480032.060 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4625
2024-08-12T16:27:12.064Z,1723480032.064 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-08-12T16:27:12.064Z,1723480032.064 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-08-12T16:27:12.064Z,1723480032.064 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-08-12T16:27:12.064Z,1723480032.064 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-08-12T16:27:12.064Z,1723480032.064 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-08-12T16:27:12.064Z,1723480032.064 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-08-12T16:27:12.065Z,1723480032.065 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-08-12T16:27:12.065Z,1723480032.065 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-08-12T16:27:12.090Z,1723480032.090 [CBIT](DEBUG): Deactivating GF circuits.
2024-08-12T16:27:12.090Z,1723480032.090 [CBIT](DEBUG): Deactivating emergency mode.
2024-08-12T16:27:12.129Z,1723480032.129 [CBIT](DEBUG): Backplane powered.
2024-08-12T16:27:12.130Z,1723480032.130 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-08-12T16:27:12.131Z,1723480032.131 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-08-12T16:27:12.131Z,1723480032.131 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-08-12T16:27:12.132Z,1723480032.132 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-08-12T16:27:12.173Z,1723480032.173 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-08-12T16:27:12.190Z,1723480032.190 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-12T16:27:12.225Z,1723480032.225 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-08-12T16:27:12.226Z,1723480032.226 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-08-12T16:27:12.226Z,1723480032.226 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-08-12T16:27:12.227Z,1723480032.227 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-08-12T16:27:12.438Z,1723480032.438 [Radio_Surface](INFO): Powering up
2024-08-12T16:27:12.502Z,1723480032.502 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-08-12T16:27:12.528Z,1723480032.528 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-08-12T16:27:12.529Z,1723480032.529 [Default:A.Wait](DEBUG): Construct Wait.
2024-08-12T16:27:12.531Z,1723480032.531 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-12T16:27:12.568Z,1723480032.568 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-08-12T16:27:12.595Z,1723480032.595 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-08-12T16:27:12.601Z,1723480032.601 [Default:E.Execute](DEBUG): Construct Execute.
2024-08-12T16:27:12.620Z,1723480032.620 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-08-12T16:27:12.624Z,1723480032.624 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-08-12T16:27:12.647Z,1723480032.647 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-12T16:27:12.716Z,1723480032.716 [Power24vConverter](INFO): Powering up.
2024-08-12T16:27:12.717Z,1723480032.717 [CANONSampler](INFO): Powering down
2024-08-12T16:27:12.804Z,1723480032.804 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-08-12T16:27:12.810Z,1723480032.810 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-08-12T16:27:12.811Z,1723480032.811 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-12T16:27:12.818Z,1723480032.818 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-12T16:27:12.819Z,1723480032.819 [MassServo](DEBUG): Initializing EZServoServo.
2024-08-12T16:27:12.826Z,1723480032.826 [MassServo](DEBUG): Initializing MassServo.
2024-08-12T16:27:12.827Z,1723480032.827 [RudderServo](DEBUG): Initializing EZServoServo.
2024-08-12T16:27:12.834Z,1723480032.834 [RudderServo](DEBUG): Initializing RudderServo.
2024-08-12T16:27:12.835Z,1723480032.835 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-08-12T16:27:12.842Z,1723480032.842 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-08-12T16:27:14.670Z,1723480034.670 [WetLabsBB2FL](INFO): Powering down
2024-08-12T16:27:18.674Z,1723480038.674 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-08-12T16:27:19.082Z,1723480039.082 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-08-12T16:27:23.638Z,1723480043.638 [DAT](INFO): DAT read:
2024-08-12T16:27:23.640Z,1723480043.640 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-08-12T16:27:25.150Z,1723480045.150 [DAT](INFO): DAT read: MF Frequency Band
2024-08-12T16:27:25.152Z,1723480045.152 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-08-12T16:27:25.152Z,1723480045.152 [DAT](INFO): DAT read: Aug 12 2024 16:27:20
2024-08-12T16:27:26.159Z,1723480046.159 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-08-12T16:27:26.160Z,1723480046.160 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-08-12T16:27:26.160Z,1723480046.160 [DAT](INFO): commRate: 1200
2024-08-12T16:27:28.225Z,1723480048.225 [DAT](INFO): entering command mode
2024-08-12T16:27:28.426Z,1723480048.426 [DAT](INFO): DAT read:
2024-08-12T16:27:28.427Z,1723480048.427 [DAT](INFO): DAT read: user:1>
2024-08-12T16:27:28.427Z,1723480048.427 [DAT](INFO): setting verbose to 3
2024-08-12T16:27:28.678Z,1723480048.678 [DAT](INFO): DAT read: user:1>
2024-08-12T16:27:28.679Z,1723480048.679 [DAT](INFO): DAT read: Verbose | 3
2024-08-12T16:27:28.680Z,1723480048.680 [DAT](INFO): set verbose to 3
2024-08-12T16:27:28.680Z,1723480048.680 [DAT](INFO): setting DatVerbose to 27440
2024-08-12T16:27:28.930Z,1723480048.930 [DAT](INFO): DAT read: user:2>
2024-08-12T16:27:28.931Z,1723480048.931 [DAT](INFO): DAT read: DatVerbose | 27440
2024-08-12T16:27:28.932Z,1723480048.932 [DAT](INFO): set DatVerbose to 27440
2024-08-12T16:27:28.932Z,1723480048.932 [DAT](INFO): setting transmit power to 8
2024-08-12T16:27:29.182Z,1723480049.182 [DAT](INFO): DAT read: user:3>
2024-08-12T16:27:29.183Z,1723480049.183 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-08-12T16:27:29.184Z,1723480049.184 [DAT](INFO): set transmit power to 8
2024-08-12T16:27:29.184Z,1723480049.184 [DAT](INFO): setting local address to 8
2024-08-12T16:27:29.434Z,1723480049.434 [DAT](INFO): DAT read: user:4>
2024-08-12T16:27:29.435Z,1723480049.435 [DAT](INFO): DAT read: LocalAddr | 8
2024-08-12T16:27:29.435Z,1723480049.435 [DAT](INFO): set local address to 8
2024-08-12T16:27:29.437Z,1723480049.437 [DAT](INFO): Setting time to: 16:27:29 And date to:8/12/2024
2024-08-12T16:27:29.689Z,1723480049.689 [DAT](INFO): DAT read: user:5>
2024-08-12T16:27:29.690Z,1723480049.690 [DAT](INFO): DAT read: Mon Aug 12, 2024 16:27:29
2024-08-12T16:27:29.690Z,1723480049.690 [DAT](INFO): Local DAT time set to Mon Aug 12, 2024 16:27:29
2024-08-12T16:27:40.567Z,1723480060.567 [NAL9602](INFO): Powering up NAL9602
2024-08-12T16:27:51.475Z,1723480071.475 [NAL9602](INFO): NAL9602 initialized
2024-08-12T16:28:12.098Z,1723480092.098 [NAL9602](INFO): SBD MO Status=2, MOMSN=54776, MT Status=2, MTMSN=0
2024-08-12T16:28:12.098Z,1723480092.098 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-12T16:28:15.737Z,1723480095.737 [SBIT](IMPORTANT): Beginning Startup BIT
2024-08-12T16:28:15.741Z,1723480095.741 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-12T16:28:17.731Z,1723480097.731 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:28:26.644Z,1723480106.644 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-08-12T16:28:27.048Z,1723480107.048 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-12T16:28:29.446Z,1723480109.446 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:28:37.987Z,1723480117.987 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-08-12T16:28:38.390Z,1723480118.390 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-12T16:28:41.566Z,1723480121.566 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:28:49.267Z,1723480129.267 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-08-12T16:28:49.268Z,1723480129.268 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-08-12T16:28:49.657Z,1723480129.657 [NAL9602](INFO): SBD MO Status=0, MOMSN=54776, MT Status=0, MTMSN=0
2024-08-12T16:28:49.657Z,1723480129.657 [NAL9602](INFO): No messages in MT queue
2024-08-12T16:28:50.870Z,1723480130.870 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162849.00,A,3648.15295,N,12147.22184,W,0.253,0.00,120824,,,A*7A
2024-08-12T16:28:50.873Z,1723480130.873 [NAL9602](INFO): GPS fix at 20240812T162849: (36.802549, -121.787031)
2024-08-12T16:28:53.691Z,1723480133.691 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:29:05.811Z,1723480145.811 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:29:09.905Z,1723480149.905 [SBIT](IMPORTANT): SBIT PASSED
2024-08-12T16:29:09.906Z,1723480149.906 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-08-12T16:29:09.906Z,1723480149.906 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=128.796039 cubic_centimeter;
2024-08-12T16:29:09.907Z,1723480149.907 [SBIT](IMPORTANT): VerticalControl.massDefault=10.940930 millimeter;
2024-08-12T16:29:10.290Z,1723480150.290 [MissionManager](IMPORTANT): Started mission Startup
2024-08-12T16:29:10.290Z,1723480150.290 [Startup] Running Loop=1
2024-08-12T16:29:10.290Z,1723480150.290 [Startup](DEBUG): Aggregate::initialize Startup
2024-08-12T16:29:10.290Z,1723480150.290 [Startup:A.GoToSurface] Running Loop=1
2024-08-12T16:29:10.290Z,1723480150.290 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-12T16:29:10.291Z,1723480150.291 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-12T16:29:10.291Z,1723480150.291 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-12T16:29:10.292Z,1723480150.292 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-12T16:29:10.292Z,1723480150.292 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-12T16:29:10.292Z,1723480150.292 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-12T16:29:10.293Z,1723480150.293 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-12T16:29:10.298Z,1723480150.298 [Startup:StartupSatComms] Running Loop=1
2024-08-12T16:29:10.299Z,1723480150.299 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-08-12T16:29:10.299Z,1723480150.299 [Startup:StartupSatComms:A] Running Loop=1
2024-08-12T16:29:10.706Z,1723480150.706 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-08-12T16:29:12.285Z,1723480152.285 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162910.00,A,3648.15238,N,12147.22201,W,0.019,0.00,120824,,,A*72
2024-08-12T16:29:12.296Z,1723480152.296 [NAL9602](INFO): GPS fix at 20240812T162910: (36.802540, -121.787034)
2024-08-12T16:29:12.311Z,1723480152.311 [Startup:StartupSatComms:A] Stopped
2024-08-12T16:29:12.311Z,1723480152.311 [Startup:StartupSatComms:B] Running Loop=1
2024-08-12T16:29:12.766Z,1723480152.766 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-12T16:29:17.528Z,1723480157.528 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:29:28.932Z,1723480168.932 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:29:32.980Z,1723480172.980 [NAL9602](INFO): SBD MO Status=1, MOMSN=54777, MT Status=0, MTMSN=0
2024-08-12T16:29:33.034Z,1723480173.034 [NAL9602](INFO): Sent 39 bytes from file Logs/20240812T153930/Courier0022.lzma
2024-08-12T16:29:33.034Z,1723480173.034 [NAL9602](INFO): Packets left to send: 0
2024-08-12T16:29:40.817Z,1723480180.817 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:29:43.584Z,1723480183.584 [NAL9602](INFO): SBD MO Status=1, MOMSN=54778, MT Status=0, MTMSN=0
2024-08-12T16:29:43.638Z,1723480183.638 [NAL9602](INFO): Sent 267 bytes from file Logs/20240812T162700/Courier0000.lzma
2024-08-12T16:29:43.638Z,1723480183.638 [NAL9602](INFO): Packets left to send: 0
2024-08-12T16:29:50.960Z,1723480190.960 [NAL9602](INFO): SBD MO Status=1, MOMSN=54779, MT Status=0, MTMSN=0
2024-08-12T16:29:51.010Z,1723480191.010 [NAL9602](INFO): Sent 143 bytes from file Logs/20240812T153930/Express0023.lzma
2024-08-12T16:29:51.010Z,1723480191.010 [NAL9602](INFO): Packets left to send: 0
2024-08-12T16:30:00.788Z,1723480200.788 [NAL9602](INFO): SBD MO Status=1, MOMSN=54780, MT Status=0, MTMSN=0
2024-08-12T16:30:00.842Z,1723480200.842 [NAL9602](INFO): Sent 332 bytes from file Logs/20240812T162700/Express0001.lzma
2024-08-12T16:30:00.842Z,1723480200.842 [NAL9602](INFO): Packets left to send: 2
2024-08-12T16:30:09.400Z,1723480209.400 [NAL9602](INFO): SBD MO Status=1, MOMSN=54781, MT Status=0, MTMSN=0
2024-08-12T16:30:09.454Z,1723480209.454 [NAL9602](INFO): Sent 332 bytes from file Logs/20240812T162700/Express0001.lzma
2024-08-12T16:30:09.454Z,1723480209.454 [NAL9602](INFO): Packets left to send: 1
2024-08-12T16:30:12.338Z,1723480212.338 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-12T16:29:12.3Z
2024-08-12T16:30:12.338Z,1723480212.338 [Startup:StartupSatComms:B] Stopped
2024-08-12T16:30:12.339Z,1723480212.339 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-08-12T16:30:12.339Z,1723480212.339 [Startup:StartupSatComms] Stopped
2024-08-12T16:30:12.339Z,1723480212.339 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-08-12T16:30:12.340Z,1723480212.340 [Startup](INFO): Completed Startup
2024-08-12T16:30:12.340Z,1723480212.340 [MissionManager](INFO): Startup is completed.
2024-08-12T16:30:12.340Z,1723480212.340 [MissionManager](INFO): Uninitializing Mission Startup
2024-08-12T16:30:12.340Z,1723480212.340 [Startup] Stopped
2024-08-12T16:30:12.340Z,1723480212.340 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-08-12T16:30:12.340Z,1723480212.340 [Startup:A.GoToSurface] Stopped
2024-08-12T16:30:12.340Z,1723480212.340 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-12T16:30:12.740Z,1723480212.740 [MissionManager](IMPORTANT): Started mission Default
2024-08-12T16:30:12.741Z,1723480212.741 [Default] Running Loop=1
2024-08-12T16:30:12.741Z,1723480212.741 [Default](DEBUG): Aggregate::initialize Default
2024-08-12T16:30:12.741Z,1723480212.741 [Default:B.GoToSurface] Running Loop=1
2024-08-12T16:30:12.741Z,1723480212.741 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-12T16:30:12.741Z,1723480212.741 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-12T16:30:12.742Z,1723480212.742 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-12T16:30:12.742Z,1723480212.742 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-12T16:30:12.742Z,1723480212.742 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-12T16:30:12.742Z,1723480212.742 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-12T16:30:12.743Z,1723480212.743 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-12T16:30:12.743Z,1723480212.743 [Default:A.Wait] Running Loop=1
2024-08-12T16:30:12.743Z,1723480212.743 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:30:16.790Z,1723480216.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=54782, MT Status=0, MTMSN=0
2024-08-12T16:30:16.841Z,1723480216.841 [NAL9602](INFO): Sent 50 bytes from file Logs/20240812T162700/Express0001.lzma
2024-08-12T16:30:16.841Z,1723480216.841 [NAL9602](INFO): Packets left to send: 0
2024-08-12T16:30:26.073Z,1723480226.073 [Default:A.Wait](INFO): Done Waiting.
2024-08-12T16:30:26.073Z,1723480226.073 [Default:A.Wait] Stopped
2024-08-12T16:30:26.073Z,1723480226.073 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T16:30:26.480Z,1723480226.480 [Default:CheckIn] Running Loop=1
2024-08-12T16:30:26.481Z,1723480226.481 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T16:30:26.481Z,1723480226.481 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T16:30:26.872Z,1723480226.872 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-08-12T16:30:28.480Z,1723480228.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163026.00,A,3648.15049,N,12147.22133,W,0.039,0.00,120824,,,A*7B
2024-08-12T16:30:28.482Z,1723480228.482 [NAL9602](INFO): GPS fix at 20240812T163026: (36.802508, -121.787022)
2024-08-12T16:30:28.513Z,1723480228.513 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T16:30:28.513Z,1723480228.513 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T16:30:28.982Z,1723480228.982 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-12T16:30:47.544Z,1723480247.544 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007818
2024-08-12T16:30:51.216Z,1723480251.216 [NAL9602](INFO): SBD MO Status=1, MOMSN=54783, MT Status=0, MTMSN=0
2024-08-12T16:30:51.274Z,1723480251.274 [NAL9602](INFO): Sent 73 bytes from file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:30:51.274Z,1723480251.274 [NAL9602](INFO): Packets left to send: 0
2024-08-12T16:30:51.351Z,1723480251.351 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T16:30:51.351Z,1723480251.351 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T16:30:51.351Z,1723480251.351 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:30:58.116Z,1723480258.116 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:30:58.116Z,1723480258.116 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:31:06.868Z,1723480266.868 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:31:06.868Z,1723480266.868 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:31:21.962Z,1723480281.962 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T16:31:37.748Z,1723480297.748 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:31:37.749Z,1723480297.749 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:32:08.700Z,1723480328.700 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:32:08.700Z,1723480328.700 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:32:08.816Z,1723480328.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:32:20.631Z,1723480340.631 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:32:32.248Z,1723480352.248 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:32:39.232Z,1723480359.232 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:32:39.232Z,1723480359.232 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:32:44.369Z,1723480364.369 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:32:56.114Z,1723480376.114 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:33:08.608Z,1723480388.608 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:33:10.048Z,1723480390.048 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:33:10.048Z,1723480390.048 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:33:20.728Z,1723480400.728 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:33:32.444Z,1723480412.444 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:33:40.616Z,1723480420.616 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:33:40.616Z,1723480420.616 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:33:44.160Z,1723480424.160 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:33:56.280Z,1723480436.280 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:34:08.004Z,1723480448.004 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:34:11.356Z,1723480451.356 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:34:11.356Z,1723480451.356 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:34:19.712Z,1723480459.712 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:34:42.100Z,1723480482.100 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:34:42.100Z,1723480482.100 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:35:12.939Z,1723480512.939 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:35:12.940Z,1723480512.940 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:35:43.708Z,1723480543.708 [DataOverHttps](IMPORTANT): Could not stat file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:35:43.708Z,1723480543.708 [DataOverHttps](FAULT): Could not open file Logs/20240812T162700/Courier0004.lzma
2024-08-12T16:35:51.837Z,1723480551.837 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T16:35:51.837Z,1723480551.837 [Default:CheckIn:C.Wait] Stopped
2024-08-12T16:35:51.837Z,1723480551.837 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T16:35:51.837Z,1723480551.837 [Default:CheckIn:D] Running Loop=1
2024-08-12T16:35:52.240Z,1723480552.240 [Default:CheckIn:D] Stopped
2024-08-12T16:35:52.240Z,1723480552.240 [Default:CheckIn:E] Running Loop=1
2024-08-12T16:35:52.645Z,1723480552.645 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.658327 min
2024-08-12T16:35:52.645Z,1723480552.645 [Default:CheckIn:E] Stopped
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn] Stopped
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn](INFO): Running loop #2
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn] Running Loop=2
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T16:35:52.646Z,1723480552.646 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T16:35:54.676Z,1723480554.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163554.00,A,3648.15169,N,12147.22116,W,0.058,0.00,120824,,,A*78
2024-08-12T16:35:54.679Z,1723480554.679 [NAL9602](INFO): GPS fix at 20240812T163554: (36.802528, -121.787019)
2024-08-12T16:35:54.689Z,1723480554.689 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T16:35:54.689Z,1723480554.689 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T16:36:02.469Z,1723480562.469 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0007.lzma
2024-08-12T16:36:03.471Z,1723480563.471 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0007.lzma.bak
2024-08-12T16:36:03.472Z,1723480563.472 [DataOverHttps](INFO): SBD MOMSN=21490498
2024-08-12T16:36:21.349Z,1723480581.349 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20240812T162700/Express0005.lzma
2024-08-12T16:36:22.351Z,1723480582.351 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0005.lzma.bak
2024-08-12T16:36:22.352Z,1723480582.352 [DataOverHttps](INFO): SBD MOMSN=21490501
2024-08-12T16:36:27.041Z,1723480587.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=54784, MT Status=2, MTMSN=0
2024-08-12T16:36:27.041Z,1723480587.041 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-12T16:36:40.462Z,1723480600.462 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20240812T162700/Express0008.lzma
2024-08-12T16:36:41.463Z,1723480601.463 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0008.lzma.bak
2024-08-12T16:36:41.463Z,1723480601.463 [DataOverHttps](INFO): SBD MOMSN=21490504
2024-08-12T16:36:42.797Z,1723480602.797 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T16:36:42.798Z,1723480602.798 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T16:36:42.798Z,1723480602.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:37:12.275Z,1723480632.275 [NAL9602](INFO): SBD MO Status=0, MOMSN=54784, MT Status=0, MTMSN=0
2024-08-12T16:37:12.275Z,1723480632.275 [NAL9602](INFO): No messages in MT queue
2024-08-12T16:37:43.040Z,1723480663.040 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T16:41:43.569Z,1723480903.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T16:41:43.569Z,1723480903.569 [Default:CheckIn:C.Wait] Stopped
2024-08-12T16:41:43.569Z,1723480903.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T16:41:43.569Z,1723480903.569 [Default:CheckIn:D] Running Loop=1
2024-08-12T16:41:43.967Z,1723480903.967 [Default:CheckIn:D] Stopped
2024-08-12T16:41:43.968Z,1723480903.968 [Default:CheckIn:E] Running Loop=1
2024-08-12T16:41:44.390Z,1723480904.390 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.520447 min
2024-08-12T16:41:44.390Z,1723480904.390 [Default:CheckIn:E] Stopped
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn] Stopped
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn](INFO): Running loop #3
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn] Running Loop=3
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T16:41:44.391Z,1723480904.391 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T16:41:46.380Z,1723480906.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164145.00,A,3648.15059,N,12147.22089,W,0.019,0.00,120824,,,A*7B
2024-08-12T16:41:46.382Z,1723480906.382 [NAL9602](INFO): GPS fix at 20240812T164145: (36.802510, -121.787015)
2024-08-12T16:41:46.393Z,1723480906.393 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T16:41:46.393Z,1723480906.393 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T16:41:52.031Z,1723480912.031 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:41:53.969Z,1723480913.969 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0010.lzma
2024-08-12T16:41:54.971Z,1723480914.971 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0010.lzma.bak
2024-08-12T16:41:54.971Z,1723480914.971 [DataOverHttps](INFO): SBD MOMSN=21490540
2024-08-12T16:42:04.149Z,1723480924.149 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:42:04.180Z,1723480924.180 [NAL9602](INFO): SBD MO Status=0, MOMSN=54785, MT Status=0, MTMSN=0
2024-08-12T16:42:04.180Z,1723480924.180 [NAL9602](INFO): No messages in MT queue
2024-08-12T16:42:11.169Z,1723480931.169 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0011.lzma
2024-08-12T16:42:12.171Z,1723480932.171 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0011.lzma.bak
2024-08-12T16:42:12.171Z,1723480932.171 [DataOverHttps](INFO): SBD MOMSN=21490551
2024-08-12T16:42:13.456Z,1723480933.456 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T16:42:13.456Z,1723480933.456 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T16:42:13.456Z,1723480933.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:42:16.268Z,1723480936.268 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:42:28.018Z,1723480948.018 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:42:34.886Z,1723480954.886 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T16:42:40.136Z,1723480960.136 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:42:51.890Z,1723480971.890 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:43:04.499Z,1723480984.499 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:43:16.528Z,1723480996.528 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:43:28.244Z,1723481008.244 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:43:40.364Z,1723481020.364 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:43:52.888Z,1723481032.888 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:44:05.008Z,1723481045.008 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:47:13.907Z,1723481233.907 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T16:47:13.907Z,1723481233.907 [Default:CheckIn:C.Wait] Stopped
2024-08-12T16:47:13.908Z,1723481233.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T16:47:13.908Z,1723481233.908 [Default:CheckIn:D] Running Loop=1
2024-08-12T16:47:14.318Z,1723481234.318 [Default:CheckIn:D] Stopped
2024-08-12T16:47:14.318Z,1723481234.318 [Default:CheckIn:E] Running Loop=1
2024-08-12T16:47:14.719Z,1723481234.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.026295 min
2024-08-12T16:47:14.719Z,1723481234.719 [Default:CheckIn:E] Stopped
2024-08-12T16:47:14.719Z,1723481234.719 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T16:47:14.719Z,1723481234.719 [Default:CheckIn] Stopped
2024-08-12T16:47:14.720Z,1723481234.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T16:47:14.720Z,1723481234.720 [Default:CheckIn](INFO): Running loop #4
2024-08-12T16:47:14.720Z,1723481234.720 [Default:CheckIn] Running Loop=4
2024-08-12T16:47:14.720Z,1723481234.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T16:47:14.720Z,1723481234.720 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T16:47:16.792Z,1723481236.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164716.00,A,3648.15103,N,12147.22106,W,0.039,0.00,120824,,,A*71
2024-08-12T16:47:16.794Z,1723481236.794 [NAL9602](INFO): GPS fix at 20240812T164716: (36.802517, -121.787018)
2024-08-12T16:47:16.810Z,1723481236.810 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T16:47:16.810Z,1723481236.810 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T16:47:24.749Z,1723481244.749 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0013.lzma
2024-08-12T16:47:25.751Z,1723481245.751 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0013.lzma.bak
2024-08-12T16:47:25.751Z,1723481245.751 [DataOverHttps](INFO): SBD MOMSN=21490608
2024-08-12T16:47:32.892Z,1723481252.892 [NAL9602](INFO): SBD MO Status=0, MOMSN=54786, MT Status=0, MTMSN=0
2024-08-12T16:47:32.892Z,1723481252.892 [NAL9602](INFO): No messages in MT queue
2024-08-12T16:47:41.989Z,1723481261.989 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240812T162700/Express0014.lzma
2024-08-12T16:47:42.991Z,1723481262.991 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0014.lzma.bak
2024-08-12T16:47:42.991Z,1723481262.991 [DataOverHttps](INFO): SBD MOMSN=21490611
2024-08-12T16:47:44.228Z,1723481264.228 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T16:47:44.228Z,1723481264.228 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T16:47:44.228Z,1723481264.228 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:48:03.589Z,1723481283.589 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T16:51:29.907Z,1723481489.907 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:51:45.597Z,1723481505.597 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:52:00.140Z,1723481520.140 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:52:15.896Z,1723481535.896 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:52:31.696Z,1723481551.696 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:52:44.640Z,1723481564.640 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T16:52:44.640Z,1723481564.640 [Default:CheckIn:C.Wait] Stopped
2024-08-12T16:52:44.640Z,1723481564.640 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T16:52:44.640Z,1723481564.640 [Default:CheckIn:D] Running Loop=1
2024-08-12T16:52:45.052Z,1723481565.052 [Default:CheckIn:D] Stopped
2024-08-12T16:52:45.052Z,1723481565.052 [Default:CheckIn:E] Running Loop=1
2024-08-12T16:52:45.450Z,1723481565.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.538519 min
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn:E] Stopped
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn] Stopped
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn](INFO): Running loop #5
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn] Running Loop=5
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T16:52:45.451Z,1723481565.451 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T16:52:47.049Z,1723481567.049 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:52:47.473Z,1723481567.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165246.00,A,3648.15064,N,12147.22131,W,0.019,0.00,120824,,,A*76
2024-08-12T16:52:47.475Z,1723481567.475 [NAL9602](INFO): GPS fix at 20240812T165246: (36.802511, -121.787022)
2024-08-12T16:52:47.494Z,1723481567.494 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T16:52:47.494Z,1723481567.494 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T16:52:57.981Z,1723481577.981 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240812T162700/Courier0016.lzma
2024-08-12T16:52:58.984Z,1723481578.984 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0016.lzma.bak
2024-08-12T16:52:58.984Z,1723481578.984 [DataOverHttps](INFO): SBD MOMSN=21490678
2024-08-12T16:53:02.876Z,1723481582.876 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:53:09.747Z,1723481589.747 [NAL9602](INFO): SBD MO Status=2, MOMSN=54787, MT Status=2, MTMSN=0
2024-08-12T16:53:09.747Z,1723481589.747 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-12T16:53:15.394Z,1723481595.394 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0017.lzma
2024-08-12T16:53:16.396Z,1723481596.396 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0017.lzma.bak
2024-08-12T16:53:16.396Z,1723481596.396 [DataOverHttps](INFO): SBD MOMSN=21490681
2024-08-12T16:53:17.478Z,1723481597.478 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T16:53:17.479Z,1723481597.479 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T16:53:17.479Z,1723481597.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:53:18.229Z,1723481598.229 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:53:33.984Z,1723481613.984 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:53:45.300Z,1723481625.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=54787, MT Status=0, MTMSN=0
2024-08-12T16:53:45.300Z,1723481625.300 [NAL9602](INFO): No messages in MT queue
2024-08-12T16:54:16.002Z,1723481656.002 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T16:56:15.720Z,1723481775.720 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:56:27.887Z,1723481787.887 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:56:38.792Z,1723481798.792 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:56:50.508Z,1723481810.508 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:57:02.672Z,1723481822.672 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:57:14.792Z,1723481834.792 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:57:26.535Z,1723481846.535 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:57:38.224Z,1723481858.224 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:57:50.344Z,1723481870.344 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:58:02.465Z,1723481882.465 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T16:58:12.970Z,1723481892.970 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2024-08-12T16:58:12.973Z,1723481892.973 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-08-12T16:58:18.245Z,1723481898.245 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T16:58:18.245Z,1723481898.245 [Default:CheckIn:C.Wait] Stopped
2024-08-12T16:58:18.245Z,1723481898.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T16:58:18.245Z,1723481898.245 [Default:CheckIn:D] Running Loop=1
2024-08-12T16:58:18.679Z,1723481898.679 [Default:CheckIn:D] Stopped
2024-08-12T16:58:18.679Z,1723481898.679 [Default:CheckIn:E] Running Loop=1
2024-08-12T16:58:19.048Z,1723481899.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.098975 min
2024-08-12T16:58:19.048Z,1723481899.048 [Default:CheckIn:E] Stopped
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn] Stopped
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn](INFO): Running loop #6
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn] Running Loop=6
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T16:58:19.049Z,1723481899.049 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T16:58:21.051Z,1723481901.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165820.00,A,3648.15091,N,12147.22098,W,0.039,0.00,120824,,,A*76
2024-08-12T16:58:21.053Z,1723481901.053 [NAL9602](INFO): GPS fix at 20240812T165820: (36.802515, -121.787016)
2024-08-12T16:58:21.096Z,1723481901.096 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T16:58:21.096Z,1723481901.096 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T16:58:30.234Z,1723481910.234 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20240812T162700/Courier0019.lzma
2024-08-12T16:58:31.235Z,1723481911.235 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0019.lzma.bak
2024-08-12T16:58:31.235Z,1723481911.235 [DataOverHttps](INFO): SBD MOMSN=21490692
2024-08-12T16:58:32.938Z,1723481912.938 [NAL9602](INFO): SBD MO Status=0, MOMSN=54788, MT Status=0, MTMSN=0
2024-08-12T16:58:32.938Z,1723481912.938 [NAL9602](INFO): No messages in MT queue
2024-08-12T16:58:47.354Z,1723481927.354 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240812T162700/Express0020.lzma
2024-08-12T16:58:48.356Z,1723481928.356 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0020.lzma.bak
2024-08-12T16:58:48.356Z,1723481928.356 [DataOverHttps](INFO): SBD MOMSN=21490696
2024-08-12T16:58:49.514Z,1723481929.514 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T16:58:49.514Z,1723481929.514 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T16:58:49.514Z,1723481929.514 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T16:59:03.694Z,1723481943.694 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:03:50.180Z,1723482230.180 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:03:50.180Z,1723482230.180 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:03:50.180Z,1723482230.180 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:03:50.180Z,1723482230.180 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:03:50.579Z,1723482230.579 [Default:CheckIn:D] Stopped
2024-08-12T17:03:50.579Z,1723482230.579 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:03:50.992Z,1723482230.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.630644 min
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn:E] Stopped
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn] Stopped
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn](INFO): Running loop #7
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn] Running Loop=7
2024-08-12T17:03:50.993Z,1723482230.993 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:03:50.994Z,1723482230.994 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:03:52.996Z,1723482232.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170352.00,A,3648.15045,N,12147.22100,W,0.039,0.00,120824,,,A*75
2024-08-12T17:03:52.998Z,1723482232.998 [NAL9602](INFO): GPS fix at 20240812T170352: (36.802507, -121.787017)
2024-08-12T17:03:53.008Z,1723482233.008 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:03:53.008Z,1723482233.008 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:04:01.005Z,1723482241.005 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0022.lzma
2024-08-12T17:04:02.007Z,1723482242.007 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0022.lzma.bak
2024-08-12T17:04:02.007Z,1723482242.007 [DataOverHttps](INFO): SBD MOMSN=21490753
2024-08-12T17:04:13.197Z,1723482253.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=54789, MT Status=0, MTMSN=0
2024-08-12T17:04:13.197Z,1723482253.197 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:04:18.869Z,1723482258.869 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0023.lzma
2024-08-12T17:04:19.871Z,1723482259.871 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0023.lzma.bak
2024-08-12T17:04:19.872Z,1723482259.872 [DataOverHttps](INFO): SBD MOMSN=21490756
2024-08-12T17:04:20.921Z,1723482260.921 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:04:20.921Z,1723482260.921 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:04:20.921Z,1723482260.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:04:43.936Z,1723482283.936 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:09:21.509Z,1723482561.509 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:09:21.509Z,1723482561.509 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:09:21.510Z,1723482561.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:09:21.510Z,1723482561.510 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:09:21.908Z,1723482561.908 [Default:CheckIn:D] Stopped
2024-08-12T17:09:21.908Z,1723482561.908 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:09:22.312Z,1723482562.312 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.152791 min
2024-08-12T17:09:22.312Z,1723482562.312 [Default:CheckIn:E] Stopped
2024-08-12T17:09:22.312Z,1723482562.312 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:09:22.312Z,1723482562.312 [Default:CheckIn] Stopped
2024-08-12T17:09:22.312Z,1723482562.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:09:22.313Z,1723482562.313 [Default:CheckIn](INFO): Running loop #8
2024-08-12T17:09:22.313Z,1723482562.313 [Default:CheckIn] Running Loop=8
2024-08-12T17:09:22.313Z,1723482562.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:09:22.313Z,1723482562.313 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:09:24.324Z,1723482564.324 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170923.00,A,3648.15108,N,12147.22090,W,0.058,0.00,120824,,,A*7E
2024-08-12T17:09:24.327Z,1723482564.327 [NAL9602](INFO): GPS fix at 20240812T170923: (36.802518, -121.787015)
2024-08-12T17:09:24.337Z,1723482564.337 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:09:24.337Z,1723482564.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:09:31.981Z,1723482571.981 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0025.lzma
2024-08-12T17:09:32.983Z,1723482572.983 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0025.lzma.bak
2024-08-12T17:09:32.983Z,1723482572.983 [DataOverHttps](INFO): SBD MOMSN=21490767
2024-08-12T17:09:36.452Z,1723482576.452 [NAL9602](INFO): SBD MO Status=0, MOMSN=54790, MT Status=0, MTMSN=0
2024-08-12T17:09:36.452Z,1723482576.452 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:09:49.126Z,1723482589.126 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240812T162700/Express0026.lzma
2024-08-12T17:09:50.127Z,1723482590.128 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0026.lzma.bak
2024-08-12T17:09:50.128Z,1723482590.128 [DataOverHttps](INFO): SBD MOMSN=21490770
2024-08-12T17:09:51.408Z,1723482591.408 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:09:51.408Z,1723482591.408 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:09:51.408Z,1723482591.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:10:07.150Z,1723482607.150 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:14:52.027Z,1723482892.027 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:14:52.027Z,1723482892.027 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:14:52.028Z,1723482892.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:14:52.028Z,1723482892.028 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:14:52.412Z,1723482892.412 [Default:CheckIn:D] Stopped
2024-08-12T17:14:52.412Z,1723482892.412 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.661194 min
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn:E] Stopped
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn] Stopped
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn](INFO): Running loop #9
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn] Running Loop=9
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:14:52.826Z,1723482892.826 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:14:54.838Z,1723482894.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171454.00,A,3648.15043,N,12147.22118,W,0.058,0.00,120824,,,A*7D
2024-08-12T17:14:54.840Z,1723482894.840 [NAL9602](INFO): GPS fix at 20240812T171454: (36.802507, -121.787020)
2024-08-12T17:14:54.850Z,1723482894.850 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:14:54.850Z,1723482894.850 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:15:02.861Z,1723482902.861 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0028.lzma
2024-08-12T17:15:03.863Z,1723482903.863 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0028.lzma.bak
2024-08-12T17:15:03.864Z,1723482903.864 [DataOverHttps](INFO): SBD MOMSN=21490836
2024-08-12T17:15:09.776Z,1723482909.776 [NAL9602](INFO): SBD MO Status=0, MOMSN=54791, MT Status=0, MTMSN=0
2024-08-12T17:15:09.776Z,1723482909.776 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:15:20.154Z,1723482920.154 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0029.lzma
2024-08-12T17:15:21.155Z,1723482921.155 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0029.lzma.bak
2024-08-12T17:15:21.156Z,1723482921.156 [DataOverHttps](INFO): SBD MOMSN=21490839
2024-08-12T17:15:22.316Z,1723482922.316 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:15:22.316Z,1723482922.316 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:15:22.316Z,1723482922.316 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:15:40.478Z,1723482940.478 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:20:22.900Z,1723483222.900 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:20:22.900Z,1723483222.900 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:20:22.901Z,1723483222.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:20:22.901Z,1723483222.901 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:20:23.313Z,1723483223.313 [Default:CheckIn:D] Stopped
2024-08-12T17:20:23.313Z,1723483223.313 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:20:23.704Z,1723483223.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.176200 min
2024-08-12T17:20:23.704Z,1723483223.704 [Default:CheckIn:E] Stopped
2024-08-12T17:20:23.704Z,1723483223.704 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:20:23.704Z,1723483223.704 [Default:CheckIn] Stopped
2024-08-12T17:20:23.705Z,1723483223.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:20:23.705Z,1723483223.705 [Default:CheckIn](INFO): Running loop #10
2024-08-12T17:20:23.705Z,1723483223.705 [Default:CheckIn] Running Loop=10
2024-08-12T17:20:23.705Z,1723483223.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:20:23.705Z,1723483223.705 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:20:25.708Z,1723483225.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172025.00,A,3648.15070,N,12147.22067,W,0.058,0.00,120824,,,A*75
2024-08-12T17:20:25.711Z,1723483225.711 [NAL9602](INFO): GPS fix at 20240812T172025: (36.802512, -121.787011)
2024-08-12T17:20:25.742Z,1723483225.742 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:20:25.742Z,1723483225.742 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:20:33.805Z,1723483233.805 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0031.lzma
2024-08-12T17:20:34.807Z,1723483234.807 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0031.lzma.bak
2024-08-12T17:20:34.808Z,1723483234.808 [DataOverHttps](INFO): SBD MOMSN=21490849
2024-08-12T17:20:40.664Z,1723483240.664 [NAL9602](INFO): SBD MO Status=0, MOMSN=54792, MT Status=0, MTMSN=0
2024-08-12T17:20:40.664Z,1723483240.664 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:20:53.742Z,1723483253.742 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240812T162700/Express0032.lzma
2024-08-12T17:20:54.745Z,1723483254.745 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0032.lzma.bak
2024-08-12T17:20:54.745Z,1723483254.745 [DataOverHttps](INFO): SBD MOMSN=21490854
2024-08-12T17:20:56.065Z,1723483256.065 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:20:56.065Z,1723483256.065 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:20:56.065Z,1723483256.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:21:11.399Z,1723483271.399 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:25:56.650Z,1723483556.650 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:25:56.650Z,1723483556.650 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:25:56.650Z,1723483556.650 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:25:56.651Z,1723483556.651 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:25:57.065Z,1723483557.065 [Default:CheckIn:D] Stopped
2024-08-12T17:25:57.065Z,1723483557.065 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:25:57.451Z,1723483557.451 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.738737 min
2024-08-12T17:25:57.451Z,1723483557.451 [Default:CheckIn:E] Stopped
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn] Stopped
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn](INFO): Running loop #11
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn] Running Loop=11
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:25:57.452Z,1723483557.452 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:25:59.464Z,1723483559.464 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172558.00,A,3648.14993,N,12147.22067,W,0.019,0.00,120824,,,A*7A
2024-08-12T17:25:59.467Z,1723483559.467 [NAL9602](INFO): GPS fix at 20240812T172558: (36.802499, -121.787011)
2024-08-12T17:25:59.478Z,1723483559.478 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:25:59.478Z,1723483559.478 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:26:06.713Z,1723483566.713 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0034.lzma
2024-08-12T17:26:07.715Z,1723483567.715 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0034.lzma.bak
2024-08-12T17:26:07.715Z,1723483567.715 [DataOverHttps](INFO): SBD MOMSN=21490914
2024-08-12T17:26:16.030Z,1723483576.030 [NAL9602](INFO): SBD MO Status=0, MOMSN=54793, MT Status=0, MTMSN=0
2024-08-12T17:26:16.030Z,1723483576.030 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:26:26.998Z,1723483586.998 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0035.lzma
2024-08-12T17:26:27.000Z,1723483588.000 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0035.lzma.bak
2024-08-12T17:26:27.000Z,1723483588.000 [DataOverHttps](INFO): SBD MOMSN=21490917
2024-08-12T17:26:29.064Z,1723483589.064 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:26:29.064Z,1723483589.064 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:26:29.064Z,1723483589.064 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:26:46.808Z,1723483606.808 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:31:29.615Z,1723483889.615 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:31:29.615Z,1723483889.615 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:31:29.615Z,1723483889.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:31:29.615Z,1723483889.615 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:31:30.019Z,1723483890.019 [Default:CheckIn:D] Stopped
2024-08-12T17:31:30.019Z,1723483890.019 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:31:30.428Z,1723483890.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.287976 min
2024-08-12T17:31:30.429Z,1723483890.429 [Default:CheckIn:E] Stopped
2024-08-12T17:31:30.429Z,1723483890.429 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:31:30.429Z,1723483890.429 [Default:CheckIn] Stopped
2024-08-12T17:31:30.429Z,1723483890.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:31:30.429Z,1723483890.429 [Default:CheckIn](INFO): Running loop #12
2024-08-12T17:31:30.429Z,1723483890.429 [Default:CheckIn] Running Loop=12
2024-08-12T17:31:30.430Z,1723483890.430 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:31:30.430Z,1723483890.430 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:31:32.441Z,1723483892.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173131.00,A,3648.15050,N,12147.22034,W,0.039,0.00,120824,,,A*73
2024-08-12T17:31:32.448Z,1723483892.448 [NAL9602](INFO): GPS fix at 20240812T173131: (36.802508, -121.787006)
2024-08-12T17:31:32.459Z,1723483892.459 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:31:32.459Z,1723483892.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:31:40.174Z,1723483900.174 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0037.lzma
2024-08-12T17:31:41.175Z,1723483901.175 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0037.lzma.bak
2024-08-12T17:31:41.176Z,1723483901.176 [DataOverHttps](INFO): SBD MOMSN=21490928
2024-08-12T17:31:54.256Z,1723483914.256 [NAL9602](INFO): SBD MO Status=0, MOMSN=54794, MT Status=0, MTMSN=0
2024-08-12T17:31:54.256Z,1723483914.256 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:31:57.389Z,1723483917.389 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240812T162700/Express0038.lzma
2024-08-12T17:31:58.391Z,1723483918.391 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0038.lzma.bak
2024-08-12T17:31:58.392Z,1723483918.392 [DataOverHttps](INFO): SBD MOMSN=21490933
2024-08-12T17:31:59.511Z,1723483919.511 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:31:59.511Z,1723483919.511 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:31:59.511Z,1723483919.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:32:24.959Z,1723483944.959 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:37:00.097Z,1723484220.097 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:37:00.097Z,1723484220.097 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:37:00.097Z,1723484220.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:37:00.097Z,1723484220.097 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:37:00.496Z,1723484220.496 [Default:CheckIn:D] Stopped
2024-08-12T17:37:00.496Z,1723484220.496 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.795931 min
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn:E] Stopped
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn] Stopped
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn](INFO): Running loop #13
2024-08-12T17:37:00.919Z,1723484220.919 [Default:CheckIn] Running Loop=13
2024-08-12T17:37:00.920Z,1723484220.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:37:00.920Z,1723484220.920 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:37:02.909Z,1723484222.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173702.00,A,3648.14996,N,12147.22100,W,0.078,0.00,120824,,,A*74
2024-08-12T17:37:02.911Z,1723484222.911 [NAL9602](INFO): GPS fix at 20240812T173702: (36.802499, -121.787017)
2024-08-12T17:37:02.921Z,1723484222.921 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:37:02.921Z,1723484222.921 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:37:03.726Z,1723484223.726 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:37:03.726Z,1723484223.726 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:37:03.727Z,1723484223.727 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:38:22.496Z,1723484302.496 [NAL9602](INFO): SBD MO Status=0, MOMSN=54795, MT Status=0, MTMSN=0
2024-08-12T17:38:22.496Z,1723484302.496 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:38:53.197Z,1723484333.197 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:42:04.299Z,1723484524.299 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:42:04.299Z,1723484524.299 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:42:04.299Z,1723484524.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:42:04.299Z,1723484524.299 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:42:04.703Z,1723484524.703 [Default:CheckIn:D] Stopped
2024-08-12T17:42:04.703Z,1723484524.703 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:42:05.110Z,1723484525.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.866048 min
2024-08-12T17:42:05.110Z,1723484525.110 [Default:CheckIn:E] Stopped
2024-08-12T17:42:05.110Z,1723484525.110 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:42:05.110Z,1723484525.110 [Default:CheckIn] Stopped
2024-08-12T17:42:05.110Z,1723484525.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:42:05.110Z,1723484525.110 [Default:CheckIn](INFO): Running loop #14
2024-08-12T17:42:05.111Z,1723484525.111 [Default:CheckIn] Running Loop=14
2024-08-12T17:42:05.111Z,1723484525.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:42:05.111Z,1723484525.111 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:42:07.122Z,1723484527.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174206.00,A,3648.14995,N,12147.22119,W,0.039,0.00,120824,,,A*7C
2024-08-12T17:42:07.124Z,1723484527.124 [NAL9602](INFO): GPS fix at 20240812T174206: (36.802499, -121.787020)
2024-08-12T17:42:07.134Z,1723484527.134 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:42:07.135Z,1723484527.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:42:14.929Z,1723484534.929 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0040.lzma
2024-08-12T17:42:15.932Z,1723484535.932 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0040.lzma.bak
2024-08-12T17:42:15.932Z,1723484535.932 [DataOverHttps](INFO): SBD MOMSN=21491007
2024-08-12T17:42:22.512Z,1723484542.512 [NAL9602](INFO): SBD MO Status=0, MOMSN=54796, MT Status=0, MTMSN=0
2024-08-12T17:42:22.512Z,1723484542.512 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:42:35.153Z,1723484555.153 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0043.lzma
2024-08-12T17:42:36.155Z,1723484556.155 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0043.lzma.bak
2024-08-12T17:42:36.156Z,1723484556.156 [DataOverHttps](INFO): SBD MOMSN=21491012
2024-08-12T17:42:53.287Z,1723484573.287 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:42:55.058Z,1723484575.058 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0041.lzma
2024-08-12T17:42:56.060Z,1723484576.060 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0041.lzma.bak
2024-08-12T17:42:56.060Z,1723484576.060 [DataOverHttps](INFO): SBD MOMSN=21491015
2024-08-12T17:43:12.374Z,1723484592.374 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0044.lzma
2024-08-12T17:43:13.376Z,1723484593.376 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0044.lzma.bak
2024-08-12T17:43:13.376Z,1723484593.376 [DataOverHttps](INFO): SBD MOMSN=21491019
2024-08-12T17:43:14.702Z,1723484594.702 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:43:14.702Z,1723484594.702 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:43:14.702Z,1723484594.702 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:48:15.112Z,1723484895.112 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:48:15.112Z,1723484895.112 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:48:15.112Z,1723484895.112 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:48:15.112Z,1723484895.112 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:48:15.516Z,1723484895.516 [Default:CheckIn:D] Stopped
2024-08-12T17:48:15.516Z,1723484895.516 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:48:15.928Z,1723484895.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.046257 min
2024-08-12T17:48:15.928Z,1723484895.928 [Default:CheckIn:E] Stopped
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn] Stopped
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn](INFO): Running loop #15
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn] Running Loop=15
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:48:15.929Z,1723484895.929 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:48:17.937Z,1723484897.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174817.00,A,3648.15055,N,12147.22073,W,0.350,0.00,120824,,,A*73
2024-08-12T17:48:17.954Z,1723484897.954 [NAL9602](INFO): GPS fix at 20240812T174817: (36.802509, -121.787012)
2024-08-12T17:48:17.973Z,1723484897.973 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:48:17.973Z,1723484897.973 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:48:28.662Z,1723484908.662 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0046.lzma
2024-08-12T17:48:29.668Z,1723484909.668 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0046.lzma.bak
2024-08-12T17:48:29.668Z,1723484909.668 [DataOverHttps](INFO): SBD MOMSN=21491078
2024-08-12T17:48:36.985Z,1723484916.985 [NAL9602](INFO): SBD MO Status=0, MOMSN=54797, MT Status=0, MTMSN=0
2024-08-12T17:48:36.985Z,1723484916.985 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:48:45.961Z,1723484925.961 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240812T162700/Express0047.lzma
2024-08-12T17:48:46.964Z,1723484926.964 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0047.lzma.bak
2024-08-12T17:48:46.964Z,1723484926.964 [DataOverHttps](INFO): SBD MOMSN=21491081
2024-08-12T17:48:48.309Z,1723484928.309 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:48:48.309Z,1723484928.309 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:48:48.309Z,1723484928.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:49:07.742Z,1723484947.742 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:53:49.051Z,1723485229.051 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:53:49.051Z,1723485229.051 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:53:49.051Z,1723485229.051 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:53:49.052Z,1723485229.052 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:53:49.456Z,1723485229.456 [Default:CheckIn:D] Stopped
2024-08-12T17:53:49.456Z,1723485229.456 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:53:49.862Z,1723485229.862 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.611922 min
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn:E] Stopped
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn] Stopped
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn](INFO): Running loop #16
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn] Running Loop=16
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:53:49.863Z,1723485229.863 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:53:51.876Z,1723485231.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175351.00,A,3648.40270,N,12147.11616,W,5.521,1.31,120824,,,A*7D
2024-08-12T17:53:51.878Z,1723485231.878 [NAL9602](INFO): GPS fix at 20240812T175351: (36.806712, -121.785269)
2024-08-12T17:53:51.903Z,1723485231.903 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:53:51.904Z,1723485231.904 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:53:59.069Z,1723485239.069 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0049.lzma
2024-08-12T17:54:00.071Z,1723485240.071 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0049.lzma.bak
2024-08-12T17:54:00.072Z,1723485240.072 [DataOverHttps](INFO): SBD MOMSN=21491092
2024-08-12T17:54:16.345Z,1723485256.345 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240812T162700/Express0050.lzma
2024-08-12T17:54:17.348Z,1723485257.348 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0050.lzma.bak
2024-08-12T17:54:17.348Z,1723485257.348 [DataOverHttps](INFO): SBD MOMSN=21491095
2024-08-12T17:54:18.566Z,1723485258.566 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:54:18.566Z,1723485258.566 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:54:18.566Z,1723485258.566 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:55:07.960Z,1723485307.960 [NAL9602](INFO): SBD MO Status=0, MOMSN=54798, MT Status=0, MTMSN=0
2024-08-12T17:55:07.960Z,1723485307.960 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:55:38.661Z,1723485338.661 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T17:59:18.912Z,1723485558.912 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T17:59:19.357Z,1723485559.357 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T17:59:19.357Z,1723485559.357 [Default:CheckIn:C.Wait] Stopped
2024-08-12T17:59:19.357Z,1723485559.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T17:59:19.357Z,1723485559.357 [Default:CheckIn:D] Running Loop=1
2024-08-12T17:59:19.733Z,1723485559.733 [Default:CheckIn:D] Stopped
2024-08-12T17:59:19.733Z,1723485559.733 [Default:CheckIn:E] Running Loop=1
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.116536 min
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn:E] Stopped
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn] Stopped
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn](INFO): Running loop #17
2024-08-12T17:59:20.157Z,1723485560.157 [Default:CheckIn] Running Loop=17
2024-08-12T17:59:20.158Z,1723485560.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T17:59:20.158Z,1723485560.158 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T17:59:22.157Z,1723485562.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175921.00,A,3648.17104,N,12147.99122,W,11.916,254.92,120824,,,A*44
2024-08-12T17:59:22.159Z,1723485562.159 [NAL9602](INFO): GPS fix at 20240812T175921: (36.802851, -121.799854)
2024-08-12T17:59:22.169Z,1723485562.169 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T17:59:22.169Z,1723485562.169 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T17:59:29.765Z,1723485569.765 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0052.lzma
2024-08-12T17:59:30.767Z,1723485570.767 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0052.lzma.bak
2024-08-12T17:59:30.767Z,1723485570.767 [DataOverHttps](INFO): SBD MOMSN=21491152
2024-08-12T17:59:31.032Z,1723485571.032 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T17:59:38.316Z,1723485578.316 [NAL9602](INFO): SBD MO Status=0, MOMSN=54799, MT Status=0, MTMSN=0
2024-08-12T17:59:38.316Z,1723485578.316 [NAL9602](INFO): No messages in MT queue
2024-08-12T17:59:43.158Z,1723485583.158 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T17:59:46.782Z,1723485586.782 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240812T162700/Express0053.lzma
2024-08-12T17:59:47.784Z,1723485587.784 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0053.lzma.bak
2024-08-12T17:59:47.784Z,1723485587.784 [DataOverHttps](INFO): SBD MOMSN=21491159
2024-08-12T17:59:48.852Z,1723485588.852 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T17:59:48.852Z,1723485588.852 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T17:59:48.852Z,1723485588.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T17:59:54.871Z,1723485594.871 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:00:06.584Z,1723485606.584 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:00:09.010Z,1723485609.010 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T18:00:19.108Z,1723485619.108 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:00:31.272Z,1723485631.272 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:00:42.602Z,1723485642.602 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:03:19.872Z,1723485799.872 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:03:33.204Z,1723485813.204 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:03:46.536Z,1723485826.536 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:03:59.876Z,1723485839.876 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:04:13.604Z,1723485853.604 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:04:27.746Z,1723485867.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:04:41.076Z,1723485881.076 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:04:49.258Z,1723485889.258 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T18:04:49.259Z,1723485889.259 [Default:CheckIn:C.Wait] Stopped
2024-08-12T18:04:49.259Z,1723485889.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T18:04:49.259Z,1723485889.259 [Default:CheckIn:D] Running Loop=1
2024-08-12T18:04:49.633Z,1723485889.633 [Default:CheckIn:D] Stopped
2024-08-12T18:04:49.633Z,1723485889.633 [Default:CheckIn:E] Running Loop=1
2024-08-12T18:04:50.055Z,1723485890.055 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.614868 min
2024-08-12T18:04:50.055Z,1723485890.055 [Default:CheckIn:E] Stopped
2024-08-12T18:04:50.055Z,1723485890.055 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T18:04:50.055Z,1723485890.055 [Default:CheckIn] Stopped
2024-08-12T18:04:50.055Z,1723485890.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T18:04:50.055Z,1723485890.055 [Default:CheckIn](INFO): Running loop #18
2024-08-12T18:04:50.056Z,1723485890.056 [Default:CheckIn] Running Loop=18
2024-08-12T18:04:50.056Z,1723485890.056 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T18:04:50.056Z,1723485890.056 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T18:04:52.068Z,1723485892.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180451.00,A,3647.84686,N,12149.50408,W,14.384,268.27,120824,,,A*4F
2024-08-12T18:04:52.076Z,1723485892.076 [NAL9602](INFO): GPS fix at 20240812T180451: (36.797448, -121.825068)
2024-08-12T18:04:52.094Z,1723485892.094 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T18:04:52.094Z,1723485892.094 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T18:04:52.837Z,1723485892.837 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0055.lzma
2024-08-12T18:04:53.559Z,1723485893.559 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0055.lzma.bak
2024-08-12T18:04:53.560Z,1723485893.560 [DataOverHttps](INFO): SBD MOMSN=21491163
2024-08-12T18:04:54.605Z,1723485894.605 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:05:07.944Z,1723485907.944 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:05:09.968Z,1723485909.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=54800, MT Status=0, MTMSN=0
2024-08-12T18:05:09.968Z,1723485909.968 [NAL9602](INFO): No messages in MT queue
2024-08-12T18:05:11.258Z,1723485911.258 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20240812T162700/Express0056.lzma
2024-08-12T18:05:12.260Z,1723485912.260 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0056.lzma.bak
2024-08-12T18:05:12.261Z,1723485912.261 [DataOverHttps](INFO): SBD MOMSN=21491169
2024-08-12T18:05:13.621Z,1723485913.621 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T18:05:13.621Z,1723485913.621 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T18:05:13.622Z,1723485913.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T18:05:21.276Z,1723485921.276 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:05:40.670Z,1723485940.670 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T18:06:14.010Z,1723485974.010 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-12T18:08:16.874Z,1723486096.874 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-12T18:08:47.998Z,1723486127.998 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-12T18:10:14.324Z,1723486214.324 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T18:10:14.324Z,1723486214.324 [Default:CheckIn:C.Wait] Stopped
2024-08-12T18:10:14.324Z,1723486214.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T18:10:14.324Z,1723486214.324 [Default:CheckIn:D] Running Loop=1
2024-08-12T18:10:14.724Z,1723486214.724 [Default:CheckIn:D] Stopped
2024-08-12T18:10:14.724Z,1723486214.724 [Default:CheckIn:E] Running Loop=1
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.033057 min
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn:E] Stopped
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn] Stopped
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn](INFO): Running loop #19
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn] Running Loop=19
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T18:10:15.136Z,1723486215.136 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T18:10:17.140Z,1723486217.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181016.00,A,3647.74810,N,12150.46049,W,0.700,72.98,120824,,,A*49
2024-08-12T18:10:17.142Z,1723486217.142 [NAL9602](INFO): GPS fix at 20240812T181016: (36.795802, -121.841008)
2024-08-12T18:10:17.167Z,1723486217.167 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T18:10:17.167Z,1723486217.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T18:10:28.863Z,1723486228.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=54801, MT Status=0, MTMSN=0
2024-08-12T18:10:28.863Z,1723486228.863 [NAL9602](INFO): No messages in MT queue
2024-08-12T18:10:50.701Z,1723486250.701 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0058.lzma
2024-08-12T18:10:51.704Z,1723486251.704 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0058.lzma.bak
2024-08-12T18:10:51.704Z,1723486251.704 [DataOverHttps](INFO): SBD MOMSN=21491239
2024-08-12T18:10:59.639Z,1723486259.639 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T18:11:22.670Z,1723486282.670 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240812T162700/Express0059.lzma
2024-08-12T18:11:23.671Z,1723486283.671 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0059.lzma.bak
2024-08-12T18:11:23.672Z,1723486283.672 [DataOverHttps](INFO): SBD MOMSN=21491244
2024-08-12T18:11:24.712Z,1723486284.712 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T18:11:24.712Z,1723486284.712 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T18:11:24.712Z,1723486284.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T18:12:49.934Z,1723486369.934 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:13:03.266Z,1723486383.266 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:13:16.248Z,1723486396.248 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:13:16.586Z,1723486396.586 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-08-12T18:13:29.924Z,1723486409.924 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:13:43.256Z,1723486423.256 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:13:56.998Z,1723486436.998 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:14:10.330Z,1723486450.330 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:14:23.656Z,1723486463.656 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:14:36.577Z,1723486476.577 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:14:50.376Z,1723486490.376 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:15:03.247Z,1723486503.247 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:16:25.281Z,1723486585.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T18:16:25.281Z,1723486585.281 [Default:CheckIn:C.Wait] Stopped
2024-08-12T18:16:25.281Z,1723486585.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T18:16:25.281Z,1723486585.281 [Default:CheckIn:D] Running Loop=1
2024-08-12T18:16:25.688Z,1723486585.688 [Default:CheckIn:D] Stopped
2024-08-12T18:16:25.688Z,1723486585.688 [Default:CheckIn:E] Running Loop=1
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.215788 min
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn:E] Stopped
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn] Stopped
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn](INFO): Running loop #20
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn] Running Loop=20
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T18:16:26.080Z,1723486586.080 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T18:16:28.172Z,1723486588.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181627.00,A,3647.79860,N,12150.40091,W,0.952,150.08,120824,,,D*70
2024-08-12T18:16:28.175Z,1723486588.175 [NAL9602](INFO): GPS fix at 20240812T181627: (36.796643, -121.840015)
2024-08-12T18:16:28.195Z,1723486588.195 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T18:16:28.195Z,1723486588.195 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T18:16:35.973Z,1723486595.973 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240812T162700/Courier0061.lzma
2024-08-12T18:16:36.976Z,1723486596.976 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0061.lzma.bak
2024-08-12T18:16:36.976Z,1723486596.976 [DataOverHttps](INFO): SBD MOMSN=21491250
2024-08-12T18:16:50.056Z,1723486610.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=54802, MT Status=2, MTMSN=0
2024-08-12T18:16:50.056Z,1723486610.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-12T18:16:56.150Z,1723486616.150 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240812T162700/Express0062.lzma
2024-08-12T18:16:57.152Z,1723486617.152 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0062.lzma.bak
2024-08-12T18:16:57.152Z,1723486617.152 [DataOverHttps](INFO): SBD MOMSN=21491264
2024-08-12T18:16:58.188Z,1723486618.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=54802, MT Status=0, MTMSN=0
2024-08-12T18:16:58.188Z,1723486618.188 [NAL9602](INFO): No messages in MT queue
2024-08-12T18:16:58.231Z,1723486618.231 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T18:16:58.231Z,1723486618.231 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T18:16:58.231Z,1723486618.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T18:17:28.874Z,1723486648.874 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T18:21:58.761Z,1723486918.761 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T18:21:58.761Z,1723486918.761 [Default:CheckIn:C.Wait] Stopped
2024-08-12T18:21:58.762Z,1723486918.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T18:21:58.762Z,1723486918.762 [Default:CheckIn:D] Running Loop=1
2024-08-12T18:21:59.179Z,1723486919.179 [Default:CheckIn:D] Stopped
2024-08-12T18:21:59.179Z,1723486919.179 [Default:CheckIn:E] Running Loop=1
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.773950 min
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn:E] Stopped
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn] Stopped
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn](INFO): Running loop #21
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn] Running Loop=21
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T18:21:59.567Z,1723486919.567 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T18:22:01.580Z,1723486921.580 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182200.00,A,3647.74851,N,12150.45399,W,0.525,77.72,120824,,,D*46
2024-08-12T18:22:01.583Z,1723486921.583 [NAL9602](INFO): GPS fix at 20240812T182200: (36.795808, -121.840900)
2024-08-12T18:22:01.593Z,1723486921.593 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T18:22:01.593Z,1723486921.593 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T18:22:09.965Z,1723486929.965 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0064.lzma
2024-08-12T18:22:10.968Z,1723486930.968 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0064.lzma.bak
2024-08-12T18:22:10.968Z,1723486930.968 [DataOverHttps](INFO): SBD MOMSN=21491315
2024-08-12T18:22:21.131Z,1723486941.131 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-12T18:22:25.872Z,1723486945.872 [NAL9602](INFO): SBD MO Status=0, MOMSN=54803, MT Status=0, MTMSN=0
2024-08-12T18:22:25.873Z,1723486945.873 [NAL9602](INFO): No messages in MT queue
2024-08-12T18:22:27.480Z,1723486947.480 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:22:43.236Z,1723486963.236 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:22:43.681Z,1723486963.681 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240812T162700/Express0065.lzma
2024-08-12T18:22:44.684Z,1723486964.684 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0065.lzma.bak
2024-08-12T18:22:44.684Z,1723486964.684 [DataOverHttps](INFO): SBD MOMSN=21491322
2024-08-12T18:22:46.101Z,1723486966.101 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T18:22:46.101Z,1723486966.101 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T18:22:46.101Z,1723486966.101 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T18:22:56.626Z,1723486976.626 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T18:22:58.263Z,1723486978.263 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:23:12.816Z,1723486992.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:23:28.542Z,1723487008.542 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:23:44.298Z,1723487024.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:24:00.053Z,1723487040.053 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:24:15.000Z,1723487055.000 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:24:30.834Z,1723487070.834 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:24:46.578Z,1723487086.578 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-08-12T18:27:20.106Z,1723487240.106 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-08-12T18:27:46.495Z,1723487266.495 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-12T18:27:46.495Z,1723487266.495 [Default:CheckIn:C.Wait] Stopped
2024-08-12T18:27:46.495Z,1723487266.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-12T18:27:46.495Z,1723487266.495 [Default:CheckIn:D] Running Loop=1
2024-08-12T18:27:46.912Z,1723487266.912 [Default:CheckIn:D] Stopped
2024-08-12T18:27:46.912Z,1723487266.912 [Default:CheckIn:E] Running Loop=1
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.569523 min
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn:E] Stopped
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn] Stopped
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn](INFO): Running loop #22
2024-08-12T18:27:47.317Z,1723487267.317 [Default:CheckIn] Running Loop=22
2024-08-12T18:27:47.318Z,1723487267.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-12T18:27:47.318Z,1723487267.318 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-12T18:27:49.312Z,1723487269.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182748.00,A,3647.81199,N,12150.66332,W,8.086,271.37,120824,,,D*79
2024-08-12T18:27:49.315Z,1723487269.315 [NAL9602](INFO): GPS fix at 20240812T182748: (36.796867, -121.844389)
2024-08-12T18:27:49.325Z,1723487269.325 [Default:CheckIn:Read_GPS] Stopped
2024-08-12T18:27:49.325Z,1723487269.325 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-12T18:27:51.250Z,1723487271.250 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-12T18:28:07.487Z,1723487287.487 [NAL9602](INFO): SBD MO Status=0, MOMSN=54804, MT Status=0, MTMSN=0
2024-08-12T18:28:07.487Z,1723487287.487 [NAL9602](INFO): No messages in MT queue
2024-08-12T18:28:22.374Z,1723487302.374 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-12T18:28:38.190Z,1723487318.190 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-12T18:28:53.506Z,1723487333.506 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-12T18:29:13.741Z,1723487353.741 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2024-08-12T18:29:13.743Z,1723487353.743 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-08-12T18:29:23.513Z,1723487363.513 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240812T162700/Courier0067.lzma
2024-08-12T18:29:24.516Z,1723487364.516 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Courier0067.lzma.bak
2024-08-12T18:29:24.516Z,1723487364.516 [DataOverHttps](INFO): SBD MOMSN=21491385
2024-08-12T18:29:42.010Z,1723487382.010 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20240812T162700/Express0068.lzma
2024-08-12T18:29:43.012Z,1723487383.012 [DataOverHttps](INFO): Moved sent file to Logs/20240812T162700/Express0068.lzma.bak
2024-08-12T18:29:43.012Z,1723487383.012 [DataOverHttps](INFO): SBD MOMSN=21491388
2024-08-12T18:29:44.085Z,1723487384.085 [Default:CheckIn:Read_Iridium] Stopped
2024-08-12T18:29:44.085Z,1723487384.085 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-12T18:29:44.085Z,1723487384.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-12T18:34:27.827Z,1723487667.827 [DataOverHttps](IMPORTANT): SBD MTMSN=20240812T183426
2024-08-12T18:34:35.150Z,1723487675.150 [DataOverHttps](INFO): Received command: restart logs