2024-08-22T21:58:01.580Z,1724363881.580 [Supervisor](DEBUG): Initializing supervisor.
2024-08-22T21:58:01.584Z,1724363881.584 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-08-22T21:58:01.585Z,1724363881.585 [SyncHandler](INFO): Protected caller Thread ID is 7585
2024-08-22T21:58:01.585Z,1724363881.585 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-08-22T21:58:01.586Z,1724363881.586 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-08-22T21:58:01.586Z,1724363881.586 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7586
2024-08-22T21:58:01.590Z,1724363881.590 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-08-22T21:58:01.608Z,1724363881.608 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-08-22T21:58:01.609Z,1724363881.609 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-08-22T21:58:01.609Z,1724363881.609 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7587
2024-08-22T21:58:01.613Z,1724363881.613 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-08-22T21:58:01.615Z,1724363881.615 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-08-22T21:58:01.615Z,1724363881.615 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7588
2024-08-22T21:58:01.617Z,1724363881.617 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-08-22T21:58:01.618Z,1724363881.618 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-08-22T21:58:01.618Z,1724363881.618 [logger ThreadHandler](INFO): Protected caller Thread ID is 7589
2024-08-22T21:58:01.622Z,1724363881.622 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-08-22T21:58:01.622Z,1724363881.622 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-08-22T21:58:01.627Z,1724363881.627 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-08-22T21:58:02.184Z,1724363882.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-08-22T21:58:02.186Z,1724363882.186 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-08-22T21:58:02.279Z,1724363882.279 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-08-22T21:58:02.913Z,1724363882.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-08-22T21:58:02.914Z,1724363882.914 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-08-22T21:58:03.037Z,1724363883.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-08-22T21:58:03.039Z,1724363883.039 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-08-22T21:58:03.262Z,1724363883.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-08-22T21:58:03.264Z,1724363883.264 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-08-22T21:58:03.817Z,1724363883.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-08-22T21:58:03.818Z,1724363883.818 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-08-22T21:58:04.115Z,1724363884.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-08-22T21:58:04.115Z,1724363884.115 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-08-22T21:58:04.350Z,1724363884.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-08-22T21:58:04.351Z,1724363884.351 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-08-22T21:58:04.552Z,1724363884.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-08-22T21:58:04.554Z,1724363884.554 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-08-22T21:58:04.665Z,1724363884.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-08-22T21:58:04.666Z,1724363884.666 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-08-22T21:58:05.012Z,1724363885.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-08-22T21:58:05.377Z,1724363885.377 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-08-22T21:58:06.533Z,1724363886.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-08-22T21:58:06.534Z,1724363886.534 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-08-22T21:58:06.630Z,1724363886.630 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-08-22T21:58:06.780Z,1724363886.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-08-22T21:58:06.781Z,1724363886.781 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-08-22T21:58:07.657Z,1724363887.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-08-22T21:58:07.657Z,1724363887.657 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-08-22T21:58:08.306Z,1724363888.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-08-22T21:58:08.308Z,1724363888.308 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-polaris/
2024-08-22T21:58:08.313Z,1724363888.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/vehicle.cfg
2024-08-22T21:58:08.539Z,1724363888.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Control.cfg
2024-08-22T21:58:08.662Z,1724363888.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Estimation.cfg
2024-08-22T21:58:08.776Z,1724363888.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/BIT.cfg
2024-08-22T21:58:08.889Z,1724363888.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Battery.cfg
2024-08-22T21:58:09.164Z,1724363889.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-08-22T21:58:09.164Z,1724363889.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Simulator.cfg
2024-08-22T21:58:09.293Z,1724363889.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Navigation.cfg
2024-08-22T21:58:09.412Z,1724363889.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Derivation.cfg
2024-08-22T21:58:09.553Z,1724363889.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/logger.cfg
2024-08-22T21:58:09.683Z,1724363889.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/secure.cfg
2024-08-22T21:58:09.801Z,1724363889.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Dock.cfg
2024-08-22T21:58:10.430Z,1724363890.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Servo.cfg
2024-08-22T21:58:10.966Z,1724363890.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/workSite.cfg
2024-08-22T21:58:11.088Z,1724363891.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Sensor.cfg
2024-08-22T21:58:11.322Z,1724363891.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Science.cfg
2024-08-22T21:58:11.515Z,1724363891.515 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-08-22T21:58:11.526Z,1724363891.526 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-08-22T21:58:12.053Z,1724363892.053 [AHRS_M2] Loaded
2024-08-22T21:58:12.053Z,1724363892.053 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-08-22T21:58:12.091Z,1724363892.091 [BackseatComponent] Loaded
2024-08-22T21:58:12.092Z,1724363892.092 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-08-22T21:58:12.093Z,1724363892.093 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408044E0
2024-08-22T21:58:12.093Z,1724363892.093 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7683
2024-08-22T21:58:12.096Z,1724363892.096 [LcmUniversalReporter] Loaded
2024-08-22T21:58:12.096Z,1724363892.096 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-08-22T21:58:12.888Z,1724363892.888 [BPC1] Loaded
2024-08-22T21:58:12.889Z,1724363892.889 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-08-22T21:58:12.975Z,1724363892.975 [DataOverHttps] Loaded
2024-08-22T21:58:12.975Z,1724363892.975 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-08-22T21:58:12.990Z,1724363892.990 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408344E0
2024-08-22T21:58:12.991Z,1724363892.991 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7684
2024-08-22T21:58:13.087Z,1724363893.087 [DepthKeller33X] Loaded
2024-08-22T21:58:13.087Z,1724363893.087 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread.
2024-08-22T21:58:13.093Z,1724363893.093 [DropWeight] Loaded
2024-08-22T21:58:13.095Z,1724363893.095 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-08-22T21:58:13.429Z,1724363893.429 [NAL9602] Loaded
2024-08-22T21:58:13.430Z,1724363893.430 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-08-22T21:58:13.459Z,1724363893.459 [Onboard] Loaded
2024-08-22T21:58:13.459Z,1724363893.459 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-08-22T21:58:13.460Z,1724363893.460 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408644E0
2024-08-22T21:58:13.461Z,1724363893.461 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7685
2024-08-22T21:58:13.466Z,1724363893.466 [PowerOnly](INFO): Adding load control power supply at /dev/loadC2
2024-08-22T21:58:13.480Z,1724363893.480 [PowerOnly](INFO): Adding load control power supply at /dev/loadC6
2024-08-22T21:58:13.492Z,1724363893.492 [PowerOnly](INFO): Adding load control power supply at /dev/loadC4
2024-08-22T21:58:13.504Z,1724363893.504 [PowerOnly] Loaded
2024-08-22T21:58:13.504Z,1724363893.504 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-08-22T21:58:13.517Z,1724363893.517 [Power24vConverter] Loaded
2024-08-22T21:58:13.517Z,1724363893.517 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-08-22T21:58:13.533Z,1724363893.533 [Radio_Surface] Loaded
2024-08-22T21:58:13.534Z,1724363893.534 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-08-22T21:58:13.535Z,1724363893.535 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408944E0
2024-08-22T21:58:13.535Z,1724363893.535 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7686
2024-08-22T21:58:13.536Z,1724363893.536 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-08-22T21:58:13.537Z,1724363893.537 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-08-22T21:58:13.680Z,1724363893.680 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-08-22T21:58:13.681Z,1724363893.681 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-08-22T21:58:13.795Z,1724363893.795 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-08-22T21:58:13.795Z,1724363893.795 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-08-22T21:58:13.801Z,1724363893.801 [UniversalFixResidualReporter] Loaded
2024-08-22T21:58:13.801Z,1724363893.801 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-08-22T21:58:13.802Z,1724363893.802 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-08-22T21:58:13.802Z,1724363893.802 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-08-22T21:58:13.911Z,1724363893.911 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-08-22T21:58:13.912Z,1724363893.912 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-08-22T21:58:14.045Z,1724363894.045 [BuoyancyServo] Loaded
2024-08-22T21:58:14.045Z,1724363894.045 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-08-22T21:58:14.070Z,1724363894.070 [ElevatorServo] Loaded
2024-08-22T21:58:14.070Z,1724363894.070 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-08-22T21:58:14.092Z,1724363894.092 [MassServo] Loaded
2024-08-22T21:58:14.093Z,1724363894.093 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-08-22T21:58:14.115Z,1724363894.115 [RudderServo] Loaded
2024-08-22T21:58:14.115Z,1724363894.115 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-08-22T21:58:14.133Z,1724363894.133 [ThrusterHE] Loaded
2024-08-22T21:58:14.133Z,1724363894.133 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-08-22T21:58:14.133Z,1724363894.133 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-08-22T21:58:14.134Z,1724363894.134 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-08-22T21:58:14.309Z,1724363894.309 [CTD_Seabird] Loaded
2024-08-22T21:58:14.309Z,1724363894.309 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-08-22T21:58:14.311Z,1724363894.311 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E74E0
2024-08-22T21:58:14.311Z,1724363894.311 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7687
2024-08-22T21:58:14.333Z,1724363894.333 [PAR_Licor] Loaded
2024-08-22T21:58:14.333Z,1724363894.333 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-08-22T21:58:14.378Z,1724363894.378 [WetLabsBB2FL] Loaded
2024-08-22T21:58:14.378Z,1724363894.378 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-08-22T21:58:14.379Z,1724363894.379 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2024-08-22T21:58:14.380Z,1724363894.380 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7688
2024-08-22T21:58:14.380Z,1724363894.380 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-08-22T21:58:14.381Z,1724363894.381 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-08-22T21:58:14.711Z,1724363894.711 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-08-22T21:58:14.711Z,1724363894.711 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-08-22T21:58:14.768Z,1724363894.768 [DepthRateCalculator] Loaded
2024-08-22T21:58:14.768Z,1724363894.768 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-08-22T21:58:14.774Z,1724363894.774 [PitchRateCalculator] Loaded
2024-08-22T21:58:14.774Z,1724363894.774 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-08-22T21:58:14.784Z,1724363894.784 [SpeedCalculator] Loaded
2024-08-22T21:58:14.784Z,1724363894.784 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-08-22T21:58:14.797Z,1724363894.797 [TempGradientCalculator] Loaded
2024-08-22T21:58:14.797Z,1724363894.797 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2024-08-22T21:58:14.812Z,1724363894.812 [VerticalHomogeneityIndexCalculator] Loaded
2024-08-22T21:58:14.812Z,1724363894.812 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2024-08-22T21:58:14.816Z,1724363894.816 [YawRateCalculator] Loaded
2024-08-22T21:58:14.817Z,1724363894.817 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-08-22T21:58:14.839Z,1724363894.839 [ElevatorOffsetCalculator] Loaded
2024-08-22T21:58:14.839Z,1724363894.839 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-08-22T21:58:14.839Z,1724363894.839 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-08-22T21:58:14.840Z,1724363894.840 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-08-22T21:58:14.982Z,1724363894.982 [SBIT](DEBUG): Construct Startup Built In Test.
2024-08-22T21:58:14.996Z,1724363894.996 [SBIT] Loaded
2024-08-22T21:58:14.996Z,1724363894.996 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-08-22T21:58:14.999Z,1724363894.999 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-08-22T21:58:15.011Z,1724363895.011 [IBIT] Loaded
2024-08-22T21:58:15.011Z,1724363895.011 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-08-22T21:58:15.017Z,1724363895.017 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-08-22T21:58:15.123Z,1724363895.123 [CBIT] Loaded
2024-08-22T21:58:15.124Z,1724363895.124 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-08-22T21:58:15.124Z,1724363895.124 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-08-22T21:58:15.124Z,1724363895.124 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-08-22T21:58:15.184Z,1724363895.184 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-08-22T21:58:15.185Z,1724363895.185 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-08-22T21:58:15.354Z,1724363895.354 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-08-22T21:58:15.355Z,1724363895.355 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-08-22T21:58:15.411Z,1724363895.411 [VerticalControl](DEBUG): Construct VerticalControl.
2024-08-22T21:58:15.461Z,1724363895.461 [VerticalControl] Loaded
2024-08-22T21:58:15.461Z,1724363895.461 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-08-22T21:58:15.464Z,1724363895.464 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-08-22T21:58:15.508Z,1724363895.508 [HorizontalControl] Loaded
2024-08-22T21:58:15.509Z,1724363895.509 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-08-22T21:58:15.511Z,1724363895.511 [SpeedControl](DEBUG): Construct SpeedControl.
2024-08-22T21:58:15.513Z,1724363895.513 [SpeedControl] Loaded
2024-08-22T21:58:15.513Z,1724363895.513 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-08-22T21:58:15.516Z,1724363895.516 [LoopControl](DEBUG): Construct LoopControl.
2024-08-22T21:58:15.516Z,1724363895.516 [LoopControl] Loaded
2024-08-22T21:58:15.517Z,1724363895.517 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-08-22T21:58:15.517Z,1724363895.517 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-08-22T21:58:15.518Z,1724363895.518 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-08-22T21:58:15.549Z,1724363895.549 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-08-22T21:58:15.555Z,1724363895.555 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-08-22T21:58:15.558Z,1724363895.558 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-08-22T21:58:15.569Z,1724363895.569 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-08-22T21:58:15.570Z,1724363895.570 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-08-22T21:58:15.571Z,1724363895.571 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7689
2024-08-22T21:58:15.575Z,1724363895.575 [Supervisor](INFO): Main Thread ID is 8516
2024-08-22T21:58:15.575Z,1724363895.575 [Supervisor](DEBUG): Running supervisor.
2024-08-22T21:58:15.576Z,1724363895.576 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7690
2024-08-22T21:58:15.576Z,1724363895.576 [CommandExec](INFO): Initializing the command executive.
2024-08-22T21:58:15.578Z,1724363895.578 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7691
2024-08-22T21:58:15.580Z,1724363895.580 [controlThread ThreadHandler](INFO): Handler Thread ID is 7692
2024-08-22T21:58:15.580Z,1724363895.580 [controlThread](DEBUG): Initializing ControlThread
2024-08-22T21:58:15.584Z,1724363895.584 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-08-22T21:58:15.587Z,1724363895.587 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-08-22T21:58:15.587Z,1724363895.587 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-08-22T21:58:15.587Z,1724363895.587 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-08-22T21:58:15.588Z,1724363895.588 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2024-08-22T21:58:15.588Z,1724363895.588 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2024-08-22T21:58:15.589Z,1724363895.589 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-08-22T21:58:15.589Z,1724363895.589 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-08-22T21:58:15.590Z,1724363895.590 [SBIT](INFO): Initialize SBIT Component.
2024-08-22T21:58:15.590Z,1724363895.590 [SBIT](IMPORTANT): git: 2024-05-22-5-gcd1858f91
2024-08-22T21:58:15.590Z,1724363895.590 [SBIT](INFO): git hash: cd1858f91ad007b0f5335e09bba474db3386574f
2024-08-22T21:58:15.591Z,1724363895.591 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-08-22T21:58:15.592Z,1724363895.592 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2024-08-22T21:58:15.593Z,1724363895.593 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2024-08-22T21:58:15.593Z,1724363895.593 [IBIT](INFO): Initialize IBIT Component.
2024-08-22T21:58:15.595Z,1724363895.595 [CBIT](DEBUG): Initialize CBIT Component.
2024-08-22T21:58:15.595Z,1724363895.595 [logger ThreadHandler](INFO): Handler Thread ID is 7693
2024-08-22T21:58:15.606Z,1724363895.606 [CBIT](DEBUG): Initialized mux pins.
2024-08-22T21:58:15.606Z,1724363895.606 [CBIT](DEBUG): Initializing the watchdog timer.
2024-08-22T21:58:15.615Z,1724363895.615 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7694
2024-08-22T21:58:15.627Z,1724363895.627 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7695
2024-08-22T21:58:15.628Z,1724363895.628 [DataOverHttps](DEBUG): dashIP=128.128.181.226 starts with a digit so assuming it is a numeric IP
2024-08-22T21:58:15.630Z,1724363895.630 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-08-22T21:58:15.631Z,1724363895.631 [CBIT](DEBUG): Initializing heartbeat.
2024-08-22T21:58:15.639Z,1724363895.639 [Onboard ThreadHandler](INFO): Handler Thread ID is 7696
2024-08-22T21:58:15.656Z,1724363895.656 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7697
2024-08-22T21:58:15.679Z,1724363895.679 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7698
2024-08-22T21:58:15.680Z,1724363895.680 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-08-22T21:58:15.684Z,1724363895.684 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7700
2024-08-22T21:58:15.687Z,1724363895.687 [WetLabsBB2FL](INFO): Powering up
2024-08-22T21:58:15.688Z,1724363895.688 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7702
2024-08-22T21:58:15.695Z,1724363895.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MA23M.000
2024-08-22T21:58:15.695Z,1724363895.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MA23M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MA43M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MA43M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3EC09M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3EC09M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3NY01M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3NY01M.000
2024-08-22T21:58:15.696Z,1724363895.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2EC03M.000
2024-08-22T21:58:15.697Z,1724363895.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2EC03M.000
2024-08-22T21:58:15.697Z,1724363895.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2EC04M.000
2024-08-22T21:58:15.697Z,1724363895.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2EC04M.000
2024-08-22T21:58:15.702Z,1724363895.702 [CBIT](DEBUG): Deactivating GF circuits.
2024-08-22T21:58:15.702Z,1724363895.702 [CBIT](DEBUG): Deactivating emergency mode.
2024-08-22T21:58:15.742Z,1724363895.742 [CBIT](DEBUG): Backplane powered.
2024-08-22T21:58:15.743Z,1724363895.743 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-08-22T21:58:15.744Z,1724363895.744 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-08-22T21:58:15.745Z,1724363895.745 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-08-22T21:58:15.745Z,1724363895.745 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-08-22T21:58:15.746Z,1724363895.746 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-08-22T21:58:15.747Z,1724363895.747 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-08-22T21:58:15.747Z,1724363895.747 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-08-22T21:58:15.752Z,1724363895.752 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-08-22T21:58:15.796Z,1724363895.796 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-08-22T21:58:15.816Z,1724363895.816 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-22T21:58:15.852Z,1724363895.852 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-08-22T21:58:15.853Z,1724363895.853 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-08-22T21:58:15.853Z,1724363895.853 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-08-22T21:58:15.856Z,1724363895.856 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-08-22T21:58:16.062Z,1724363896.062 [Radio_Surface](INFO): Powering up
2024-08-22T21:58:16.147Z,1724363896.147 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-08-22T21:58:16.180Z,1724363896.180 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-08-22T21:58:16.181Z,1724363896.181 [Default:A.Wait](DEBUG): Construct Wait.
2024-08-22T21:58:16.183Z,1724363896.183 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-22T21:58:16.204Z,1724363896.204 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-08-22T21:58:16.222Z,1724363896.222 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-08-22T21:58:16.237Z,1724363896.237 [Default:E.Execute](DEBUG): Construct Execute.
2024-08-22T21:58:16.240Z,1724363896.240 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-08-22T21:58:16.259Z,1724363896.259 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DepthKeller33X,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-22T21:58:16.265Z,1724363896.265 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-22T21:58:16.327Z,1724363896.327 [DepthKeller33X](INFO): Initializing.
2024-08-22T21:58:16.348Z,1724363896.348 [Power24vConverter](INFO): Powering up.
2024-08-22T21:58:16.355Z,1724363896.355 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-22T21:58:16.373Z,1724363896.373 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-08-22T21:58:16.379Z,1724363896.379 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-08-22T21:58:16.380Z,1724363896.380 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-22T21:58:16.387Z,1724363896.387 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-22T21:58:16.387Z,1724363896.387 [MassServo](DEBUG): Initializing EZServoServo.
2024-08-22T21:58:16.395Z,1724363896.395 [MassServo](DEBUG): Initializing MassServo.
2024-08-22T21:58:16.395Z,1724363896.395 [RudderServo](DEBUG): Initializing EZServoServo.
2024-08-22T21:58:16.403Z,1724363896.403 [RudderServo](DEBUG): Initializing RudderServo.
2024-08-22T21:58:16.403Z,1724363896.403 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-08-22T21:58:16.411Z,1724363896.411 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-08-22T21:58:16.720Z,1724363896.720 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-22T21:58:17.083Z,1724363897.083 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-22T21:58:17.487Z,1724363897.487 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-22T21:58:17.923Z,1724363897.923 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-22T21:58:18.303Z,1724363898.303 [WetLabsBB2FL](INFO): Powering down
2024-08-22T21:58:22.711Z,1724363902.711 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-08-22T21:58:43.744Z,1724363923.744 [NAL9602](INFO): Powering up NAL9602
2024-08-22T21:58:54.652Z,1724363934.652 [NAL9602](INFO): NAL9602 initialized
2024-08-22T21:59:08.817Z,1724363948.817 [NAL9602](INFO): SBD MO Status=0, MOMSN=47312, MT Status=0, MTMSN=0
2024-08-22T21:59:08.817Z,1724363948.817 [NAL9602](INFO): No messages in MT queue
2024-08-22T21:59:35.070Z,1724363975.070 [SBIT](IMPORTANT): Beginning Startup BIT
2024-08-22T21:59:35.074Z,1724363975.074 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-22T21:59:46.037Z,1724363986.037 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007798
CHAN A1 (24V): -0.025748
CHAN A2 (12V): -0.006441
CHAN A3 (5V): -0.001386
CHAN B0 (3.3V): 0.000456
CHAN B1 (3.15aV): 0.000416
CHAN B2 (3.15bV): 0.000426
CHAN B3 (GND): 0.002425
OPEN: 0.005374
Full Scale: +/- 1 mA
2024-08-22T22:00:29.230Z,1724364029.230 [SBIT](IMPORTANT): SBIT PASSED
2024-08-22T22:00:29.231Z,1724364029.231 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-08-22T22:00:29.231Z,1724364029.231 [SBIT](IMPORTANT): CBIT.abortDepth=100 meter;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): CBIT.stopDepth=80 meter;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.050000 practical_salinity_unit;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.500000 celsius;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): NavChart.loadAtStartup=0 bool;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): RDI_PathfinderUp.loadAtStartup=0 bool;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=265.936319 cubic_centimeter;
2024-08-22T22:00:29.232Z,1724364029.232 [SBIT](IMPORTANT): VerticalControl.massDefault=14.963524 millimeter;
2024-08-22T22:00:29.694Z,1724364029.694 [MissionManager](IMPORTANT): Started mission Startup
2024-08-22T22:00:29.694Z,1724364029.694 [Startup] Running Loop=1
2024-08-22T22:00:29.694Z,1724364029.694 [Startup](DEBUG): Aggregate::initialize Startup
2024-08-22T22:00:29.695Z,1724364029.695 [Startup:A.GoToSurface] Running Loop=1
2024-08-22T22:00:29.695Z,1724364029.695 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-22T22:00:29.695Z,1724364029.695 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-22T22:00:29.696Z,1724364029.696 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-22T22:00:29.696Z,1724364029.696 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-22T22:00:29.696Z,1724364029.696 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-22T22:00:29.697Z,1724364029.697 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-22T22:00:29.697Z,1724364029.697 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-22T22:00:29.699Z,1724364029.699 [Startup:StartupSatComms] Running Loop=1
2024-08-22T22:00:29.699Z,1724364029.699 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-08-22T22:00:29.699Z,1724364029.699 [Startup:StartupSatComms:A] Running Loop=1
2024-08-22T22:00:30.040Z,1724364030.040 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-08-22T22:00:57.870Z,1724364057.870 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-08-22T22:01:15.676Z,1724364075.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-08-22T22:01:15.676Z,1724364075.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-22T22:01:15.714Z,1724364075.714 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-22T22:01:16.089Z,1724364076.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-22T22:01:16.089Z,1724364076.089 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-08-22T22:01:29.819Z,1724364089.819 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-22T22:00:29.7Z
2024-08-22T22:01:29.819Z,1724364089.819 [Startup:StartupSatComms:A] Stopped
2024-08-22T22:01:29.819Z,1724364089.819 [Startup:StartupSatComms:B] Running Loop=1
2024-08-22T22:01:30.217Z,1724364090.217 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-22T22:02:03.832Z,1724364123.832 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47313, MT Status=1, MTMSN=1804
2024-08-22T22:02:03.886Z,1724364123.886 [NAL9602](INFO): Sent 52 bytes from file Logs/20240822T205225/Courier0015.lzma
2024-08-22T22:02:03.886Z,1724364123.886 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:02:04.363Z,1724364124.363 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadB5
2024-08-22T22:02:04.392Z,1724364124.392 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB5
2024-08-22T22:02:29.969Z,1724364149.969 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-22T22:01:29.8Z
2024-08-22T22:02:29.970Z,1724364149.970 [Startup:StartupSatComms:B] Stopped
2024-08-22T22:02:29.970Z,1724364149.970 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-08-22T22:02:29.970Z,1724364149.970 [Startup:StartupSatComms] Stopped
2024-08-22T22:02:29.970Z,1724364149.970 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-08-22T22:02:29.971Z,1724364149.971 [Startup](INFO): Completed Startup
2024-08-22T22:02:29.971Z,1724364149.971 [MissionManager](INFO): Startup is completed.
2024-08-22T22:02:29.972Z,1724364149.972 [MissionManager](INFO): Uninitializing Mission Startup
2024-08-22T22:02:29.972Z,1724364149.972 [Startup] Stopped
2024-08-22T22:02:29.972Z,1724364149.972 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-08-22T22:02:29.972Z,1724364149.972 [Startup:A.GoToSurface] Stopped
2024-08-22T22:02:29.972Z,1724364149.972 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-22T22:02:30.514Z,1724364150.514 [NAL9602](INFO): SBD MO Status=2, MOMSN=47314, MT Status=2, MTMSN=0
2024-08-22T22:02:30.514Z,1724364150.514 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T22:02:30.540Z,1724364150.540 [MissionManager](IMPORTANT): Started mission Default
2024-08-22T22:02:30.541Z,1724364150.541 [Default] Running Loop=1
2024-08-22T22:02:30.541Z,1724364150.541 [Default](DEBUG): Aggregate::initialize Default
2024-08-22T22:02:30.541Z,1724364150.541 [Default:B.GoToSurface] Running Loop=1
2024-08-22T22:02:30.541Z,1724364150.541 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-22T22:02:30.541Z,1724364150.541 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-22T22:02:30.542Z,1724364150.542 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-22T22:02:30.542Z,1724364150.542 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-22T22:02:30.542Z,1724364150.542 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-22T22:02:30.543Z,1724364150.543 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-22T22:02:30.543Z,1724364150.543 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-22T22:02:30.543Z,1724364150.543 [Default:A.Wait] Running Loop=1
2024-08-22T22:02:30.543Z,1724364150.543 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:02:31.602Z,1724364151.602 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220229.00,A,4058.80202,N,07031.08253,W,0.311,269.09,220824,,,A*74
2024-08-22T22:02:31.605Z,1724364151.605 [NAL9602](INFO): GPS fix at 20240822T220229: (40.980034, -70.518042)
2024-08-22T22:02:43.865Z,1724364163.865 [Default:A.Wait](INFO): Done Waiting.
2024-08-22T22:02:43.865Z,1724364163.865 [Default:A.Wait] Stopped
2024-08-22T22:02:43.865Z,1724364163.865 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:02:44.175Z,1724364164.175 [Default:CheckIn] Running Loop=1
2024-08-22T22:02:44.175Z,1724364164.175 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:02:44.175Z,1724364164.175 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:02:44.546Z,1724364164.546 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-08-22T22:02:46.123Z,1724364166.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220244.00,A,4058.80125,N,07031.08472,W,0.447,231.22,220824,,,A*7C
2024-08-22T22:02:46.126Z,1724364166.126 [NAL9602](INFO): GPS fix at 20240822T220244: (40.980021, -70.518079)
2024-08-22T22:02:46.144Z,1724364166.144 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:02:46.144Z,1724364166.144 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:02:46.559Z,1724364166.559 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-22T22:03:05.991Z,1724364185.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=47314, MT Status=2, MTMSN=0
2024-08-22T22:03:05.992Z,1724364185.992 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T22:03:24.833Z,1724364204.833 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47314, MT Status=1, MTMSN=1805
2024-08-22T22:03:24.883Z,1724364204.883 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Courier0004.lzma
2024-08-22T22:03:24.883Z,1724364204.883 [NAL9602](INFO): Packets left to send: 1
2024-08-22T22:03:25.295Z,1724364205.295 [NAL9602](INFO): Received command: gfscan
2024-08-22T22:03:25.336Z,1724364205.336 [CommandExec](IMPORTANT): got command gfscan
2024-08-22T22:03:25.628Z,1724364205.628 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-22T22:03:38.391Z,1724364218.391 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007772
CHAN A1 (24V): -0.025809
CHAN A2 (12V): -0.006349
CHAN A3 (5V): -0.001753
CHAN B0 (3.3V): 0.000400
CHAN B1 (3.15aV): 0.000378
CHAN B2 (3.15bV): 0.000254
CHAN B3 (GND): 0.001888
OPEN: 0.005303
Full Scale: +/- 1 mA
2024-08-22T22:03:39.144Z,1724364219.144 [NAL9602](INFO): SBD MO Status=1, MOMSN=47315, MT Status=0, MTMSN=0
2024-08-22T22:03:39.188Z,1724364219.188 [NAL9602](INFO): Sent 70 bytes from file Logs/20240822T215801/Courier0004.lzma
2024-08-22T22:03:39.188Z,1724364219.188 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:03:53.673Z,1724364233.673 [NAL9602](INFO): SBD MO Status=1, MOMSN=47316, MT Status=0, MTMSN=0
2024-08-22T22:03:53.723Z,1724364233.723 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T205225/Express0013.lzma
2024-08-22T22:03:53.723Z,1724364233.723 [NAL9602](INFO): Packets left to send: 1
2024-08-22T22:04:02.983Z,1724364242.983 [NAL9602](INFO): SBD MO Status=1, MOMSN=47317, MT Status=0, MTMSN=0
2024-08-22T22:04:03.035Z,1724364243.035 [NAL9602](INFO): Sent 15 bytes from file Logs/20240822T205225/Express0013.lzma
2024-08-22T22:04:03.035Z,1724364243.035 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:04:15.849Z,1724364255.849 [NAL9602](INFO): SBD MO Status=1, MOMSN=47318, MT Status=0, MTMSN=0
2024-08-22T22:04:15.899Z,1724364255.899 [NAL9602](INFO): Sent 144 bytes from file Logs/20240822T205225/Express0016.lzma
2024-08-22T22:04:15.899Z,1724364255.899 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:04:28.422Z,1724364268.422 [NAL9602](INFO): SBD MO Status=2, MOMSN=47319, MT Status=2, MTMSN=0
2024-08-22T22:04:28.423Z,1724364268.423 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T22:05:05.128Z,1724364305.128 [NAL9602](INFO): SBD MO Status=2, MOMSN=47319, MT Status=2, MTMSN=0
2024-08-22T22:05:05.128Z,1724364305.128 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T22:05:23.249Z,1724364323.249 [NAL9602](INFO): SBD MO Status=1, MOMSN=47319, MT Status=0, MTMSN=0
2024-08-22T22:05:23.319Z,1724364323.319 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Express0001.lzma
2024-08-22T22:05:23.319Z,1724364323.319 [NAL9602](INFO): Packets left to send: 4
2024-08-22T22:05:33.305Z,1724364333.305 [NAL9602](INFO): SBD MO Status=1, MOMSN=47320, MT Status=0, MTMSN=0
2024-08-22T22:05:33.356Z,1724364333.356 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Express0001.lzma
2024-08-22T22:05:33.356Z,1724364333.356 [NAL9602](INFO): Packets left to send: 3
2024-08-22T22:05:39.717Z,1724364339.717 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-08-22T22:05:39.721Z,1724364339.721 [BPC1](INFO): Received data from all battery sticks.
2024-08-22T22:05:44.162Z,1724364344.162 [NAL9602](INFO): SBD MO Status=2, MOMSN=47321, MT Status=2, MTMSN=0
2024-08-22T22:05:44.163Z,1724364344.163 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T22:05:58.227Z,1724364358.227 [NAL9602](INFO): SBD MO Status=1, MOMSN=47321, MT Status=0, MTMSN=0
2024-08-22T22:05:58.275Z,1724364358.275 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Express0001.lzma
2024-08-22T22:05:58.275Z,1724364358.275 [NAL9602](INFO): Packets left to send: 2
2024-08-22T22:06:05.889Z,1724364365.889 [NAL9602](INFO): SBD MO Status=1, MOMSN=47322, MT Status=0, MTMSN=0
2024-08-22T22:06:05.939Z,1724364365.939 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Express0001.lzma
2024-08-22T22:06:05.939Z,1724364365.939 [NAL9602](INFO): Packets left to send: 1
2024-08-22T22:06:19.953Z,1724364379.953 [NAL9602](INFO): SBD MO Status=1, MOMSN=47323, MT Status=0, MTMSN=0
2024-08-22T22:06:20.007Z,1724364380.007 [NAL9602](INFO): Sent 122 bytes from file Logs/20240822T215801/Express0001.lzma
2024-08-22T22:06:20.007Z,1724364380.007 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:06:34.465Z,1724364394.465 [NAL9602](INFO): SBD MO Status=1, MOMSN=47324, MT Status=0, MTMSN=0
2024-08-22T22:06:34.515Z,1724364394.515 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Express0005.lzma
2024-08-22T22:06:34.515Z,1724364394.515 [NAL9602](INFO): Packets left to send: 1
2024-08-22T22:06:48.112Z,1724364408.112 [NAL9602](INFO): SBD MO Status=1, MOMSN=47325, MT Status=0, MTMSN=0
2024-08-22T22:06:48.164Z,1724364408.164 [NAL9602](INFO): Sent 161 bytes from file Logs/20240822T215801/Express0005.lzma
2024-08-22T22:06:48.164Z,1724364408.164 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:06:59.635Z,1724364419.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=47326, MT Status=0, MTMSN=0
2024-08-22T22:07:11.873Z,1724364431.873 [NAL9602](INFO): SBD MO Status=1, MOMSN=47327, MT Status=0, MTMSN=0
2024-08-22T22:07:11.923Z,1724364431.923 [NAL9602](INFO): Sent 180 bytes from file Logs/20240822T215801/Courier0007.lzma
2024-08-22T22:07:11.923Z,1724364431.923 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:07:23.502Z,1724364443.502 [NAL9602](INFO): SBD MO Status=1, MOMSN=47328, MT Status=0, MTMSN=0
2024-08-22T22:07:23.554Z,1724364443.554 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Express0008.lzma
2024-08-22T22:07:23.554Z,1724364443.554 [NAL9602](INFO): Packets left to send: 1
2024-08-22T22:07:32.687Z,1724364452.687 [NAL9602](INFO): SBD MO Status=1, MOMSN=47329, MT Status=0, MTMSN=0
2024-08-22T22:07:32.743Z,1724364452.743 [NAL9602](INFO): Sent 136 bytes from file Logs/20240822T215801/Express0008.lzma
2024-08-22T22:07:32.743Z,1724364452.743 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:07:43.009Z,1724364463.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=47330, MT Status=0, MTMSN=0
2024-08-22T22:07:43.087Z,1724364463.087 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:07:43.087Z,1724364463.087 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:07:43.087Z,1724364463.087 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:08:13.690Z,1724364493.690 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:08:17.336Z,1724364497.336 [Power24vConverter](INFO): Powering down.
2024-08-22T22:12:43.591Z,1724364763.591 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:12:43.591Z,1724364763.591 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:12:43.591Z,1724364763.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:12:43.591Z,1724364763.591 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:12:43.990Z,1724364763.990 [Default:CheckIn:D] Stopped
2024-08-22T22:12:43.990Z,1724364763.990 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:12:44.392Z,1724364764.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.224150 min
2024-08-22T22:12:44.392Z,1724364764.392 [Default:CheckIn:E] Stopped
2024-08-22T22:12:44.392Z,1724364764.392 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:12:44.393Z,1724364764.393 [Default:CheckIn] Stopped
2024-08-22T22:12:44.393Z,1724364764.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:12:44.393Z,1724364764.393 [Default:CheckIn](INFO): Running loop #2
2024-08-22T22:12:44.393Z,1724364764.393 [Default:CheckIn] Running Loop=2
2024-08-22T22:12:44.393Z,1724364764.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:12:44.393Z,1724364764.393 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:12:46.403Z,1724364766.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221245.00,A,4058.75265,N,07031.17763,W,1.030,231.97,220824,,,A*77
2024-08-22T22:12:46.410Z,1724364766.410 [NAL9602](INFO): GPS fix at 20240822T221245: (40.979211, -70.519627)
2024-08-22T22:12:46.420Z,1724364766.420 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:12:46.420Z,1724364766.420 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:13:01.477Z,1724364781.477 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47331, MT Status=1, MTMSN=1806
2024-08-22T22:13:01.527Z,1724364781.527 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0010.lzma
2024-08-22T22:13:01.527Z,1724364781.527 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:13:01.959Z,1724364781.959 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadC3
2024-08-22T22:13:01.984Z,1724364781.984 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadC3
2024-08-22T22:13:02.348Z,1724364782.348 [CommandExec](CRITICAL): SIGABRT: execution aborted (failed assertion, corrupted heap, or other problem?)
2024-08-22T22:13:02.348Z,1724364782.348 [CommandExec] Software Fault, FailCount= 1
2024-08-22T22:13:02.348Z,1724364782.348 [CommandExec](ERROR): Software Fault
2024-08-22T22:13:02.716Z,1724364782.716 [CommandExec](FAULT): Scheduling is paused
2024-08-22T22:13:02.716Z,1724364782.716 [CBIT](INFO): Critical error at 20240822T221302
2024-08-22T22:13:02.717Z,1724364782.717 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-08-22T22:13:02.720Z,1724364782.720 [CBIT](ERROR): Software Fault in component: CommandExec
2024-08-22T22:13:02.721Z,1724364782.721 [CBIT](CRITICAL): Software Fault in component: CommandExec
2024-08-22T22:13:03.141Z,1724364783.141 [CBIT](INFO): Critical error at 20240822T221302
2024-08-22T22:13:20.561Z,1724364800.561 [NAL9602](INFO): SBD MO Status=1, MOMSN=47332, MT Status=0, MTMSN=0
2024-08-22T22:13:20.619Z,1724364800.619 [NAL9602](INFO): Sent 163 bytes from file Logs/20240822T215801/Express0011.lzma
2024-08-22T22:13:20.619Z,1724364800.619 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:13:31.065Z,1724364811.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=47333, MT Status=0, MTMSN=0
2024-08-22T22:13:51.776Z,1724364831.776 [NAL9602](INFO): SBD MO Status=1, MOMSN=47334, MT Status=0, MTMSN=0
2024-08-22T22:13:51.827Z,1724364831.827 [NAL9602](INFO): Sent 184 bytes from file Logs/20240822T215801/Courier0013.lzma
2024-08-22T22:13:51.827Z,1724364831.827 [NAL9602](INFO): Packets left to send: 1
2024-08-22T22:13:59.431Z,1724364839.431 [NAL9602](INFO): SBD MO Status=1, MOMSN=47335, MT Status=0, MTMSN=0
2024-08-22T22:13:59.479Z,1724364839.479 [NAL9602](INFO): Sent 17 bytes from file Logs/20240822T215801/Courier0013.lzma
2024-08-22T22:13:59.479Z,1724364839.479 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:14:10.449Z,1724364850.449 [NAL9602](INFO): SBD MO Status=1, MOMSN=47336, MT Status=0, MTMSN=0
2024-08-22T22:14:10.499Z,1724364850.499 [NAL9602](INFO): Sent 183 bytes from file Logs/20240822T215801/Express0014.lzma
2024-08-22T22:14:10.500Z,1724364850.500 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:14:22.581Z,1724364862.581 [NAL9602](INFO): SBD MO Status=0, MOMSN=47337, MT Status=0, MTMSN=0
2024-08-22T22:14:22.658Z,1724364862.658 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:14:22.658Z,1724364862.658 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:14:22.658Z,1724364862.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:14:53.262Z,1724364893.262 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:19:23.161Z,1724365163.161 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:19:23.161Z,1724365163.161 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:19:23.162Z,1724365163.162 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:19:23.162Z,1724365163.162 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:19:23.562Z,1724365163.562 [Default:CheckIn:D] Stopped
2024-08-22T22:19:23.562Z,1724365163.562 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:19:23.990Z,1724365163.990 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.883684 min
2024-08-22T22:19:23.990Z,1724365163.990 [Default:CheckIn:E] Stopped
2024-08-22T22:19:23.990Z,1724365163.990 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:19:23.990Z,1724365163.990 [Default:CheckIn] Stopped
2024-08-22T22:19:23.991Z,1724365163.991 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:19:23.991Z,1724365163.991 [Default:CheckIn](INFO): Running loop #3
2024-08-22T22:19:23.991Z,1724365163.991 [Default:CheckIn] Running Loop=3
2024-08-22T22:19:23.991Z,1724365163.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:19:23.991Z,1724365163.991 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:19:25.964Z,1724365165.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221925.00,A,4058.71647,N,07031.24759,W,0.914,263.42,220824,,,A*72
2024-08-22T22:19:25.966Z,1724365165.966 [NAL9602](INFO): GPS fix at 20240822T221925: (40.978608, -70.520793)
2024-08-22T22:19:26.008Z,1724365166.008 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:19:26.008Z,1724365166.008 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:19:47.453Z,1724365187.453 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47338, MT Status=1, MTMSN=1807
2024-08-22T22:19:47.503Z,1724365187.503 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0016.lzma
2024-08-22T22:19:47.503Z,1724365187.503 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:19:47.947Z,1724365187.947 [NAL9602](INFO): Received command: gfscan
2024-08-22T22:20:04.541Z,1724365204.541 [NAL9602](INFO): SBD MO Status=1, MOMSN=47339, MT Status=0, MTMSN=0
2024-08-22T22:20:04.591Z,1724365204.591 [NAL9602](INFO): Sent 129 bytes from file Logs/20240822T215801/Express0017.lzma
2024-08-22T22:20:04.591Z,1724365204.591 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:20:16.209Z,1724365216.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=47340, MT Status=0, MTMSN=0
2024-08-22T22:20:28.823Z,1724365228.823 [NAL9602](INFO): SBD MO Status=1, MOMSN=47341, MT Status=0, MTMSN=0
2024-08-22T22:20:28.871Z,1724365228.871 [NAL9602](INFO): Sent 52 bytes from file Logs/20240822T215801/Courier0019.lzma
2024-08-22T22:20:28.871Z,1724365228.871 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:20:45.893Z,1724365245.893 [NAL9602](INFO): SBD MO Status=1, MOMSN=47342, MT Status=0, MTMSN=0
2024-08-22T22:20:45.943Z,1724365245.943 [NAL9602](INFO): Sent 120 bytes from file Logs/20240822T215801/Express0020.lzma
2024-08-22T22:20:45.943Z,1724365245.943 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:20:55.969Z,1724365255.969 [NAL9602](INFO): SBD MO Status=0, MOMSN=47343, MT Status=0, MTMSN=0
2024-08-22T22:20:56.054Z,1724365256.054 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:20:56.054Z,1724365256.054 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:20:56.054Z,1724365256.054 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:21:26.654Z,1724365286.654 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:25:56.538Z,1724365556.538 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:25:56.538Z,1724365556.538 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:25:56.538Z,1724365556.538 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:25:56.538Z,1724365556.538 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:25:56.953Z,1724365556.953 [Default:CheckIn:D] Stopped
2024-08-22T22:25:56.953Z,1724365556.953 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:25:57.375Z,1724365557.375 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.440204 min
2024-08-22T22:25:57.375Z,1724365557.375 [Default:CheckIn:E] Stopped
2024-08-22T22:25:57.375Z,1724365557.375 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:25:57.375Z,1724365557.375 [Default:CheckIn] Stopped
2024-08-22T22:25:57.375Z,1724365557.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:25:57.376Z,1724365557.376 [Default:CheckIn](INFO): Running loop #4
2024-08-22T22:25:57.376Z,1724365557.376 [Default:CheckIn] Running Loop=4
2024-08-22T22:25:57.376Z,1724365557.376 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:25:57.376Z,1724365557.376 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:25:59.366Z,1724365559.366 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222558.00,A,4058.67837,N,07031.31677,W,1.263,142.03,220824,,,A*7F
2024-08-22T22:25:59.368Z,1724365559.368 [NAL9602](INFO): GPS fix at 20240822T222558: (40.977973, -70.521946)
2024-08-22T22:25:59.386Z,1724365559.386 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:25:59.386Z,1724365559.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:26:13.157Z,1724365573.157 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47344, MT Status=1, MTMSN=1808
2024-08-22T22:26:13.208Z,1724365573.208 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0022.lzma
2024-08-22T22:26:13.208Z,1724365573.208 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:26:13.647Z,1724365573.647 [NAL9602](INFO): Received command: gfscan
2024-08-22T22:26:38.774Z,1724365598.774 [NAL9602](INFO): SBD MO Status=1, MOMSN=47345, MT Status=0, MTMSN=0
2024-08-22T22:26:38.823Z,1724365598.823 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0023.lzma
2024-08-22T22:26:38.823Z,1724365598.823 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:26:48.810Z,1724365608.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=47346, MT Status=0, MTMSN=0
2024-08-22T22:27:06.293Z,1724365626.293 [NAL9602](INFO): SBD MO Status=1, MOMSN=47347, MT Status=0, MTMSN=0
2024-08-22T22:27:06.343Z,1724365626.343 [NAL9602](INFO): Sent 52 bytes from file Logs/20240822T215801/Courier0025.lzma
2024-08-22T22:27:06.343Z,1724365626.343 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:27:15.393Z,1724365635.393 [NAL9602](INFO): SBD MO Status=1, MOMSN=47348, MT Status=0, MTMSN=0
2024-08-22T22:27:15.443Z,1724365635.443 [NAL9602](INFO): Sent 119 bytes from file Logs/20240822T215801/Express0026.lzma
2024-08-22T22:27:15.443Z,1724365635.443 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:27:33.559Z,1724365653.559 [NAL9602](INFO): SBD MO Status=0, MOMSN=47349, MT Status=0, MTMSN=0
2024-08-22T22:27:33.638Z,1724365653.638 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:27:33.638Z,1724365653.638 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:27:33.638Z,1724365653.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:28:04.267Z,1724365684.267 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:32:34.157Z,1724365954.157 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:32:34.157Z,1724365954.157 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:32:34.157Z,1724365954.157 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:32:34.157Z,1724365954.157 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:32:34.573Z,1724365954.573 [Default:CheckIn:D] Stopped
2024-08-22T22:32:34.573Z,1724365954.573 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.067200 min
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn:E] Stopped
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn] Stopped
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn](INFO): Running loop #5
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn] Running Loop=5
2024-08-22T22:32:34.992Z,1724365954.992 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:32:34.993Z,1724365954.993 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:32:36.977Z,1724365956.977 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223236.00,A,4058.64974,N,07031.38148,W,0.622,281.01,220824,,,A*78
2024-08-22T22:32:36.979Z,1724365956.979 [NAL9602](INFO): GPS fix at 20240822T223236: (40.977496, -70.523025)
2024-08-22T22:32:37.024Z,1724365957.024 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:32:37.024Z,1724365957.024 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:32:51.187Z,1724365971.187 [NAL9602](INFO): SBD MO Status=1, MOMSN=47350, MT Status=0, MTMSN=0
2024-08-22T22:32:51.231Z,1724365971.231 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0028.lzma
2024-08-22T22:32:51.231Z,1724365971.231 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:33:01.477Z,1724365981.477 [NAL9602](INFO): SBD MO Status=1, MOMSN=47351, MT Status=0, MTMSN=0
2024-08-22T22:33:01.527Z,1724365981.527 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0029.lzma
2024-08-22T22:33:01.527Z,1724365981.527 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:33:10.365Z,1724365990.365 [NAL9602](INFO): SBD MO Status=0, MOMSN=47352, MT Status=0, MTMSN=0
2024-08-22T22:33:10.442Z,1724365990.442 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:33:10.442Z,1724365990.442 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:33:10.442Z,1724365990.442 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:33:41.076Z,1724366021.076 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:38:10.978Z,1724366290.978 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:38:10.979Z,1724366290.979 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:38:10.979Z,1724366290.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:38:10.979Z,1724366290.979 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:38:11.343Z,1724366291.343 [Default:CheckIn:D] Stopped
2024-08-22T22:38:11.343Z,1724366291.343 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:38:11.730Z,1724366291.730 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.680029 min
2024-08-22T22:38:11.730Z,1724366291.730 [Default:CheckIn:E] Stopped
2024-08-22T22:38:11.730Z,1724366291.730 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:38:11.730Z,1724366291.730 [Default:CheckIn] Stopped
2024-08-22T22:38:11.731Z,1724366291.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:38:11.731Z,1724366291.731 [Default:CheckIn](INFO): Running loop #6
2024-08-22T22:38:11.731Z,1724366291.731 [Default:CheckIn] Running Loop=6
2024-08-22T22:38:11.731Z,1724366291.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:38:11.731Z,1724366291.731 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:38:13.765Z,1724366293.765 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223813.00,A,4058.63070,N,07031.43471,W,0.214,255.60,220824,,,A*73
2024-08-22T22:38:13.768Z,1724366293.768 [NAL9602](INFO): GPS fix at 20240822T223813: (40.977178, -70.523912)
2024-08-22T22:38:13.801Z,1724366293.801 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:38:13.801Z,1724366293.801 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:38:34.799Z,1724366314.799 [NAL9602](INFO): SBD MO Status=2, MOMSN=47353, MT Status=2, MTMSN=0
2024-08-22T22:38:34.799Z,1724366314.799 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T22:38:44.829Z,1724366324.829 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47353, MT Status=1, MTMSN=1809
2024-08-22T22:38:44.879Z,1724366324.879 [NAL9602](INFO): Sent 73 bytes from file Logs/20240822T215801/Courier0031.lzma
2024-08-22T22:38:44.879Z,1724366324.879 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:38:45.295Z,1724366325.295 [NAL9602](INFO): Received command: restart app
2024-08-22T22:39:07.157Z,1724366347.157 [NAL9602](INFO): SBD MO Status=1, MOMSN=47354, MT Status=0, MTMSN=0
2024-08-22T22:39:07.207Z,1724366347.207 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0032.lzma
2024-08-22T22:39:07.207Z,1724366347.207 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:39:17.240Z,1724366357.240 [NAL9602](INFO): SBD MO Status=0, MOMSN=47355, MT Status=0, MTMSN=0
2024-08-22T22:39:29.027Z,1724366369.027 [NAL9602](INFO): SBD MO Status=1, MOMSN=47356, MT Status=0, MTMSN=0
2024-08-22T22:39:29.071Z,1724366369.071 [NAL9602](INFO): Sent 52 bytes from file Logs/20240822T215801/Courier0034.lzma
2024-08-22T22:39:29.071Z,1724366369.071 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:39:40.037Z,1724366380.037 [NAL9602](INFO): SBD MO Status=1, MOMSN=47357, MT Status=0, MTMSN=0
2024-08-22T22:39:40.087Z,1724366380.087 [NAL9602](INFO): Sent 118 bytes from file Logs/20240822T215801/Express0035.lzma
2024-08-22T22:39:40.087Z,1724366380.087 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:39:51.711Z,1724366391.711 [NAL9602](INFO): SBD MO Status=0, MOMSN=47358, MT Status=0, MTMSN=0
2024-08-22T22:39:51.794Z,1724366391.794 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:39:51.794Z,1724366391.794 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:39:51.810Z,1724366391.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:40:22.415Z,1724366422.415 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:44:52.325Z,1724366692.325 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:44:52.325Z,1724366692.325 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:44:52.325Z,1724366692.325 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:44:52.325Z,1724366692.325 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:44:52.718Z,1724366692.718 [Default:CheckIn:D] Stopped
2024-08-22T22:44:52.718Z,1724366692.718 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.369629 min
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn:E] Stopped
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn] Stopped
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn](INFO): Running loop #7
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn] Running Loop=7
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:44:53.132Z,1724366693.132 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:44:55.116Z,1724366695.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224454.00,A,4058.60937,N,07031.49837,W,0.855,296.77,220824,,,A*70
2024-08-22T22:44:55.118Z,1724366695.118 [NAL9602](INFO): GPS fix at 20240822T224454: (40.976823, -70.524973)
2024-08-22T22:44:55.162Z,1724366695.162 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:44:55.162Z,1724366695.162 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:45:17.041Z,1724366717.041 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47359, MT Status=1, MTMSN=1810
2024-08-22T22:45:17.091Z,1724366717.091 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0037.lzma
2024-08-22T22:45:17.091Z,1724366717.091 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:45:17.547Z,1724366717.547 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadC3
2024-08-22T22:45:28.472Z,1724366728.472 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47360, MT Status=1, MTMSN=1811
2024-08-22T22:45:28.531Z,1724366728.531 [NAL9602](INFO): Sent 131 bytes from file Logs/20240822T215801/Express0038.lzma
2024-08-22T22:45:28.531Z,1724366728.531 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:45:28.959Z,1724366728.959 [NAL9602](INFO): Received command: gfscan
2024-08-22T22:45:38.645Z,1724366738.645 [NAL9602](INFO): SBD MO Status=0, MOMSN=47361, MT Status=0, MTMSN=0
2024-08-22T22:45:49.301Z,1724366749.301 [NAL9602](INFO): SBD MO Status=1, MOMSN=47362, MT Status=0, MTMSN=0
2024-08-22T22:45:49.355Z,1724366749.355 [NAL9602](INFO): Sent 51 bytes from file Logs/20240822T215801/Courier0040.lzma
2024-08-22T22:45:49.355Z,1724366749.355 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:45:58.661Z,1724366758.661 [NAL9602](INFO): SBD MO Status=1, MOMSN=47363, MT Status=0, MTMSN=0
2024-08-22T22:45:58.715Z,1724366758.715 [NAL9602](INFO): Sent 127 bytes from file Logs/20240822T215801/Express0041.lzma
2024-08-22T22:45:58.715Z,1724366758.715 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:46:07.469Z,1724366767.469 [NAL9602](INFO): SBD MO Status=0, MOMSN=47364, MT Status=0, MTMSN=0
2024-08-22T22:46:07.547Z,1724366767.547 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:46:07.547Z,1724366767.547 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:46:07.547Z,1724366767.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:46:38.188Z,1724366798.188 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:51:08.053Z,1724367068.053 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:51:08.054Z,1724367068.054 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:51:08.054Z,1724367068.054 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:51:08.054Z,1724367068.054 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:51:08.462Z,1724367068.462 [Default:CheckIn:D] Stopped
2024-08-22T22:51:08.466Z,1724367068.466 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:51:08.889Z,1724367068.889 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.632019 min
2024-08-22T22:51:08.889Z,1724367068.889 [Default:CheckIn:E] Stopped
2024-08-22T22:51:08.889Z,1724367068.889 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:51:08.889Z,1724367068.889 [Default:CheckIn] Stopped
2024-08-22T22:51:08.889Z,1724367068.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:51:08.890Z,1724367068.890 [Default:CheckIn](INFO): Running loop #8
2024-08-22T22:51:08.890Z,1724367068.890 [Default:CheckIn] Running Loop=8
2024-08-22T22:51:08.890Z,1724367068.890 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:51:08.890Z,1724367068.890 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:51:10.876Z,1724367070.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225110.00,A,4058.59441,N,07031.55598,W,1.147,267.49,220824,,,A*7F
2024-08-22T22:51:10.879Z,1724367070.879 [NAL9602](INFO): GPS fix at 20240822T225110: (40.976574, -70.525933)
2024-08-22T22:51:10.896Z,1724367070.896 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:51:10.896Z,1724367070.896 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:51:25.423Z,1724367085.423 [NAL9602](INFO): SBD MO Status=1, MOMSN=47365, MT Status=0, MTMSN=0
2024-08-22T22:51:25.471Z,1724367085.471 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0043.lzma
2024-08-22T22:51:25.471Z,1724367085.471 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:51:35.901Z,1724367095.901 [NAL9602](INFO): SBD MO Status=1, MOMSN=47366, MT Status=0, MTMSN=0
2024-08-22T22:51:35.951Z,1724367095.951 [NAL9602](INFO): Sent 131 bytes from file Logs/20240822T215801/Express0044.lzma
2024-08-22T22:51:35.951Z,1724367095.951 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:51:43.109Z,1724367103.109 [NAL9602](INFO): SBD MO Status=0, MOMSN=47367, MT Status=0, MTMSN=0
2024-08-22T22:51:43.185Z,1724367103.185 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:51:43.185Z,1724367103.185 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:51:43.185Z,1724367103.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:52:13.815Z,1724367133.815 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T22:56:43.713Z,1724367403.713 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T22:56:43.713Z,1724367403.713 [Default:CheckIn:C.Wait] Stopped
2024-08-22T22:56:43.713Z,1724367403.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T22:56:43.713Z,1724367403.713 [Default:CheckIn:D] Running Loop=1
2024-08-22T22:56:44.118Z,1724367404.118 [Default:CheckIn:D] Stopped
2024-08-22T22:56:44.118Z,1724367404.118 [Default:CheckIn:E] Running Loop=1
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.226282 min
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn:E] Stopped
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn] Stopped
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn](INFO): Running loop #9
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn] Running Loop=9
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T22:56:44.536Z,1724367404.536 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T22:56:46.518Z,1724367406.518 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225645.00,A,4058.57733,N,07031.61746,W,1.127,257.79,220824,,,A*70
2024-08-22T22:56:46.520Z,1724367406.520 [NAL9602](INFO): GPS fix at 20240822T225645: (40.976289, -70.526958)
2024-08-22T22:56:46.541Z,1724367406.541 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T22:56:46.541Z,1724367406.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T22:57:11.237Z,1724367431.237 [NAL9602](INFO): SBD MO Status=1, MOMSN=47368, MT Status=0, MTMSN=0
2024-08-22T22:57:11.291Z,1724367431.291 [NAL9602](INFO): Sent 73 bytes from file Logs/20240822T215801/Courier0046.lzma
2024-08-22T22:57:11.291Z,1724367431.291 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:57:31.957Z,1724367451.957 [NAL9602](INFO): SBD MO Status=1, MOMSN=47369, MT Status=0, MTMSN=0
2024-08-22T22:57:32.011Z,1724367452.011 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0047.lzma
2024-08-22T22:57:32.011Z,1724367452.011 [NAL9602](INFO): Packets left to send: 0
2024-08-22T22:57:42.437Z,1724367462.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=47370, MT Status=0, MTMSN=0
2024-08-22T22:57:42.514Z,1724367462.514 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T22:57:42.514Z,1724367462.514 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T22:57:42.514Z,1724367462.514 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T22:58:13.129Z,1724367493.129 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:02:43.047Z,1724367763.047 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:02:43.047Z,1724367763.047 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:02:43.047Z,1724367763.047 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:02:43.047Z,1724367763.047 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:02:43.434Z,1724367763.434 [Default:CheckIn:D] Stopped
2024-08-22T23:02:43.434Z,1724367763.434 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:02:43.873Z,1724367763.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.214880 min
2024-08-22T23:02:43.873Z,1724367763.873 [Default:CheckIn:E] Stopped
2024-08-22T23:02:43.873Z,1724367763.873 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:02:43.873Z,1724367763.873 [Default:CheckIn] Stopped
2024-08-22T23:02:43.873Z,1724367763.873 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:02:43.873Z,1724367763.873 [Default:CheckIn](INFO): Running loop #10
2024-08-22T23:02:43.874Z,1724367763.874 [Default:CheckIn] Running Loop=10
2024-08-22T23:02:43.874Z,1724367763.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:02:43.874Z,1724367763.874 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:02:45.852Z,1724367765.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230245.00,A,4058.56666,N,07031.67452,W,0.914,271.29,220824,,,A*78
2024-08-22T23:02:45.854Z,1724367765.854 [NAL9602](INFO): GPS fix at 20240822T230245: (40.976111, -70.527909)
2024-08-22T23:02:45.893Z,1724367765.893 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:02:45.893Z,1724367765.893 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:03:00.921Z,1724367780.921 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47371, MT Status=1, MTMSN=1812
2024-08-22T23:03:00.971Z,1724367780.971 [NAL9602](INFO): Sent 73 bytes from file Logs/20240822T215801/Courier0049.lzma
2024-08-22T23:03:00.971Z,1724367780.971 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:03:01.467Z,1724367781.467 [NAL9602](INFO): Received command: configSet RDI_Pathfinder.loadAtStartup 1 bool persist
2024-08-22T23:03:18.828Z,1724367798.828 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47372, MT Status=1, MTMSN=1813
2024-08-22T23:03:18.887Z,1724367798.887 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0050.lzma
2024-08-22T23:03:18.887Z,1724367798.887 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:03:19.355Z,1724367799.355 [NAL9602](INFO): Received command: configset CBIT.gfScanTimeout 1 hour persist
2024-08-22T23:03:30.701Z,1724367810.701 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=47373, MT Status=1, MTMSN=1814
2024-08-22T23:03:31.191Z,1724367811.191 [NAL9602](INFO): Received command: restart app
2024-08-22T23:03:40.001Z,1724367820.001 [NAL9602](INFO): SBD MO Status=0, MOMSN=47374, MT Status=0, MTMSN=0
2024-08-22T23:03:50.195Z,1724367830.195 [NAL9602](INFO): SBD MO Status=1, MOMSN=47375, MT Status=0, MTMSN=0
2024-08-22T23:03:50.255Z,1724367830.255 [NAL9602](INFO): Sent 52 bytes from file Logs/20240822T215801/Courier0052.lzma
2024-08-22T23:03:50.255Z,1724367830.255 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:03:57.173Z,1724367837.173 [NAL9602](INFO): SBD MO Status=1, MOMSN=47376, MT Status=0, MTMSN=0
2024-08-22T23:03:57.223Z,1724367837.223 [NAL9602](INFO): Sent 136 bytes from file Logs/20240822T215801/Express0053.lzma
2024-08-22T23:03:57.223Z,1724367837.223 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:04:04.019Z,1724367844.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=47377, MT Status=0, MTMSN=0
2024-08-22T23:04:04.103Z,1724367844.103 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:04:04.103Z,1724367844.103 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:04:04.103Z,1724367844.103 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:04:34.721Z,1724367874.721 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:09:04.610Z,1724368144.610 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:09:04.610Z,1724368144.610 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:09:04.610Z,1724368144.610 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:09:04.610Z,1724368144.610 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:09:05.019Z,1724368145.019 [Default:CheckIn:D] Stopped
2024-08-22T23:09:05.019Z,1724368145.019 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:09:05.426Z,1724368145.426 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.574634 min
2024-08-22T23:09:05.426Z,1724368145.426 [Default:CheckIn:E] Stopped
2024-08-22T23:09:05.426Z,1724368145.426 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:09:05.427Z,1724368145.427 [Default:CheckIn] Stopped
2024-08-22T23:09:05.427Z,1724368145.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:09:05.427Z,1724368145.427 [Default:CheckIn](INFO): Running loop #11
2024-08-22T23:09:05.427Z,1724368145.427 [Default:CheckIn] Running Loop=11
2024-08-22T23:09:05.427Z,1724368145.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:09:05.427Z,1724368145.427 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:09:07.423Z,1724368147.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230906.00,A,4058.55541,N,07031.73444,W,1.458,174.16,220824,,,A*7D
2024-08-22T23:09:07.425Z,1724368147.425 [NAL9602](INFO): GPS fix at 20240822T230906: (40.975924, -70.528907)
2024-08-22T23:09:07.484Z,1724368147.484 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:09:07.484Z,1724368147.484 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:09:21.393Z,1724368161.393 [NAL9602](INFO): SBD MO Status=1, MOMSN=47378, MT Status=0, MTMSN=0
2024-08-22T23:09:21.451Z,1724368161.451 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0055.lzma
2024-08-22T23:09:21.451Z,1724368161.451 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:09:28.344Z,1724368168.344 [NAL9602](INFO): SBD MO Status=1, MOMSN=47379, MT Status=0, MTMSN=0
2024-08-22T23:09:28.395Z,1724368168.395 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0056.lzma
2024-08-22T23:09:28.395Z,1724368168.395 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:09:35.641Z,1724368175.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=47380, MT Status=0, MTMSN=0
2024-08-22T23:09:35.718Z,1724368175.718 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:09:35.718Z,1724368175.718 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:09:35.718Z,1724368175.718 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:10:06.340Z,1724368206.340 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:13:00.487Z,1724368380.487 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-08-22T23:13:00.490Z,1724368380.490 [BPC1](INFO): Received data from all battery sticks.
2024-08-22T23:14:36.264Z,1724368476.264 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:14:36.264Z,1724368476.264 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:14:36.264Z,1724368476.264 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:14:36.264Z,1724368476.264 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:14:36.634Z,1724368476.634 [Default:CheckIn:D] Stopped
2024-08-22T23:14:36.634Z,1724368476.634 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:14:37.042Z,1724368477.042 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.101554 min
2024-08-22T23:14:37.046Z,1724368477.046 [Default:CheckIn:E] Stopped
2024-08-22T23:14:37.046Z,1724368477.046 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:14:37.046Z,1724368477.046 [Default:CheckIn] Stopped
2024-08-22T23:14:37.047Z,1724368477.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:14:37.047Z,1724368477.047 [Default:CheckIn](INFO): Running loop #12
2024-08-22T23:14:37.047Z,1724368477.047 [Default:CheckIn] Running Loop=12
2024-08-22T23:14:37.047Z,1724368477.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:14:37.047Z,1724368477.047 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:14:39.054Z,1724368479.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231438.00,A,4058.54502,N,07031.79000,W,0.505,286.59,220824,,,A*79
2024-08-22T23:14:39.064Z,1724368479.064 [NAL9602](INFO): GPS fix at 20240822T231438: (40.975750, -70.529833)
2024-08-22T23:14:39.087Z,1724368479.087 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:14:39.087Z,1724368479.087 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:14:52.409Z,1724368492.409 [NAL9602](INFO): SBD MO Status=1, MOMSN=47381, MT Status=0, MTMSN=0
2024-08-22T23:14:52.459Z,1724368492.459 [NAL9602](INFO): Sent 83 bytes from file Logs/20240822T215801/Courier0058.lzma
2024-08-22T23:14:52.459Z,1724368492.459 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:15:02.610Z,1724368502.610 [NAL9602](INFO): SBD MO Status=1, MOMSN=47382, MT Status=0, MTMSN=0
2024-08-22T23:15:02.663Z,1724368502.663 [NAL9602](INFO): Sent 131 bytes from file Logs/20240822T215801/Express0059.lzma
2024-08-22T23:15:02.663Z,1724368502.663 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:15:11.891Z,1724368511.891 [NAL9602](INFO): SBD MO Status=0, MOMSN=47383, MT Status=0, MTMSN=0
2024-08-22T23:15:11.975Z,1724368511.975 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:15:11.975Z,1724368511.975 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:15:11.975Z,1724368511.975 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:15:42.595Z,1724368542.595 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:20:12.473Z,1724368812.473 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:20:12.473Z,1724368812.473 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:20:12.473Z,1724368812.473 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:20:12.473Z,1724368812.473 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:20:12.893Z,1724368812.893 [Default:CheckIn:D] Stopped
2024-08-22T23:20:12.893Z,1724368812.893 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:20:13.307Z,1724368813.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.705868 min
2024-08-22T23:20:13.307Z,1724368813.307 [Default:CheckIn:E] Stopped
2024-08-22T23:20:13.307Z,1724368813.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:20:13.308Z,1724368813.308 [Default:CheckIn] Stopped
2024-08-22T23:20:13.308Z,1724368813.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:20:13.308Z,1724368813.308 [Default:CheckIn](INFO): Running loop #13
2024-08-22T23:20:13.308Z,1724368813.308 [Default:CheckIn] Running Loop=13
2024-08-22T23:20:13.308Z,1724368813.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:20:13.308Z,1724368813.308 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:20:15.295Z,1724368815.295 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232014.00,A,4058.54270,N,07031.84583,W,0.622,334.00,220824,,,A*7C
2024-08-22T23:20:15.297Z,1724368815.297 [NAL9602](INFO): GPS fix at 20240822T232014: (40.975712, -70.530764)
2024-08-22T23:20:15.334Z,1724368815.334 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:20:15.334Z,1724368815.334 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:20:30.813Z,1724368830.813 [NAL9602](INFO): SBD MO Status=1, MOMSN=47384, MT Status=0, MTMSN=0
2024-08-22T23:20:30.863Z,1724368830.863 [NAL9602](INFO): Sent 72 bytes from file Logs/20240822T215801/Courier0061.lzma
2024-08-22T23:20:30.863Z,1724368830.863 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:20:40.594Z,1724368840.594 [NAL9602](INFO): SBD MO Status=1, MOMSN=47385, MT Status=0, MTMSN=0
2024-08-22T23:20:40.647Z,1724368840.647 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0062.lzma
2024-08-22T23:20:40.647Z,1724368840.647 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:20:49.226Z,1724368849.226 [NAL9602](INFO): SBD MO Status=0, MOMSN=47386, MT Status=0, MTMSN=0
2024-08-22T23:20:49.319Z,1724368849.319 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:20:49.319Z,1724368849.319 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:20:49.319Z,1724368849.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:21:19.928Z,1724368879.928 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:25:49.831Z,1724369149.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:25:49.832Z,1724369149.832 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:25:49.832Z,1724369149.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:25:49.832Z,1724369149.832 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:25:50.222Z,1724369150.222 [Default:CheckIn:D] Stopped
2024-08-22T23:25:50.222Z,1724369150.222 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:25:50.642Z,1724369150.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.327954 min
2024-08-22T23:25:50.642Z,1724369150.642 [Default:CheckIn:E] Stopped
2024-08-22T23:25:50.642Z,1724369150.642 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:25:50.642Z,1724369150.642 [Default:CheckIn] Stopped
2024-08-22T23:25:50.642Z,1724369150.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:25:50.643Z,1724369150.643 [Default:CheckIn](INFO): Running loop #14
2024-08-22T23:25:50.643Z,1724369150.643 [Default:CheckIn] Running Loop=14
2024-08-22T23:25:50.643Z,1724369150.643 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:25:50.643Z,1724369150.643 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:25:52.631Z,1724369152.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232551.00,A,4058.54558,N,07031.89899,W,0.875,223.89,220824,,,A*74
2024-08-22T23:25:52.633Z,1724369152.633 [NAL9602](INFO): GPS fix at 20240822T232551: (40.975760, -70.531650)
2024-08-22T23:25:52.667Z,1724369152.667 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:25:52.667Z,1724369152.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:26:11.285Z,1724369171.285 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=47387, MT Status=1, MTMSN=1815
2024-08-22T23:26:11.335Z,1724369171.335 [NAL9602](INFO): Sent 73 bytes from file Logs/20240822T215801/Courier0064.lzma
2024-08-22T23:26:11.335Z,1724369171.335 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:26:11.751Z,1724369171.751 [NAL9602](INFO): Received command: restart system
2024-08-22T23:26:35.204Z,1724369195.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=47388, MT Status=2, MTMSN=0
2024-08-22T23:26:35.204Z,1724369195.204 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-08-22T23:27:06.832Z,1724369226.832 [NAL9602](INFO): SBD MO Status=1, MOMSN=47388, MT Status=0, MTMSN=0
2024-08-22T23:27:06.891Z,1724369226.891 [NAL9602](INFO): Sent 130 bytes from file Logs/20240822T215801/Express0065.lzma
2024-08-22T23:27:06.891Z,1724369226.891 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:27:18.973Z,1724369238.973 [NAL9602](INFO): SBD MO Status=0, MOMSN=47389, MT Status=0, MTMSN=0
2024-08-22T23:27:37.227Z,1724369257.227 [NAL9602](INFO): SBD MO Status=1, MOMSN=47390, MT Status=0, MTMSN=0
2024-08-22T23:27:37.283Z,1724369257.283 [NAL9602](INFO): Sent 52 bytes from file Logs/20240822T215801/Courier0067.lzma
2024-08-22T23:27:37.283Z,1724369257.283 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:27:52.719Z,1724369272.719 [NAL9602](INFO): SBD MO Status=1, MOMSN=47391, MT Status=0, MTMSN=0
2024-08-22T23:27:52.771Z,1724369272.771 [NAL9602](INFO): Sent 119 bytes from file Logs/20240822T215801/Express0068.lzma
2024-08-22T23:27:52.771Z,1724369272.771 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:27:57.961Z,1724369277.961 [NAL9602](INFO): SBD MO Status=0, MOMSN=47392, MT Status=0, MTMSN=0
2024-08-22T23:27:58.062Z,1724369278.062 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:27:58.070Z,1724369278.070 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:27:58.070Z,1724369278.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:28:28.645Z,1724369308.645 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:32:58.559Z,1724369578.559 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:32:58.559Z,1724369578.559 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:32:58.559Z,1724369578.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:32:58.559Z,1724369578.559 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:32:58.981Z,1724369578.981 [Default:CheckIn:D] Stopped
2024-08-22T23:32:58.981Z,1724369578.981 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:32:59.373Z,1724369579.373 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.474007 min
2024-08-22T23:32:59.373Z,1724369579.373 [Default:CheckIn:E] Stopped
2024-08-22T23:32:59.373Z,1724369579.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:32:59.373Z,1724369579.373 [Default:CheckIn] Stopped
2024-08-22T23:32:59.373Z,1724369579.373 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:32:59.374Z,1724369579.374 [Default:CheckIn](INFO): Running loop #15
2024-08-22T23:32:59.374Z,1724369579.374 [Default:CheckIn] Running Loop=15
2024-08-22T23:32:59.374Z,1724369579.374 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:32:59.374Z,1724369579.374 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:33:01.370Z,1724369581.370 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233300.00,A,4058.55299,N,07031.96463,W,2.255,337.49,220824,,,A*79
2024-08-22T23:33:01.372Z,1724369581.372 [NAL9602](INFO): GPS fix at 20240822T233300: (40.975883, -70.532744)
2024-08-22T23:33:01.393Z,1724369581.393 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:33:01.393Z,1724369581.393 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:33:18.337Z,1724369598.337 [NAL9602](INFO): SBD MO Status=1, MOMSN=47393, MT Status=0, MTMSN=0
2024-08-22T23:33:18.399Z,1724369598.399 [NAL9602](INFO): Sent 71 bytes from file Logs/20240822T215801/Courier0070.lzma
2024-08-22T23:33:18.399Z,1724369598.399 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:33:27.349Z,1724369607.349 [NAL9602](INFO): SBD MO Status=1, MOMSN=47394, MT Status=0, MTMSN=0
2024-08-22T23:33:27.399Z,1724369607.399 [NAL9602](INFO): Sent 131 bytes from file Logs/20240822T215801/Express0071.lzma
2024-08-22T23:33:27.399Z,1724369607.399 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:33:35.414Z,1724369615.414 [NAL9602](INFO): SBD MO Status=0, MOMSN=47395, MT Status=0, MTMSN=0
2024-08-22T23:33:35.497Z,1724369615.497 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:33:35.497Z,1724369615.497 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:33:35.497Z,1724369615.497 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:34:06.127Z,1724369646.127 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:38:36.012Z,1724369916.012 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:38:36.012Z,1724369916.012 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:38:36.012Z,1724369916.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:38:36.012Z,1724369916.012 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:38:36.398Z,1724369916.398 [Default:CheckIn:D] Stopped
2024-08-22T23:38:36.398Z,1724369916.398 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:38:36.805Z,1724369916.805 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.097616 min
2024-08-22T23:38:36.805Z,1724369916.805 [Default:CheckIn:E] Stopped
2024-08-22T23:38:36.805Z,1724369916.805 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:38:36.805Z,1724369916.805 [Default:CheckIn] Stopped
2024-08-22T23:38:36.805Z,1724369916.805 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:38:36.806Z,1724369916.806 [Default:CheckIn](INFO): Running loop #16
2024-08-22T23:38:36.806Z,1724369916.806 [Default:CheckIn] Running Loop=16
2024-08-22T23:38:36.806Z,1724369916.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:38:36.806Z,1724369916.806 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:38:38.815Z,1724369918.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233838.00,A,4058.55912,N,07032.01746,W,0.661,257.48,220824,,,A*7F
2024-08-22T23:38:38.818Z,1724369918.818 [NAL9602](INFO): GPS fix at 20240822T233838: (40.975985, -70.533624)
2024-08-22T23:38:38.842Z,1724369918.842 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:38:38.842Z,1724369918.842 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-22T23:38:55.881Z,1724369935.881 [NAL9602](INFO): SBD MO Status=1, MOMSN=47396, MT Status=0, MTMSN=0
2024-08-22T23:38:55.932Z,1724369935.932 [NAL9602](INFO): Sent 71 bytes from file Logs/20240822T215801/Courier0073.lzma
2024-08-22T23:38:55.932Z,1724369935.932 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:39:08.529Z,1724369948.529 [NAL9602](INFO): SBD MO Status=1, MOMSN=47397, MT Status=0, MTMSN=0
2024-08-22T23:39:08.575Z,1724369948.575 [NAL9602](INFO): Sent 129 bytes from file Logs/20240822T215801/Express0074.lzma
2024-08-22T23:39:08.575Z,1724369948.575 [NAL9602](INFO): Packets left to send: 0
2024-08-22T23:39:12.129Z,1724369952.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=47398, MT Status=0, MTMSN=0
2024-08-22T23:39:12.205Z,1724369952.205 [Default:CheckIn:Read_Iridium] Stopped
2024-08-22T23:39:12.205Z,1724369952.205 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-22T23:39:12.205Z,1724369952.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-22T23:39:42.836Z,1724369982.836 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-22T23:44:12.715Z,1724370252.715 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-22T23:44:12.715Z,1724370252.715 [Default:CheckIn:C.Wait] Stopped
2024-08-22T23:44:12.715Z,1724370252.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-22T23:44:12.715Z,1724370252.715 [Default:CheckIn:D] Running Loop=1
2024-08-22T23:44:13.139Z,1724370253.139 [Default:CheckIn:D] Stopped
2024-08-22T23:44:13.139Z,1724370253.139 [Default:CheckIn:E] Running Loop=1
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.709969 min
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn:E] Stopped
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn] Stopped
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn](INFO): Running loop #17
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn] Running Loop=17
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-22T23:44:13.517Z,1724370253.517 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-22T23:44:15.526Z,1724370255.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234414.00,A,4058.56886,N,07032.06715,W,0.739,337.01,220824,,,A*72
2024-08-22T23:44:15.528Z,1724370255.528 [NAL9602](INFO): GPS fix at 20240822T234414: (40.976148, -70.534453)
2024-08-22T23:44:15.565Z,1724370255.565 [Default:CheckIn:Read_GPS] Stopped
2024-08-22T23:44:15.565Z,1724370255.565 [Default:C