2024-09-16T18:29:35.939Z,1726511375.939 [Supervisor](DEBUG): Initializing supervisor.
2024-09-16T18:29:35.944Z,1726511375.944 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-16T18:29:35.944Z,1726511375.944 [SyncHandler](INFO): Protected caller Thread ID is 1030
2024-09-16T18:29:35.945Z,1726511375.945 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-16T18:29:35.945Z,1726511375.945 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-16T18:29:35.946Z,1726511375.946 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1031
2024-09-16T18:29:35.950Z,1726511375.950 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-16T18:29:35.967Z,1726511375.967 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-16T18:29:35.968Z,1726511375.968 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-16T18:29:35.968Z,1726511375.968 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1032
2024-09-16T18:29:35.973Z,1726511375.973 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-16T18:29:35.974Z,1726511375.974 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-16T18:29:35.974Z,1726511375.974 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1033
2024-09-16T18:29:35.976Z,1726511375.976 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-16T18:29:35.977Z,1726511375.977 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-16T18:29:35.977Z,1726511375.977 [logger ThreadHandler](INFO): Protected caller Thread ID is 1034
2024-09-16T18:29:35.981Z,1726511375.981 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-16T18:29:35.981Z,1726511375.981 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-16T18:29:35.983Z,1726511375.983 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-16T18:29:36.212Z,1726511376.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-16T18:29:36.213Z,1726511376.213 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-16T18:29:36.334Z,1726511376.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-16T18:29:36.335Z,1726511376.335 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-16T18:29:36.444Z,1726511376.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-16T18:29:36.445Z,1726511376.445 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-16T18:29:36.958Z,1726511376.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-16T18:29:36.959Z,1726511376.959 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-16T18:29:37.333Z,1726511377.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-16T18:29:37.333Z,1726511377.333 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-16T18:29:37.699Z,1726511377.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-16T18:29:37.699Z,1726511377.699 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-16T18:29:37.905Z,1726511377.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-16T18:29:37.906Z,1726511377.906 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-16T18:29:37.998Z,1726511377.998 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-16T18:29:38.092Z,1726511378.092 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-16T18:29:38.541Z,1726511378.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-16T18:29:38.542Z,1726511378.542 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-16T18:29:38.853Z,1726511378.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-16T18:29:38.854Z,1726511378.854 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-16T18:29:39.455Z,1726511379.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-16T18:29:39.456Z,1726511379.456 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-16T18:29:39.703Z,1726511379.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-16T18:29:39.703Z,1726511379.703 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-16T18:29:39.970Z,1726511379.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-16T18:29:39.971Z,1726511379.971 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-16T18:29:40.165Z,1726511380.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-16T18:29:40.165Z,1726511380.165 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-16T18:29:41.699Z,1726511381.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-16T18:29:41.701Z,1726511381.701 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2024-09-16T18:29:41.701Z,1726511381.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2024-09-16T18:29:41.797Z,1726511381.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2024-09-16T18:29:41.941Z,1726511381.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2024-09-16T18:29:42.217Z,1726511382.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-16T18:29:42.218Z,1726511382.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2024-09-16T18:29:42.357Z,1726511382.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2024-09-16T18:29:42.469Z,1726511382.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2024-09-16T18:29:42.575Z,1726511382.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2024-09-16T18:29:42.697Z,1726511382.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2024-09-16T18:29:42.804Z,1726511382.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2024-09-16T18:29:42.902Z,1726511382.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2024-09-16T18:29:43.005Z,1726511383.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2024-09-16T18:29:43.192Z,1726511383.192 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2024-09-16T18:29:43.192Z,1726511383.192 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-16T18:29:43.200Z,1726511383.200 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-16T18:29:43.252Z,1726511383.252 [DepthRateCalculator] Loaded
2024-09-16T18:29:43.252Z,1726511383.252 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-16T18:29:43.258Z,1726511383.258 [PitchRateCalculator] Loaded
2024-09-16T18:29:43.258Z,1726511383.258 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-16T18:29:43.268Z,1726511383.268 [SpeedCalculator] Loaded
2024-09-16T18:29:43.268Z,1726511383.268 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-16T18:29:43.273Z,1726511383.273 [YawRateCalculator] Loaded
2024-09-16T18:29:43.273Z,1726511383.273 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-16T18:29:43.295Z,1726511383.295 [ElevatorOffsetCalculator] Loaded
2024-09-16T18:29:43.295Z,1726511383.295 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-16T18:29:43.295Z,1726511383.295 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-16T18:29:43.296Z,1726511383.296 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-16T18:29:43.323Z,1726511383.323 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-16T18:29:43.323Z,1726511383.323 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-16T18:29:43.333Z,1726511383.333 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-16T18:29:43.333Z,1726511383.333 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-16T18:29:43.814Z,1726511383.814 [AHRS_M2] Loaded
2024-09-16T18:29:43.814Z,1726511383.814 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-16T18:29:43.852Z,1726511383.852 [BackseatComponent] Loaded
2024-09-16T18:29:43.852Z,1726511383.852 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-16T18:29:43.853Z,1726511383.853 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2024-09-16T18:29:43.854Z,1726511383.854 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1116
2024-09-16T18:29:43.856Z,1726511383.856 [LcmUniversalReporter] Loaded
2024-09-16T18:29:43.857Z,1726511383.857 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-16T18:29:44.647Z,1726511384.647 [BPC1] Loaded
2024-09-16T18:29:44.648Z,1726511384.648 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-16T18:29:44.730Z,1726511384.730 [DATMMP] Loaded
2024-09-16T18:29:44.731Z,1726511384.731 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-16T18:29:44.732Z,1726511384.732 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408A04E0
2024-09-16T18:29:44.732Z,1726511384.732 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1117
2024-09-16T18:29:44.803Z,1726511384.803 [DataOverHttps] Loaded
2024-09-16T18:29:44.803Z,1726511384.803 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-16T18:29:44.804Z,1726511384.804 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2024-09-16T18:29:44.804Z,1726511384.804 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1118
2024-09-16T18:29:44.827Z,1726511384.827 [Depth_Keller] Loaded
2024-09-16T18:29:44.827Z,1726511384.827 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-16T18:29:44.832Z,1726511384.832 [DropWeight] Loaded
2024-09-16T18:29:44.832Z,1726511384.832 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-16T18:29:44.895Z,1726511384.895 [NAL9602] Loaded
2024-09-16T18:29:44.895Z,1726511384.895 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-16T18:29:44.924Z,1726511384.924 [Onboard] Loaded
2024-09-16T18:29:44.924Z,1726511384.924 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-16T18:29:44.925Z,1726511384.925 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2024-09-16T18:29:44.925Z,1726511384.925 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1119
2024-09-16T18:29:44.939Z,1726511384.939 [Power24vConverter] Loaded
2024-09-16T18:29:44.939Z,1726511384.939 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-16T18:29:44.955Z,1726511384.955 [Radio_Surface] Loaded
2024-09-16T18:29:44.956Z,1726511384.956 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-16T18:29:44.957Z,1726511384.957 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2024-09-16T18:29:44.957Z,1726511384.957 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1120
2024-09-16T18:29:44.972Z,1726511384.972 [Sonardyne_Nano] Loaded
2024-09-16T18:29:44.972Z,1726511384.972 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-16T18:29:44.996Z,1726511384.996 [Waterlinked] Loaded
2024-09-16T18:29:44.996Z,1726511384.996 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-09-16T18:29:44.997Z,1726511384.997 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-16T18:29:44.997Z,1726511384.997 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-16T18:29:45.106Z,1726511385.106 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-16T18:29:45.106Z,1726511385.106 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-16T18:29:45.148Z,1726511385.148 [DeadReckonUsingSpeedCalculator] Loaded
2024-09-16T18:29:45.148Z,1726511385.148 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-09-16T18:29:45.162Z,1726511385.162 [NavChart] Loaded
2024-09-16T18:29:45.162Z,1726511385.162 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-16T18:29:45.167Z,1726511385.167 [UniversalFixResidualReporter] Loaded
2024-09-16T18:29:45.168Z,1726511385.168 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-16T18:29:45.168Z,1726511385.168 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-16T18:29:45.169Z,1726511385.169 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-16T18:29:45.547Z,1726511385.547 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-16T18:29:45.561Z,1726511385.561 [SBIT] Loaded
2024-09-16T18:29:45.561Z,1726511385.561 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-16T18:29:45.584Z,1726511385.584 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-16T18:29:45.608Z,1726511385.608 [IBIT] Loaded
2024-09-16T18:29:45.609Z,1726511385.609 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-16T18:29:45.631Z,1726511385.631 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-16T18:29:45.854Z,1726511385.854 [CBIT] Loaded
2024-09-16T18:29:45.854Z,1726511385.854 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-16T18:29:45.854Z,1726511385.854 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-16T18:29:45.855Z,1726511385.855 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-16T18:29:46.029Z,1726511386.029 [CTD_Seabird] Loaded
2024-09-16T18:29:46.029Z,1726511386.029 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-16T18:29:46.030Z,1726511386.030 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A384E0
2024-09-16T18:29:46.030Z,1726511386.030 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1121
2024-09-16T18:29:46.053Z,1726511386.053 [PAR_Licor] Loaded
2024-09-16T18:29:46.054Z,1726511386.054 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-16T18:29:46.098Z,1726511386.098 [WetLabsBB2FL] Loaded
2024-09-16T18:29:46.098Z,1726511386.098 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-16T18:29:46.099Z,1726511386.099 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A684E0
2024-09-16T18:29:46.100Z,1726511386.100 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1122
2024-09-16T18:29:46.101Z,1726511386.101 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-16T18:29:46.101Z,1726511386.101 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-16T18:29:46.233Z,1726511386.233 [BuoyancyServo] Loaded
2024-09-16T18:29:46.233Z,1726511386.233 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-16T18:29:46.257Z,1726511386.257 [ElevatorServo] Loaded
2024-09-16T18:29:46.257Z,1726511386.257 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-16T18:29:46.280Z,1726511386.280 [MassServo] Loaded
2024-09-16T18:29:46.281Z,1726511386.281 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-16T18:29:46.302Z,1726511386.302 [RudderServo] Loaded
2024-09-16T18:29:46.303Z,1726511386.303 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-16T18:29:46.320Z,1726511386.320 [ThrusterHE] Loaded
2024-09-16T18:29:46.320Z,1726511386.320 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-16T18:29:46.321Z,1726511386.321 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-16T18:29:46.321Z,1726511386.321 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-16T18:29:46.374Z,1726511386.374 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-16T18:29:46.424Z,1726511386.424 [VerticalControl] Loaded
2024-09-16T18:29:46.425Z,1726511386.425 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-16T18:29:46.427Z,1726511386.427 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-16T18:29:46.471Z,1726511386.471 [HorizontalControl] Loaded
2024-09-16T18:29:46.471Z,1726511386.471 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-16T18:29:46.474Z,1726511386.474 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-16T18:29:46.475Z,1726511386.475 [SpeedControl] Loaded
2024-09-16T18:29:46.476Z,1726511386.476 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-16T18:29:46.479Z,1726511386.479 [LoopControl](DEBUG): Construct LoopControl.
2024-09-16T18:29:46.479Z,1726511386.479 [LoopControl] Loaded
2024-09-16T18:29:46.479Z,1726511386.479 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-16T18:29:46.480Z,1726511386.480 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-16T18:29:46.480Z,1726511386.480 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-16T18:29:46.589Z,1726511386.589 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-16T18:29:46.590Z,1726511386.590 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-16T18:29:46.760Z,1726511386.760 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-16T18:29:46.761Z,1726511386.761 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-16T18:29:47.099Z,1726511387.099 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-16T18:29:47.099Z,1726511387.099 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-16T18:29:47.170Z,1726511387.170 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-16T18:29:47.176Z,1726511387.176 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-16T18:29:47.179Z,1726511387.179 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-16T18:29:47.190Z,1726511387.190 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-16T18:29:47.191Z,1726511387.191 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-09-16T18:29:47.192Z,1726511387.192 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1123
2024-09-16T18:29:47.196Z,1726511387.196 [Supervisor](INFO): Main Thread ID is 829
2024-09-16T18:29:47.196Z,1726511387.196 [Supervisor](DEBUG): Running supervisor.
2024-09-16T18:29:47.197Z,1726511387.197 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1124
2024-09-16T18:29:47.197Z,1726511387.197 [CommandExec](INFO): Initializing the command executive.
2024-09-16T18:29:47.199Z,1726511387.199 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1125
2024-09-16T18:29:47.202Z,1726511387.202 [controlThread ThreadHandler](INFO): Handler Thread ID is 1126
2024-09-16T18:29:47.202Z,1726511387.202 [controlThread](DEBUG): Initializing ControlThread
2024-09-16T18:29:47.203Z,1726511387.203 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-16T18:29:47.203Z,1726511387.203 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-16T18:29:47.204Z,1726511387.204 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-16T18:29:47.204Z,1726511387.204 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-16T18:29:47.205Z,1726511387.205 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-16T18:29:47.210Z,1726511387.210 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-16T18:29:47.211Z,1726511387.211 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-16T18:29:47.211Z,1726511387.211 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-16T18:29:47.212Z,1726511387.212 [SBIT](INFO): Initialize SBIT Component.
2024-09-16T18:29:47.212Z,1726511387.212 [SBIT](IMPORTANT): git: 2024-09-16_B
2024-09-16T18:29:47.212Z,1726511387.212 [SBIT](INFO): git hash: 99f9462fcf59f51f8462e4e844b71b4239fb11a8
2024-09-16T18:29:47.213Z,1726511387.213 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-16T18:29:47.214Z,1726511387.214 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-09-16T18:29:47.215Z,1726511387.215 [SBIT](INFO): Beginning SBIT in 104.000000 seconds.
2024-09-16T18:29:47.215Z,1726511387.215 [IBIT](INFO): Initialize IBIT Component.
2024-09-16T18:29:47.216Z,1726511387.216 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-16T18:29:47.217Z,1726511387.217 [logger ThreadHandler](INFO): Handler Thread ID is 1127
2024-09-16T18:29:47.228Z,1726511387.228 [CBIT](DEBUG): Initialized mux pins.
2024-09-16T18:29:47.228Z,1726511387.228 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-16T18:29:47.236Z,1726511387.236 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1128
2024-09-16T18:29:47.248Z,1726511387.248 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1129
2024-09-16T18:29:47.249Z,1726511387.249 [DATMMP](INFO): Start
2024-09-16T18:29:47.249Z,1726511387.249 [DATMMP](INFO): Powering up
2024-09-16T18:29:47.249Z,1726511387.249 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-16T18:29:47.252Z,1726511387.252 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-16T18:29:47.252Z,1726511387.252 [CBIT](DEBUG): Initializing heartbeat.
2024-09-16T18:29:47.254Z,1726511387.254 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1131
2024-09-16T18:29:47.256Z,1726511387.256 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-16T18:29:47.265Z,1726511387.265 [Onboard ThreadHandler](INFO): Handler Thread ID is 1132
2024-09-16T18:29:47.294Z,1726511387.294 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1133
2024-09-16T18:29:47.313Z,1726511387.313 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1134
2024-09-16T18:29:47.313Z,1726511387.313 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-16T18:29:47.317Z,1726511387.317 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1136
2024-09-16T18:29:47.320Z,1726511387.320 [WetLabsBB2FL](INFO): Powering up
2024-09-16T18:29:47.322Z,1726511387.322 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1138
2024-09-16T18:29:47.324Z,1726511387.324 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-16T18:29:47.324Z,1726511387.324 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-16T18:29:47.325Z,1726511387.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-09-16T18:29:47.325Z,1726511387.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-09-16T18:29:47.325Z,1726511387.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-09-16T18:29:47.325Z,1726511387.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-09-16T18:29:47.325Z,1726511387.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-09-16T18:29:47.325Z,1726511387.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-09-16T18:29:47.326Z,1726511387.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-09-16T18:29:47.326Z,1726511387.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-09-16T18:29:47.364Z,1726511387.364 [CBIT](DEBUG): Backplane powered.
2024-09-16T18:29:47.369Z,1726511387.369 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-16T18:29:47.371Z,1726511387.371 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-16T18:29:47.372Z,1726511387.372 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-16T18:29:47.372Z,1726511387.372 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-16T18:29:47.373Z,1726511387.373 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-16T18:29:47.374Z,1726511387.374 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-16T18:29:47.374Z,1726511387.374 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-16T18:29:47.375Z,1726511387.375 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-16T18:29:47.415Z,1726511387.415 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-16T18:29:47.432Z,1726511387.432 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T18:29:47.468Z,1726511387.468 [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-09-16T18:29:47.469Z,1726511387.469 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-16T18:29:47.469Z,1726511387.469 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-16T18:29:47.470Z,1726511387.470 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-16T18:29:47.500Z,1726511387.500 [DATMMP](INFO): Starting
2024-09-16T18:29:47.690Z,1726511387.690 [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-09-16T18:29:47.695Z,1726511387.695 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-16T18:29:47.696Z,1726511387.696 [Radio_Surface](INFO): Powering up
2024-09-16T18:29:47.701Z,1726511387.701 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-16T18:29:47.703Z,1726511387.703 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T18:29:47.723Z,1726511387.723 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-16T18:29:47.725Z,1726511387.725 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-16T18:29:47.752Z,1726511387.752 [DATMMP](INFO): Starting
2024-09-16T18:29:47.756Z,1726511387.756 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-16T18:29:47.759Z,1726511387.759 [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-09-16T18:29:47.771Z,1726511387.771 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-16T18:29:47.788Z,1726511387.788 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-16T18:29:47.851Z,1726511387.851 [Power24vConverter](INFO): Powering up.
2024-09-16T18:29:47.851Z,1726511387.851 [Sonardyne_Nano](INFO): Initializing.
2024-09-16T18:29:47.926Z,1726511387.926 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:29:47.932Z,1726511387.932 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-16T18:29:47.933Z,1726511387.933 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:29:47.940Z,1726511387.940 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-16T18:29:47.941Z,1726511387.941 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:29:47.948Z,1726511387.948 [MassServo](DEBUG): Initializing MassServo.
2024-09-16T18:29:47.949Z,1726511387.949 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-16T18:29:47.956Z,1726511387.956 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-16T18:29:47.957Z,1726511387.957 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-16T18:29:47.976Z,1726511387.976 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-16T18:29:48.004Z,1726511388.004 [DATMMP](INFO): Starting
2024-09-16T18:29:48.257Z,1726511388.257 [DATMMP](INFO): Starting
2024-09-16T18:29:48.508Z,1726511388.508 [DATMMP](INFO): Starting
2024-09-16T18:29:48.760Z,1726511388.760 [DATMMP](INFO): Starting
2024-09-16T18:29:49.012Z,1726511389.012 [DATMMP](INFO): Starting
2024-09-16T18:29:49.264Z,1726511389.264 [DATMMP](INFO): Starting
2024-09-16T18:29:49.516Z,1726511389.516 [DATMMP](INFO): Starting
2024-09-16T18:29:49.768Z,1726511389.768 [DATMMP](INFO): Starting
2024-09-16T18:29:49.798Z,1726511389.798 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-16T18:29:49.932Z,1726511389.932 [WetLabsBB2FL](INFO): Powering down
2024-09-16T18:29:50.020Z,1726511390.020 [DATMMP](INFO): Starting
2024-09-16T18:29:50.272Z,1726511390.272 [DATMMP](INFO): Starting
2024-09-16T18:29:50.524Z,1726511390.524 [DATMMP](INFO): Starting
2024-09-16T18:29:50.776Z,1726511390.776 [DATMMP](INFO): Starting
2024-09-16T18:29:51.029Z,1726511391.029 [DATMMP](INFO): Starting
2024-09-16T18:29:51.280Z,1726511391.280 [DATMMP](INFO): Starting
2024-09-16T18:29:51.452Z,1726511391.452 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2105
2024-09-16T18:29:51.532Z,1726511391.532 [DATMMP](INFO): Starting
2024-09-16T18:29:51.784Z,1726511391.784 [DATMMP](INFO): Starting
2024-09-16T18:29:52.036Z,1726511392.036 [DATMMP](INFO): Starting
2024-09-16T18:29:52.289Z,1726511392.289 [DATMMP](INFO): Starting
2024-09-16T18:29:52.540Z,1726511392.540 [DATMMP](INFO): Starting
2024-09-16T18:29:52.792Z,1726511392.792 [DATMMP](INFO): Starting
2024-09-16T18:29:53.045Z,1726511393.045 [DATMMP](INFO): Starting
2024-09-16T18:29:53.296Z,1726511393.296 [DATMMP](INFO): Starting
2024-09-16T18:29:53.548Z,1726511393.548 [DATMMP](INFO): Starting
2024-09-16T18:29:53.657Z,1726511393.657 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-16T18:29:53.800Z,1726511393.800 [DATMMP](INFO): Starting
2024-09-16T18:29:54.052Z,1726511394.052 [DATMMP](INFO): Starting
2024-09-16T18:29:54.304Z,1726511394.304 [DATMMP](INFO): Starting
2024-09-16T18:29:54.345Z,1726511394.345 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-09-16T18:29:54.556Z,1726511394.556 [DATMMP](INFO): Starting
2024-09-16T18:29:54.808Z,1726511394.808 [DATMMP](INFO): Starting
2024-09-16T18:29:55.061Z,1726511395.061 [DATMMP](INFO): Starting
2024-09-16T18:29:55.312Z,1726511395.312 [DATMMP](INFO): Starting
2024-09-16T18:29:55.564Z,1726511395.564 [DATMMP](INFO): Starting
2024-09-16T18:29:55.816Z,1726511395.816 [DATMMP](INFO): Starting
2024-09-16T18:29:56.068Z,1726511396.068 [DATMMP](INFO): Starting
2024-09-16T18:29:56.320Z,1726511396.320 [DATMMP](INFO): Starting
2024-09-16T18:29:56.572Z,1726511396.572 [DATMMP](INFO): Starting
2024-09-16T18:29:56.824Z,1726511396.824 [DATMMP](INFO): Starting
2024-09-16T18:29:57.076Z,1726511397.076 [DATMMP](INFO): Starting
2024-09-16T18:29:57.328Z,1726511397.328 [DATMMP](INFO): Starting
2024-09-16T18:29:57.580Z,1726511397.580 [DATMMP](INFO): Starting
2024-09-16T18:29:57.832Z,1726511397.832 [DATMMP](INFO): Starting
2024-09-16T18:29:58.084Z,1726511398.084 [DATMMP](INFO): Starting
2024-09-16T18:29:58.336Z,1726511398.336 [DATMMP](INFO): Starting
2024-09-16T18:29:58.588Z,1726511398.588 [DATMMP](INFO): Starting
2024-09-16T18:29:58.840Z,1726511398.840 [DATMMP](INFO): Starting
2024-09-16T18:29:59.092Z,1726511399.092 [DATMMP](INFO): Starting
2024-09-16T18:29:59.344Z,1726511399.344 [DATMMP](INFO): Starting
2024-09-16T18:29:59.596Z,1726511399.596 [DATMMP](INFO): Starting
2024-09-16T18:29:59.596Z,1726511399.596 [DATMMP](INFO): DAT read:
2024-09-16T18:29:59.597Z,1726511399.597 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-16T18:29:59.848Z,1726511399.848 [DATMMP](INFO): Starting
2024-09-16T18:30:00.100Z,1726511400.100 [DATMMP](INFO): Starting
2024-09-16T18:30:00.352Z,1726511400.352 [DATMMP](INFO): Starting
2024-09-16T18:30:00.604Z,1726511400.604 [DATMMP](INFO): Starting
2024-09-16T18:30:00.856Z,1726511400.856 [DATMMP](INFO): Starting
2024-09-16T18:30:01.108Z,1726511401.108 [DATMMP](INFO): Starting
2024-09-16T18:30:01.360Z,1726511401.360 [DATMMP](INFO): Starting
2024-09-16T18:30:01.361Z,1726511401.361 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-16T18:30:01.362Z,1726511401.362 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-16T18:30:01.362Z,1726511401.362 [DATMMP](INFO): DAT read: Sep 16 2024 18:29:55
2024-09-16T18:30:01.612Z,1726511401.612 [DATMMP](INFO): Starting
2024-09-16T18:30:01.864Z,1726511401.864 [DATMMP](INFO): Starting
2024-09-16T18:30:02.116Z,1726511402.116 [DATMMP](INFO): Starting
2024-09-16T18:30:02.368Z,1726511402.368 [DATMMP](INFO): Starting
2024-09-16T18:30:02.620Z,1726511402.620 [DATMMP](INFO): Starting
2024-09-16T18:30:02.621Z,1726511402.621 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-16T18:30:02.622Z,1726511402.622 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-09-16T18:30:02.622Z,1726511402.622 [DATMMP](INFO): commRate: 800
2024-09-16T18:30:02.872Z,1726511402.872 [DATMMP](INFO): Starting
2024-09-16T18:30:03.125Z,1726511403.125 [DATMMP](INFO): Starting
2024-09-16T18:30:03.376Z,1726511403.376 [DATMMP](INFO): Starting
2024-09-16T18:30:03.628Z,1726511403.628 [DATMMP](INFO): Starting
2024-09-16T18:30:03.881Z,1726511403.881 [DATMMP](INFO): Starting
2024-09-16T18:30:04.132Z,1726511404.132 [DATMMP](INFO): Starting
2024-09-16T18:30:04.384Z,1726511404.384 [DATMMP](INFO): Starting
2024-09-16T18:30:04.636Z,1726511404.636 [DATMMP](INFO): Starting
2024-09-16T18:30:04.688Z,1726511404.688 [DATMMP](INFO): entering command mode
2024-09-16T18:30:04.888Z,1726511404.888 [DATMMP](INFO): Starting
2024-09-16T18:30:04.888Z,1726511404.888 [DATMMP](INFO): DAT read:
2024-09-16T18:30:04.889Z,1726511404.889 [DATMMP](INFO): DAT read: user:1>
2024-09-16T18:30:04.889Z,1726511404.889 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-16T18:30:04.889Z,1726511404.889 [DATMMP](INFO): entering MMP mode
2024-09-16T18:30:05.140Z,1726511405.140 [DATMMP](INFO): Starting
2024-09-16T18:30:05.141Z,1726511405.141 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T18:30:05.142Z,1726511405.142 [DATMMP](INFO): status rx: x1
2024-09-16T18:30:05.143Z,1726511405.143 [DATMMP](INFO): MMP status: 1: started
2024-09-16T18:30:05.143Z,1726511405.143 [DATMMP](INFO): Received message type: status
2024-09-16T18:30:05.143Z,1726511405.143 [DATMMP](INFO): Handled
2024-09-16T18:30:05.392Z,1726511405.392 [DATMMP](INFO): Starting
2024-09-16T18:30:05.393Z,1726511405.393 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-16T18:30:05.393Z,1726511405.393 [DATMMP](INFO): Setting local address: 9
2024-09-16T18:30:05.395Z,1726511405.395 [DATMMP](INFO): Setting time to: 18:30:5 And date to:9/16/2024
2024-09-16T18:30:05.644Z,1726511405.644 [DATMMP](INFO): Starting
2024-09-16T18:30:05.644Z,1726511405.644 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T18:30:05.645Z,1726511405.645 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-16T18:30:05.645Z,1726511405.645 [DATMMP](INFO): Received message type: mdmpower
2024-09-16T18:30:05.646Z,1726511405.646 [DATMMP](INFO): Handled
2024-09-16T18:30:05.896Z,1726511405.896 [DATMMP](INFO): Starting
2024-09-16T18:30:05.896Z,1726511405.896 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-16T18:30:05.897Z,1726511405.897 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-16T18:30:05.897Z,1726511405.897 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-16T18:30:05.897Z,1726511405.897 [DATMMP](INFO): Match for : sreg_txpower
2024-09-16T18:30:05.897Z,1726511405.897 [DATMMP](INFO): ACK
2024-09-16T18:30:06.148Z,1726511406.148 [DATMMP](INFO): Starting
2024-09-16T18:30:06.148Z,1726511406.148 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T18:30:06.149Z,1726511406.149 [DATMMP](INFO): mdmlocal rx: x9 x0 x0 x0
2024-09-16T18:30:06.149Z,1726511406.149 [DATMMP](INFO): Received message type: mdmlocal
2024-09-16T18:30:06.149Z,1726511406.149 [DATMMP](INFO): Handled
2024-09-16T18:30:06.400Z,1726511406.400 [DATMMP](INFO): Starting
2024-09-16T18:30:06.401Z,1726511406.401 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-16T18:30:06.401Z,1726511406.401 [DATMMP](INFO): sreg_locaddr rx: x9
2024-09-16T18:30:06.402Z,1726511406.402 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-16T18:30:06.402Z,1726511406.402 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-16T18:30:06.402Z,1726511406.402 [DATMMP](INFO): ACK
2024-09-16T18:30:06.652Z,1726511406.652 [DATMMP](INFO): Starting
2024-09-16T18:30:06.652Z,1726511406.652 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-16T18:30:06.654Z,1726511406.654 [DATMMP](INFO): timedate rx: x0 x12 x1E x5 x8 x10 x7C x0
2024-09-16T18:30:06.654Z,1726511406.654 [DATMMP](INFO): Received message type: timedate
2024-09-16T18:30:06.654Z,1726511406.654 [DATMMP](INFO): Match for : timedate
2024-09-16T18:30:06.654Z,1726511406.654 [DATMMP](INFO): ACK
2024-09-16T18:30:06.904Z,1726511406.904 [DATMMP](INFO): Starting
2024-09-16T18:30:06.904Z,1726511406.904 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T18:30:06.905Z,1726511406.905 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-16T18:30:06.905Z,1726511406.905 [DATMMP](INFO): Received message type: sys_verb
2024-09-16T18:30:06.906Z,1726511406.906 [DATMMP](INFO): Handled
2024-09-16T18:30:07.156Z,1726511407.156 [DATMMP](INFO): Starting
2024-09-16T18:30:07.156Z,1726511407.156 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-16T18:30:07.157Z,1726511407.157 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-16T18:30:07.157Z,1726511407.157 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-16T18:30:07.157Z,1726511407.157 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-16T18:30:07.157Z,1726511407.157 [DATMMP](INFO): ACK
2024-09-16T18:30:07.408Z,1726511407.408 [DATMMP](INFO): Starting
2024-09-16T18:30:07.408Z,1726511407.408 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-16T18:30:07.409Z,1726511407.409 [DATMMP](INFO): version rx: x2 x5
2024-09-16T18:30:07.409Z,1726511407.409 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-16T18:30:07.409Z,1726511407.409 [DATMMP](INFO): Received message type: version
2024-09-16T18:30:07.410Z,1726511407.410 [DATMMP](INFO): Match for : version
2024-09-16T18:30:07.410Z,1726511407.410 [DATMMP](INFO): ACK
2024-09-16T18:30:07.410Z,1726511407.410 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-16T18:30:15.257Z,1726511415.257 [NAL9602](INFO): Powering up NAL9602
2024-09-16T18:30:21.777Z,1726511421.777 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T18:30:21.778Z,1726511421.778 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-16T18:30:21.778Z,1726511421.778 [DATMMP](INFO): Received message type: timesync
2024-09-16T18:30:21.778Z,1726511421.778 [DATMMP](INFO): Handled
2024-09-16T18:30:26.166Z,1726511426.166 [NAL9602](INFO): NAL9602 initialized
2024-09-16T18:30:31.872Z,1726511431.872 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T18:30:31.873Z,1726511431.873 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-16T18:30:31.873Z,1726511431.873 [DATMMP](INFO): Received message type: timesync
2024-09-16T18:30:31.874Z,1726511431.874 [DATMMP](INFO): Handled
2024-09-16T18:31:24.816Z,1726511484.816 [Waterlinked](INFO): Powering down
2024-09-16T18:31:31.656Z,1726511491.656 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-16T18:31:31.661Z,1726511491.661 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:31:42.589Z,1726511502.589 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007644
CHAN A1 (24V): 0.000157
CHAN A2 (12V): -0.005987
CHAN A3 (5V): -0.003087
CHAN B0 (3.3V): -0.001245
CHAN B1 (3.15aV): -0.001161
CHAN B2 (3.15bV): -0.001579
CHAN B3 (GND): -0.000277
OPEN: 0.004674
Full Scale: +/- 1 mA
2024-09-16T18:32:25.359Z,1726511545.359 [SBIT](IMPORTANT): SBIT PASSED
2024-09-16T18:32:25.359Z,1726511545.359 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-16T18:32:25.360Z,1726511545.360 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-09-16T18:32:25.361Z,1726511545.361 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-09-16T18:32:25.361Z,1726511545.361 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-16T18:32:25.361Z,1726511545.361 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;
2024-09-16T18:32:25.361Z,1726511545.361 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-09-16T18:32:25.361Z,1726511545.361 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=180.764238 cubic_centimeter;
2024-09-16T18:32:25.361Z,1726511545.361 [SBIT](IMPORTANT): VerticalControl.massDefault=21.225185 millimeter;
2024-09-16T18:32:25.763Z,1726511545.763 [MissionManager](IMPORTANT): Started mission Startup
2024-09-16T18:32:25.763Z,1726511545.763 [Startup] Running Loop=1
2024-09-16T18:32:25.763Z,1726511545.763 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-16T18:32:25.763Z,1726511545.763 [Startup:A.GoToSurface] Running Loop=1
2024-09-16T18:32:25.763Z,1726511545.763 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T18:32:25.764Z,1726511545.764 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T18:32:25.765Z,1726511545.765 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T18:32:25.765Z,1726511545.765 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T18:32:25.765Z,1726511545.765 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T18:32:25.766Z,1726511545.766 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T18:32:25.766Z,1726511545.766 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T18:32:25.768Z,1726511545.768 [Startup:StartupSatComms] Running Loop=1
2024-09-16T18:32:25.768Z,1726511545.768 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-16T18:32:25.768Z,1726511545.768 [Startup:StartupSatComms:A] Running Loop=1
2024-09-16T18:32:26.171Z,1726511546.171 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-16T18:32:47.577Z,1726511567.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-16T18:32:47.577Z,1726511567.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T18:32:47.578Z,1726511567.578 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-09-16T18:32:47.578Z,1726511567.578 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-16T18:32:47.589Z,1726511567.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T18:32:47.589Z,1726511567.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-16T18:32:48.001Z,1726511568.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T18:32:48.001Z,1726511568.001 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-16T18:32:48.001Z,1726511568.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-16T18:32:48.001Z,1726511568.001 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-09-16T18:32:48.381Z,1726511568.381 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-16T18:33:10.322Z,1726511590.322 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004472
2024-09-16T18:33:25.982Z,1726511605.982 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-16T18:32:25.8Z
2024-09-16T18:33:25.982Z,1726511605.982 [Startup:StartupSatComms:A] Stopped
2024-09-16T18:33:25.982Z,1726511605.982 [Startup:StartupSatComms:B] Running Loop=1
2024-09-16T18:33:26.375Z,1726511606.375 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T18:33:33.699Z,1726511613.699 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240916T180604/Courier0010.lzma
2024-09-16T18:33:34.702Z,1726511614.702 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180604/Courier0010.lzma.bak
2024-09-16T18:33:34.702Z,1726511614.702 [DataOverHttps](INFO): SBD MOMSN=23190983
2024-09-16T18:33:50.377Z,1726511630.377 [CommandExec](IMPORTANT): got command ibit
2024-09-16T18:33:50.770Z,1726511630.770 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-16T18:33:50.770Z,1726511630.770 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-16T18:33:50.773Z,1726511630.773 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:33:52.619Z,1726511632.619 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240916T182935/Courier0000.lzma
2024-09-16T18:33:53.622Z,1726511633.622 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0000.lzma.bak
2024-09-16T18:33:53.622Z,1726511633.622 [DataOverHttps](INFO): SBD MOMSN=23190995
2024-09-16T18:34:01.555Z,1726511641.555 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007541
CHAN A1 (24V): 0.000240
CHAN A2 (12V): -0.006314
CHAN A3 (5V): -0.002404
CHAN B0 (3.3V): -0.001420
CHAN B1 (3.15aV): -0.001700
CHAN B2 (3.15bV): -0.001146
CHAN B3 (GND): -0.000042
OPEN: 0.004541
Full Scale: +/- 1 mA
2024-09-16T18:34:09.184Z,1726511649.184 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20240916T180604/Express0011.lzma
2024-09-16T18:34:10.186Z,1726511650.186 [DataOverHttps](INFO): Moved sent file to Logs/20240916T180604/Express0011.lzma.bak
2024-09-16T18:34:10.186Z,1726511650.186 [DataOverHttps](INFO): SBD MOMSN=23191026
2024-09-16T18:34:25.781Z,1726511665.781 [DataOverHttps](INFO): Sending 869 bytes from file Logs/20240916T182935/Express0001.lzma
2024-09-16T18:34:26.782Z,1726511666.782 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0001.lzma.bak
2024-09-16T18:34:26.782Z,1726511666.782 [DataOverHttps](INFO): SBD MOMSN=23191035
2024-09-16T18:35:13.745Z,1726511713.745 [CommandLine](INFO): End of History
2024-09-16T18:35:13.759Z,1726511713.759 [CommandLine](INFO): End of History
2024-09-16T18:35:13.827Z,1726511713.827 [CommandLine](INFO): End of History
2024-09-16T18:35:28.818Z,1726511728.818 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T18:35:35.617Z,1726511735.617 [CommandExec](IMPORTANT): got command ibit
2024-09-16T18:35:35.717Z,1726511735.717 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-16T18:35:35.717Z,1726511735.717 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-16T18:35:35.721Z,1726511735.721 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:35:46.719Z,1726511746.719 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007219
CHAN A1 (24V): 0.000234
CHAN A2 (12V): -0.006134
CHAN A3 (5V): -0.002140
CHAN B0 (3.3V): -0.001428
CHAN B1 (3.15aV): -0.001521
CHAN B2 (3.15bV): -0.001599
CHAN B3 (GND): -0.000159
OPEN: 0.004774
Full Scale: +/- 1 mA
2024-09-16T18:35:48.705Z,1726511748.705 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-16T18:35:48.705Z,1726511748.705 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T18:35:48.706Z,1726511748.706 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-09-16T18:35:48.706Z,1726511748.706 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-16T18:35:48.787Z,1726511748.787 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T18:35:48.800Z,1726511748.800 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-16T18:35:49.169Z,1726511749.169 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T18:35:49.169Z,1726511749.169 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-16T18:35:49.169Z,1726511749.169 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-16T18:35:49.169Z,1726511749.169 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-09-16T18:35:49.552Z,1726511749.552 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-16T18:36:06.289Z,1726511766.289 [CommandExec](IMPORTANT): got command ibit
2024-09-16T18:37:26.490Z,1726511846.490 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-09-16T18:37:26.865Z,1726511846.865 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2024-09-16T18:37:27.284Z,1726511847.284 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-16T18:37:27.284Z,1726511847.284 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-16T18:37:27.284Z,1726511847.284 [IBIT](IMPORTANT): Pressure:9.332458 PSI
2024-09-16T18:37:27.285Z,1726511847.285 [IBIT](IMPORTANT): Humidity:17.623001 %
2024-09-16T18:37:27.733Z,1726511847.733 [IBIT](IMPORTANT): Vehicle Pitch:0.647427 degrees
2024-09-16T18:37:27.733Z,1726511847.733 [IBIT](IMPORTANT): Vehicle Roll:2.512041 degrees
2024-09-16T18:37:27.733Z,1726511847.733 [IBIT](IMPORTANT): Vehicle Heading:170.709930 degrees
2024-09-16T18:37:28.103Z,1726511848.103 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-16T18:37:28.108Z,1726511848.108 [IBIT](IMPORTANT): buoyancyNeutral: 180.764236 cc
2024-09-16T18:37:28.109Z,1726511848.109 [IBIT](IMPORTANT): massDefault: 2.122519 cm
2024-09-16T18:37:28.109Z,1726511848.109 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-09-16T18:37:28.109Z,1726511848.109 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-09-16T18:37:28.109Z,1726511848.109 [IBIT](IMPORTANT): IBIT FAILED
2024-09-16T18:37:28.502Z,1726511848.502 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-16T18:33:25.0Z
2024-09-16T18:37:28.502Z,1726511848.502 [Startup:StartupSatComms:B] Stopped
2024-09-16T18:37:28.502Z,1726511848.502 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-16T18:37:28.503Z,1726511848.503 [Startup:StartupSatComms] Stopped
2024-09-16T18:37:28.503Z,1726511848.503 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-16T18:37:28.540Z,1726511848.540 [Startup](INFO): Completed Startup
2024-09-16T18:37:28.542Z,1726511848.542 [MissionManager](INFO): Startup is completed.
2024-09-16T18:37:28.542Z,1726511848.542 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-16T18:37:28.542Z,1726511848.542 [Startup] Stopped
2024-09-16T18:37:28.542Z,1726511848.542 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-16T18:37:28.542Z,1726511848.542 [Startup:A.GoToSurface] Stopped
2024-09-16T18:37:28.542Z,1726511848.542 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T18:37:28.998Z,1726511848.998 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T18:37:28.998Z,1726511848.998 [Default] Running Loop=1
2024-09-16T18:37:28.998Z,1726511848.998 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T18:37:28.998Z,1726511848.998 [Default:B.GoToSurface] Running Loop=1
2024-09-16T18:37:28.998Z,1726511848.998 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T18:37:28.998Z,1726511848.998 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T18:37:28.999Z,1726511848.999 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T18:37:28.999Z,1726511848.999 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T18:37:28.999Z,1726511848.999 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T18:37:29.004Z,1726511849.004 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T18:37:29.004Z,1726511849.004 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T18:37:29.004Z,1726511849.004 [Default:A.Wait] Running Loop=1
2024-09-16T18:37:29.005Z,1726511849.005 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:37:42.211Z,1726511862.211 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T18:37:42.211Z,1726511862.211 [Default:A.Wait] Stopped
2024-09-16T18:37:42.211Z,1726511862.211 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:37:42.622Z,1726511862.622 [Default:CheckIn] Running Loop=1
2024-09-16T18:37:42.622Z,1726511862.622 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:37:42.622Z,1726511862.622 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:37:43.019Z,1726511863.019 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-16T18:38:34.729Z,1726511914.729 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183833.00,A,3648.17155,N,12147.27228,W,1.924,115.71,160924,,,A*79
2024-09-16T18:38:34.733Z,1726511914.733 [NAL9602](INFO): GPS fix at 20240916T183833: (36.802859, -121.787871)
2024-09-16T18:38:34.747Z,1726511914.747 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:38:34.747Z,1726511914.747 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:38:35.166Z,1726511915.166 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T18:38:44.140Z,1726511924.140 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240916T182935/Courier0004.lzma
2024-09-16T18:38:45.142Z,1726511925.142 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0004.lzma.bak
2024-09-16T18:38:45.142Z,1726511925.142 [DataOverHttps](INFO): SBD MOMSN=23191058
2024-09-16T18:39:01.013Z,1726511941.013 [DataOverHttps](INFO): Sending 958 bytes from file Logs/20240916T182935/Express0005.lzma
2024-09-16T18:39:02.014Z,1726511942.014 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0005.lzma.bak
2024-09-16T18:39:02.014Z,1726511942.014 [DataOverHttps](INFO): SBD MOMSN=23191069
2024-09-16T18:39:03.081Z,1726511943.081 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:39:03.081Z,1726511943.081 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:39:03.081Z,1726511943.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:39:07.469Z,1726511947.469 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:40:36.061Z,1726512036.061 [CommandExec](IMPORTANT): got command ibit
2024-09-16T18:40:36.349Z,1726512036.349 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-16T18:40:36.349Z,1726512036.349 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-16T18:40:36.369Z,1726512036.369 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:40:37.947Z,1726512037.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184037.00,A,3648.15412,N,12147.25652,W,0.019,204.14,160924,,,A*7B
2024-09-16T18:40:37.949Z,1726512037.949 [NAL9602](INFO): GPS fix at 20240916T184037: (36.802569, -121.787609)
2024-09-16T18:40:47.292Z,1726512047.292 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006489
CHAN A1 (24V): 0.000455
CHAN A2 (12V): -0.005785
CHAN A3 (5V): -0.002121
CHAN B0 (3.3V): -0.001152
CHAN B1 (3.15aV): -0.001362
CHAN B2 (3.15bV): -0.001611
CHAN B3 (GND): -0.000615
OPEN: 0.004453
Full Scale: +/- 1 mA
2024-09-16T18:41:08.645Z,1726512068.645 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-16T18:41:08.728Z,1726512068.728 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-09-16T18:41:22.870Z,1726512082.870 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802570 Longitude: -121.787605
2024-09-16T18:41:23.233Z,1726512083.233 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2024-09-16T18:41:23.653Z,1726512083.653 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-16T18:41:23.654Z,1726512083.654 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-16T18:41:23.655Z,1726512083.655 [IBIT](IMPORTANT): Pressure:9.336185 PSI
2024-09-16T18:41:23.655Z,1726512083.655 [IBIT](IMPORTANT): Humidity:17.623001 %
2024-09-16T18:41:24.035Z,1726512084.035 [IBIT](IMPORTANT): Vehicle Pitch:-0.784274 degrees
2024-09-16T18:41:24.036Z,1726512084.036 [IBIT](IMPORTANT): Vehicle Roll:-0.575416 degrees
2024-09-16T18:41:24.036Z,1726512084.036 [IBIT](IMPORTANT): Vehicle Heading:202.528656 degrees
2024-09-16T18:41:24.430Z,1726512084.430 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-16T18:41:24.431Z,1726512084.431 [IBIT](IMPORTANT): buoyancyNeutral: 180.764236 cc
2024-09-16T18:41:24.431Z,1726512084.431 [IBIT](IMPORTANT): massDefault: 2.122519 cm
2024-09-16T18:41:24.431Z,1726512084.431 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-09-16T18:41:24.432Z,1726512084.432 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-09-16T18:41:24.432Z,1726512084.432 [IBIT](IMPORTANT): IBIT PASSED
2024-09-16T18:41:29.740Z,1726512089.740 [CommandLine](INFO): End of History
2024-09-16T18:41:32.121Z,1726512092.121 [CommandExec](IMPORTANT): got command ibit
2024-09-16T18:41:32.505Z,1726512092.505 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-16T18:41:32.505Z,1726512092.505 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-16T18:41:32.508Z,1726512092.508 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T18:41:35.715Z,1726512095.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=17471, MT Status=2, MTMSN=0
2024-09-16T18:41:35.715Z,1726512095.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T18:41:43.459Z,1726512103.459 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006539
CHAN A1 (24V): 0.000614
CHAN A2 (12V): -0.005708
CHAN A3 (5V): -0.002093
CHAN B0 (3.3V): -0.001061
CHAN B1 (3.15aV): -0.001350
CHAN B2 (3.15bV): -0.001685
CHAN B3 (GND): -0.000548
OPEN: 0.004312
Full Scale: +/- 1 mA
2024-09-16T18:42:03.205Z,1726512123.205 [NAL9602](INFO): SBD MO Status=0, MOMSN=17471, MT Status=0, MTMSN=0
2024-09-16T18:42:03.205Z,1726512123.205 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:42:04.423Z,1726512124.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184203.00,A,3648.13787,N,12147.23249,W,2.235,97.39,160924,,,A*46
2024-09-16T18:42:04.425Z,1726512124.425 [NAL9602](INFO): GPS fix at 20240916T184203: (36.802298, -121.787208)
2024-09-16T18:42:06.835Z,1726512126.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184206.00,A,3648.13810,N,12147.23094,W,1.458,82.64,160924,,,A*42
2024-09-16T18:42:06.847Z,1726512126.847 [NAL9602](INFO): GPS fix at 20240916T184206: (36.802302, -121.787182)
2024-09-16T18:42:10.067Z,1726512130.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184209.00,A,3648.13865,N,12147.22984,W,1.089,76.22,160924,,,A*47
2024-09-16T18:42:10.081Z,1726512130.081 [NAL9602](INFO): GPS fix at 20240916T184209: (36.802311, -121.787164)
2024-09-16T18:42:12.893Z,1726512132.893 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184212.00,A,3648.13877,N,12147.22990,W,0.739,76.22,160924,,,A*46
2024-09-16T18:42:12.895Z,1726512132.895 [NAL9602](INFO): GPS fix at 20240916T184212: (36.802313, -121.787165)
2024-09-16T18:42:16.123Z,1726512136.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184215.00,A,3648.13927,N,12147.22966,W,0.680,76.22,160924,,,A*4F
2024-09-16T18:42:16.125Z,1726512136.125 [NAL9602](INFO): GPS fix at 20240916T184215: (36.802321, -121.787161)
2024-09-16T18:42:18.955Z,1726512138.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184218.00,A,3648.13987,N,12147.22981,W,0.778,76.22,160924,,,A*47
2024-09-16T18:42:18.958Z,1726512138.958 [NAL9602](INFO): GPS fix at 20240916T184218: (36.802331, -121.787164)
2024-09-16T18:42:19.005Z,1726512139.005 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802330 Longitude: -121.787163
2024-09-16T18:42:19.399Z,1726512139.399 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2024-09-16T18:42:19.779Z,1726512139.779 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-16T18:42:19.779Z,1726512139.779 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-16T18:42:19.779Z,1726512139.779 [IBIT](IMPORTANT): Pressure:9.262032 PSI
2024-09-16T18:42:19.780Z,1726512139.780 [IBIT](IMPORTANT): Humidity:17.592480 %
2024-09-16T18:42:20.180Z,1726512140.180 [IBIT](IMPORTANT): Vehicle Pitch:-0.977532 degrees
2024-09-16T18:42:20.180Z,1726512140.180 [IBIT](IMPORTANT): Vehicle Roll:2.115698 degrees
2024-09-16T18:42:20.181Z,1726512140.181 [IBIT](IMPORTANT): Vehicle Heading:43.420303 degrees
2024-09-16T18:42:20.606Z,1726512140.606 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-16T18:42:20.606Z,1726512140.606 [IBIT](IMPORTANT): buoyancyNeutral: 180.764236 cc
2024-09-16T18:42:20.607Z,1726512140.607 [IBIT](IMPORTANT): massDefault: 2.122519 cm
2024-09-16T18:42:20.607Z,1726512140.607 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2024-09-16T18:42:20.607Z,1726512140.607 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-09-16T18:42:20.607Z,1726512140.607 [IBIT](IMPORTANT): IBIT PASSED
2024-09-16T18:42:51.273Z,1726512171.273 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:44:03.190Z,1726512243.190 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2024-09-16T18:44:03.193Z,1726512243.193 [BPC1](INFO): Received data from all battery sticks.
2024-09-16T18:44:03.608Z,1726512243.608 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:44:03.608Z,1726512243.608 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:44:03.608Z,1726512243.608 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:44:03.608Z,1726512243.608 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:44:04.002Z,1726512244.002 [Default:CheckIn:D] Stopped
2024-09-16T18:44:04.002Z,1726512244.002 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.583396 min
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn:E] Stopped
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn] Stopped
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn](INFO): Running loop #2
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn] Running Loop=2
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:44:04.410Z,1726512244.410 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:44:06.416Z,1726512246.416 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184405.00,A,3648.14045,N,12147.22980,W,0.058,76.22,160924,,,A*49
2024-09-16T18:44:06.418Z,1726512246.418 [NAL9602](INFO): GPS fix at 20240916T184405: (36.802341, -121.787163)
2024-09-16T18:44:06.431Z,1726512246.431 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:44:06.431Z,1726512246.431 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:44:13.624Z,1726512253.624 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20240916T182935/Courier0007.lzma
2024-09-16T18:44:14.626Z,1726512254.626 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0007.lzma.bak
2024-09-16T18:44:14.626Z,1726512254.626 [DataOverHttps](INFO): SBD MOMSN=23191130
2024-09-16T18:44:18.943Z,1726512258.943 [NAL9602](INFO): SBD MO Status=0, MOMSN=17472, MT Status=0, MTMSN=0
2024-09-16T18:44:18.943Z,1726512258.943 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:44:30.521Z,1726512270.521 [DataOverHttps](INFO): Sending 923 bytes from file Logs/20240916T182935/Express0008.lzma
2024-09-16T18:44:31.522Z,1726512271.522 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0008.lzma.bak
2024-09-16T18:44:31.522Z,1726512271.522 [DataOverHttps](INFO): SBD MOMSN=23191164
2024-09-16T18:44:32.684Z,1726512272.684 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:44:32.684Z,1726512272.684 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:44:32.684Z,1726512272.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:44:49.649Z,1726512289.649 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:48:27.101Z,1726512507.101 [CommandExec](IMPORTANT): got command failComponent
2024-09-16T18:48:27.101Z,1726512507.101 [CommandExec](IMPORTANT): Failed components:
2024-09-16T18:48:27.101Z,1726512507.101 [CommandExec](IMPORTANT): No failed Components.
2024-09-16T18:49:33.341Z,1726512573.341 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:49:33.341Z,1726512573.341 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:49:33.341Z,1726512573.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:49:33.341Z,1726512573.341 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:49:33.716Z,1726512573.716 [Default:CheckIn:D] Stopped
2024-09-16T18:49:33.716Z,1726512573.716 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:49:34.122Z,1726512574.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.078627 min
2024-09-16T18:49:34.122Z,1726512574.122 [Default:CheckIn:E] Stopped
2024-09-16T18:49:34.122Z,1726512574.122 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:49:34.122Z,1726512574.122 [Default:CheckIn] Stopped
2024-09-16T18:49:34.122Z,1726512574.122 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:49:34.123Z,1726512574.123 [Default:CheckIn](INFO): Running loop #3
2024-09-16T18:49:34.123Z,1726512574.123 [Default:CheckIn] Running Loop=3
2024-09-16T18:49:34.123Z,1726512574.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:49:34.123Z,1726512574.123 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:49:36.123Z,1726512576.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184935.00,A,3648.14009,N,12147.23043,W,0.019,76.22,160924,,,D*48
2024-09-16T18:49:36.126Z,1726512576.126 [NAL9602](INFO): GPS fix at 20240916T184935: (36.802335, -121.787174)
2024-09-16T18:49:36.162Z,1726512576.162 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:49:36.162Z,1726512576.162 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:49:43.111Z,1726512583.111 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0010.lzma
2024-09-16T18:49:44.114Z,1726512584.114 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0010.lzma.bak
2024-09-16T18:49:44.114Z,1726512584.114 [DataOverHttps](INFO): SBD MOMSN=23191217
2024-09-16T18:49:47.435Z,1726512587.435 [NAL9602](INFO): SBD MO Status=0, MOMSN=17473, MT Status=0, MTMSN=0
2024-09-16T18:49:47.435Z,1726512587.435 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:49:59.887Z,1726512599.887 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20240916T182935/Express0011.lzma
2024-09-16T18:50:00.890Z,1726512600.890 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0011.lzma.bak
2024-09-16T18:50:00.890Z,1726512600.890 [DataOverHttps](INFO): SBD MOMSN=23191225
2024-09-16T18:50:01.998Z,1726512601.998 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:50:01.998Z,1726512601.998 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:50:01.998Z,1726512601.998 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:50:18.141Z,1726512618.141 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T18:55:02.589Z,1726512902.589 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T18:55:02.589Z,1726512902.589 [Default:CheckIn:C.Wait] Stopped
2024-09-16T18:55:02.589Z,1726512902.589 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T18:55:02.589Z,1726512902.589 [Default:CheckIn:D] Running Loop=1
2024-09-16T18:55:02.985Z,1726512902.985 [Default:CheckIn:D] Stopped
2024-09-16T18:55:02.985Z,1726512902.985 [Default:CheckIn:E] Running Loop=1
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.566445 min
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn:E] Stopped
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn] Stopped
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn](INFO): Running loop #4
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn] Running Loop=4
2024-09-16T18:55:03.389Z,1726512903.389 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T18:55:03.390Z,1726512903.390 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T18:55:05.403Z,1726512905.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185504.00,A,3648.14982,N,12147.22212,W,0.078,62.80,160924,,,D*40
2024-09-16T18:55:05.406Z,1726512905.406 [NAL9602](INFO): GPS fix at 20240916T185504: (36.802497, -121.787035)
2024-09-16T18:55:05.438Z,1726512905.438 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T18:55:05.438Z,1726512905.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T18:55:12.951Z,1726512912.951 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T182935/Courier0013.lzma
2024-09-16T18:55:13.954Z,1726512913.954 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0013.lzma.bak
2024-09-16T18:55:13.954Z,1726512913.954 [DataOverHttps](INFO): SBD MOMSN=23191267
2024-09-16T18:55:29.731Z,1726512929.731 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T182935/Express0014.lzma
2024-09-16T18:55:30.734Z,1726512930.734 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0014.lzma.bak
2024-09-16T18:55:30.734Z,1726512930.734 [DataOverHttps](INFO): SBD MOMSN=23191270
2024-09-16T18:55:32.073Z,1726512932.073 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T18:55:32.073Z,1726512932.073 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T18:55:32.073Z,1726512932.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T18:55:48.223Z,1726512948.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=17474, MT Status=2, MTMSN=0
2024-09-16T18:55:48.223Z,1726512948.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T18:56:32.254Z,1726512992.254 [NAL9602](INFO): SBD MO Status=0, MOMSN=17474, MT Status=0, MTMSN=0
2024-09-16T18:56:32.254Z,1726512992.254 [NAL9602](INFO): No messages in MT queue
2024-09-16T18:57:02.955Z,1726513022.955 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:00:32.662Z,1726513232.662 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:00:32.662Z,1726513232.662 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:00:32.662Z,1726513232.662 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:00:32.662Z,1726513232.662 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:00:33.055Z,1726513233.055 [Default:CheckIn:D] Stopped
2024-09-16T19:00:33.055Z,1726513233.055 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.067621 min
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn:E] Stopped
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn] Stopped
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn](INFO): Running loop #5
2024-09-16T19:00:33.465Z,1726513233.465 [Default:CheckIn] Running Loop=5
2024-09-16T19:00:33.466Z,1726513233.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:00:33.466Z,1726513233.466 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:00:35.470Z,1726513235.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190034.00,A,3648.15058,N,12147.22185,W,0.019,62.80,160924,,,D*47
2024-09-16T19:00:35.472Z,1726513235.472 [NAL9602](INFO): GPS fix at 20240916T190034: (36.802510, -121.787031)
2024-09-16T19:00:35.505Z,1726513235.505 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:00:35.505Z,1726513235.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:00:43.133Z,1726513243.133 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0016.lzma
2024-09-16T19:00:44.130Z,1726513244.130 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0016.lzma.bak
2024-09-16T19:00:44.130Z,1726513244.130 [DataOverHttps](INFO): SBD MOMSN=23191329
2024-09-16T19:00:52.438Z,1726513252.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=17475, MT Status=0, MTMSN=0
2024-09-16T19:00:52.438Z,1726513252.438 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:01:00.012Z,1726513260.012 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T182935/Express0017.lzma
2024-09-16T19:01:01.014Z,1726513261.014 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0017.lzma.bak
2024-09-16T19:01:01.014Z,1726513261.014 [DataOverHttps](INFO): SBD MOMSN=23191332
2024-09-16T19:01:02.153Z,1726513262.153 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:01:02.153Z,1726513262.153 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:01:02.153Z,1726513262.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:01:23.142Z,1726513283.142 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:06:02.776Z,1726513562.776 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:06:02.776Z,1726513562.776 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:06:02.776Z,1726513562.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:06:02.776Z,1726513562.776 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:06:03.183Z,1726513563.183 [Default:CheckIn:D] Stopped
2024-09-16T19:06:03.183Z,1726513563.183 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:06:03.594Z,1726513563.594 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.569745 min
2024-09-16T19:06:03.594Z,1726513563.594 [Default:CheckIn:E] Stopped
2024-09-16T19:06:03.594Z,1726513563.594 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:06:03.594Z,1726513563.594 [Default:CheckIn] Stopped
2024-09-16T19:06:03.594Z,1726513563.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:06:03.595Z,1726513563.595 [Default:CheckIn](INFO): Running loop #6
2024-09-16T19:06:03.595Z,1726513563.595 [Default:CheckIn] Running Loop=6
2024-09-16T19:06:03.595Z,1726513563.595 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:06:03.595Z,1726513563.595 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:06:05.599Z,1726513565.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190604.00,A,3648.15082,N,12147.22288,W,0.097,62.80,160924,,,D*4D
2024-09-16T19:06:05.601Z,1726513565.601 [NAL9602](INFO): GPS fix at 20240916T190604: (36.802514, -121.787048)
2024-09-16T19:06:05.649Z,1726513565.649 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:06:05.649Z,1726513565.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:06:13.247Z,1726513573.247 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T182935/Courier0019.lzma
2024-09-16T19:06:14.250Z,1726513574.250 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0019.lzma.bak
2024-09-16T19:06:14.250Z,1726513574.250 [DataOverHttps](INFO): SBD MOMSN=23191351
2024-09-16T19:06:29.026Z,1726513589.026 [NAL9602](INFO): SBD MO Status=0, MOMSN=17476, MT Status=0, MTMSN=0
2024-09-16T19:06:29.026Z,1726513589.026 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:06:30.211Z,1726513590.211 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240916T182935/Express0020.lzma
2024-09-16T19:06:31.214Z,1726513591.214 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0020.lzma.bak
2024-09-16T19:06:31.214Z,1726513591.214 [DataOverHttps](INFO): SBD MOMSN=23191354
2024-09-16T19:06:32.272Z,1726513592.272 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:06:32.272Z,1726513592.272 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:06:32.273Z,1726513592.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:06:59.737Z,1726513619.737 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:11:32.854Z,1726513892.854 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:11:32.854Z,1726513892.854 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:11:32.854Z,1726513892.854 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:11:32.854Z,1726513892.854 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:11:33.269Z,1726513893.269 [Default:CheckIn:D] Stopped
2024-09-16T19:11:33.269Z,1726513893.269 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:11:33.652Z,1726513893.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.071179 min
2024-09-16T19:11:33.652Z,1726513893.652 [Default:CheckIn:E] Stopped
2024-09-16T19:11:33.652Z,1726513893.652 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:11:33.652Z,1726513893.652 [Default:CheckIn] Stopped
2024-09-16T19:11:33.653Z,1726513893.653 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:11:33.653Z,1726513893.653 [Default:CheckIn](INFO): Running loop #7
2024-09-16T19:11:33.653Z,1726513893.653 [Default:CheckIn] Running Loop=7
2024-09-16T19:11:33.653Z,1726513893.653 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:11:33.653Z,1726513893.653 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:11:35.663Z,1726513895.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191134.00,A,3648.15091,N,12147.22308,W,0.019,62.80,160924,,,D*45
2024-09-16T19:11:35.665Z,1726513895.665 [NAL9602](INFO): GPS fix at 20240916T191134: (36.802515, -121.787051)
2024-09-16T19:11:35.679Z,1726513895.679 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:11:35.679Z,1726513895.679 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:11:43.095Z,1726513903.095 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0022.lzma
2024-09-16T19:11:44.098Z,1726513904.098 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0022.lzma.bak
2024-09-16T19:11:44.098Z,1726513904.098 [DataOverHttps](INFO): SBD MOMSN=23191399
2024-09-16T19:11:50.250Z,1726513910.250 [NAL9602](INFO): SBD MO Status=0, MOMSN=17477, MT Status=0, MTMSN=0
2024-09-16T19:11:50.251Z,1726513910.251 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:12:02.883Z,1726513922.883 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240916T182935/Express0023.lzma
2024-09-16T19:12:03.886Z,1726513923.886 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0023.lzma.bak
2024-09-16T19:12:03.886Z,1726513923.886 [DataOverHttps](INFO): SBD MOMSN=23191402
2024-09-16T19:12:05.221Z,1726513925.221 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:12:05.221Z,1726513925.221 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:12:05.221Z,1726513925.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:12:20.969Z,1726513940.969 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:17:05.804Z,1726514225.804 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:17:05.804Z,1726514225.804 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:17:05.805Z,1726514225.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:17:05.805Z,1726514225.805 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:17:06.211Z,1726514226.211 [Default:CheckIn:D] Stopped
2024-09-16T19:17:06.211Z,1726514226.211 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:17:06.622Z,1726514226.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.620211 min
2024-09-16T19:17:06.622Z,1726514226.622 [Default:CheckIn:E] Stopped
2024-09-16T19:17:06.622Z,1726514226.622 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:17:06.622Z,1726514226.622 [Default:CheckIn] Stopped
2024-09-16T19:17:06.622Z,1726514226.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:17:06.623Z,1726514226.623 [Default:CheckIn](INFO): Running loop #8
2024-09-16T19:17:06.623Z,1726514226.623 [Default:CheckIn] Running Loop=8
2024-09-16T19:17:06.623Z,1726514226.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:17:06.623Z,1726514226.623 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:17:08.622Z,1726514228.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191707.00,A,3648.15033,N,12147.22227,W,0.019,0.00,160924,,,D*7B
2024-09-16T19:17:08.624Z,1726514228.624 [NAL9602](INFO): GPS fix at 20240916T191707: (36.802506, -121.787038)
2024-09-16T19:17:08.637Z,1726514228.637 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:17:08.637Z,1726514228.637 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:17:15.531Z,1726514235.531 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T182935/Courier0025.lzma
2024-09-16T19:17:16.534Z,1726514236.534 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0025.lzma.bak
2024-09-16T19:17:16.534Z,1726514236.534 [DataOverHttps](INFO): SBD MOMSN=23191435
2024-09-16T19:17:20.743Z,1726514240.743 [NAL9602](INFO): SBD MO Status=0, MOMSN=17478, MT Status=0, MTMSN=0
2024-09-16T19:17:20.743Z,1726514240.743 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:17:32.391Z,1726514252.391 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T182935/Express0026.lzma
2024-09-16T19:17:33.394Z,1726514253.394 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0026.lzma.bak
2024-09-16T19:17:33.394Z,1726514253.394 [DataOverHttps](INFO): SBD MOMSN=23191438
2024-09-16T19:17:34.488Z,1726514254.488 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:17:34.488Z,1726514254.488 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:17:34.488Z,1726514254.488 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:17:51.446Z,1726514271.446 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:22:35.065Z,1726514555.065 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:22:35.065Z,1726514555.065 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:22:35.065Z,1726514555.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:22:35.065Z,1726514555.065 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:22:35.469Z,1726514555.469 [Default:CheckIn:D] Stopped
2024-09-16T19:22:35.469Z,1726514555.469 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:22:35.885Z,1726514555.885 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.107853 min
2024-09-16T19:22:35.885Z,1726514555.885 [Default:CheckIn:E] Stopped
2024-09-16T19:22:35.885Z,1726514555.885 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:22:35.885Z,1726514555.885 [Default:CheckIn] Stopped
2024-09-16T19:22:35.885Z,1726514555.885 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:22:35.885Z,1726514555.885 [Default:CheckIn](INFO): Running loop #9
2024-09-16T19:22:35.886Z,1726514555.886 [Default:CheckIn] Running Loop=9
2024-09-16T19:22:35.886Z,1726514555.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:22:35.886Z,1726514555.886 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:22:37.887Z,1726514557.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192237.00,A,3648.15101,N,12147.22281,W,0.097,0.00,160924,,,D*74
2024-09-16T19:22:37.890Z,1726514557.890 [NAL9602](INFO): GPS fix at 20240916T192237: (36.802517, -121.787047)
2024-09-16T19:22:37.926Z,1726514557.926 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:22:37.926Z,1726514557.926 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:22:45.228Z,1726514565.228 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0028.lzma
2024-09-16T19:22:46.226Z,1726514566.226 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0028.lzma.bak
2024-09-16T19:22:46.226Z,1726514566.226 [DataOverHttps](INFO): SBD MOMSN=23191463
2024-09-16T19:23:02.071Z,1726514582.071 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240916T182935/Express0029.lzma
2024-09-16T19:23:03.075Z,1726514583.075 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0029.lzma.bak
2024-09-16T19:23:03.076Z,1726514583.076 [DataOverHttps](INFO): SBD MOMSN=23191466
2024-09-16T19:23:04.157Z,1726514584.157 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:23:04.157Z,1726514584.157 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:23:04.157Z,1726514584.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:23:47.791Z,1726514627.791 [NAL9602](INFO): SBD MO Status=0, MOMSN=17479, MT Status=0, MTMSN=0
2024-09-16T19:23:47.791Z,1726514627.791 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:24:18.497Z,1726514658.497 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:28:04.771Z,1726514884.771 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:28:04.771Z,1726514884.771 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:28:04.771Z,1726514884.771 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:28:04.771Z,1726514884.771 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:28:05.181Z,1726514885.181 [Default:CheckIn:D] Stopped
2024-09-16T19:28:05.181Z,1726514885.181 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:28:05.589Z,1726514885.589 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.603056 min
2024-09-16T19:28:05.589Z,1726514885.589 [Default:CheckIn:E] Stopped
2024-09-16T19:28:05.589Z,1726514885.589 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:28:05.589Z,1726514885.589 [Default:CheckIn] Stopped
2024-09-16T19:28:05.590Z,1726514885.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:28:05.590Z,1726514885.590 [Default:CheckIn](INFO): Running loop #10
2024-09-16T19:28:05.590Z,1726514885.590 [Default:CheckIn] Running Loop=10
2024-09-16T19:28:05.590Z,1726514885.590 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:28:05.590Z,1726514885.590 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:28:07.591Z,1726514887.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192806.00,A,3648.15145,N,12147.22436,W,0.136,0.00,160924,,,D*7C
2024-09-16T19:28:07.594Z,1726514887.594 [NAL9602](INFO): GPS fix at 20240916T192806: (36.802524, -121.787073)
2024-09-16T19:28:07.630Z,1726514887.630 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:28:07.630Z,1726514887.630 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:28:14.911Z,1726514894.911 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0031.lzma
2024-09-16T19:28:15.914Z,1726514895.914 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0031.lzma.bak
2024-09-16T19:28:15.914Z,1726514895.914 [DataOverHttps](INFO): SBD MOMSN=23191485
2024-09-16T19:28:22.946Z,1726514902.946 [NAL9602](INFO): SBD MO Status=0, MOMSN=17480, MT Status=0, MTMSN=0
2024-09-16T19:28:22.946Z,1726514902.946 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:28:31.891Z,1726514911.891 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T182935/Express0032.lzma
2024-09-16T19:28:32.894Z,1726514912.894 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0032.lzma.bak
2024-09-16T19:28:32.894Z,1726514912.894 [DataOverHttps](INFO): SBD MOMSN=23191488
2024-09-16T19:28:34.280Z,1726514914.280 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:28:34.281Z,1726514914.281 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:28:34.281Z,1726514914.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:28:53.657Z,1726514933.657 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:33:34.899Z,1726515214.899 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:33:34.899Z,1726515214.899 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:33:34.900Z,1726515214.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:33:34.900Z,1726515214.900 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:33:35.293Z,1726515215.293 [Default:CheckIn:D] Stopped
2024-09-16T19:33:35.293Z,1726515215.293 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:33:35.701Z,1726515215.701 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.104911 min
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn:E] Stopped
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn] Stopped
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn](INFO): Running loop #11
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn] Running Loop=11
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:33:35.702Z,1726515215.702 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:33:37.703Z,1726515217.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193337.00,A,3648.14870,N,12147.22175,W,0.097,170.61,160924,,,D*73
2024-09-16T19:33:37.713Z,1726515217.713 [NAL9602](INFO): GPS fix at 20240916T193337: (36.802478, -121.787029)
2024-09-16T19:33:37.726Z,1726515217.726 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:33:37.726Z,1726515217.726 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:33:44.731Z,1726515224.731 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0034.lzma
2024-09-16T19:33:45.734Z,1726515225.734 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0034.lzma.bak
2024-09-16T19:33:45.734Z,1726515225.734 [DataOverHttps](INFO): SBD MOMSN=23191517
2024-09-16T19:34:01.479Z,1726515241.479 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T182935/Express0035.lzma
2024-09-16T19:34:02.482Z,1726515242.482 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0035.lzma.bak
2024-09-16T19:34:02.482Z,1726515242.482 [DataOverHttps](INFO): SBD MOMSN=23191520
2024-09-16T19:34:03.568Z,1726515243.568 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:34:03.568Z,1726515243.568 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:34:03.568Z,1726515243.568 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:34:05.982Z,1726515245.982 [NAL9602](INFO): SBD MO Status=2, MOMSN=17481, MT Status=2, MTMSN=0
2024-09-16T19:34:05.983Z,1726515245.983 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T19:34:25.379Z,1726515265.379 [NAL9602](INFO): SBD MO Status=0, MOMSN=17481, MT Status=0, MTMSN=0
2024-09-16T19:34:25.380Z,1726515265.380 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:34:56.078Z,1726515296.078 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:38:19.957Z,1726515499.957 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T193819
2024-09-16T19:38:27.348Z,1726515507.348 [DataOverHttps](INFO): Received command: strobe off
2024-09-16T19:38:27.350Z,1726515507.350 [CommandExec](IMPORTANT): got command strobe off
2024-09-16T19:38:27.350Z,1726515507.350 [CommandExec](IMPORTANT): Deactivating strobe
2024-09-16T19:39:03.874Z,1726515543.874 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T193902
2024-09-16T19:39:04.153Z,1726515544.153 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:39:04.153Z,1726515544.153 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:39:04.153Z,1726515544.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:39:04.153Z,1726515544.153 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:39:04.567Z,1726515544.567 [Default:CheckIn:D] Stopped
2024-09-16T19:39:04.567Z,1726515544.567 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:39:04.965Z,1726515544.965 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.592818 min
2024-09-16T19:39:04.965Z,1726515544.965 [Default:CheckIn:E] Stopped
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn] Stopped
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn](INFO): Running loop #12
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn] Running Loop=12
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:39:04.966Z,1726515544.966 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:39:06.971Z,1726515546.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193906.00,A,3648.25620,N,12147.13477,W,4.471,9.98,160924,,,D*76
2024-09-16T19:39:06.973Z,1726515546.973 [NAL9602](INFO): GPS fix at 20240916T193906: (36.804270, -121.785579)
2024-09-16T19:39:06.998Z,1726515546.998 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:39:06.998Z,1726515546.998 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:39:10.755Z,1726515550.755 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0037.lzma
2024-09-16T19:39:10.757Z,1726515550.757 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;run
2024-09-16T19:39:10.850Z,1726515550.850 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2024-09-16T19:39:10.851Z,1726515550.851 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2024-09-16T19:39:10.851Z,1726515550.851 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2024-09-16T19:39:10.866Z,1726515550.866 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2024-09-16T19:39:11.630Z,1726515551.630 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2024-09-16T19:39:11.660Z,1726515551.660 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2024-09-16T19:39:11.663Z,1726515551.663 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2024-09-16T19:39:11.670Z,1726515551.670 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2024-09-16T19:39:11.677Z,1726515551.677 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2024-09-16T19:39:11.684Z,1726515551.684 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2024-09-16T19:39:11.687Z,1726515551.687 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2024-09-16T19:39:11.694Z,1726515551.694 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2024-09-16T19:39:11.746Z,1726515551.746 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2024-09-16T19:39:11.750Z,1726515551.750 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2024-09-16T19:39:11.761Z,1726515551.761 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2024-09-16T19:39:11.769Z,1726515551.769 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2024-09-16T19:39:11.792Z,1726515551.792 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2024-09-16T19:39:11.794Z,1726515551.794 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2024-09-16T19:39:11.795Z,1726515551.795 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2024-09-16T19:39:11.795Z,1726515551.795 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2024-09-16T19:39:11.804Z,1726515551.804 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2024-09-16T19:39:12.087Z,1726515552.087 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0037.lzma.bak
2024-09-16T19:39:12.087Z,1726515552.087 [DataOverHttps](INFO): SBD MOMSN=23191549
2024-09-16T19:39:12.385Z,1726515552.385 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2024-09-16T19:39:12.390Z,1726515552.390 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2024-09-16T19:39:12.394Z,1726515552.394 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2024-09-16T19:39:12.397Z,1726515552.397 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2024-09-16T19:39:12.399Z,1726515552.399 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2024-09-16T19:39:12.402Z,1726515552.402 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2024-09-16T19:39:12.406Z,1726515552.406 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2024-09-16T19:39:12.409Z,1726515552.409 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2024-09-16T19:39:12.413Z,1726515552.413 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2024-09-16T19:39:12.420Z,1726515552.420 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T19:39:12.486Z,1726515552.486 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-09-16T19:39:12.486Z,1726515552.486 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-09-16T19:39:12.491Z,1726515552.491 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-09-16T19:39:12.785Z,1726515552.785 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-09-16T19:39:12.794Z,1726515552.794 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2024-09-16T19:39:12.797Z,1726515552.797 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-09-16T19:39:12.808Z,1726515552.808 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2024-09-16T19:39:12.811Z,1726515552.811 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-09-16T19:39:12.816Z,1726515552.816 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-09-16T19:39:12.841Z,1726515552.841 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-09-16T19:39:12.861Z,1726515552.861 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-09-16T19:39:12.885Z,1726515552.885 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-09-16T19:39:12.885Z,1726515552.885 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-09-16T19:39:12.886Z,1726515552.886 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-09-16T19:39:12.940Z,1726515552.940 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-09-16T19:39:12.943Z,1726515552.943 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2024-09-16T19:39:12.950Z,1726515552.950 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-09-16T19:39:12.954Z,1726515552.954 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-09-16T19:39:12.954Z,1726515552.954 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-09-16T19:39:12.955Z,1726515552.955 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-09-16T19:39:13.254Z,1726515553.254 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-09-16T19:39:13.263Z,1726515553.263 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2024-09-16T19:39:13.267Z,1726515553.267 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2024-09-16T19:39:13.277Z,1726515553.277 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2024-09-16T19:39:13.284Z,1726515553.284 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2024-09-16T19:39:13.287Z,1726515553.287 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2024-09-16T19:39:13.303Z,1726515553.303 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-09-16T19:39:13.309Z,1726515553.309 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2024-09-16T19:39:13.314Z,1726515553.314 [transit:MassHold.Pitch](DEBUG): Construct.
2024-09-16T19:39:13.341Z,1726515553.341 [transit:Transit:A.Pitch](DEBUG): Construct.
2024-09-16T19:39:13.351Z,1726515553.351 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2024-09-16T19:39:13.359Z,1726515553.359 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2024-09-16T19:39:13.398Z,1726515553.398 [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 transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2024-09-16T19:39:13.399Z,1726515553.399 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2024-09-16T19:39:19.966Z,1726515559.966 [NAL9602](INFO): SBD MO Status=0, MOMSN=17482, MT Status=0, MTMSN=0
2024-09-16T19:39:19.967Z,1726515559.967 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:39:23.228Z,1726515563.228 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2024-09-16T19:39:23.229Z,1726515563.229 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute
2024-09-16T19:39:23.230Z,1726515563.230 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2024-09-16T19:39:23.230Z,1726515563.230 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2024-09-16T19:39:23.231Z,1726515563.231 [CommandExec](IMPORTANT): got command run
2024-09-16T19:39:23.233Z,1726515563.233 [CommandExec](IMPORTANT): Running
2024-09-16T19:39:23.309Z,1726515563.309 [Default] Stopped
2024-09-16T19:39:23.309Z,1726515563.309 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-16T19:39:23.309Z,1726515563.309 [Default:B.GoToSurface] Stopped
2024-09-16T19:39:23.309Z,1726515563.309 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T19:39:23.309Z,1726515563.309 [Default:CheckIn] Stopped
2024-09-16T19:39:23.309Z,1726515563.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:39:23.309Z,1726515563.309 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:39:23.309Z,1726515563.309 [MissionManager](IMPORTANT): Started mission transit
2024-09-16T19:39:23.310Z,1726515563.310 [transit] Running Loop=1
2024-09-16T19:39:23.310Z,1726515563.310 [transit](DEBUG): Aggregate::initialize transit
2024-09-16T19:39:23.310Z,1726515563.310 [transit:StandardEnvelopes] Running Loop=1
2024-09-16T19:39:23.310Z,1726515563.310 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2024-09-16T19:39:23.310Z,1726515563.310 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-16T19:39:23.310Z,1726515563.310 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-09-16T19:39:23.310Z,1726515563.310 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-16T19:39:23.310Z,1726515563.310 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-09-16T19:39:23.311Z,1726515563.311 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-09-16T19:39:23.311Z,1726515563.311 [transit:BackseatDriver] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2024-09-16T19:39:23.311Z,1726515563.311 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:PowerOnly] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2024-09-16T19:39:23.311Z,1726515563.311 [transit:PowerOnly:A] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:PowerOnly:B] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:PowerOnly:C] Running Loop=1
2024-09-16T19:39:23.311Z,1726515563.311 [transit:PowerOnly:D] Running Loop=1
2024-09-16T19:39:23.312Z,1726515563.312 [transit:PowerOnly:E.Wait] Running Loop=1
2024-09-16T19:39:23.312Z,1726515563.312 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:39:23.313Z,1726515563.313 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-16T19:39:23.313Z,1726515563.313 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-09-16T19:39:23.313Z,1726515563.313 [transit:MassHold.Pitch] Running Loop=1
2024-09-16T19:39:23.313Z,1726515563.313 [transit:MassHold.Pitch](DEBUG): Initialize.
2024-09-16T19:39:23.313Z,1726515563.313 [transit:B] Running Loop=1
2024-09-16T19:39:23.314Z,1726515563.314 [transit:MassHold.Pitch] Running Loop=1
2024-09-16T19:39:23.314Z,1726515563.314 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-16T19:39:23.314Z,1726515563.314 [transit:PowerOnly] Running Loop=1
2024-09-16T19:39:23.315Z,1726515563.315 [transit:PowerOnly:D] Stopped
2024-09-16T19:39:23.315Z,1726515563.315 [transit:PowerOnly:C] Stopped
2024-09-16T19:39:23.315Z,1726515563.315 [transit:PowerOnly:B] Stopped
2024-09-16T19:39:23.315Z,1726515563.315 [transit:PowerOnly:A] Stopped
2024-09-16T19:39:23.315Z,1726515563.315 [transit:BackseatDriver] Running Loop=1
2024-09-16T19:39:23.315Z,1726515563.315 [transit:StandardEnvelopes] Running Loop=1
2024-09-16T19:39:23.315Z,1726515563.315 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-16T19:39:23.316Z,1726515563.316 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-16T19:39:23.317Z,1726515563.317 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-16T19:39:23.317Z,1726515563.317 [transit:B] Stopped
2024-09-16T19:39:23.317Z,1726515563.317 [transit:C] Running Loop=1
2024-09-16T19:39:23.623Z,1726515563.623 [transit:C] Stopped
2024-09-16T19:39:23.623Z,1726515563.623 [transit:E] Running Loop=1
2024-09-16T19:39:24.026Z,1726515564.026 [transit:E] Stopped
2024-09-16T19:39:24.026Z,1726515564.026 [transit:F] Running Loop=1
2024-09-16T19:39:24.422Z,1726515564.422 [transit:F] Stopped
2024-09-16T19:39:24.422Z,1726515564.422 [transit:G] Running Loop=1
2024-09-16T19:39:24.831Z,1726515564.831 [transit:G] Stopped
2024-09-16T19:39:24.831Z,1726515564.831 [transit:Transit] Running Loop=1
2024-09-16T19:39:24.831Z,1726515564.831 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2024-09-16T19:39:24.831Z,1726515564.831 [transit:Transit:A.Pitch] Running Loop=1
2024-09-16T19:39:24.831Z,1726515564.831 [transit:Transit:A.Pitch](DEBUG): Initialize.
2024-09-16T19:39:24.832Z,1726515564.832 [transit:Transit:B.SetSpeed] Running Loop=1
2024-09-16T19:39:24.832Z,1726515564.832 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2024-09-16T19:39:24.832Z,1726515564.832 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2024-09-16T19:39:24.832Z,1726515564.832 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2024-09-16T19:39:24.834Z,1726515564.834 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2024-09-16T19:39:24.835Z,1726515564.835 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2024-09-16T19:39:25.232Z,1726515565.232 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2024-09-16T19:39:25.233Z,1726515565.233 [transit:Transit:B.SetSpeed] Running Loop=1
2024-09-16T19:39:25.233Z,1726515565.233 [transit:Transit:A.Pitch] Running Loop=1
2024-09-16T19:39:25.262Z,1726515565.262 [HorizontalControl](DEBUG): kpHeading = 0.400000
2024-09-16T19:39:25.263Z,1726515565.263 [HorizontalControl](DEBUG): kiHeading = 0.001000
2024-09-16T19:39:25.263Z,1726515565.263 [HorizontalControl](DEBUG): kdHeading = 0.050000
2024-09-16T19:39:31.291Z,1726515571.291 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240916T182935/Courier0043.lzma
2024-09-16T19:39:32.295Z,1726515572.295 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0043.lzma.bak
2024-09-16T19:39:32.295Z,1726515572.295 [DataOverHttps](INFO): SBD MOMSN=23191554
2024-09-16T19:39:50.665Z,1726515590.665 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:40:08.209Z,1726515608.209 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T194007
2024-09-16T19:40:16.051Z,1726515616.051 [DataOverHttps](INFO): Received command: stop
2024-09-16T19:40:16.073Z,1726515616.073 [CommandExec](IMPORTANT): got command stop
2024-09-16T19:40:16.073Z,1726515616.073 [CommandExec](IMPORTANT): Scheduling is paused
2024-09-16T19:40:16.073Z,1726515616.073 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2024-09-16T19:40:16.159Z,1726515616.159 [MissionManager](INFO): MissionManager is completed.
2024-09-16T19:40:16.159Z,1726515616.159 [MissionManager](INFO): Uninitializing Mission transit
2024-09-16T19:40:16.159Z,1726515616.159 [transit] Stopped
2024-09-16T19:40:16.159Z,1726515616.159 [transit](DEBUG): Aggregate::uninitialize transit
2024-09-16T19:40:16.159Z,1726515616.159 [transit:StandardEnvelopes] Stopped
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2024-09-16T19:40:16.160Z,1726515616.160 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2024-09-16T19:40:16.160Z,1726515616.160 [transit:BackseatDriver] Stopped
2024-09-16T19:40:16.160Z,1726515616.160 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2024-09-16T19:40:16.160Z,1726515616.160 [transit:BackseatDriver:A.BackseatDriver] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:PowerOnly] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2024-09-16T19:40:16.161Z,1726515616.161 [transit:PowerOnly:E.Wait] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:40:16.161Z,1726515616.161 [transit:BuoyancyHold.Buoyancy] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-09-16T19:40:16.161Z,1726515616.161 [transit:MassHold.Pitch] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:Transit] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2024-09-16T19:40:16.161Z,1726515616.161 [transit:Transit:A.Pitch] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:Transit:B.SetSpeed] Stopped
2024-09-16T19:40:16.161Z,1726515616.161 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2024-09-16T19:40:16.161Z,1726515616.161 [transit:Transit:Wpt1.Waypoint] Stopped
2024-09-16T19:40:16.162Z,1726515616.162 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2024-09-16T19:40:16.542Z,1726515616.542 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T19:40:16.543Z,1726515616.543 [Default] Running Loop=1
2024-09-16T19:40:16.543Z,1726515616.543 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T19:40:16.543Z,1726515616.543 [Default:B.GoToSurface] Running Loop=1
2024-09-16T19:40:16.543Z,1726515616.543 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T19:40:16.543Z,1726515616.543 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T19:40:16.544Z,1726515616.544 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T19:40:16.544Z,1726515616.544 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T19:40:16.544Z,1726515616.544 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T19:40:16.545Z,1726515616.545 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T19:40:16.545Z,1726515616.545 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T19:40:16.545Z,1726515616.545 [Default:A.Wait] Running Loop=1
2024-09-16T19:40:16.546Z,1726515616.546 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:40:29.870Z,1726515629.870 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T19:40:29.870Z,1726515629.870 [Default:A.Wait] Stopped
2024-09-16T19:40:29.870Z,1726515629.870 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:40:30.269Z,1726515630.269 [Default:CheckIn] Running Loop=1
2024-09-16T19:40:30.269Z,1726515630.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:40:30.270Z,1726515630.270 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:40:32.287Z,1726515632.287 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194031.00,A,3648.36428,N,12147.12073,W,4.685,7.58,160924,,,D*7E
2024-09-16T19:40:32.289Z,1726515632.289 [NAL9602](INFO): GPS fix at 20240916T194031: (36.806071, -121.785346)
2024-09-16T19:40:32.302Z,1726515632.302 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:40:32.302Z,1726515632.302 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:40:39.883Z,1726515639.883 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0046.lzma
2024-09-16T19:40:40.886Z,1726515640.886 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0046.lzma.bak
2024-09-16T19:40:40.886Z,1726515640.886 [DataOverHttps](INFO): SBD MOMSN=23191577
2024-09-16T19:40:56.964Z,1726515656.964 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20240916T182935/Express0038.lzma
2024-09-16T19:40:57.966Z,1726515657.966 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0038.lzma.bak
2024-09-16T19:40:57.966Z,1726515657.966 [DataOverHttps](INFO): SBD MOMSN=23191581
2024-09-16T19:41:12.298Z,1726515672.298 [NAL9602](INFO): SBD MO Status=2, MOMSN=17483, MT Status=2, MTMSN=0
2024-09-16T19:41:12.299Z,1726515672.299 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T19:41:13.899Z,1726515673.899 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240916T182935/Express0041.lzma
2024-09-16T19:41:14.902Z,1726515674.902 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0041.lzma.bak
2024-09-16T19:41:14.902Z,1726515674.902 [DataOverHttps](INFO): SBD MOMSN=23191588
2024-09-16T19:41:30.751Z,1726515690.751 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20240916T182935/Express0044.lzma
2024-09-16T19:41:31.754Z,1726515691.754 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0044.lzma.bak
2024-09-16T19:41:31.754Z,1726515691.754 [DataOverHttps](INFO): SBD MOMSN=23191593
2024-09-16T19:41:38.974Z,1726515698.974 [NAL9602](INFO): SBD MO Status=0, MOMSN=17483, MT Status=0, MTMSN=0
2024-09-16T19:41:38.974Z,1726515698.974 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:41:48.665Z,1726515708.665 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20240916T182935/Express0047.lzma
2024-09-16T19:41:49.663Z,1726515709.663 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0047.lzma.bak
2024-09-16T19:41:49.663Z,1726515709.663 [DataOverHttps](INFO): SBD MOMSN=23191616
2024-09-16T19:42:07.719Z,1726515727.719 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240916T182935/Express0050.lzma
2024-09-16T19:42:08.722Z,1726515728.722 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0050.lzma.bak
2024-09-16T19:42:08.722Z,1726515728.722 [DataOverHttps](INFO): SBD MOMSN=23191633
2024-09-16T19:42:09.717Z,1726515729.717 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:42:09.747Z,1726515729.747 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:42:09.747Z,1726515729.747 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:42:09.772Z,1726515729.772 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:47:10.305Z,1726516030.305 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:47:10.305Z,1726516030.305 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:47:10.305Z,1726516030.305 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:47:10.306Z,1726516030.306 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:47:10.719Z,1726516030.719 [Default:CheckIn:D] Stopped
2024-09-16T19:47:10.719Z,1726516030.719 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:47:11.187Z,1726516031.187 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.902941 min
2024-09-16T19:47:11.187Z,1726516031.187 [Default:CheckIn:E] Stopped
2024-09-16T19:47:11.187Z,1726516031.187 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:47:11.187Z,1726516031.187 [Default:CheckIn] Stopped
2024-09-16T19:47:11.188Z,1726516031.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:47:11.188Z,1726516031.188 [Default:CheckIn](INFO): Running loop #2
2024-09-16T19:47:11.188Z,1726516031.188 [Default:CheckIn] Running Loop=2
2024-09-16T19:47:11.188Z,1726516031.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:47:11.188Z,1726516031.188 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:47:13.123Z,1726516033.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194712.00,A,3648.42238,N,12148.13946,W,15.220,290.60,160924,,,D*41
2024-09-16T19:47:13.125Z,1726516033.125 [NAL9602](INFO): GPS fix at 20240916T194712: (36.807040, -121.802324)
2024-09-16T19:47:13.137Z,1726516033.137 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:47:13.137Z,1726516033.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:47:22.651Z,1726516042.651 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T182935/Courier0052.lzma
2024-09-16T19:47:23.657Z,1726516043.657 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0052.lzma.bak
2024-09-16T19:47:23.657Z,1726516043.657 [DataOverHttps](INFO): SBD MOMSN=23191657
2024-09-16T19:47:34.539Z,1726516054.539 [NAL9602](INFO): SBD MO Status=0, MOMSN=17484, MT Status=0, MTMSN=0
2024-09-16T19:47:34.539Z,1726516054.539 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:47:51.452Z,1726516071.452 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240916T182935/Express0053.lzma
2024-09-16T19:48:05.249Z,1726516085.249 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:48:09.538Z,1726516089.538 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0053.lzma.bak
2024-09-16T19:48:09.538Z,1726516089.538 [DataOverHttps](INFO): SBD MOMSN=23191661
2024-09-16T19:48:10.904Z,1726516090.904 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:48:10.904Z,1726516090.904 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:48:10.904Z,1726516090.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:48:32.307Z,1726516112.307 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-09-16T19:49:19.144Z,1726516159.144 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243183
2024-09-16T19:51:14.476Z,1726516274.476 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002896
2024-09-16T19:53:11.509Z,1726516391.509 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T19:53:11.510Z,1726516391.510 [Default:CheckIn:C.Wait] Stopped
2024-09-16T19:53:11.510Z,1726516391.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T19:53:11.513Z,1726516391.513 [Default:CheckIn:D] Running Loop=1
2024-09-16T19:53:11.911Z,1726516391.911 [Default:CheckIn:D] Stopped
2024-09-16T19:53:11.911Z,1726516391.911 [Default:CheckIn:E] Running Loop=1
2024-09-16T19:53:12.292Z,1726516392.292 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.922802 min
2024-09-16T19:53:12.292Z,1726516392.292 [Default:CheckIn:E] Stopped
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn] Stopped
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn](INFO): Running loop #3
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn] Running Loop=3
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T19:53:12.293Z,1726516392.293 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T19:53:14.308Z,1726516394.308 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195313.00,A,3648.61121,N,12149.10906,W,2.099,297.64,160924,,,A*79
2024-09-16T19:53:14.310Z,1726516394.310 [NAL9602](INFO): GPS fix at 20240916T195313: (36.810187, -121.818484)
2024-09-16T19:53:14.322Z,1726516394.322 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T19:53:14.322Z,1726516394.322 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T19:53:27.634Z,1726516407.634 [NAL9602](INFO): SBD MO Status=0, MOMSN=17485, MT Status=0, MTMSN=0
2024-09-16T19:53:27.635Z,1726516407.635 [NAL9602](INFO): No messages in MT queue
2024-09-16T19:53:58.401Z,1726516438.401 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T19:53:58.767Z,1726516438.767 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T182935/Courier0055.lzma
2024-09-16T19:53:59.770Z,1726516439.770 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Courier0055.lzma.bak
2024-09-16T19:53:59.770Z,1726516439.770 [DataOverHttps](INFO): SBD MOMSN=23191689
2024-09-16T19:54:15.603Z,1726516455.603 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240916T182935/Express0056.lzma
2024-09-16T19:54:16.606Z,1726516456.606 [DataOverHttps](INFO): Moved sent file to Logs/20240916T182935/Express0056.lzma.bak
2024-09-16T19:54:16.606Z,1726516456.606 [DataOverHttps](INFO): SBD MOMSN=23191692
2024-09-16T19:54:17.808Z,1726516457.808 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T19:54:17.809Z,1726516457.809 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T19:54:17.809Z,1726516457.809 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T19:56:11.913Z,1726516571.913 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T195610
2024-09-16T19:56:19.355Z,1726516579.355 [DataOverHttps](INFO): Received command: restart logs