2024-10-16T19:18:59.972Z,1729106339.972 [Supervisor](DEBUG): Initializing supervisor.
2024-10-16T19:18:59.976Z,1729106339.976 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-10-16T19:18:59.977Z,1729106339.977 [SyncHandler](INFO): Protected caller Thread ID is 405
2024-10-16T19:18:59.977Z,1729106339.977 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-10-16T19:18:59.978Z,1729106339.978 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-10-16T19:18:59.978Z,1729106339.978 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 406
2024-10-16T19:18:59.982Z,1729106339.982 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-10-16T19:19:00.000Z,1729106340.000 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-10-16T19:19:00.001Z,1729106340.001 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-10-16T19:19:00.002Z,1729106340.002 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 407
2024-10-16T19:19:00.006Z,1729106340.006 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-10-16T19:19:00.007Z,1729106340.007 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-10-16T19:19:00.007Z,1729106340.007 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 408
2024-10-16T19:19:00.009Z,1729106340.009 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-10-16T19:19:00.010Z,1729106340.010 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-10-16T19:19:00.011Z,1729106340.011 [logger ThreadHandler](INFO): Protected caller Thread ID is 409
2024-10-16T19:19:00.014Z,1729106340.014 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-10-16T19:19:00.015Z,1729106340.015 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-10-16T19:19:00.018Z,1729106340.018 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-10-16T19:19:00.370Z,1729106340.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-10-16T19:19:00.371Z,1729106340.371 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-10-16T19:19:00.468Z,1729106340.468 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-10-16T19:19:01.055Z,1729106341.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-10-16T19:19:01.056Z,1729106341.056 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-10-16T19:19:01.679Z,1729106341.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-10-16T19:19:01.680Z,1729106341.680 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-10-16T19:19:01.791Z,1729106341.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-10-16T19:19:01.792Z,1729106341.792 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-10-16T19:19:02.242Z,1729106342.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-10-16T19:19:02.242Z,1729106342.242 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-10-16T19:19:02.480Z,1729106342.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-10-16T19:19:02.481Z,1729106342.481 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-10-16T19:19:03.058Z,1729106343.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-10-16T19:19:03.058Z,1729106343.058 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-10-16T19:19:03.212Z,1729106343.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-10-16T19:19:03.212Z,1729106343.212 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-10-16T19:19:03.306Z,1729106343.306 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-10-16T19:19:04.208Z,1729106344.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-10-16T19:19:04.208Z,1729106344.208 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-10-16T19:19:04.625Z,1729106344.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-10-16T19:19:04.626Z,1729106344.626 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-10-16T19:19:04.839Z,1729106344.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-10-16T19:19:04.840Z,1729106344.840 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-10-16T19:19:04.967Z,1729106344.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-10-16T19:19:04.968Z,1729106344.968 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-10-16T19:19:05.218Z,1729106345.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-10-16T19:19:05.219Z,1729106345.219 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-10-16T19:19:05.497Z,1729106345.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-10-16T19:19:05.499Z,1729106345.499 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-10-16T19:19:05.501Z,1729106345.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-10-16T19:19:05.607Z,1729106345.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-10-16T19:19:05.708Z,1729106345.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-10-16T19:19:05.826Z,1729106345.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-10-16T19:19:05.924Z,1729106345.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-10-16T19:19:06.041Z,1729106346.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-10-16T19:19:06.165Z,1729106346.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-10-16T19:19:06.818Z,1729106346.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-10-16T19:19:07.354Z,1729106347.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-10-16T19:19:07.525Z,1729106347.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-10-16T19:19:07.652Z,1729106347.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-10-16T19:19:07.933Z,1729106347.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-10-16T19:19:07.935Z,1729106347.935 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-10-16T19:19:07.935Z,1729106347.935 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-10-16T19:19:07.947Z,1729106347.947 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-10-16T19:19:08.002Z,1729106348.002 [VerticalControl](DEBUG): Construct VerticalControl.
2024-10-16T19:19:08.063Z,1729106348.063 [VerticalControl] Loaded
2024-10-16T19:19:08.064Z,1729106348.064 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-10-16T19:19:08.067Z,1729106348.067 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-10-16T19:19:08.115Z,1729106348.115 [HorizontalControl] Loaded
2024-10-16T19:19:08.116Z,1729106348.116 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-10-16T19:19:08.118Z,1729106348.118 [SpeedControl](DEBUG): Construct SpeedControl.
2024-10-16T19:19:08.121Z,1729106348.121 [SpeedControl] Loaded
2024-10-16T19:19:08.122Z,1729106348.122 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-10-16T19:19:08.124Z,1729106348.124 [LoopControl](DEBUG): Construct LoopControl.
2024-10-16T19:19:08.125Z,1729106348.125 [LoopControl] Loaded
2024-10-16T19:19:08.125Z,1729106348.125 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-10-16T19:19:08.125Z,1729106348.125 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-10-16T19:19:08.126Z,1729106348.126 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-10-16T19:19:08.137Z,1729106348.137 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-10-16T19:19:08.138Z,1729106348.138 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-10-16T19:19:08.309Z,1729106348.309 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-10-16T19:19:08.310Z,1729106348.310 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-10-16T19:19:08.471Z,1729106348.471 [BuoyancyServo] Loaded
2024-10-16T19:19:08.471Z,1729106348.471 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-10-16T19:19:08.493Z,1729106348.493 [ElevatorServo] Loaded
2024-10-16T19:19:08.494Z,1729106348.494 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-10-16T19:19:08.515Z,1729106348.515 [MassServo] Loaded
2024-10-16T19:19:08.515Z,1729106348.515 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-10-16T19:19:08.536Z,1729106348.536 [RudderServo] Loaded
2024-10-16T19:19:08.536Z,1729106348.536 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-10-16T19:19:08.552Z,1729106348.552 [ThrusterHE] Loaded
2024-10-16T19:19:08.552Z,1729106348.552 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-10-16T19:19:08.553Z,1729106348.553 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-10-16T19:19:08.553Z,1729106348.553 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-10-16T19:19:08.664Z,1729106348.664 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-10-16T19:19:08.664Z,1729106348.664 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-10-16T19:19:08.678Z,1729106348.678 [NavChart] Loaded
2024-10-16T19:19:08.678Z,1729106348.678 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-10-16T19:19:08.684Z,1729106348.684 [UniversalFixResidualReporter] Loaded
2024-10-16T19:19:08.684Z,1729106348.684 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-10-16T19:19:08.685Z,1729106348.685 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-10-16T19:19:08.685Z,1729106348.685 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-10-16T19:19:08.742Z,1729106348.742 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-10-16T19:19:08.742Z,1729106348.742 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-10-16T19:19:09.178Z,1729106349.178 [AHRS_M2] Loaded
2024-10-16T19:19:09.178Z,1729106349.178 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-10-16T19:19:10.007Z,1729106350.007 [BPC1] Loaded
2024-10-16T19:19:10.008Z,1729106350.008 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-10-16T19:19:10.084Z,1729106350.084 [DataOverHttps] Loaded
2024-10-16T19:19:10.084Z,1729106350.084 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-10-16T19:19:10.085Z,1729106350.085 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-10-16T19:19:10.086Z,1729106350.086 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 492
2024-10-16T19:19:10.109Z,1729106350.109 [Depth_Keller] Loaded
2024-10-16T19:19:10.109Z,1729106350.109 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-10-16T19:19:10.115Z,1729106350.115 [DropWeight] Loaded
2024-10-16T19:19:10.115Z,1729106350.115 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-10-16T19:19:10.178Z,1729106350.178 [NAL9602] Loaded
2024-10-16T19:19:10.179Z,1729106350.179 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-10-16T19:19:10.208Z,1729106350.208 [Onboard] Loaded
2024-10-16T19:19:10.209Z,1729106350.209 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-10-16T19:19:10.210Z,1729106350.210 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-10-16T19:19:10.210Z,1729106350.210 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 493
2024-10-16T19:19:10.217Z,1729106350.217 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2024-10-16T19:19:10.231Z,1729106350.231 [PowerOnly] Loaded
2024-10-16T19:19:10.231Z,1729106350.231 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-10-16T19:19:10.245Z,1729106350.245 [Power24vConverter] Loaded
2024-10-16T19:19:10.245Z,1729106350.245 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-10-16T19:19:10.260Z,1729106350.260 [Radio_Surface] Loaded
2024-10-16T19:19:10.260Z,1729106350.260 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-10-16T19:19:10.261Z,1729106350.261 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-10-16T19:19:10.262Z,1729106350.262 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 494
2024-10-16T19:19:10.263Z,1729106350.263 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-10-16T19:19:10.263Z,1729106350.263 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-10-16T19:19:10.320Z,1729106350.320 [DepthRateCalculator] Loaded
2024-10-16T19:19:10.321Z,1729106350.321 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-10-16T19:19:10.326Z,1729106350.326 [PitchRateCalculator] Loaded
2024-10-16T19:19:10.326Z,1729106350.326 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-10-16T19:19:10.335Z,1729106350.335 [SpeedCalculator] Loaded
2024-10-16T19:19:10.335Z,1729106350.335 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-10-16T19:19:10.339Z,1729106350.339 [YawRateCalculator] Loaded
2024-10-16T19:19:10.340Z,1729106350.340 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-10-16T19:19:10.359Z,1729106350.359 [ElevatorOffsetCalculator] Loaded
2024-10-16T19:19:10.359Z,1729106350.359 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-10-16T19:19:10.360Z,1729106350.360 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-10-16T19:19:10.360Z,1729106350.360 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-10-16T19:19:10.540Z,1729106350.540 [CTD_Seabird] Loaded
2024-10-16T19:19:10.540Z,1729106350.540 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-10-16T19:19:10.541Z,1729106350.541 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2024-10-16T19:19:10.542Z,1729106350.542 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 495
2024-10-16T19:19:10.564Z,1729106350.564 [PAR_Licor] Loaded
2024-10-16T19:19:10.564Z,1729106350.564 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-10-16T19:19:10.609Z,1729106350.609 [WetLabsBB2FL] Loaded
2024-10-16T19:19:10.610Z,1729106350.610 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-10-16T19:19:10.611Z,1729106350.611 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-10-16T19:19:10.611Z,1729106350.611 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 496
2024-10-16T19:19:10.612Z,1729106350.612 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-10-16T19:19:10.612Z,1729106350.612 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-10-16T19:19:10.643Z,1729106350.643 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-10-16T19:19:10.644Z,1729106350.644 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-10-16T19:19:10.980Z,1729106350.980 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-10-16T19:19:10.980Z,1729106350.980 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-10-16T19:19:11.121Z,1729106351.121 [SBIT](DEBUG): Construct Startup Built In Test.
2024-10-16T19:19:11.130Z,1729106351.130 [SBIT] Loaded
2024-10-16T19:19:11.131Z,1729106351.131 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-10-16T19:19:11.133Z,1729106351.133 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-10-16T19:19:11.147Z,1729106351.147 [IBIT] Loaded
2024-10-16T19:19:11.147Z,1729106351.147 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-10-16T19:19:11.153Z,1729106351.153 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-10-16T19:19:11.260Z,1729106351.260 [CBIT] Loaded
2024-10-16T19:19:11.260Z,1729106351.260 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-10-16T19:19:11.261Z,1729106351.261 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-10-16T19:19:11.261Z,1729106351.261 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-10-16T19:19:11.497Z,1729106351.497 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-10-16T19:19:11.503Z,1729106351.503 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-10-16T19:19:11.506Z,1729106351.506 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-10-16T19:19:11.517Z,1729106351.517 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-10-16T19:19:11.518Z,1729106351.518 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-10-16T19:19:11.519Z,1729106351.519 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 497
2024-10-16T19:19:11.523Z,1729106351.523 [Supervisor](INFO): Main Thread ID is 830
2024-10-16T19:19:11.523Z,1729106351.523 [Supervisor](DEBUG): Running supervisor.
2024-10-16T19:19:11.524Z,1729106351.524 [CommandExec ThreadHandler](INFO): Handler Thread ID is 498
2024-10-16T19:19:11.525Z,1729106351.525 [CommandExec](INFO): Initializing the command executive.
2024-10-16T19:19:11.526Z,1729106351.526 [CommandLine ThreadHandler](INFO): Handler Thread ID is 499
2024-10-16T19:19:11.529Z,1729106351.529 [controlThread ThreadHandler](INFO): Handler Thread ID is 500
2024-10-16T19:19:11.530Z,1729106351.530 [controlThread](DEBUG): Initializing ControlThread
2024-10-16T19:19:11.531Z,1729106351.531 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-10-16T19:19:11.532Z,1729106351.532 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-10-16T19:19:11.533Z,1729106351.533 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-10-16T19:19:11.533Z,1729106351.533 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-10-16T19:19:11.535Z,1729106351.535 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-10-16T19:19:11.536Z,1729106351.536 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-10-16T19:19:11.540Z,1729106351.540 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-10-16T19:19:11.541Z,1729106351.541 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-10-16T19:19:11.541Z,1729106351.541 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-10-16T19:19:11.541Z,1729106351.541 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-10-16T19:19:11.541Z,1729106351.541 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-10-16T19:19:11.547Z,1729106351.547 [SBIT](INFO): Initialize SBIT Component.
2024-10-16T19:19:11.547Z,1729106351.547 [SBIT](IMPORTANT): git: 2024-10-14
2024-10-16T19:19:11.547Z,1729106351.547 [SBIT](INFO): git hash: 9dce2eadf13f2d8198d8ed7416351995a1f4c5f3
2024-10-16T19:19:11.548Z,1729106351.548 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-10-16T19:19:11.549Z,1729106351.549 [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-10-16T19:19:11.550Z,1729106351.550 [SBIT](INFO): Beginning SBIT in 69.000000 seconds.
2024-10-16T19:19:11.550Z,1729106351.550 [IBIT](INFO): Initialize IBIT Component.
2024-10-16T19:19:11.551Z,1729106351.551 [CBIT](DEBUG): Initialize CBIT Component.
2024-10-16T19:19:11.552Z,1729106351.552 [logger ThreadHandler](INFO): Handler Thread ID is 501
2024-10-16T19:19:11.562Z,1729106351.562 [CBIT](DEBUG): Initialized mux pins.
2024-10-16T19:19:11.562Z,1729106351.562 [CBIT](DEBUG): Initializing the watchdog timer.
2024-10-16T19:19:11.571Z,1729106351.571 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 502
2024-10-16T19:19:11.572Z,1729106351.572 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-10-16T19:19:11.583Z,1729106351.583 [Onboard ThreadHandler](INFO): Handler Thread ID is 503
2024-10-16T19:19:11.587Z,1729106351.587 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-10-16T19:19:11.587Z,1729106351.587 [CBIT](DEBUG): Initializing heartbeat.
2024-10-16T19:19:11.603Z,1729106351.603 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 504
2024-10-16T19:19:11.619Z,1729106351.619 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 505
2024-10-16T19:19:11.619Z,1729106351.619 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-10-16T19:19:11.624Z,1729106351.624 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 507
2024-10-16T19:19:11.627Z,1729106351.627 [WetLabsBB2FL](INFO): Powering up
2024-10-16T19:19:11.629Z,1729106351.629 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 509
2024-10-16T19:19:11.638Z,1729106351.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-10-16T19:19:11.638Z,1729106351.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-10-16T19:19:11.638Z,1729106351.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-10-16T19:19:11.639Z,1729106351.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-10-16T19:19:11.639Z,1729106351.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-10-16T19:19:11.639Z,1729106351.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-10-16T19:19:11.639Z,1729106351.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-10-16T19:19:11.639Z,1729106351.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-10-16T19:19:11.658Z,1729106351.658 [CBIT](DEBUG): Deactivating GF circuits.
2024-10-16T19:19:11.659Z,1729106351.659 [CBIT](DEBUG): Deactivating emergency mode.
2024-10-16T19:19:11.698Z,1729106351.698 [CBIT](DEBUG): Backplane powered.
2024-10-16T19:19:11.699Z,1729106351.699 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-10-16T19:19:11.700Z,1729106351.700 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-10-16T19:19:11.700Z,1729106351.700 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-10-16T19:19:11.703Z,1729106351.703 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-10-16T19:19:11.753Z,1729106351.753 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-10-16T19:19:11.774Z,1729106351.774 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-16T19:19:11.802Z,1729106351.802 [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-10-16T19:19:11.818Z,1729106351.818 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-10-16T19:19:11.819Z,1729106351.819 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-10-16T19:19:11.822Z,1729106351.822 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-10-16T19:19:12.006Z,1729106352.006 [Radio_Surface](INFO): Powering up
2024-10-16T19:19:12.095Z,1729106352.095 [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-10-16T19:19:12.101Z,1729106352.101 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-10-16T19:19:12.102Z,1729106352.102 [Default:A.Wait](DEBUG): Construct Wait.
2024-10-16T19:19:12.140Z,1729106352.140 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-16T19:19:12.180Z,1729106352.180 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-10-16T19:19:12.199Z,1729106352.199 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-10-16T19:19:12.205Z,1729106352.205 [Default:E.Execute](DEBUG): Construct Execute.
2024-10-16T19:19:12.224Z,1729106352.224 [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-10-16T19:19:12.228Z,1729106352.228 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-10-16T19:19:12.251Z,1729106352.251 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-10-16T19:19:12.318Z,1729106352.318 [PowerOnly](INFO): Powering up loadControl
2024-10-16T19:19:12.335Z,1729106352.335 [Power24vConverter](INFO): Powering up.
2024-10-16T19:19:12.367Z,1729106352.367 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-10-16T19:19:12.375Z,1729106352.375 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-10-16T19:19:12.376Z,1729106352.376 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-10-16T19:19:12.383Z,1729106352.383 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-10-16T19:19:12.384Z,1729106352.384 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-16T19:19:12.391Z,1729106352.391 [MassServo](DEBUG): Initializing MassServo.
2024-10-16T19:19:12.391Z,1729106352.391 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-16T19:19:12.399Z,1729106352.399 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-16T19:19:12.399Z,1729106352.399 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-10-16T19:19:12.407Z,1729106352.407 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-10-16T19:19:14.271Z,1729106354.271 [WetLabsBB2FL](INFO): Powering down
2024-10-16T19:19:18.115Z,1729106358.115 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-10-16T19:19:18.662Z,1729106358.662 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-10-16T19:19:31.358Z,1729106371.358 [MassServo](ERROR): getPosition uart error serial timeout
2024-10-16T19:19:31.359Z,1729106371.359 [MassServo](FAULT): Invalid EZ Servo response:"\n"
2024-10-16T19:19:31.359Z,1729106371.359 [MassServo] Communications Fault, FailCount= 1
2024-10-16T19:19:31.359Z,1729106371.359 [MassServo](ERROR): Communications Fault
2024-10-16T19:19:31.393Z,1729106371.393 [CBIT](ERROR): Communications Fault in component: MassServo
2024-10-16T19:19:31.656Z,1729106371.656 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-10-16T19:19:31.656Z,1729106371.656 [MassServo](INFO): Powering down
2024-10-16T19:19:32.483Z,1729106372.483 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-16T19:19:32.599Z,1729106372.599 [MassServo](DEBUG): Initializing MassServo.
2024-10-16T19:19:32.604Z,1729106372.604 [CBIT](INFO): Clearing failed state for component MassServo
2024-10-16T19:19:32.604Z,1729106372.604 [MassServo] No Fault, FailCount= 1
2024-10-16T19:19:40.525Z,1729106380.525 [NAL9602](INFO): Powering up NAL9602
2024-10-16T19:19:51.428Z,1729106391.428 [NAL9602](INFO): NAL9602 initialized
2024-10-16T19:20:21.347Z,1729106421.347 [SBIT](IMPORTANT): Beginning Startup BIT
2024-10-16T19:20:21.351Z,1729106421.351 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T19:20:26.169Z,1729106426.169 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:20:32.254Z,1729106432.254 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T19:20:32.657Z,1729106432.657 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T19:20:39.903Z,1729106439.903 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:20:43.611Z,1729106443.611 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T19:20:43.995Z,1729106443.995 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T19:20:48.851Z,1729106448.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=57938, MT Status=2, MTMSN=0
2024-10-16T19:20:48.851Z,1729106448.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T19:20:54.884Z,1729106454.884 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001968
CHAN A1 (24V): 0.000509
CHAN A2 (12V): -0.002497
CHAN A3 (5V): -0.001569
CHAN B0 (3.3V): -0.000140
CHAN B1 (3.15aV): -0.000340
CHAN B2 (3.15bV): -0.000022
CHAN B3 (GND): -0.000245
OPEN: -0.000465
Full Scale: +/- 1 mA
2024-10-16T19:21:02.145Z,1729106462.145 [NAL9602](INFO): SBD MO Status=2, MOMSN=57938, MT Status=2, MTMSN=0
2024-10-16T19:21:02.145Z,1729106462.145 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T19:21:15.497Z,1729106475.497 [SBIT](IMPORTANT): SBIT PASSED
2024-10-16T19:21:15.498Z,1729106475.498 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2024-10-16T19:21:15.499Z,1729106475.499 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=190.711675 cubic_centimeter;
2024-10-16T19:21:15.500Z,1729106475.500 [SBIT](IMPORTANT): VerticalControl.massDefault=12.351280 millimeter;
2024-10-16T19:21:15.892Z,1729106475.892 [MissionManager](IMPORTANT): Started mission Startup
2024-10-16T19:21:15.893Z,1729106475.893 [Startup] Running Loop=1
2024-10-16T19:21:15.893Z,1729106475.893 [Startup](DEBUG): Aggregate::initialize Startup
2024-10-16T19:21:15.893Z,1729106475.893 [Startup:A.GoToSurface] Running Loop=1
2024-10-16T19:21:15.893Z,1729106475.893 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-16T19:21:15.893Z,1729106475.893 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-16T19:21:15.894Z,1729106475.894 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-16T19:21:15.895Z,1729106475.895 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-16T19:21:15.895Z,1729106475.895 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-16T19:21:15.895Z,1729106475.895 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-16T19:21:15.896Z,1729106475.896 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-16T19:21:15.897Z,1729106475.897 [Startup:StartupSatComms] Running Loop=1
2024-10-16T19:21:15.897Z,1729106475.897 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-10-16T19:21:15.897Z,1729106475.897 [Startup:StartupSatComms:A] Running Loop=1
2024-10-16T19:21:16.307Z,1729106476.307 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-10-16T19:22:11.632Z,1729106531.632 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-16T19:22:11.632Z,1729106531.632 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-16T19:22:11.711Z,1729106531.711 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-16T19:22:12.102Z,1729106532.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-16T19:22:12.102Z,1729106532.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-16T19:22:16.078Z,1729106536.078 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-16T19:21:15.9Z
2024-10-16T19:22:16.078Z,1729106536.078 [Startup:StartupSatComms:A] Stopped
2024-10-16T19:22:16.079Z,1729106536.079 [Startup:StartupSatComms:B] Running Loop=1
2024-10-16T19:22:16.501Z,1729106536.501 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-16T19:22:46.877Z,1729106566.877 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004918
2024-10-16T19:22:57.030Z,1729106577.030 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T220041/Courier0663.lzma
2024-10-16T19:22:58.033Z,1729106578.033 [DataOverHttps](INFO): Moved sent file to Logs/20241015T220041/Courier0663.lzma.bak
2024-10-16T19:22:58.033Z,1729106578.033 [DataOverHttps](INFO): SBD MOMSN=23309304
2024-10-16T19:23:14.078Z,1729106594.078 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241016T191859/Courier0000.lzma
2024-10-16T19:23:15.080Z,1729106595.080 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0000.lzma.bak
2024-10-16T19:23:15.080Z,1729106595.080 [DataOverHttps](INFO): SBD MOMSN=23309306
2024-10-16T19:23:16.336Z,1729106596.336 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-16T19:22:16.1Z
2024-10-16T19:23:16.337Z,1729106596.337 [Startup:StartupSatComms:B] Stopped
2024-10-16T19:23:16.337Z,1729106596.337 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-10-16T19:23:16.337Z,1729106596.337 [Startup:StartupSatComms] Stopped
2024-10-16T19:23:16.337Z,1729106596.337 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-10-16T19:23:16.338Z,1729106596.338 [Startup](INFO): Completed Startup
2024-10-16T19:23:16.338Z,1729106596.338 [MissionManager](INFO): Startup is completed.
2024-10-16T19:23:16.338Z,1729106596.338 [MissionManager](INFO): Uninitializing Mission Startup
2024-10-16T19:23:16.338Z,1729106596.338 [Startup] Stopped
2024-10-16T19:23:16.339Z,1729106596.339 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-10-16T19:23:16.339Z,1729106596.339 [Startup:A.GoToSurface] Stopped
2024-10-16T19:23:16.339Z,1729106596.339 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-16T19:23:16.748Z,1729106596.748 [MissionManager](IMPORTANT): Started mission Default
2024-10-16T19:23:16.749Z,1729106596.749 [Default] Running Loop=1
2024-10-16T19:23:16.749Z,1729106596.749 [Default](DEBUG): Aggregate::initialize Default
2024-10-16T19:23:16.749Z,1729106596.749 [Default:B.GoToSurface] Running Loop=1
2024-10-16T19:23:16.749Z,1729106596.749 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-16T19:23:16.749Z,1729106596.749 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-16T19:23:16.749Z,1729106596.749 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-16T19:23:16.750Z,1729106596.750 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-16T19:23:16.750Z,1729106596.750 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-16T19:23:16.751Z,1729106596.751 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-16T19:23:16.751Z,1729106596.751 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-16T19:23:16.751Z,1729106596.751 [Default:A.Wait] Running Loop=1
2024-10-16T19:23:16.751Z,1729106596.751 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:23:30.065Z,1729106610.065 [Default:A.Wait](INFO): Done Waiting.
2024-10-16T19:23:30.065Z,1729106610.065 [Default:A.Wait] Stopped
2024-10-16T19:23:30.065Z,1729106610.065 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T19:23:30.480Z,1729106610.480 [Default:CheckIn] Running Loop=1
2024-10-16T19:23:30.480Z,1729106610.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T19:23:30.480Z,1729106610.480 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T19:23:30.876Z,1729106610.876 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-10-16T19:23:53.430Z,1729106633.430 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20241015T220041/Express0664.lzma
2024-10-16T19:23:54.433Z,1729106634.433 [DataOverHttps](INFO): Moved sent file to Logs/20241015T220041/Express0664.lzma.bak
2024-10-16T19:23:54.433Z,1729106634.433 [DataOverHttps](INFO): SBD MOMSN=23309309
2024-10-16T19:24:55.437Z,1729106695.437 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-16T19:25:11.492Z,1729106711.492 [CommandExec](IMPORTANT): got command burn on
2024-10-16T19:25:11.492Z,1729106711.492 [CommandExec](IMPORTANT): Activating dropweight wire
2024-10-16T19:25:12.807Z,1729106712.807 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-16T19:25:12.807Z,1729106712.807 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-16T19:25:12.819Z,1729106712.819 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-16T19:25:13.224Z,1729106713.224 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-16T19:25:13.225Z,1729106713.225 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-16T19:25:18.571Z,1729106718.571 [CommandExec](IMPORTANT): got command burn off
2024-10-16T19:25:18.571Z,1729106718.571 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-10-16T19:28:09.029Z,1729106889.029 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:28:13.928Z,1729106893.928 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-10-16T19:28:13.928Z,1729106893.928 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-16T19:28:13.939Z,1729106893.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-16T19:28:14.321Z,1729106894.321 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-16T19:28:14.321Z,1729106894.321 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-10-16T19:28:22.360Z,1729106902.360 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:28:30.509Z,1729106910.509 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-16T19:23:30.5Z
2024-10-16T19:28:30.509Z,1729106910.509 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T19:28:30.509Z,1729106910.509 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T19:28:30.919Z,1729106910.919 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-16T19:28:35.736Z,1729106915.736 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:28:37.518Z,1729106917.518 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241016T191859/Courier0004.lzma
2024-10-16T19:28:38.520Z,1729106918.520 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0004.lzma.bak
2024-10-16T19:28:38.520Z,1729106918.520 [DataOverHttps](INFO): SBD MOMSN=23309312
2024-10-16T19:28:49.068Z,1729106929.068 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:28:56.652Z,1729106936.652 [DataOverHttps](INFO): Sending 951 bytes from file Logs/20241016T191859/Express0001.lzma
2024-10-16T19:28:57.652Z,1729106937.652 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0001.lzma.bak
2024-10-16T19:28:57.652Z,1729106937.652 [DataOverHttps](INFO): SBD MOMSN=23309314
2024-10-16T19:29:02.055Z,1729106942.055 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:29:13.147Z,1729106953.147 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20241016T191859/Express0005.lzma
2024-10-16T19:29:14.148Z,1729106954.148 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0005.lzma.bak
2024-10-16T19:29:14.148Z,1729106954.148 [DataOverHttps](INFO): SBD MOMSN=23309342
2024-10-16T19:29:15.387Z,1729106955.387 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:29:15.405Z,1729106955.405 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T19:29:15.405Z,1729106955.405 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T19:29:15.405Z,1729106955.405 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:29:29.528Z,1729106969.528 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:29:42.863Z,1729106982.863 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:29:52.564Z,1729106992.564 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-16T19:29:52.564Z,1729106992.564 [NAL9602] Data Fault, FailCount= 1
2024-10-16T19:29:52.564Z,1729106992.564 [NAL9602](ERROR): Data Fault
2024-10-16T19:29:52.627Z,1729106992.627 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-16T19:29:52.981Z,1729106992.981 [NAL9602](INFO): Powering down
2024-10-16T19:29:53.805Z,1729106993.805 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-16T19:29:53.805Z,1729106993.805 [NAL9602] No Fault, FailCount= 1
2024-10-16T19:29:56.191Z,1729106996.191 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:30:09.119Z,1729107009.119 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:30:23.348Z,1729107023.348 [NAL9602](INFO): Powering up NAL9602
2024-10-16T19:30:34.269Z,1729107034.269 [NAL9602](INFO): NAL9602 initialized
2024-10-16T19:31:15.092Z,1729107075.092 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-10-16T19:31:15.092Z,1729107075.092 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-16T19:31:15.119Z,1729107075.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-16T19:31:15.509Z,1729107075.509 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-16T19:31:15.509Z,1729107075.509 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-10-16T19:34:16.193Z,1729107256.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-10-16T19:34:16.193Z,1729107256.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-16T19:34:16.195Z,1729107256.195 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T19:34:16.195Z,1729107256.195 [Default:CheckIn:C.Wait] Stopped
2024-10-16T19:34:16.195Z,1729107256.195 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T19:34:16.195Z,1729107256.195 [Default:CheckIn:D] Running Loop=1
2024-10-16T19:34:16.204Z,1729107256.204 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-16T19:34:16.583Z,1729107256.583 [Default:CheckIn:D] Stopped
2024-10-16T19:34:16.583Z,1729107256.583 [Default:CheckIn:E] Running Loop=1
2024-10-16T19:34:16.608Z,1729107256.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-16T19:34:16.608Z,1729107256.608 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-10-16T19:34:16.977Z,1729107256.977 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.997235 min
2024-10-16T19:34:16.977Z,1729107256.977 [Default:CheckIn:E] Stopped
2024-10-16T19:34:16.977Z,1729107256.977 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T19:34:16.977Z,1729107256.977 [Default:CheckIn] Stopped
2024-10-16T19:34:16.978Z,1729107256.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T19:34:16.978Z,1729107256.978 [Default:CheckIn](INFO): Running loop #2
2024-10-16T19:34:16.978Z,1729107256.978 [Default:CheckIn] Running Loop=2
2024-10-16T19:34:16.978Z,1729107256.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T19:34:16.978Z,1729107256.978 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T19:36:17.151Z,1729107377.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193615.00,A,3648.16784,N,12147.28074,W,0.175,159.24,161024,,,A*70
2024-10-16T19:36:17.159Z,1729107377.159 [NAL9602](INFO): GPS fix at 20241016T193615: (36.802797, -121.788012)
2024-10-16T19:36:17.238Z,1729107377.238 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T19:36:17.239Z,1729107377.239 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T19:36:32.628Z,1729107392.628 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20241016T191859/Courier0007.lzma
2024-10-16T19:36:33.624Z,1729107393.624 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0007.lzma.bak
2024-10-16T19:36:33.624Z,1729107393.624 [DataOverHttps](INFO): SBD MOMSN=23309364
2024-10-16T19:36:49.639Z,1729107409.639 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T19:36:52.515Z,1729107412.515 [DataOverHttps](INFO): Sending 345 bytes from file Logs/20241016T191859/Express0008.lzma
2024-10-16T19:36:53.517Z,1729107413.517 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0008.lzma.bak
2024-10-16T19:36:53.517Z,1729107413.517 [DataOverHttps](INFO): SBD MOMSN=23309367
2024-10-16T19:36:54.914Z,1729107414.914 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T19:36:54.914Z,1729107414.914 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T19:36:54.914Z,1729107414.914 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:37:26.067Z,1729107446.067 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-16T19:37:32.471Z,1729107452.471 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:37:48.225Z,1729107468.225 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:38:03.981Z,1729107483.981 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:38:19.363Z,1729107499.363 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:38:34.298Z,1729107514.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:38:50.054Z,1729107530.054 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:39:05.405Z,1729107545.405 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:39:19.955Z,1729107559.955 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:39:34.898Z,1729107574.898 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:39:50.653Z,1729107590.653 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:41:55.503Z,1729107715.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T19:41:55.503Z,1729107715.503 [Default:CheckIn:C.Wait] Stopped
2024-10-16T19:41:55.503Z,1729107715.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T19:41:55.503Z,1729107715.503 [Default:CheckIn:D] Running Loop=1
2024-10-16T19:41:55.919Z,1729107715.919 [Default:CheckIn:D] Stopped
2024-10-16T19:41:55.919Z,1729107715.919 [Default:CheckIn:E] Running Loop=1
2024-10-16T19:41:56.314Z,1729107716.314 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.652838 min
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn:E] Stopped
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn] Stopped
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn](INFO): Running loop #3
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn] Running Loop=3
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T19:41:56.315Z,1729107716.315 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T19:41:58.381Z,1729107718.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194156.00,A,3648.15860,N,12147.25506,W,2.391,359.21,161024,,,A*71
2024-10-16T19:41:58.383Z,1729107718.383 [NAL9602](INFO): GPS fix at 20241016T194156: (36.802643, -121.787584)
2024-10-16T19:41:58.394Z,1729107718.394 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T19:41:58.394Z,1729107718.394 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T19:42:06.198Z,1729107726.198 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241016T191859/Courier0010.lzma
2024-10-16T19:42:07.200Z,1729107727.200 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0010.lzma.bak
2024-10-16T19:42:07.200Z,1729107727.200 [DataOverHttps](INFO): SBD MOMSN=23309391
2024-10-16T19:42:12.889Z,1729107732.889 [NAL9602](INFO): SBD MO Status=0, MOMSN=57938, MT Status=0, MTMSN=0
2024-10-16T19:42:12.889Z,1729107732.889 [NAL9602](INFO): No messages in MT queue
2024-10-16T19:42:25.975Z,1729107745.975 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20241016T191859/Express0011.lzma
2024-10-16T19:42:26.976Z,1729107746.976 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0011.lzma.bak
2024-10-16T19:42:26.977Z,1729107746.977 [DataOverHttps](INFO): SBD MOMSN=23309394
2024-10-16T19:42:28.314Z,1729107748.314 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T19:42:28.314Z,1729107748.314 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T19:42:28.314Z,1729107748.314 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:42:43.651Z,1729107763.651 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T19:44:39.997Z,1729107879.997 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-16T19:47:00.181Z,1729108020.181 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-16T19:47:05.837Z,1729108025.837 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:47:18.809Z,1729108038.809 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:47:28.898Z,1729108048.898 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T19:47:28.898Z,1729108048.898 [Default:CheckIn:C.Wait] Stopped
2024-10-16T19:47:28.898Z,1729108048.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T19:47:28.898Z,1729108048.898 [Default:CheckIn:D] Running Loop=1
2024-10-16T19:47:29.307Z,1729108049.307 [Default:CheckIn:D] Stopped
2024-10-16T19:47:29.307Z,1729108049.307 [Default:CheckIn:E] Running Loop=1
2024-10-16T19:47:29.691Z,1729108049.691 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.209296 min
2024-10-16T19:47:29.691Z,1729108049.691 [Default:CheckIn:E] Stopped
2024-10-16T19:47:29.691Z,1729108049.691 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T19:47:29.691Z,1729108049.691 [Default:CheckIn] Stopped
2024-10-16T19:47:29.691Z,1729108049.691 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T19:47:29.692Z,1729108049.692 [Default:CheckIn](INFO): Running loop #4
2024-10-16T19:47:29.692Z,1729108049.692 [Default:CheckIn] Running Loop=4
2024-10-16T19:47:29.692Z,1729108049.692 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T19:47:29.692Z,1729108049.692 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T19:47:31.729Z,1729108051.729 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194729.00,A,3648.13828,N,12147.20896,W,0.000,54.30,161024,,,D*46
2024-10-16T19:47:31.731Z,1729108051.731 [NAL9602](INFO): GPS fix at 20241016T194729: (36.802305, -121.786816)
2024-10-16T19:47:31.741Z,1729108051.741 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T19:47:31.741Z,1729108051.741 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T19:47:32.098Z,1729108052.098 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:47:39.498Z,1729108059.498 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0013.lzma
2024-10-16T19:47:40.500Z,1729108060.500 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0013.lzma.bak
2024-10-16T19:47:40.500Z,1729108060.500 [DataOverHttps](INFO): SBD MOMSN=23309398
2024-10-16T19:47:45.069Z,1729108065.069 [NAL9602](INFO): SBD MO Status=0, MOMSN=57939, MT Status=0, MTMSN=0
2024-10-16T19:47:45.069Z,1729108065.069 [NAL9602](INFO): No messages in MT queue
2024-10-16T19:47:45.431Z,1729108065.431 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:47:56.746Z,1729108076.746 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20241016T191859/Express0014.lzma
2024-10-16T19:47:57.748Z,1729108077.748 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0014.lzma.bak
2024-10-16T19:47:57.748Z,1729108077.748 [DataOverHttps](INFO): SBD MOMSN=23309401
2024-10-16T19:47:58.785Z,1729108078.785 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:47:59.197Z,1729108079.197 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T19:47:59.197Z,1729108079.197 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T19:47:59.197Z,1729108079.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:48:12.096Z,1729108092.096 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:48:15.735Z,1729108095.735 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T19:48:25.428Z,1729108105.428 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:48:38.757Z,1729108118.757 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:48:52.493Z,1729108132.493 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:49:06.647Z,1729108146.647 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:49:20.371Z,1729108160.371 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T19:50:12.487Z,1729108212.487 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4.
2024-10-16T19:50:12.494Z,1729108212.494 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-10-16T19:51:40.392Z,1729108300.392 [DataOverHttps](IMPORTANT): SBD MTMSN=20241016T195139
2024-10-16T19:51:47.727Z,1729108307.727 [DataOverHttps](INFO): Received command: ibit
2024-10-16T19:51:47.791Z,1729108307.791 [CommandExec](IMPORTANT): got command ibit
2024-10-16T19:51:47.875Z,1729108307.875 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-10-16T19:51:47.875Z,1729108307.875 [IBIT](IMPORTANT): Beginning control surface checks.
2024-10-16T19:51:47.878Z,1729108307.878 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T19:51:48.728Z,1729108308.728 [DataOverHttps](IMPORTANT): SBD MTMSN=20241016T195147
2024-10-16T19:51:49.457Z,1729108309.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195148.00,A,3648.13786,N,12147.21011,W,0.058,54.30,161024,,,D*46
2024-10-16T19:51:49.460Z,1729108309.460 [NAL9602](INFO): GPS fix at 20241016T195148: (36.802298, -121.786835)
2024-10-16T19:51:56.330Z,1729108316.330 [DataOverHttps](INFO): Received command: failc
2024-10-16T19:51:56.347Z,1729108316.347 [CommandExec](IMPORTANT): got command failComponent
2024-10-16T19:51:56.347Z,1729108316.347 [CommandExec](IMPORTANT): Failed components:
2024-10-16T19:51:56.347Z,1729108316.347 [CommandExec](IMPORTANT): No failed Components.
2024-10-16T19:51:58.784Z,1729108318.784 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T19:51:59.195Z,1729108319.195 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T19:52:10.135Z,1729108330.135 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T19:52:10.599Z,1729108330.599 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T19:52:21.862Z,1729108341.862 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002388
CHAN A1 (24V): 0.000542
CHAN A2 (12V): -0.002462
CHAN A3 (5V): -0.001557
CHAN B0 (3.3V): -0.000083
CHAN B1 (3.15aV): -0.000781
CHAN B2 (3.15bV): -0.000128
CHAN B3 (GND): 0.000400
OPEN: -0.000800
Full Scale: +/- 1 mA
2024-10-16T19:52:23.825Z,1729108343.825 [NAL9602](INFO): SBD MO Status=2, MOMSN=57940, MT Status=2, MTMSN=0
2024-10-16T19:52:23.825Z,1729108343.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T19:52:34.024Z,1729108354.024 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802299 Longitude: -121.786835
2024-10-16T19:52:34.420Z,1729108354.420 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.294001
2024-10-16T19:52:34.420Z,1729108354.420 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-10-16T19:52:34.420Z,1729108354.420 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-10-16T19:52:34.824Z,1729108354.824 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-10-16T19:52:34.824Z,1729108354.824 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-10-16T19:52:34.825Z,1729108354.825 [IBIT](IMPORTANT): Pressure:9.303590 PSI
2024-10-16T19:52:34.825Z,1729108354.825 [IBIT](IMPORTANT): Humidity:15.242340 %
2024-10-16T19:52:35.244Z,1729108355.244 [IBIT](IMPORTANT): Vehicle Pitch:-0.667162 degrees
2024-10-16T19:52:35.244Z,1729108355.244 [IBIT](IMPORTANT): Vehicle Roll:4.944108 degrees
2024-10-16T19:52:35.244Z,1729108355.244 [IBIT](IMPORTANT): Vehicle Heading:28.242828 degrees
2024-10-16T19:52:35.628Z,1729108355.628 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-10-16T19:52:35.628Z,1729108355.628 [IBIT](IMPORTANT): buoyancyNeutral: 190.711670 cc
2024-10-16T19:52:35.628Z,1729108355.628 [IBIT](IMPORTANT): massDefault: 1.235128 cm
2024-10-16T19:52:35.629Z,1729108355.629 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-10-16T19:52:35.629Z,1729108355.629 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-10-16T19:52:35.629Z,1729108355.629 [IBIT](IMPORTANT): IBIT PASSED
2024-10-16T19:52:41.619Z,1729108361.619 [NAL9602](INFO): SBD MO Status=0, MOMSN=57940, MT Status=0, MTMSN=0
2024-10-16T19:52:41.619Z,1729108361.619 [NAL9602](INFO): No messages in MT queue
2024-10-16T19:52:59.830Z,1729108379.830 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T19:52:59.830Z,1729108379.830 [Default:CheckIn:C.Wait] Stopped
2024-10-16T19:52:59.830Z,1729108379.830 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T19:52:59.830Z,1729108379.830 [Default:CheckIn:D] Running Loop=1
2024-10-16T19:53:00.243Z,1729108380.243 [Default:CheckIn:D] Stopped
2024-10-16T19:53:00.243Z,1729108380.243 [Default:CheckIn:E] Running Loop=1
2024-10-16T19:53:00.627Z,1729108380.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.724900 min
2024-10-16T19:53:00.627Z,1729108380.627 [Default:CheckIn:E] Stopped
2024-10-16T19:53:00.627Z,1729108380.627 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T19:53:00.627Z,1729108380.627 [Default:CheckIn] Stopped
2024-10-16T19:53:00.628Z,1729108380.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T19:53:00.628Z,1729108380.628 [Default:CheckIn](INFO): Running loop #5
2024-10-16T19:53:00.628Z,1729108380.628 [Default:CheckIn] Running Loop=5
2024-10-16T19:53:00.628Z,1729108380.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T19:53:00.628Z,1729108380.628 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T19:53:02.634Z,1729108382.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195301.00,A,3648.13795,N,12147.21053,W,0.039,54.30,161024,,,D*4A
2024-10-16T19:53:02.636Z,1729108382.636 [NAL9602](INFO): GPS fix at 20241016T195301: (36.802299, -121.786842)
2024-10-16T19:53:02.647Z,1729108382.647 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T19:53:02.647Z,1729108382.647 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T19:53:10.046Z,1729108390.046 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20241016T191859/Courier0016.lzma
2024-10-16T19:53:11.048Z,1729108391.048 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0016.lzma.bak
2024-10-16T19:53:11.048Z,1729108391.048 [DataOverHttps](INFO): SBD MOMSN=23309431
2024-10-16T19:53:27.158Z,1729108407.158 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241016T191859/Courier0019.lzma
2024-10-16T19:53:28.160Z,1729108408.160 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0019.lzma.bak
2024-10-16T19:53:28.160Z,1729108408.160 [DataOverHttps](INFO): SBD MOMSN=23309434
2024-10-16T19:53:34.547Z,1729108414.547 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T19:53:44.416Z,1729108424.416 [DataOverHttps](INFO): Sending 870 bytes from file Logs/20241016T191859/Express0017.lzma
2024-10-16T19:53:45.416Z,1729108425.416 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0017.lzma.bak
2024-10-16T19:53:45.416Z,1729108425.416 [DataOverHttps](INFO): SBD MOMSN=23309436
2024-10-16T19:53:46.694Z,1729108426.694 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T19:53:46.694Z,1729108426.694 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T19:53:46.694Z,1729108426.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:58:47.123Z,1729108727.123 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T19:58:47.123Z,1729108727.123 [Default:CheckIn:C.Wait] Stopped
2024-10-16T19:58:47.123Z,1729108727.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T19:58:47.123Z,1729108727.123 [Default:CheckIn:D] Running Loop=1
2024-10-16T19:58:47.534Z,1729108727.534 [Default:CheckIn:D] Stopped
2024-10-16T19:58:47.542Z,1729108727.542 [Default:CheckIn:E] Running Loop=1
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.513086 min
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn:E] Stopped
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn] Stopped
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn](INFO): Running loop #6
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn] Running Loop=6
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T19:58:47.937Z,1729108727.937 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T19:58:49.929Z,1729108729.929 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195849.00,A,3648.13836,N,12147.21052,W,0.019,54.30,161024,,,D*48
2024-10-16T19:58:49.931Z,1729108729.931 [NAL9602](INFO): GPS fix at 20241016T195849: (36.802306, -121.786842)
2024-10-16T19:58:50.005Z,1729108730.005 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T19:58:50.005Z,1729108730.005 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T19:59:00.610Z,1729108740.610 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0022.lzma
2024-10-16T19:59:01.612Z,1729108741.612 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0022.lzma.bak
2024-10-16T19:59:01.612Z,1729108741.612 [DataOverHttps](INFO): SBD MOMSN=23309468
2024-10-16T19:59:08.657Z,1729108748.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=57941, MT Status=2, MTMSN=0
2024-10-16T19:59:08.657Z,1729108748.657 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T19:59:18.062Z,1729108758.062 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20241016T191859/Express0023.lzma
2024-10-16T19:59:19.064Z,1729108759.064 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0023.lzma.bak
2024-10-16T19:59:19.064Z,1729108759.064 [DataOverHttps](INFO): SBD MOMSN=23309471
2024-10-16T19:59:20.391Z,1729108760.391 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T19:59:20.391Z,1729108760.391 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T19:59:20.391Z,1729108760.391 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T19:59:38.148Z,1729108778.148 [NAL9602](INFO): SBD MO Status=2, MOMSN=57941, MT Status=2, MTMSN=0
2024-10-16T19:59:38.148Z,1729108778.148 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T20:00:08.453Z,1729108808.453 [NAL9602](INFO): SBD MO Status=2, MOMSN=57941, MT Status=2, MTMSN=0
2024-10-16T20:00:08.453Z,1729108808.453 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T20:00:31.501Z,1729108831.501 [NAL9602](INFO): SBD MO Status=2, MOMSN=57941, MT Status=2, MTMSN=0
2024-10-16T20:00:31.501Z,1729108831.501 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T20:01:28.121Z,1729108888.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=57941, MT Status=2, MTMSN=0
2024-10-16T20:01:28.121Z,1729108888.121 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T20:01:44.691Z,1729108904.691 [NAL9602](INFO): SBD MO Status=0, MOMSN=57941, MT Status=0, MTMSN=0
2024-10-16T20:01:44.692Z,1729108904.692 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:02:15.382Z,1729108935.382 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:04:21.069Z,1729109061.069 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:04:21.069Z,1729109061.069 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:04:21.069Z,1729109061.069 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:04:21.069Z,1729109061.069 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:04:21.450Z,1729109061.450 [Default:CheckIn:D] Stopped
2024-10-16T20:04:21.450Z,1729109061.450 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:04:21.854Z,1729109061.854 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.078353 min
2024-10-16T20:04:21.854Z,1729109061.854 [Default:CheckIn:E] Stopped
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn] Stopped
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn](INFO): Running loop #7
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn] Running Loop=7
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:04:21.855Z,1729109061.855 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:04:23.861Z,1729109063.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200423.00,A,3648.13813,N,12147.20724,W,0.058,320.83,161024,,,D*7A
2024-10-16T20:04:23.864Z,1729109063.864 [NAL9602](INFO): GPS fix at 20241016T200423: (36.802302, -121.786787)
2024-10-16T20:04:23.875Z,1729109063.875 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:04:23.875Z,1729109063.875 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:04:32.430Z,1729109072.430 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241016T191859/Courier0025.lzma
2024-10-16T20:04:33.432Z,1729109073.432 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0025.lzma.bak
2024-10-16T20:04:33.432Z,1729109073.432 [DataOverHttps](INFO): SBD MOMSN=23309495
2024-10-16T20:04:39.209Z,1729109079.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=57942, MT Status=0, MTMSN=0
2024-10-16T20:04:39.209Z,1729109079.209 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:04:50.642Z,1729109090.642 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241016T191859/Express0026.lzma
2024-10-16T20:04:51.644Z,1729109091.644 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0026.lzma.bak
2024-10-16T20:04:51.644Z,1729109091.644 [DataOverHttps](INFO): SBD MOMSN=23309498
2024-10-16T20:04:52.961Z,1729109092.961 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:04:52.962Z,1729109092.962 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:04:52.962Z,1729109092.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:05:09.946Z,1729109109.946 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:09:53.574Z,1729109393.574 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:09:53.574Z,1729109393.574 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:09:53.574Z,1729109393.574 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:09:53.575Z,1729109393.575 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:09:53.990Z,1729109393.990 [Default:CheckIn:D] Stopped
2024-10-16T20:09:53.990Z,1729109393.990 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:09:54.386Z,1729109394.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.620683 min
2024-10-16T20:09:54.386Z,1729109394.386 [Default:CheckIn:E] Stopped
2024-10-16T20:09:54.386Z,1729109394.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:09:54.387Z,1729109394.387 [Default:CheckIn] Stopped
2024-10-16T20:09:54.387Z,1729109394.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:09:54.387Z,1729109394.387 [Default:CheckIn](INFO): Running loop #8
2024-10-16T20:09:54.387Z,1729109394.387 [Default:CheckIn] Running Loop=8
2024-10-16T20:09:54.387Z,1729109394.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:09:54.387Z,1729109394.387 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:09:56.399Z,1729109396.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200955.00,A,3648.13805,N,12147.20708,W,0.097,320.83,161024,,,D*7C
2024-10-16T20:09:56.401Z,1729109396.401 [NAL9602](INFO): GPS fix at 20241016T200955: (36.802301, -121.786785)
2024-10-16T20:09:56.412Z,1729109396.412 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:09:56.412Z,1729109396.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:10:03.590Z,1729109403.590 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0028.lzma
2024-10-16T20:10:04.592Z,1729109404.592 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0028.lzma.bak
2024-10-16T20:10:04.592Z,1729109404.592 [DataOverHttps](INFO): SBD MOMSN=23309502
2024-10-16T20:10:23.126Z,1729109423.126 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20241016T191859/Express0029.lzma
2024-10-16T20:10:24.128Z,1729109424.128 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0029.lzma.bak
2024-10-16T20:10:24.129Z,1729109424.129 [DataOverHttps](INFO): SBD MOMSN=23309505
2024-10-16T20:10:24.720Z,1729109424.720 [NAL9602](INFO): SBD MO Status=2, MOMSN=57943, MT Status=2, MTMSN=0
2024-10-16T20:10:24.721Z,1729109424.721 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-16T20:10:25.547Z,1729109425.547 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:10:25.547Z,1729109425.547 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:10:25.547Z,1729109425.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:10:42.916Z,1729109442.916 [NAL9602](INFO): SBD MO Status=0, MOMSN=57943, MT Status=0, MTMSN=0
2024-10-16T20:10:42.916Z,1729109442.916 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:11:13.617Z,1729109473.617 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:15:26.117Z,1729109726.117 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:15:26.117Z,1729109726.117 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:15:26.117Z,1729109726.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:15:26.117Z,1729109726.117 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:15:26.537Z,1729109726.537 [Default:CheckIn:D] Stopped
2024-10-16T20:15:26.537Z,1729109726.537 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:15:26.926Z,1729109726.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.163135 min
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn:E] Stopped
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn] Stopped
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn](INFO): Running loop #9
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn] Running Loop=9
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:15:26.927Z,1729109726.927 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:15:28.943Z,1729109728.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201528.00,A,3648.13771,N,12147.20647,W,0.039,320.83,161024,,,D*79
2024-10-16T20:15:28.945Z,1729109728.945 [NAL9602](INFO): GPS fix at 20241016T201528: (36.802295, -121.786775)
2024-10-16T20:15:28.963Z,1729109728.963 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:15:28.963Z,1729109728.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:15:36.090Z,1729109736.090 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241016T191859/Courier0031.lzma
2024-10-16T20:15:37.092Z,1729109737.092 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0031.lzma.bak
2024-10-16T20:15:37.092Z,1729109737.092 [DataOverHttps](INFO): SBD MOMSN=23309529
2024-10-16T20:15:50.766Z,1729109750.766 [NAL9602](INFO): SBD MO Status=0, MOMSN=57944, MT Status=0, MTMSN=0
2024-10-16T20:15:50.767Z,1729109750.767 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:15:53.246Z,1729109753.246 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20241016T191859/Express0032.lzma
2024-10-16T20:15:54.248Z,1729109754.248 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0032.lzma.bak
2024-10-16T20:15:54.248Z,1729109754.248 [DataOverHttps](INFO): SBD MOMSN=23309532
2024-10-16T20:15:55.623Z,1729109755.623 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:15:55.623Z,1729109755.623 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:15:55.623Z,1729109755.623 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:16:21.459Z,1729109781.459 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:20:56.217Z,1729110056.217 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:20:56.217Z,1729110056.217 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:20:56.217Z,1729110056.217 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:20:56.217Z,1729110056.217 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:20:56.599Z,1729110056.599 [Default:CheckIn:D] Stopped
2024-10-16T20:20:56.599Z,1729110056.599 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.664168 min
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn:E] Stopped
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn] Stopped
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn](INFO): Running loop #10
2024-10-16T20:20:56.998Z,1729110056.998 [Default:CheckIn] Running Loop=10
2024-10-16T20:20:56.999Z,1729110056.999 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:20:56.999Z,1729110056.999 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:20:59.013Z,1729110059.013 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202058.00,A,3648.13782,N,12147.20612,W,0.019,320.83,161024,,,D*76
2024-10-16T20:20:59.015Z,1729110059.015 [NAL9602](INFO): GPS fix at 20241016T202058: (36.802297, -121.786769)
2024-10-16T20:20:59.054Z,1729110059.054 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:20:59.055Z,1729110059.055 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:21:06.942Z,1729110066.942 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0034.lzma
2024-10-16T20:21:07.956Z,1729110067.956 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0034.lzma.bak
2024-10-16T20:21:07.956Z,1729110067.956 [DataOverHttps](INFO): SBD MOMSN=23309537
2024-10-16T20:21:13.153Z,1729110073.153 [NAL9602](INFO): SBD MO Status=0, MOMSN=57945, MT Status=0, MTMSN=0
2024-10-16T20:21:13.153Z,1729110073.153 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:21:23.718Z,1729110083.718 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241016T191859/Express0035.lzma
2024-10-16T20:21:24.720Z,1729110084.720 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0035.lzma.bak
2024-10-16T20:21:24.721Z,1729110084.721 [DataOverHttps](INFO): SBD MOMSN=23309540
2024-10-16T20:21:26.095Z,1729110086.095 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:21:26.095Z,1729110086.095 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:21:26.095Z,1729110086.095 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:21:43.855Z,1729110103.855 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:26:26.691Z,1729110386.691 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:26:26.691Z,1729110386.691 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:26:26.691Z,1729110386.691 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:26:26.691Z,1729110386.691 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:26:27.074Z,1729110387.074 [Default:CheckIn:D] Stopped
2024-10-16T20:26:27.074Z,1729110387.074 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:26:27.478Z,1729110387.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.172083 min
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn:E] Stopped
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn] Stopped
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn](INFO): Running loop #11
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn] Running Loop=11
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:26:27.479Z,1729110387.479 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:26:29.485Z,1729110389.485 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202628.00,A,3648.13819,N,12147.20822,W,0.019,320.83,161024,,,D*77
2024-10-16T20:26:29.488Z,1729110389.488 [NAL9602](INFO): GPS fix at 20241016T202628: (36.802303, -121.786804)
2024-10-16T20:26:29.531Z,1729110389.531 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:26:29.532Z,1729110389.532 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:26:35.197Z,1729110395.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=57946, MT Status=0, MTMSN=0
2024-10-16T20:26:35.197Z,1729110395.197 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:26:36.450Z,1729110396.450 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241016T191859/Courier0037.lzma
2024-10-16T20:26:37.453Z,1729110397.453 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0037.lzma.bak
2024-10-16T20:26:37.453Z,1729110397.453 [DataOverHttps](INFO): SBD MOMSN=23309566
2024-10-16T20:26:55.966Z,1729110415.966 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20241016T191859/Express0038.lzma
2024-10-16T20:26:56.968Z,1729110416.968 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0038.lzma.bak
2024-10-16T20:26:56.969Z,1729110416.969 [DataOverHttps](INFO): SBD MOMSN=23309570
2024-10-16T20:26:58.295Z,1729110418.295 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:26:58.295Z,1729110418.295 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:26:58.295Z,1729110418.295 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:27:05.943Z,1729110425.943 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:31:58.858Z,1729110718.858 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:31:58.858Z,1729110718.858 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:31:58.858Z,1729110718.858 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:31:58.858Z,1729110718.858 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:31:59.276Z,1729110719.276 [Default:CheckIn:D] Stopped
2024-10-16T20:31:59.276Z,1729110719.276 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.708789 min
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn:E] Stopped
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn] Stopped
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn](INFO): Running loop #12
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn] Running Loop=12
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:31:59.665Z,1729110719.665 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:32:01.677Z,1729110721.677 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203200.00,A,3648.14038,N,12147.20334,W,1.225,47.28,161024,,,D*47
2024-10-16T20:32:01.679Z,1729110721.679 [NAL9602](INFO): GPS fix at 20241016T203200: (36.802340, -121.786722)
2024-10-16T20:32:01.690Z,1729110721.690 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:32:01.690Z,1729110721.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:32:09.702Z,1729110729.702 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241016T191859/Courier0040.lzma
2024-10-16T20:32:10.704Z,1729110730.704 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0040.lzma.bak
2024-10-16T20:32:10.704Z,1729110730.704 [DataOverHttps](INFO): SBD MOMSN=23309576
2024-10-16T20:32:21.068Z,1729110741.068 [NAL9602](INFO): SBD MO Status=0, MOMSN=57947, MT Status=0, MTMSN=0
2024-10-16T20:32:21.068Z,1729110741.068 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:32:29.418Z,1729110749.418 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20241016T191859/Express0041.lzma
2024-10-16T20:32:30.421Z,1729110750.421 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0041.lzma.bak
2024-10-16T20:32:30.422Z,1729110750.422 [DataOverHttps](INFO): SBD MOMSN=23309583
2024-10-16T20:32:31.647Z,1729110751.647 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:32:31.647Z,1729110751.647 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:32:31.647Z,1729110751.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:32:51.833Z,1729110771.833 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:37:32.246Z,1729111052.246 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:37:32.246Z,1729111052.246 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:37:32.246Z,1729111052.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:37:32.246Z,1729111052.246 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:37:32.638Z,1729111052.638 [Default:CheckIn:D] Stopped
2024-10-16T20:37:32.638Z,1729111052.638 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:37:33.038Z,1729111053.038 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.264819 min
2024-10-16T20:37:33.038Z,1729111053.038 [Default:CheckIn:E] Stopped
2024-10-16T20:37:33.038Z,1729111053.038 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:37:33.038Z,1729111053.038 [Default:CheckIn] Stopped
2024-10-16T20:37:33.038Z,1729111053.038 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:37:33.038Z,1729111053.038 [Default:CheckIn](INFO): Running loop #13
2024-10-16T20:37:33.039Z,1729111053.039 [Default:CheckIn] Running Loop=13
2024-10-16T20:37:33.039Z,1729111053.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:37:33.039Z,1729111053.039 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:37:35.053Z,1729111055.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203734.00,A,3648.15176,N,12147.21455,W,0.175,281.94,161024,,,D*76
2024-10-16T20:37:35.065Z,1729111055.065 [NAL9602](INFO): GPS fix at 20241016T203734: (36.802529, -121.786909)
2024-10-16T20:37:35.076Z,1729111055.076 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:37:35.076Z,1729111055.076 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:37:42.062Z,1729111062.062 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0043.lzma
2024-10-16T20:37:43.064Z,1729111063.064 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0043.lzma.bak
2024-10-16T20:37:43.064Z,1729111063.064 [DataOverHttps](INFO): SBD MOMSN=23309599
2024-10-16T20:37:49.593Z,1729111069.593 [NAL9602](INFO): SBD MO Status=0, MOMSN=57948, MT Status=0, MTMSN=0
2024-10-16T20:37:49.593Z,1729111069.593 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:37:59.146Z,1729111079.146 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241016T191859/Express0044.lzma
2024-10-16T20:38:00.148Z,1729111080.148 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0044.lzma.bak
2024-10-16T20:38:00.149Z,1729111080.149 [DataOverHttps](INFO): SBD MOMSN=23309602
2024-10-16T20:38:01.323Z,1729111081.323 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:38:01.323Z,1729111081.323 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:38:01.323Z,1729111081.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:38:20.296Z,1729111100.296 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:43:01.909Z,1729111381.909 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:43:01.909Z,1729111381.909 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:43:01.909Z,1729111381.909 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:43:01.909Z,1729111381.909 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:43:02.315Z,1729111382.315 [Default:CheckIn:D] Stopped
2024-10-16T20:43:02.315Z,1729111382.315 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.759432 min
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn:E] Stopped
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn] Stopped
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn](INFO): Running loop #14
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn] Running Loop=14
2024-10-16T20:43:02.723Z,1729111382.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:43:02.724Z,1729111382.724 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:43:04.723Z,1729111384.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204303.00,A,3648.38948,N,12147.11946,W,5.229,3.06,161024,,,D*7B
2024-10-16T20:43:04.726Z,1729111384.726 [NAL9602](INFO): GPS fix at 20241016T204303: (36.806491, -121.785324)
2024-10-16T20:43:04.736Z,1729111384.736 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:43:04.737Z,1729111384.737 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:43:12.462Z,1729111392.462 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0046.lzma
2024-10-16T20:43:13.464Z,1729111393.464 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0046.lzma.bak
2024-10-16T20:43:13.464Z,1729111393.464 [DataOverHttps](INFO): SBD MOMSN=23309615
2024-10-16T20:43:29.318Z,1729111409.318 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20241016T191859/Express0047.lzma
2024-10-16T20:43:30.430Z,1729111410.430 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0047.lzma.bak
2024-10-16T20:43:30.430Z,1729111410.430 [DataOverHttps](INFO): SBD MOMSN=23309622
2024-10-16T20:43:31.803Z,1729111411.803 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:43:31.803Z,1729111411.803 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:43:31.803Z,1729111411.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:43:34.213Z,1729111414.213 [NAL9602](INFO): SBD MO Status=0, MOMSN=57949, MT Status=0, MTMSN=0
2024-10-16T20:43:34.213Z,1729111414.213 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:44:04.915Z,1729111444.915 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:45:40.740Z,1729111540.740 [DataOverHttps](IMPORTANT): SBD MTMSN=20241016T204539
2024-10-16T20:45:48.058Z,1729111548.058 [DataOverHttps](INFO): Received command: ibit
2024-10-16T20:45:48.079Z,1729111548.079 [CommandExec](IMPORTANT): got command ibit
2024-10-16T20:45:48.384Z,1729111548.384 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-10-16T20:45:48.384Z,1729111548.384 [IBIT](IMPORTANT): Beginning control surface checks.
2024-10-16T20:45:48.392Z,1729111548.392 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T20:45:49.961Z,1729111549.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204549.00,A,3648.42978,N,12147.35317,W,10.108,235.17,161024,,,D*46
2024-10-16T20:45:49.963Z,1729111549.963 [NAL9602](INFO): GPS fix at 20241016T204549: (36.807163, -121.789220)
2024-10-16T20:45:59.269Z,1729111559.269 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T20:45:59.687Z,1729111559.687 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T20:46:06.116Z,1729111566.116 [NAL9602](INFO): SBD MO Status=0, MOMSN=57950, MT Status=0, MTMSN=0
2024-10-16T20:46:06.116Z,1729111566.116 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:46:07.333Z,1729111567.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204606.00,A,3648.40134,N,12147.40187,W,10.166,233.68,161024,,,D*43
2024-10-16T20:46:07.336Z,1729111567.336 [NAL9602](INFO): GPS fix at 20241016T204606: (36.806689, -121.790031)
2024-10-16T20:46:10.161Z,1729111570.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204609.00,A,3648.39648,N,12147.41024,W,9.797,234.47,161024,,,D*7D
2024-10-16T20:46:10.164Z,1729111570.164 [NAL9602](INFO): GPS fix at 20241016T204609: (36.806608, -121.790171)
2024-10-16T20:46:10.659Z,1729111570.659 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T20:46:11.100Z,1729111571.100 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-16T20:46:12.989Z,1729111572.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204612.00,A,3648.39160,N,12147.41882,W,9.816,234.41,161024,,,D*7E
2024-10-16T20:46:12.991Z,1729111572.991 [NAL9602](INFO): GPS fix at 20241016T204612: (36.806527, -121.790314)
2024-10-16T20:46:15.813Z,1729111575.813 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204615.00,A,3648.38680,N,12147.42701,W,9.583,232.87,161024,,,D*7B
2024-10-16T20:46:15.824Z,1729111575.824 [NAL9602](INFO): GPS fix at 20241016T204615: (36.806447, -121.790450)
2024-10-16T20:46:19.065Z,1729111579.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204618.00,A,3648.38165,N,12147.43499,W,9.855,229.53,161024,,,D*7C
2024-10-16T20:46:19.068Z,1729111579.068 [NAL9602](INFO): GPS fix at 20241016T204618: (36.806361, -121.790583)
2024-10-16T20:46:21.899Z,1729111581.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204621.00,A,3648.37637,N,12147.44229,W,9.136,227.03,161024,,,D*74
2024-10-16T20:46:21.901Z,1729111581.901 [NAL9602](INFO): GPS fix at 20241016T204621: (36.806273, -121.790705)
2024-10-16T20:46:21.983Z,1729111581.983 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-16T20:46:21.983Z,1729111581.983 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-10-16T20:46:25.120Z,1729111585.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204624.00,A,3648.37153,N,12147.44983,W,9.117,231.65,161024,,,D*7B
2024-10-16T20:46:25.123Z,1729111585.123 [NAL9602](INFO): GPS fix at 20241016T204624: (36.806192, -121.790830)
2024-10-16T20:46:27.949Z,1729111587.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204627.00,A,3648.36678,N,12147.45750,W,9.214,234.63,161024,,,D*75
2024-10-16T20:46:27.951Z,1729111587.951 [NAL9602](INFO): GPS fix at 20241016T204627: (36.806113, -121.790958)
2024-10-16T20:46:31.181Z,1729111591.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204630.00,A,3648.36209,N,12147.46498,W,9.214,232.81,161024,,,D*7F
2024-10-16T20:46:31.184Z,1729111591.184 [NAL9602](INFO): GPS fix at 20241016T204630: (36.806035, -121.791083)
2024-10-16T20:46:34.008Z,1729111594.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204633.00,A,3648.35747,N,12147.47277,W,9.078,234.33,161024,,,D*71
2024-10-16T20:46:34.010Z,1729111594.010 [NAL9602](INFO): GPS fix at 20241016T204633: (36.805958, -121.791213)
2024-10-16T20:46:34.460Z,1729111594.460 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.805958 Longitude: -121.791214
2024-10-16T20:46:34.832Z,1729111594.832 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.294001
2024-10-16T20:46:34.832Z,1729111594.832 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-10-16T20:46:34.832Z,1729111594.832 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-10-16T20:46:35.245Z,1729111595.245 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-10-16T20:46:35.245Z,1729111595.245 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-10-16T20:46:35.245Z,1729111595.245 [IBIT](IMPORTANT): Pressure:9.375366 PSI
2024-10-16T20:46:35.246Z,1729111595.246 [IBIT](IMPORTANT): Humidity:14.399952 %
2024-10-16T20:46:35.665Z,1729111595.665 [IBIT](IMPORTANT): Vehicle Pitch:-0.306157 degrees
2024-10-16T20:46:35.665Z,1729111595.665 [IBIT](IMPORTANT): Vehicle Roll:3.996312 degrees
2024-10-16T20:46:35.665Z,1729111595.665 [IBIT](IMPORTANT): Vehicle Heading:237.547256 degrees
2024-10-16T20:46:36.048Z,1729111596.048 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-10-16T20:46:36.048Z,1729111596.048 [IBIT](IMPORTANT): buoyancyNeutral: 190.711670 cc
2024-10-16T20:46:36.049Z,1729111596.049 [IBIT](IMPORTANT): massDefault: 1.235128 cm
2024-10-16T20:46:36.049Z,1729111596.049 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-10-16T20:46:36.049Z,1729111596.049 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-10-16T20:46:36.049Z,1729111596.049 [IBIT](IMPORTANT): IBIT PASSED
2024-10-16T20:47:06.351Z,1729111626.351 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:48:32.437Z,1729111712.437 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:48:32.437Z,1729111712.437 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:48:32.437Z,1729111712.437 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:48:32.438Z,1729111712.438 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:48:32.850Z,1729111712.850 [Default:CheckIn:D] Stopped
2024-10-16T20:48:32.850Z,1729111712.850 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:48:33.246Z,1729111713.246 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.268351 min
2024-10-16T20:48:33.246Z,1729111713.246 [Default:CheckIn:E] Stopped
2024-10-16T20:48:33.246Z,1729111713.246 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:48:33.246Z,1729111713.246 [Default:CheckIn] Stopped
2024-10-16T20:48:33.246Z,1729111713.246 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:48:33.247Z,1729111713.247 [Default:CheckIn](INFO): Running loop #15
2024-10-16T20:48:33.247Z,1729111713.247 [Default:CheckIn] Running Loop=15
2024-10-16T20:48:33.247Z,1729111713.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:48:33.247Z,1729111713.247 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:48:35.254Z,1729111715.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204834.00,A,3648.27535,N,12147.79449,W,8.844,296.15,161024,,,D*70
2024-10-16T20:48:35.256Z,1729111715.256 [NAL9602](INFO): GPS fix at 20241016T204834: (36.804589, -121.796575)
2024-10-16T20:48:35.267Z,1729111715.267 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:48:35.267Z,1729111715.267 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:48:43.359Z,1729111723.359 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0049.lzma
2024-10-16T20:48:44.360Z,1729111724.360 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0049.lzma.bak
2024-10-16T20:48:44.360Z,1729111724.360 [DataOverHttps](INFO): SBD MOMSN=23309639
2024-10-16T20:49:03.573Z,1729111743.573 [NAL9602](INFO): SBD MO Status=0, MOMSN=57951, MT Status=0, MTMSN=0
2024-10-16T20:49:03.573Z,1729111743.573 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:49:04.322Z,1729111744.322 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241016T191859/Courier0052.lzma
2024-10-16T20:49:05.494Z,1729111745.494 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0052.lzma.bak
2024-10-16T20:49:05.494Z,1729111745.494 [DataOverHttps](INFO): SBD MOMSN=23309642
2024-10-16T20:49:21.636Z,1729111761.636 [DataOverHttps](INFO): Sending 817 bytes from file Logs/20241016T191859/Express0050.lzma
2024-10-16T20:49:22.636Z,1729111762.636 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0050.lzma.bak
2024-10-16T20:49:22.637Z,1729111762.637 [DataOverHttps](INFO): SBD MOMSN=23309644
2024-10-16T20:49:34.287Z,1729111774.287 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:49:38.642Z,1729111778.642 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20241016T191859/Express0053.lzma
2024-10-16T20:49:39.644Z,1729111779.644 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0053.lzma.bak
2024-10-16T20:49:39.644Z,1729111779.644 [DataOverHttps](INFO): SBD MOMSN=23309673
2024-10-16T20:49:41.168Z,1729111781.168 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:49:41.168Z,1729111781.168 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:49:41.168Z,1729111781.168 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:54:41.777Z,1729112081.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T20:54:41.777Z,1729112081.777 [Default:CheckIn:C.Wait] Stopped
2024-10-16T20:54:41.777Z,1729112081.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T20:54:41.778Z,1729112081.778 [Default:CheckIn:D] Running Loop=1
2024-10-16T20:54:42.181Z,1729112082.181 [Default:CheckIn:D] Stopped
2024-10-16T20:54:42.181Z,1729112082.181 [Default:CheckIn:E] Running Loop=1
2024-10-16T20:54:42.586Z,1729112082.586 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.423869 min
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn:E] Stopped
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn] Stopped
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn](INFO): Running loop #16
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn] Running Loop=16
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T20:54:42.587Z,1729112082.587 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T20:54:44.663Z,1729112084.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205443.00,A,3648.08237,N,12149.61897,W,14.190,255.15,161024,,,D*4F
2024-10-16T20:54:44.665Z,1729112084.665 [NAL9602](INFO): GPS fix at 20241016T205443: (36.801373, -121.826983)
2024-10-16T20:54:44.676Z,1729112084.676 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T20:54:44.676Z,1729112084.676 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T20:54:55.435Z,1729112095.435 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0055.lzma
2024-10-16T20:54:56.365Z,1729112096.365 [NAL9602](INFO): SBD MO Status=0, MOMSN=57952, MT Status=0, MTMSN=0
2024-10-16T20:54:56.365Z,1729112096.365 [NAL9602](INFO): No messages in MT queue
2024-10-16T20:54:57.440Z,1729112097.440 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0055.lzma.bak
2024-10-16T20:54:57.441Z,1729112097.441 [DataOverHttps](INFO): SBD MOMSN=23309694
2024-10-16T20:55:12.522Z,1729112112.522 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:55:17.579Z,1729112117.579 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20241016T191859/Express0056.lzma
2024-10-16T20:55:18.580Z,1729112118.580 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0056.lzma.bak
2024-10-16T20:55:18.580Z,1729112118.580 [DataOverHttps](INFO): SBD MOMSN=23309698
2024-10-16T20:55:20.273Z,1729112120.273 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T20:55:20.273Z,1729112120.273 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T20:55:20.273Z,1729112120.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T20:55:26.259Z,1729112126.259 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:55:27.092Z,1729112127.092 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T20:55:39.589Z,1729112139.589 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:55:52.922Z,1729112152.922 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:56:07.061Z,1729112167.061 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:56:20.394Z,1729112180.394 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:56:34.536Z,1729112194.536 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:56:48.673Z,1729112208.673 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:57:02.417Z,1729112222.417 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:57:16.153Z,1729112236.153 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:57:29.485Z,1729112249.485 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T20:58:22.839Z,1729112302.839 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-16T20:58:53.963Z,1729112333.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-16T21:00:20.813Z,1729112420.813 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T21:00:20.813Z,1729112420.813 [Default:CheckIn:C.Wait] Stopped
2024-10-16T21:00:20.814Z,1729112420.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T21:00:20.814Z,1729112420.814 [Default:CheckIn:D] Running Loop=1
2024-10-16T21:00:21.267Z,1729112421.267 [Default:CheckIn:D] Stopped
2024-10-16T21:00:21.267Z,1729112421.267 [Default:CheckIn:E] Running Loop=1
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.075155 min
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn:E] Stopped
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn] Stopped
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn](INFO): Running loop #17
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn] Running Loop=17
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T21:00:21.619Z,1729112421.619 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T21:00:23.613Z,1729112423.613 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210022.00,A,3647.81289,N,12150.81683,W,0.233,118.75,161024,,,D*7D
2024-10-16T21:00:23.615Z,1729112423.615 [NAL9602](INFO): GPS fix at 20241016T210022: (36.796881, -121.846947)
2024-10-16T21:00:23.655Z,1729112423.655 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T21:00:23.655Z,1729112423.655 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T21:00:32.018Z,1729112432.018 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241016T191859/Courier0058.lzma
2024-10-16T21:00:33.020Z,1729112433.020 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0058.lzma.bak
2024-10-16T21:00:33.020Z,1729112433.020 [DataOverHttps](INFO): SBD MOMSN=23309701
2024-10-16T21:00:45.069Z,1729112445.069 [NAL9602](INFO): SBD MO Status=0, MOMSN=57953, MT Status=0, MTMSN=0
2024-10-16T21:00:45.069Z,1729112445.069 [NAL9602](INFO): No messages in MT queue
2024-10-16T21:00:52.355Z,1729112452.355 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20241016T191859/Express0059.lzma
2024-10-16T21:00:53.353Z,1729112453.353 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Express0059.lzma.bak
2024-10-16T21:00:53.353Z,1729112453.353 [DataOverHttps](INFO): SBD MOMSN=23309704
2024-10-16T21:00:54.841Z,1729112454.841 [Default:CheckIn:Read_Iridium] Stopped
2024-10-16T21:00:54.841Z,1729112454.841 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-16T21:00:54.841Z,1729112454.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-16T21:01:15.804Z,1729112475.804 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-16T21:03:27.159Z,1729112607.159 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-16T21:03:58.283Z,1729112638.283 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-16T21:04:29.415Z,1729112669.415 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-16T21:04:40.695Z,1729112680.695 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-16T21:04:47.097Z,1729112687.097 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T21:05:02.853Z,1729112702.853 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T21:05:18.219Z,1729112718.219 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T21:05:32.749Z,1729112732.749 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T21:05:48.508Z,1729112748.508 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T21:05:55.397Z,1729112755.397 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-16T21:05:55.397Z,1729112755.397 [Default:CheckIn:C.Wait] Stopped
2024-10-16T21:05:55.397Z,1729112755.397 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-16T21:05:55.397Z,1729112755.397 [Default:CheckIn:D] Running Loop=1
2024-10-16T21:05:55.794Z,1729112755.794 [Default:CheckIn:D] Stopped
2024-10-16T21:05:55.794Z,1729112755.794 [Default:CheckIn:E] Running Loop=1
2024-10-16T21:05:56.205Z,1729112756.205 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.650757 min
2024-10-16T21:05:56.205Z,1729112756.205 [Default:CheckIn:E] Stopped
2024-10-16T21:05:56.206Z,1729112756.206 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-16T21:05:56.206Z,1729112756.206 [Default:CheckIn] Stopped
2024-10-16T21:05:56.206Z,1729112756.206 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-16T21:05:56.206Z,1729112756.206 [Default:CheckIn](INFO): Running loop #18
2024-10-16T21:05:56.206Z,1729112756.206 [Default:CheckIn] Running Loop=18
2024-10-16T21:05:56.226Z,1729112756.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-16T21:05:56.226Z,1729112756.226 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-16T21:05:58.237Z,1729112758.237 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210557.00,A,3647.82113,N,12150.73582,W,1.302,18.59,161024,,,D*4B
2024-10-16T21:05:58.244Z,1729112758.244 [NAL9602](INFO): GPS fix at 20241016T210557: (36.797019, -121.845597)
2024-10-16T21:05:58.256Z,1729112758.256 [Default:CheckIn:Read_GPS] Stopped
2024-10-16T21:05:58.256Z,1729112758.256 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-16T21:06:03.858Z,1729112763.858 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-16T21:06:09.587Z,1729112769.587 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241016T191859/Courier0061.lzma
2024-10-16T21:06:10.588Z,1729112770.588 [DataOverHttps](INFO): Moved sent file to Logs/20241016T191859/Courier0061.lzma.bak
2024-10-16T21:06:10.589Z,1729112770.589 [DataOverHttps](IMPORTANT): SBD MOMSN=23309731, MTMSN=20241016T210610
2024-10-16T21:06:12.366Z,1729112772.366 [NAL9602](INFO): SBD MO Status=0, MOMSN=57954, MT Status=0, MTMSN=0
2024-10-16T21:06:12.366Z,1729112772.366 [NAL9602](INFO): No messages in MT queue
2024-10-16T21:06:18.101Z,1729112778.101 [DataOverHttps](INFO): Received command: restart logs