2024-09-16T23:12:34.610Z,1726528354.610 [Supervisor](DEBUG): Initializing supervisor.
2024-09-16T23:12:34.614Z,1726528354.614 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-16T23:12:34.614Z,1726528354.614 [SyncHandler](INFO): Protected caller Thread ID is 834
2024-09-16T23:12:34.615Z,1726528354.615 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-16T23:12:34.616Z,1726528354.616 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-16T23:12:34.616Z,1726528354.616 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2024-09-16T23:12:34.620Z,1726528354.620 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-16T23:12:34.637Z,1726528354.637 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-16T23:12:34.638Z,1726528354.638 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-16T23:12:34.639Z,1726528354.639 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2024-09-16T23:12:34.643Z,1726528354.643 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-16T23:12:34.644Z,1726528354.644 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-16T23:12:34.644Z,1726528354.644 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2024-09-16T23:12:34.646Z,1726528354.646 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-16T23:12:34.647Z,1726528354.647 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-16T23:12:34.648Z,1726528354.648 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2024-09-16T23:12:34.651Z,1726528354.651 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-16T23:12:34.651Z,1726528354.651 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-16T23:12:34.656Z,1726528354.656 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-16T23:12:35.049Z,1726528355.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-16T23:12:35.049Z,1726528355.049 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-16T23:12:36.200Z,1726528356.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-16T23:12:36.202Z,1726528356.202 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-16T23:12:36.432Z,1726528356.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-16T23:12:36.434Z,1726528356.434 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-16T23:12:37.339Z,1726528357.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-16T23:12:37.341Z,1726528357.341 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-16T23:12:37.455Z,1726528357.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-16T23:12:37.457Z,1726528357.457 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-16T23:12:37.554Z,1726528357.554 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-16T23:12:38.075Z,1726528358.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-16T23:12:38.076Z,1726528358.076 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-16T23:12:38.783Z,1726528358.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-16T23:12:38.784Z,1726528358.784 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-16T23:12:38.977Z,1726528358.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-16T23:12:38.978Z,1726528358.978 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-16T23:12:39.270Z,1726528359.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-16T23:12:39.272Z,1726528359.272 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-16T23:12:39.899Z,1726528359.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-16T23:12:39.901Z,1726528359.901 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-16T23:12:40.344Z,1726528360.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-16T23:12:40.345Z,1726528360.345 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-16T23:12:40.443Z,1726528360.443 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-16T23:12:40.678Z,1726528360.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-16T23:12:40.678Z,1726528360.678 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-16T23:12:40.949Z,1726528360.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-16T23:12:40.951Z,1726528360.951 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-16T23:12:41.299Z,1726528361.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-16T23:12:41.301Z,1726528361.301 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2024-09-16T23:12:41.305Z,1726528361.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2024-09-16T23:12:41.434Z,1726528361.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2024-09-16T23:12:41.614Z,1726528361.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2024-09-16T23:12:41.716Z,1726528361.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2024-09-16T23:12:41.815Z,1726528361.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2024-09-16T23:12:41.946Z,1726528361.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2024-09-16T23:12:42.048Z,1726528362.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2024-09-16T23:12:42.173Z,1726528362.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2024-09-16T23:12:42.464Z,1726528362.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-16T23:12:42.465Z,1726528362.465 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2024-09-16T23:12:42.625Z,1726528362.625 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2024-09-16T23:12:42.736Z,1726528362.736 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2024-09-16T23:12:42.846Z,1726528362.846 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2024-09-16T23:12:42.959Z,1726528362.959 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-16T23:12:42.979Z,1726528362.979 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-16T23:12:43.132Z,1726528363.132 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-16T23:12:43.134Z,1726528363.134 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-16T23:12:43.311Z,1726528363.311 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-16T23:12:43.312Z,1726528363.312 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-16T23:12:44.207Z,1726528364.207 [DepthRateCalculator] Loaded
2024-09-16T23:12:44.207Z,1726528364.207 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-16T23:12:44.213Z,1726528364.213 [PitchRateCalculator] Loaded
2024-09-16T23:12:44.213Z,1726528364.213 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-16T23:12:44.224Z,1726528364.224 [SpeedCalculator] Loaded
2024-09-16T23:12:44.224Z,1726528364.224 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-16T23:12:44.230Z,1726528364.230 [YawRateCalculator] Loaded
2024-09-16T23:12:44.230Z,1726528364.230 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-16T23:12:44.251Z,1726528364.251 [ElevatorOffsetCalculator] Loaded
2024-09-16T23:12:44.252Z,1726528364.252 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-16T23:12:44.252Z,1726528364.252 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-16T23:12:44.253Z,1726528364.253 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-16T23:12:44.328Z,1726528364.328 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-16T23:12:44.385Z,1726528364.385 [VerticalControl] Loaded
2024-09-16T23:12:44.385Z,1726528364.385 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-16T23:12:44.388Z,1726528364.388 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-16T23:12:44.436Z,1726528364.436 [HorizontalControl] Loaded
2024-09-16T23:12:44.436Z,1726528364.436 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-16T23:12:44.438Z,1726528364.438 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-16T23:12:44.441Z,1726528364.441 [SpeedControl] Loaded
2024-09-16T23:12:44.441Z,1726528364.441 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-16T23:12:44.444Z,1726528364.444 [LoopControl](DEBUG): Construct LoopControl.
2024-09-16T23:12:44.445Z,1726528364.445 [LoopControl] Loaded
2024-09-16T23:12:44.445Z,1726528364.445 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-16T23:12:44.445Z,1726528364.445 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-16T23:12:44.446Z,1726528364.446 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-16T23:12:44.590Z,1726528364.590 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-16T23:12:44.590Z,1726528364.590 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-16T23:12:44.604Z,1726528364.604 [NavChart] Loaded
2024-09-16T23:12:44.605Z,1726528364.605 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-16T23:12:44.610Z,1726528364.610 [UniversalFixResidualReporter] Loaded
2024-09-16T23:12:44.611Z,1726528364.611 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-16T23:12:44.611Z,1726528364.611 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-16T23:12:44.612Z,1726528364.612 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-16T23:12:44.843Z,1726528364.843 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-16T23:12:44.844Z,1726528364.844 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-16T23:12:45.709Z,1726528365.709 [AHRS_M2] Loaded
2024-09-16T23:12:45.709Z,1726528365.709 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-16T23:12:45.964Z,1726528365.964 [BackseatComponent] Loaded
2024-09-16T23:12:45.965Z,1726528365.965 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-16T23:12:45.966Z,1726528365.966 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0
2024-09-16T23:12:45.966Z,1726528365.966 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-09-16T23:12:45.969Z,1726528365.969 [LcmUniversalReporter] Loaded
2024-09-16T23:12:45.969Z,1726528365.969 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-16T23:12:46.833Z,1726528366.833 [BPC1] Loaded
2024-09-16T23:12:46.834Z,1726528366.834 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-16T23:12:46.923Z,1726528366.923 [DATMMP] Loaded
2024-09-16T23:12:46.923Z,1726528366.923 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-16T23:12:46.924Z,1726528366.924 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409694E0
2024-09-16T23:12:46.925Z,1726528366.925 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 925
2024-09-16T23:12:47.196Z,1726528367.196 [DataOverHttps] Loaded
2024-09-16T23:12:47.196Z,1726528367.196 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-16T23:12:47.221Z,1726528367.221 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2024-09-16T23:12:47.222Z,1726528367.222 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2024-09-16T23:12:47.265Z,1726528367.265 [Depth_Keller] Loaded
2024-09-16T23:12:47.269Z,1726528367.269 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-16T23:12:47.395Z,1726528367.395 [DVL_micro] Loaded
2024-09-16T23:12:47.395Z,1726528367.395 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-09-16T23:12:47.467Z,1726528367.467 [NAL9602] Loaded
2024-09-16T23:12:47.468Z,1726528367.468 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-16T23:12:47.499Z,1726528367.499 [Onboard] Loaded
2024-09-16T23:12:47.499Z,1726528367.499 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-16T23:12:47.500Z,1726528367.500 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0
2024-09-16T23:12:47.500Z,1726528367.500 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2024-09-16T23:12:47.515Z,1726528367.515 [Power24vConverter] Loaded
2024-09-16T23:12:47.515Z,1726528367.515 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-16T23:12:47.531Z,1726528367.531 [Radio_Surface] Loaded
2024-09-16T23:12:47.532Z,1726528367.532 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-16T23:12:47.533Z,1726528367.533 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0
2024-09-16T23:12:47.533Z,1726528367.533 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2024-09-16T23:12:47.549Z,1726528367.549 [Sonardyne_Nano] Loaded
2024-09-16T23:12:47.550Z,1726528367.550 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-16T23:12:47.550Z,1726528367.550 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-16T23:12:47.551Z,1726528367.551 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-16T23:12:47.724Z,1726528367.724 [BuoyancyServo] Loaded
2024-09-16T23:12:47.725Z,1726528367.725 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-16T23:12:47.748Z,1726528367.748 [ElevatorServo] Loaded
2024-09-16T23:12:47.748Z,1726528367.748 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-16T23:12:47.771Z,1726528367.771 [MassServo] Loaded
2024-09-16T23:12:47.771Z,1726528367.771 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-16T23:12:47.792Z,1726528367.792 [RudderServo] Loaded
2024-09-16T23:12:47.793Z,1726528367.793 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-16T23:12:47.810Z,1726528367.810 [ThrusterHE] Loaded
2024-09-16T23:12:47.810Z,1726528367.810 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-16T23:12:47.811Z,1726528367.811 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-16T23:12:47.811Z,1726528367.811 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-16T23:12:47.916Z,1726528367.916 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-16T23:12:47.917Z,1726528367.917 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-16T23:12:47.962Z,1726528367.962 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-16T23:12:47.963Z,1726528367.963 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-16T23:12:48.400Z,1726528368.400 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-16T23:12:48.401Z,1726528368.401 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-16T23:12:48.639Z,1726528368.639 [CTD_Seabird] Loaded
2024-09-16T23:12:48.640Z,1726528368.640 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-16T23:12:48.641Z,1726528368.641 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB34E0
2024-09-16T23:12:48.641Z,1726528368.641 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929
2024-09-16T23:12:48.679Z,1726528368.679 [ESPComponent] Loaded
2024-09-16T23:12:48.679Z,1726528368.679 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-16T23:12:48.702Z,1726528368.702 [PAR_Licor] Loaded
2024-09-16T23:12:48.702Z,1726528368.702 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-16T23:12:48.749Z,1726528368.749 [WetLabsBB2FL] Loaded
2024-09-16T23:12:48.749Z,1726528368.749 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-16T23:12:48.750Z,1726528368.750 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE34E0
2024-09-16T23:12:48.751Z,1726528368.751 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930
2024-09-16T23:12:48.752Z,1726528368.752 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-16T23:12:48.753Z,1726528368.753 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-16T23:12:48.940Z,1726528368.940 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-16T23:12:48.949Z,1726528368.949 [SBIT] Loaded
2024-09-16T23:12:48.949Z,1726528368.949 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-16T23:12:48.952Z,1726528368.952 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-16T23:12:48.965Z,1726528368.965 [IBIT] Loaded
2024-09-16T23:12:48.965Z,1726528368.965 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-16T23:12:48.971Z,1726528368.971 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-16T23:12:49.080Z,1726528369.080 [CBIT] Loaded
2024-09-16T23:12:49.080Z,1726528369.080 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-16T23:12:49.081Z,1726528369.081 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-16T23:12:49.089Z,1726528369.089 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-16T23:12:49.092Z,1726528369.092 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-16T23:12:49.103Z,1726528369.103 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-16T23:12:49.104Z,1726528369.104 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-09-16T23:12:49.104Z,1726528369.104 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931
2024-09-16T23:12:49.127Z,1726528369.127 [Supervisor](INFO): Main Thread ID is 827
2024-09-16T23:12:49.127Z,1726528369.127 [Supervisor](DEBUG): Running supervisor.
2024-09-16T23:12:49.128Z,1726528369.128 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932
2024-09-16T23:12:49.128Z,1726528369.128 [CommandExec](INFO): Initializing the command executive.
2024-09-16T23:12:49.130Z,1726528369.130 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933
2024-09-16T23:12:49.132Z,1726528369.132 [controlThread ThreadHandler](INFO): Handler Thread ID is 934
2024-09-16T23:12:49.133Z,1726528369.133 [controlThread](DEBUG): Initializing ControlThread
2024-09-16T23:12:49.134Z,1726528369.134 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-16T23:12:49.134Z,1726528369.134 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-16T23:12:49.134Z,1726528369.134 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-16T23:12:49.135Z,1726528369.135 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-16T23:12:49.135Z,1726528369.135 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-16T23:12:49.136Z,1726528369.136 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-16T23:12:49.138Z,1726528369.138 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-16T23:12:49.138Z,1726528369.138 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-16T23:12:49.139Z,1726528369.139 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-16T23:12:49.140Z,1726528369.140 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-16T23:12:49.140Z,1726528369.140 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-16T23:12:49.150Z,1726528369.150 [SBIT](INFO): Initialize SBIT Component.
2024-09-16T23:12:49.150Z,1726528369.150 [SBIT](IMPORTANT): git: 2024-09-16_D
2024-09-16T23:12:49.150Z,1726528369.150 [SBIT](INFO): git hash: 0de9f8b4fab077d10194b536abee104cbdf12c92
2024-09-16T23:12:49.150Z,1726528369.150 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-16T23:12:49.151Z,1726528369.151 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-09-16T23:12:49.153Z,1726528369.153 [SBIT](INFO): Beginning SBIT in 93.000000 seconds.
2024-09-16T23:12:49.153Z,1726528369.153 [IBIT](INFO): Initialize IBIT Component.
2024-09-16T23:12:49.154Z,1726528369.154 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-16T23:12:49.155Z,1726528369.155 [logger ThreadHandler](INFO): Handler Thread ID is 935
2024-09-16T23:12:49.165Z,1726528369.165 [CBIT](DEBUG): Initialized mux pins.
2024-09-16T23:12:49.165Z,1726528369.165 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-16T23:12:49.174Z,1726528369.174 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936
2024-09-16T23:12:49.186Z,1726528369.186 [DATMMP ThreadHandler](INFO): Handler Thread ID is 937
2024-09-16T23:12:49.186Z,1726528369.186 [DATMMP](INFO): Start
2024-09-16T23:12:49.186Z,1726528369.186 [DATMMP](INFO): Powering up
2024-09-16T23:12:49.187Z,1726528369.187 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-16T23:12:49.189Z,1726528369.189 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-16T23:12:49.190Z,1726528369.190 [CBIT](DEBUG): Initializing heartbeat.
2024-09-16T23:12:49.191Z,1726528369.191 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939
2024-09-16T23:12:49.193Z,1726528369.193 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-16T23:12:49.202Z,1726528369.202 [Onboard ThreadHandler](INFO): Handler Thread ID is 940
2024-09-16T23:12:49.219Z,1726528369.219 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941
2024-09-16T23:12:49.242Z,1726528369.242 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942
2024-09-16T23:12:49.242Z,1726528369.242 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-16T23:12:49.246Z,1726528369.246 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944
2024-09-16T23:12:49.250Z,1726528369.250 [WetLabsBB2FL](INFO): Powering up
2024-09-16T23:12:49.251Z,1726528369.251 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 946
2024-09-16T23:12:49.259Z,1726528369.259 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-09-16T23:12:49.259Z,1726528369.259 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-09-16T23:12:49.260Z,1726528369.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-09-16T23:12:49.260Z,1726528369.260 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-09-16T23:12:49.260Z,1726528369.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-09-16T23:12:49.260Z,1726528369.260 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-09-16T23:12:49.260Z,1726528369.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-09-16T23:12:49.260Z,1726528369.260 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-09-16T23:12:49.261Z,1726528369.261 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-16T23:12:49.261Z,1726528369.261 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-16T23:12:49.301Z,1726528369.301 [CBIT](DEBUG): Backplane powered.
2024-09-16T23:12:49.302Z,1726528369.302 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-16T23:12:49.303Z,1726528369.303 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-16T23:12:49.303Z,1726528369.303 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-16T23:12:49.318Z,1726528369.318 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-16T23:12:49.351Z,1726528369.351 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-16T23:12:49.372Z,1726528369.372 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T23:12:49.395Z,1726528369.395 [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-16T23:12:49.396Z,1726528369.396 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-16T23:12:49.396Z,1726528369.396 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-16T23:12:49.414Z,1726528369.414 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-16T23:12:49.437Z,1726528369.437 [DATMMP](INFO): Starting
2024-09-16T23:12:49.625Z,1726528369.625 [Radio_Surface](INFO): Powering up
2024-09-16T23:12:49.676Z,1726528369.676 [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-16T23:12:49.689Z,1726528369.689 [DATMMP](INFO): Starting
2024-09-16T23:12:49.694Z,1726528369.694 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-16T23:12:49.696Z,1726528369.696 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-16T23:12:49.718Z,1726528369.718 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T23:12:49.755Z,1726528369.755 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-16T23:12:49.773Z,1726528369.773 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-16T23:12:49.779Z,1726528369.779 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-16T23:12:49.798Z,1726528369.798 [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-16T23:12:49.802Z,1726528369.802 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-16T23:12:49.815Z,1726528369.815 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-16T23:12:49.941Z,1726528369.941 [DATMMP](INFO): Starting
2024-09-16T23:12:50.006Z,1726528370.006 [Power24vConverter](INFO): Powering up.
2024-09-16T23:12:50.007Z,1726528370.007 [Sonardyne_Nano](INFO): Initializing.
2024-09-16T23:12:50.083Z,1726528370.083 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-16T23:12:50.089Z,1726528370.089 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-16T23:12:50.090Z,1726528370.090 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-16T23:12:50.097Z,1726528370.097 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-16T23:12:50.098Z,1726528370.098 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-16T23:12:50.105Z,1726528370.105 [MassServo](DEBUG): Initializing MassServo.
2024-09-16T23:12:50.106Z,1726528370.106 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-16T23:12:50.113Z,1726528370.113 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-16T23:12:50.114Z,1726528370.114 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-16T23:12:50.121Z,1726528370.121 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-16T23:12:50.194Z,1726528370.194 [DATMMP](INFO): Starting
2024-09-16T23:12:50.445Z,1726528370.445 [DATMMP](INFO): Starting
2024-09-16T23:12:50.697Z,1726528370.697 [DATMMP](INFO): Starting
2024-09-16T23:12:50.949Z,1726528370.949 [DATMMP](INFO): Starting
2024-09-16T23:12:51.202Z,1726528371.202 [DATMMP](INFO): Starting
2024-09-16T23:12:51.453Z,1726528371.453 [DATMMP](INFO): Starting
2024-09-16T23:12:51.706Z,1726528371.706 [DATMMP](INFO): Starting
2024-09-16T23:12:51.862Z,1726528371.862 [WetLabsBB2FL](INFO): Powering down
2024-09-16T23:12:51.957Z,1726528371.957 [DATMMP](INFO): Starting
2024-09-16T23:12:52.209Z,1726528372.209 [DATMMP](INFO): Starting
2024-09-16T23:12:52.235Z,1726528372.235 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-16T23:12:52.461Z,1726528372.461 [DATMMP](INFO): Starting
2024-09-16T23:12:52.713Z,1726528372.713 [DATMMP](INFO): Starting
2024-09-16T23:12:52.966Z,1726528372.966 [DATMMP](INFO): Starting
2024-09-16T23:12:53.217Z,1726528373.217 [DATMMP](INFO): Starting
2024-09-16T23:12:53.469Z,1726528373.469 [DATMMP](INFO): Starting
2024-09-16T23:12:53.483Z,1726528373.483 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2024-09-16T23:12:53.721Z,1726528373.721 [DATMMP](INFO): Starting
2024-09-16T23:12:53.973Z,1726528373.973 [DATMMP](INFO): Starting
2024-09-16T23:12:54.226Z,1726528374.226 [DATMMP](INFO): Starting
2024-09-16T23:12:54.478Z,1726528374.478 [DATMMP](INFO): Starting
2024-09-16T23:12:54.729Z,1726528374.729 [DATMMP](INFO): Starting
2024-09-16T23:12:54.981Z,1726528374.981 [DATMMP](INFO): Starting
2024-09-16T23:12:55.233Z,1726528375.233 [DATMMP](INFO): Starting
2024-09-16T23:12:55.486Z,1726528375.486 [DATMMP](INFO): Starting
2024-09-16T23:12:55.590Z,1726528375.590 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-16T23:12:55.737Z,1726528375.737 [DATMMP](INFO): Starting
2024-09-16T23:12:55.989Z,1726528375.989 [DATMMP](INFO): Starting
2024-09-16T23:12:56.241Z,1726528376.241 [DATMMP](INFO): Starting
2024-09-16T23:12:56.493Z,1726528376.493 [DATMMP](INFO): Starting
2024-09-16T23:12:56.745Z,1726528376.745 [DATMMP](INFO): Starting
2024-09-16T23:12:56.997Z,1726528376.997 [DATMMP](INFO): Starting
2024-09-16T23:12:57.249Z,1726528377.249 [DATMMP](INFO): Starting
2024-09-16T23:12:57.503Z,1726528377.503 [DATMMP](INFO): Starting
2024-09-16T23:12:57.753Z,1726528377.753 [DATMMP](INFO): Starting
2024-09-16T23:12:58.005Z,1726528378.005 [DATMMP](INFO): Starting
2024-09-16T23:12:58.257Z,1726528378.257 [DATMMP](INFO): Starting
2024-09-16T23:12:58.509Z,1726528378.509 [DATMMP](INFO): Starting
2024-09-16T23:12:58.761Z,1726528378.761 [DATMMP](INFO): Starting
2024-09-16T23:12:59.013Z,1726528379.013 [DATMMP](INFO): Starting
2024-09-16T23:12:59.265Z,1726528379.265 [DATMMP](INFO): Starting
2024-09-16T23:12:59.517Z,1726528379.517 [DATMMP](INFO): Starting
2024-09-16T23:12:59.770Z,1726528379.770 [DATMMP](INFO): Starting
2024-09-16T23:13:00.021Z,1726528380.021 [DATMMP](INFO): Starting
2024-09-16T23:13:00.273Z,1726528380.273 [DATMMP](INFO): Starting
2024-09-16T23:13:00.525Z,1726528380.525 [DATMMP](INFO): Starting
2024-09-16T23:13:00.778Z,1726528380.778 [DATMMP](INFO): Starting
2024-09-16T23:13:01.029Z,1726528381.029 [DATMMP](INFO): Starting
2024-09-16T23:13:01.281Z,1726528381.281 [DATMMP](INFO): Starting
2024-09-16T23:13:01.282Z,1726528381.282 [DATMMP](INFO): DAT read:
2024-09-16T23:13:01.283Z,1726528381.283 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-16T23:13:01.533Z,1726528381.533 [DATMMP](INFO): Starting
2024-09-16T23:13:01.785Z,1726528381.785 [DATMMP](INFO): Starting
2024-09-16T23:13:02.037Z,1726528382.037 [DATMMP](INFO): Starting
2024-09-16T23:13:02.289Z,1726528382.289 [DATMMP](INFO): Starting
2024-09-16T23:13:02.541Z,1726528382.541 [DATMMP](INFO): Starting
2024-09-16T23:13:02.793Z,1726528382.793 [DATMMP](INFO): Starting
2024-09-16T23:13:03.045Z,1726528383.045 [DATMMP](INFO): Starting
2024-09-16T23:13:03.046Z,1726528383.046 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-16T23:13:03.047Z,1726528383.047 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-16T23:13:03.048Z,1726528383.048 [DATMMP](INFO): DAT read: Aug 16 2000 23:12:58
2024-09-16T23:13:03.297Z,1726528383.297 [DATMMP](INFO): Starting
2024-09-16T23:13:03.549Z,1726528383.549 [DATMMP](INFO): Starting
2024-09-16T23:13:03.801Z,1726528383.801 [DATMMP](INFO): Starting
2024-09-16T23:13:04.053Z,1726528384.053 [DATMMP](INFO): Starting
2024-09-16T23:13:04.305Z,1726528384.305 [DATMMP](INFO): Starting
2024-09-16T23:13:04.306Z,1726528384.306 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-16T23:13:04.307Z,1726528384.307 [DATMMP](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2024-09-16T23:13:04.308Z,1726528384.308 [DATMMP](INFO): commRate: 600
2024-09-16T23:13:04.557Z,1726528384.557 [DATMMP](INFO): Starting
2024-09-16T23:13:04.809Z,1726528384.809 [DATMMP](INFO): Starting
2024-09-16T23:13:05.062Z,1726528385.062 [DATMMP](INFO): Starting
2024-09-16T23:13:05.313Z,1726528385.313 [DATMMP](INFO): Starting
2024-09-16T23:13:05.565Z,1726528385.565 [DATMMP](INFO): Starting
2024-09-16T23:13:05.817Z,1726528385.817 [DATMMP](INFO): Starting
2024-09-16T23:13:06.069Z,1726528386.069 [DATMMP](INFO): Starting
2024-09-16T23:13:06.321Z,1726528386.321 [DATMMP](INFO): Starting
2024-09-16T23:13:06.373Z,1726528386.373 [DATMMP](INFO): entering command mode
2024-09-16T23:13:06.573Z,1726528386.573 [DATMMP](INFO): Starting
2024-09-16T23:13:06.574Z,1726528386.574 [DATMMP](INFO): DAT read:
2024-09-16T23:13:06.574Z,1726528386.574 [DATMMP](INFO): DAT read: user:1>
2024-09-16T23:13:06.574Z,1726528386.574 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-16T23:13:06.575Z,1726528386.575 [DATMMP](INFO): entering MMP mode
2024-09-16T23:13:06.825Z,1726528386.825 [DATMMP](INFO): Starting
2024-09-16T23:13:06.826Z,1726528386.826 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:13:06.828Z,1726528386.828 [DATMMP](INFO): status rx: x1
2024-09-16T23:13:06.828Z,1726528386.828 [DATMMP](INFO): MMP status: 1: started
2024-09-16T23:13:06.828Z,1726528386.828 [DATMMP](INFO): Received message type: status
2024-09-16T23:13:06.828Z,1726528386.828 [DATMMP](INFO): Handled
2024-09-16T23:13:07.077Z,1726528387.077 [DATMMP](INFO): Starting
2024-09-16T23:13:07.079Z,1726528387.079 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-16T23:13:07.079Z,1726528387.079 [DATMMP](INFO): Setting local address: 11
2024-09-16T23:13:07.081Z,1726528387.081 [DATMMP](INFO): Setting time to: 23:13:7 And date to:9/16/2024
2024-09-16T23:13:07.329Z,1726528387.329 [DATMMP](INFO): Starting
2024-09-16T23:13:07.330Z,1726528387.330 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:13:07.331Z,1726528387.331 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-16T23:13:07.331Z,1726528387.331 [DATMMP](INFO): Received message type: mdmpower
2024-09-16T23:13:07.331Z,1726528387.331 [DATMMP](INFO): Handled
2024-09-16T23:13:07.581Z,1726528387.581 [DATMMP](INFO): Starting
2024-09-16T23:13:07.582Z,1726528387.582 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-16T23:13:07.582Z,1726528387.582 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-16T23:13:07.582Z,1726528387.582 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-16T23:13:07.583Z,1726528387.583 [DATMMP](INFO): Match for : sreg_txpower
2024-09-16T23:13:07.583Z,1726528387.583 [DATMMP](INFO): ACK
2024-09-16T23:13:07.833Z,1726528387.833 [DATMMP](INFO): Starting
2024-09-16T23:13:07.834Z,1726528387.834 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:13:07.835Z,1726528387.835 [DATMMP](INFO): mdmlocal rx: xB x0 x0 x0
2024-09-16T23:13:07.835Z,1726528387.835 [DATMMP](INFO): Received message type: mdmlocal
2024-09-16T23:13:07.835Z,1726528387.835 [DATMMP](INFO): Handled
2024-09-16T23:13:08.085Z,1726528388.085 [DATMMP](INFO): Starting
2024-09-16T23:13:08.086Z,1726528388.086 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-16T23:13:08.086Z,1726528388.086 [DATMMP](INFO): sreg_locaddr rx: xB
2024-09-16T23:13:08.087Z,1726528388.087 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-16T23:13:08.087Z,1726528388.087 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-16T23:13:08.087Z,1726528388.087 [DATMMP](INFO): ACK
2024-09-16T23:13:08.337Z,1726528388.337 [DATMMP](INFO): Starting
2024-09-16T23:13:08.338Z,1726528388.338 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-16T23:13:08.339Z,1726528388.339 [DATMMP](INFO): timedate rx: x0 x17 xD x7 x8 x10 x7C x0
2024-09-16T23:13:08.339Z,1726528388.339 [DATMMP](INFO): Received message type: timedate
2024-09-16T23:13:08.339Z,1726528388.339 [DATMMP](INFO): Match for : timedate
2024-09-16T23:13:08.339Z,1726528388.339 [DATMMP](INFO): ACK
2024-09-16T23:13:08.589Z,1726528388.589 [DATMMP](INFO): Starting
2024-09-16T23:13:08.590Z,1726528388.590 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:13:08.591Z,1726528388.591 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-16T23:13:08.591Z,1726528388.591 [DATMMP](INFO): Received message type: sys_verb
2024-09-16T23:13:08.591Z,1726528388.591 [DATMMP](INFO): Handled
2024-09-16T23:13:08.842Z,1726528388.842 [DATMMP](INFO): Starting
2024-09-16T23:13:08.844Z,1726528388.844 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-16T23:13:08.845Z,1726528388.845 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-16T23:13:08.845Z,1726528388.845 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-16T23:13:08.845Z,1726528388.845 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-16T23:13:08.845Z,1726528388.845 [DATMMP](INFO): ACK
2024-09-16T23:13:09.093Z,1726528389.093 [DATMMP](INFO): Starting
2024-09-16T23:13:09.094Z,1726528389.094 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-16T23:13:09.094Z,1726528389.094 [DATMMP](INFO): version rx: x2 x5
2024-09-16T23:13:09.095Z,1726528389.095 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-16T23:13:09.095Z,1726528389.095 [DATMMP](INFO): Received message type: version
2024-09-16T23:13:09.095Z,1726528389.095 [DATMMP](INFO): Match for : version
2024-09-16T23:13:09.095Z,1726528389.095 [DATMMP](INFO): ACK
2024-09-16T23:13:09.095Z,1726528389.095 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-16T23:13:17.695Z,1726528397.695 [NAL9602](INFO): Powering up NAL9602
2024-09-16T23:13:23.710Z,1726528403.710 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:13:23.710Z,1726528403.710 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-16T23:13:23.711Z,1726528403.711 [DATMMP](INFO): Received message type: timesync
2024-09-16T23:13:23.711Z,1726528403.711 [DATMMP](INFO): Handled
2024-09-16T23:13:28.597Z,1726528408.597 [NAL9602](INFO): NAL9602 initialized
2024-09-16T23:13:33.558Z,1726528413.558 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:13:33.559Z,1726528413.559 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-16T23:13:33.559Z,1726528413.559 [DATMMP](INFO): Received message type: timesync
2024-09-16T23:13:33.559Z,1726528413.559 [DATMMP](INFO): Handled
2024-09-16T23:13:55.274Z,1726528435.274 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:14:03.550Z,1726528443.550 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:14:03.551Z,1726528443.551 [DATMMP](INFO): timesync rx: x0 x0 x0 x0
2024-09-16T23:14:03.551Z,1726528443.551 [DATMMP](INFO): Received message type: timesync
2024-09-16T23:14:03.551Z,1726528443.551 [DATMMP](INFO): Handled
2024-09-16T23:14:09.001Z,1726528449.001 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:14:22.804Z,1726528462.804 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-16T23:14:22.831Z,1726528462.831 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T23:14:23.145Z,1726528463.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:14:24.722Z,1726528464.722 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:14:24.723Z,1726528464.723 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-16T23:14:24.723Z,1726528464.723 [DATMMP](INFO): Received message type: timesync
2024-09-16T23:14:24.723Z,1726528464.723 [DATMMP](INFO): Handled
2024-09-16T23:14:33.862Z,1726528473.862 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.012553
CHAN A1 (24V): 0.003224
CHAN A2 (12V): -0.009295
CHAN A3 (5V): -0.015765
CHAN B0 (3.3V): -0.004664
CHAN B1 (3.15aV): -0.001500
CHAN B2 (3.15bV): 0.000009
CHAN B3 (GND): -0.006213
OPEN: 0.000877
Full Scale: +/- 1 mA
2024-09-16T23:14:34.550Z,1726528474.550 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T23:14:34.551Z,1726528474.551 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-16T23:14:34.551Z,1726528474.551 [DATMMP](INFO): Received message type: timesync
2024-09-16T23:14:34.551Z,1726528474.551 [DATMMP](INFO): Handled
2024-09-16T23:14:36.885Z,1726528476.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:14:51.025Z,1726528491.025 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:15:04.754Z,1726528504.754 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:15:16.897Z,1726528516.897 [SBIT](IMPORTANT): SBIT PASSED
2024-09-16T23:15:16.897Z,1726528516.897 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-16T23:15:16.898Z,1726528516.898 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-09-16T23:15:16.898Z,1726528516.898 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-09-16T23:15:16.898Z,1726528516.898 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-16T23:15:16.898Z,1726528516.898 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;
2024-09-16T23:15:16.898Z,1726528516.898 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=96.445098 cubic_centimeter;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=1.25 second;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=1.25 reciprocal_second;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=1.25 none;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): VerticalControl.massDefault=18.420757 millimeter;
2024-09-16T23:15:16.899Z,1726528516.899 [SBIT](IMPORTANT): VerticalControl.massOnlyForceInt=0 bool;
2024-09-16T23:15:17.305Z,1726528517.305 [MissionManager](IMPORTANT): Started mission Startup
2024-09-16T23:15:17.306Z,1726528517.306 [Startup] Running Loop=1
2024-09-16T23:15:17.306Z,1726528517.306 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-16T23:15:17.306Z,1726528517.306 [Startup:A.GoToSurface] Running Loop=1
2024-09-16T23:15:17.306Z,1726528517.306 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T23:15:17.306Z,1726528517.306 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T23:15:17.307Z,1726528517.307 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T23:15:17.307Z,1726528517.307 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T23:15:17.308Z,1726528517.308 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T23:15:17.308Z,1726528517.308 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T23:15:17.308Z,1726528517.308 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T23:15:17.310Z,1726528517.310 [Startup:StartupSatComms] Running Loop=1
2024-09-16T23:15:17.310Z,1726528517.310 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-16T23:15:17.310Z,1726528517.310 [Startup:StartupSatComms:A] Running Loop=1
2024-09-16T23:15:17.730Z,1726528517.730 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-16T23:15:18.894Z,1726528518.894 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:15:32.631Z,1726528532.631 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:15:35.363Z,1726528535.363 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004252
2024-09-16T23:15:49.211Z,1726528549.211 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-16T23:15:49.211Z,1726528549.211 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T23:15:49.291Z,1726528549.291 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T23:15:49.701Z,1726528549.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T23:15:49.701Z,1726528549.701 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-16T23:16:17.645Z,1726528577.645 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-16T23:15:17.3Z
2024-09-16T23:16:17.645Z,1726528577.645 [Startup:StartupSatComms:A] Stopped
2024-09-16T23:16:17.645Z,1726528577.645 [Startup:StartupSatComms:B] Running Loop=1
2024-09-16T23:16:18.073Z,1726528578.073 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T23:16:24.696Z,1726528584.696 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240916T202059/Courier0073.lzma
2024-09-16T23:16:25.696Z,1726528585.696 [DataOverHttps](INFO): Moved sent file to Logs/20240916T202059/Courier0073.lzma.bak
2024-09-16T23:16:25.696Z,1726528585.696 [DataOverHttps](INFO): SBD MOMSN=23193291
2024-09-16T23:16:41.497Z,1726528601.497 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240916T231234/Courier0000.lzma
2024-09-16T23:16:42.500Z,1726528602.500 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0000.lzma.bak
2024-09-16T23:16:42.500Z,1726528602.500 [DataOverHttps](INFO): SBD MOMSN=23193296
2024-09-16T23:16:58.109Z,1726528618.109 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240916T202059/Express0074.lzma
2024-09-16T23:16:59.112Z,1726528619.112 [DataOverHttps](INFO): Moved sent file to Logs/20240916T202059/Express0074.lzma.bak
2024-09-16T23:16:59.112Z,1726528619.112 [DataOverHttps](INFO): SBD MOMSN=23193299
2024-09-16T23:17:14.823Z,1726528634.823 [DataOverHttps](INFO): Sending 970 bytes from file Logs/20240916T231234/Express0001.lzma
2024-09-16T23:17:15.823Z,1726528635.823 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0001.lzma.bak
2024-09-16T23:17:15.824Z,1726528635.824 [DataOverHttps](INFO): SBD MOMSN=23193321
2024-09-16T23:17:17.599Z,1726528637.599 [Startup:StartupSatComms:B] Stopped
2024-09-16T23:17:17.599Z,1726528637.599 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-16T23:17:17.599Z,1726528637.599 [Startup:StartupSatComms] Stopped
2024-09-16T23:17:17.599Z,1726528637.599 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-16T23:17:17.600Z,1726528637.600 [Startup](INFO): Completed Startup
2024-09-16T23:17:17.600Z,1726528637.600 [MissionManager](INFO): Startup is completed.
2024-09-16T23:17:17.600Z,1726528637.600 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-16T23:17:17.600Z,1726528637.600 [Startup] Stopped
2024-09-16T23:17:17.600Z,1726528637.600 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-16T23:17:17.600Z,1726528637.600 [Startup:A.GoToSurface] Stopped
2024-09-16T23:17:17.601Z,1726528637.601 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T23:17:17.994Z,1726528637.994 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T23:17:17.995Z,1726528637.995 [Default] Running Loop=1
2024-09-16T23:17:17.995Z,1726528637.995 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T23:17:17.995Z,1726528637.995 [Default:B.GoToSurface] Running Loop=1
2024-09-16T23:17:17.995Z,1726528637.995 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T23:17:17.995Z,1726528637.995 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T23:17:17.995Z,1726528637.995 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T23:17:17.996Z,1726528637.996 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T23:17:17.996Z,1726528637.996 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T23:17:17.996Z,1726528637.996 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T23:17:17.997Z,1726528637.997 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T23:17:17.997Z,1726528637.997 [Default:A.Wait] Running Loop=1
2024-09-16T23:17:17.997Z,1726528637.997 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:17:31.327Z,1726528651.327 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T23:17:31.327Z,1726528651.327 [Default:A.Wait] Stopped
2024-09-16T23:17:31.327Z,1726528651.327 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:17:31.741Z,1726528651.741 [Default:CheckIn] Running Loop=1
2024-09-16T23:17:31.741Z,1726528651.741 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:17:31.741Z,1726528651.741 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:17:32.139Z,1726528652.139 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-16T23:18:31.508Z,1726528711.508 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T23:18:50.119Z,1726528730.119 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-16T23:18:50.119Z,1726528730.119 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T23:18:50.129Z,1726528730.129 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T23:18:50.544Z,1726528730.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T23:18:50.544Z,1726528730.544 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-16T23:19:01.806Z,1726528741.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231858.00,A,3648.17709,N,12147.28967,W,0.661,316.01,160924,,,A*77
2024-09-16T23:19:01.810Z,1726528741.810 [NAL9602](INFO): GPS fix at 20240916T231858: (36.802951, -121.788161)
2024-09-16T23:19:01.821Z,1726528741.821 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:19:01.821Z,1726528741.821 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:19:02.238Z,1726528742.238 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T23:19:09.853Z,1726528749.853 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240916T231234/Courier0004.lzma
2024-09-16T23:19:10.856Z,1726528750.856 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0004.lzma.bak
2024-09-16T23:19:10.856Z,1726528750.856 [DataOverHttps](INFO): SBD MOMSN=23193351
2024-09-16T23:19:28.994Z,1726528768.994 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20240916T231234/Express0005.lzma
2024-09-16T23:19:29.996Z,1726528769.996 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0005.lzma.bak
2024-09-16T23:19:29.996Z,1726528769.996 [DataOverHttps](INFO): SBD MOMSN=23193354
2024-09-16T23:19:31.730Z,1726528771.730 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:19:31.731Z,1726528771.731 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:19:31.731Z,1726528771.731 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:19:34.532Z,1726528774.532 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T23:23:04.200Z,1726528984.200 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:23:18.342Z,1726528998.342 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:23:32.081Z,1726529012.081 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:23:46.217Z,1726529026.217 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:23:59.953Z,1726529039.953 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:24:14.092Z,1726529054.092 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:24:27.830Z,1726529067.830 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:24:32.336Z,1726529072.336 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:24:32.336Z,1726529072.336 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:24:32.336Z,1726529072.336 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:24:32.336Z,1726529072.336 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:24:32.710Z,1726529072.710 [Default:CheckIn:D] Stopped
2024-09-16T23:24:32.710Z,1726529072.710 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.245262 min
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn:E] Stopped
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn] Stopped
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn](INFO): Running loop #2
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn] Running Loop=2
2024-09-16T23:24:33.110Z,1726529073.110 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:24:33.111Z,1726529073.111 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:24:35.118Z,1726529075.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232431.00,A,3648.16385,N,12147.27981,W,0.136,308.87,160924,,,A*75
2024-09-16T23:24:35.120Z,1726529075.120 [NAL9602](INFO): GPS fix at 20240916T232431: (36.802731, -121.787997)
2024-09-16T23:24:35.158Z,1726529075.158 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:24:35.158Z,1726529075.158 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:24:41.968Z,1726529081.968 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:24:42.637Z,1726529082.637 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0007.lzma
2024-09-16T23:24:43.640Z,1726529083.640 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0007.lzma.bak
2024-09-16T23:24:43.640Z,1726529083.640 [DataOverHttps](INFO): SBD MOMSN=23193410
2024-09-16T23:24:55.707Z,1726529095.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:24:59.673Z,1726529099.673 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20240916T231234/Express0008.lzma
2024-09-16T23:25:00.676Z,1726529100.676 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0008.lzma.bak
2024-09-16T23:25:00.676Z,1726529100.676 [DataOverHttps](INFO): SBD MOMSN=23193423
2024-09-16T23:25:02.597Z,1726529102.597 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:25:02.597Z,1726529102.597 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:25:02.597Z,1726529102.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:25:05.816Z,1726529105.816 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-16T23:25:05.890Z,1726529105.890 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-09-16T23:25:09.343Z,1726529109.343 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T232508
2024-09-16T23:25:09.846Z,1726529109.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:25:16.770Z,1726529116.770 [DataOverHttps](INFO): Received command: strobe off
2024-09-16T23:25:16.799Z,1726529116.799 [CommandExec](IMPORTANT): got command strobe off
2024-09-16T23:25:16.799Z,1726529116.799 [CommandExec](IMPORTANT): Deactivating strobe
2024-09-16T23:25:23.591Z,1726529123.591 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:29:37.346Z,1726529377.346 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T23:30:03.235Z,1726529403.235 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:30:03.235Z,1726529403.235 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:30:03.235Z,1726529403.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:30:03.236Z,1726529403.236 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:30:03.643Z,1726529403.643 [Default:CheckIn:D] Stopped
2024-09-16T23:30:03.643Z,1726529403.643 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.760516 min
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn:E] Stopped
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn] Stopped
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn](INFO): Running loop #3
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn] Running Loop=3
2024-09-16T23:30:04.011Z,1726529404.011 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:30:04.012Z,1726529404.012 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:30:06.051Z,1726529406.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233005.00,A,3648.16720,N,12147.28343,W,0.467,324.28,160924,,,A*7D
2024-09-16T23:30:06.065Z,1726529406.065 [NAL9602](INFO): GPS fix at 20240916T233005: (36.802787, -121.788057)
2024-09-16T23:30:06.093Z,1726529406.093 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:30:06.093Z,1726529406.093 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:30:13.277Z,1726529413.277 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0010.lzma
2024-09-16T23:30:14.280Z,1726529414.280 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0010.lzma.bak
2024-09-16T23:30:14.280Z,1726529414.280 [DataOverHttps](INFO): SBD MOMSN=23193523
2024-09-16T23:30:30.258Z,1726529430.258 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20240916T231234/Express0011.lzma
2024-09-16T23:30:31.260Z,1726529431.260 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0011.lzma.bak
2024-09-16T23:30:31.260Z,1726529431.260 [DataOverHttps](INFO): SBD MOMSN=23193526
2024-09-16T23:30:38.342Z,1726529438.342 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T23:30:48.345Z,1726529448.345 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20240916T231234/Express0012.lzma
2024-09-16T23:30:49.348Z,1726529449.348 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0012.lzma.bak
2024-09-16T23:30:49.348Z,1726529449.348 [DataOverHttps](INFO): SBD MOMSN=23193533
2024-09-16T23:30:51.299Z,1726529451.299 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:30:51.299Z,1726529451.299 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:30:51.299Z,1726529451.299 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:32:52.477Z,1726529572.477 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:32:52.483Z,1726529572.483 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-09-16T23:33:06.232Z,1726529586.232 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:33:20.347Z,1726529600.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:33:34.082Z,1726529614.082 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:33:48.220Z,1726529628.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:34:01.976Z,1726529641.976 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:34:16.121Z,1726529656.121 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:34:29.864Z,1726529669.864 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:34:44.005Z,1726529684.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:34:57.754Z,1726529697.754 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:35:11.889Z,1726529711.889 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:35:51.905Z,1726529751.905 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:35:51.905Z,1726529751.905 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:35:51.905Z,1726529751.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:35:51.905Z,1726529751.905 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:35:52.311Z,1726529752.311 [Default:CheckIn:D] Stopped
2024-09-16T23:35:52.311Z,1726529752.311 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.571932 min
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn:E] Stopped
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn] Stopped
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn](INFO): Running loop #4
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn] Running Loop=4
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:35:52.735Z,1726529752.735 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:35:54.724Z,1726529754.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233554.00,A,3648.16343,N,12147.28353,W,0.194,324.28,160924,,,A*75
2024-09-16T23:35:54.726Z,1726529754.726 [NAL9602](INFO): GPS fix at 20240916T233554: (36.802724, -121.788059)
2024-09-16T23:35:54.738Z,1726529754.738 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:35:54.738Z,1726529754.738 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:36:02.341Z,1726529762.341 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0015.lzma
2024-09-16T23:36:03.344Z,1726529763.344 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0015.lzma.bak
2024-09-16T23:36:03.344Z,1726529763.344 [DataOverHttps](INFO): SBD MOMSN=23193557
2024-09-16T23:36:19.325Z,1726529779.325 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240916T231234/Express0017.lzma
2024-09-16T23:36:20.328Z,1726529780.328 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0017.lzma.bak
2024-09-16T23:36:20.328Z,1726529780.328 [DataOverHttps](INFO): SBD MOMSN=23193564
2024-09-16T23:36:22.203Z,1726529782.203 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:36:22.203Z,1726529782.203 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:36:22.203Z,1726529782.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:36:25.419Z,1726529785.419 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-16T23:36:25.502Z,1726529785.502 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-09-16T23:36:50.883Z,1726529810.883 [DVL_micro](ERROR): Failed to parse:
:SA,-01.81,+02.53,128.4
2024-09-16T23:40:38.735Z,1726530038.735 [NAL9602](INFO): SBD MO Status=2, MOMSN=21941, MT Status=2, MTMSN=0
2024-09-16T23:40:38.735Z,1726530038.735 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T23:40:57.319Z,1726530057.319 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T23:41:22.787Z,1726530082.787 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:41:22.787Z,1726530082.787 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:41:22.787Z,1726530082.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:41:22.787Z,1726530082.787 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:41:23.188Z,1726530083.188 [Default:CheckIn:D] Stopped
2024-09-16T23:41:23.188Z,1726530083.188 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.086548 min
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn:E] Stopped
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn] Stopped
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn](INFO): Running loop #5
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn] Running Loop=5
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:41:23.664Z,1726530083.664 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:41:25.593Z,1726530085.593 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234124.00,A,3648.16831,N,12147.28249,W,0.447,338.56,160924,,,A*7A
2024-09-16T23:41:25.596Z,1726530085.596 [NAL9602](INFO): GPS fix at 20240916T234124: (36.802805, -121.788042)
2024-09-16T23:41:25.607Z,1726530085.607 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:41:25.607Z,1726530085.607 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:41:32.997Z,1726530092.997 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0019.lzma
2024-09-16T23:41:34.001Z,1726530094.001 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0019.lzma.bak
2024-09-16T23:41:34.001Z,1726530094.001 [DataOverHttps](INFO): SBD MOMSN=23193601
2024-09-16T23:41:49.835Z,1726530109.835 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240916T231234/Express0020.lzma
2024-09-16T23:41:50.836Z,1726530110.836 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0020.lzma.bak
2024-09-16T23:41:50.836Z,1726530110.836 [DataOverHttps](INFO): SBD MOMSN=23193607
2024-09-16T23:41:52.690Z,1726530112.690 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:41:52.690Z,1726530112.690 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:41:52.690Z,1726530112.690 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:41:58.315Z,1726530118.315 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T23:42:43.158Z,1726530163.158 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:42:57.357Z,1726530177.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:43:11.047Z,1726530191.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:43:25.181Z,1726530205.181 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:43:38.908Z,1726530218.908 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-16T23:43:50.235Z,1726530230.235 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4.
2024-09-16T23:43:50.239Z,1726530230.239 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-09-16T23:44:22.767Z,1726530262.767 [DataOverHttps](IMPORTANT): SBD MTMSN=20240916T234421
2024-09-16T23:44:30.233Z,1726530270.233 [DataOverHttps](INFO): Received command: ibit
2024-09-16T23:44:30.298Z,1726530270.298 [CommandExec](IMPORTANT): got command ibit
2024-09-16T23:44:30.680Z,1726530270.680 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-16T23:44:30.680Z,1726530270.680 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-16T23:44:30.688Z,1726530270.688 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T23:44:32.253Z,1726530272.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234431.00,A,3648.14651,N,12147.25525,W,0.117,190.12,160924,,,A*71
2024-09-16T23:44:32.255Z,1726530272.255 [NAL9602](INFO): GPS fix at 20240916T234431: (36.802442, -121.787588)
2024-09-16T23:44:41.641Z,1726530281.641 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015390
CHAN A1 (24V): 0.005687
CHAN A2 (12V): -0.011222
CHAN A3 (5V): -0.014306
CHAN B0 (3.3V): -0.006352
CHAN B1 (3.15aV): -0.001062
CHAN B2 (3.15bV): -0.000773
CHAN B3 (GND): -0.007225
OPEN: 0.005520
Full Scale: +/- 1 mA
2024-09-16T23:45:05.385Z,1726530305.385 [NAL9602](INFO): SBD MO Status=0, MOMSN=21941, MT Status=0, MTMSN=0
2024-09-16T23:45:05.385Z,1726530305.385 [NAL9602](INFO): No messages in MT queue
2024-09-16T23:45:06.601Z,1726530306.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234506.00,A,3648.14207,N,12147.24281,W,0.058,112.19,160924,,,D*75
2024-09-16T23:45:06.604Z,1726530306.604 [NAL9602](INFO): GPS fix at 20240916T234506: (36.802368, -121.787380)
2024-09-16T23:45:09.025Z,1726530309.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234508.00,A,3648.14158,N,12147.24267,W,0.914,141.58,160924,,,D*78
2024-09-16T23:45:09.028Z,1726530309.028 [NAL9602](INFO): GPS fix at 20240916T234508: (36.802360, -121.787378)
2024-09-16T23:45:12.257Z,1726530312.257 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234511.00,A,3648.14132,N,12147.24238,W,0.058,141.58,160924,,,D*77
2024-09-16T23:45:12.260Z,1726530312.260 [NAL9602](INFO): GPS fix at 20240916T234511: (36.802355, -121.787373)
2024-09-16T23:45:15.081Z,1726530315.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234514.00,A,3648.14135,N,12147.24249,W,0.058,141.58,160924,,,D*73
2024-09-16T23:45:15.084Z,1726530315.084 [NAL9602](INFO): GPS fix at 20240916T234514: (36.802356, -121.787375)
2024-09-16T23:45:17.160Z,1726530317.160 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802357 Longitude: -121.787376
2024-09-16T23:45:17.574Z,1726530317.574 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.452999
2024-09-16T23:45:17.574Z,1726530317.574 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-09-16T23:45:17.575Z,1726530317.575 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-09-16T23:45:17.917Z,1726530317.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234517.00,A,3648.14137,N,12147.24254,W,0.058,141.58,160924,,,D*7E
2024-09-16T23:45:17.927Z,1726530317.927 [NAL9602](INFO): GPS fix at 20240916T234517: (36.802356, -121.787376)
2024-09-16T23:45:17.944Z,1726530317.944 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-16T23:45:17.944Z,1726530317.944 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-16T23:45:17.944Z,1726530317.944 [IBIT](IMPORTANT): Pressure:9.894693 PSI
2024-09-16T23:45:17.945Z,1726530317.945 [IBIT](IMPORTANT): Humidity:13.752898 %
2024-09-16T23:45:18.365Z,1726530318.365 [IBIT](IMPORTANT): Vehicle Pitch:0.028870 degrees
2024-09-16T23:45:18.365Z,1726530318.365 [IBIT](IMPORTANT): Vehicle Roll:1.955601 degrees
2024-09-16T23:45:18.365Z,1726530318.365 [IBIT](IMPORTANT): Vehicle Heading:6.870187 degrees
2024-09-16T23:45:18.740Z,1726530318.740 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-16T23:45:18.740Z,1726530318.740 [IBIT](IMPORTANT): buoyancyNeutral: 96.445099 cc
2024-09-16T23:45:18.741Z,1726530318.741 [IBIT](IMPORTANT): massDefault: 1.842076 cm
2024-09-16T23:45:18.741Z,1726530318.741 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-09-16T23:45:18.741Z,1726530318.741 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-09-16T23:45:18.741Z,1726530318.741 [IBIT](IMPORTANT): IBIT PASSED
2024-09-16T23:45:50.659Z,1726530350.659 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T23:46:53.338Z,1726530413.338 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:46:53.338Z,1726530413.338 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:46:53.338Z,1726530413.338 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:46:53.338Z,1726530413.338 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:46:53.740Z,1726530413.740 [Default:CheckIn:D] Stopped
2024-09-16T23:46:53.740Z,1726530413.740 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:46:54.175Z,1726530414.175 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.595756 min
2024-09-16T23:46:54.175Z,1726530414.175 [Default:CheckIn:E] Stopped
2024-09-16T23:46:54.175Z,1726530414.175 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:46:54.175Z,1726530414.175 [Default:CheckIn] Stopped
2024-09-16T23:46:54.175Z,1726530414.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:46:54.176Z,1726530414.176 [Default:CheckIn](INFO): Running loop #6
2024-09-16T23:46:54.176Z,1726530414.176 [Default:CheckIn] Running Loop=6
2024-09-16T23:46:54.176Z,1726530414.176 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:46:54.176Z,1726530414.176 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:46:56.157Z,1726530416.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234655.00,A,3648.14339,N,12147.22995,W,0.136,74.03,160924,,,D*47
2024-09-16T23:46:56.159Z,1726530416.159 [NAL9602](INFO): GPS fix at 20240916T234655: (36.802390, -121.787166)
2024-09-16T23:46:56.170Z,1726530416.170 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:46:56.170Z,1726530416.170 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:47:05.866Z,1726530425.866 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240916T231234/Courier0022.lzma
2024-09-16T23:47:06.868Z,1726530426.868 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0022.lzma.bak
2024-09-16T23:47:06.868Z,1726530426.868 [DataOverHttps](INFO): SBD MOMSN=23193624
2024-09-16T23:47:25.567Z,1726530445.567 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20240916T231234/Express0023.lzma
2024-09-16T23:47:26.468Z,1726530446.468 [NAL9602](INFO): SBD MO Status=0, MOMSN=21942, MT Status=0, MTMSN=0
2024-09-16T23:47:26.468Z,1726530446.468 [NAL9602](INFO): No messages in MT queue
2024-09-16T23:47:26.568Z,1726530446.568 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0023.lzma.bak
2024-09-16T23:47:26.568Z,1726530446.568 [DataOverHttps](INFO): SBD MOMSN=23193634
2024-09-16T23:47:28.486Z,1726530448.486 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:47:28.486Z,1726530448.486 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:47:28.486Z,1726530448.486 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:47:57.167Z,1726530477.167 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T23:52:29.174Z,1726530749.174 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:52:29.174Z,1726530749.174 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:52:29.174Z,1726530749.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:52:29.174Z,1726530749.174 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:52:29.568Z,1726530749.568 [Default:CheckIn:D] Stopped
2024-09-16T23:52:29.568Z,1726530749.568 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:52:29.986Z,1726530749.986 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.192891 min
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn:E] Stopped
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn] Stopped
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn](INFO): Running loop #7
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn] Running Loop=7
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:52:29.987Z,1726530749.987 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:52:31.973Z,1726530751.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235231.00,A,3648.14312,N,12147.22934,W,0.019,74.03,160924,,,D*4E
2024-09-16T23:52:31.975Z,1726530751.975 [NAL9602](INFO): GPS fix at 20240916T235231: (36.802385, -121.787156)
2024-09-16T23:52:31.987Z,1726530751.987 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:52:31.987Z,1726530751.987 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:52:40.793Z,1726530760.793 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0025.lzma
2024-09-16T23:52:41.796Z,1726530761.796 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0025.lzma.bak
2024-09-16T23:52:41.796Z,1726530761.796 [DataOverHttps](INFO): SBD MOMSN=23193704
2024-09-16T23:52:54.882Z,1726530774.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=21943, MT Status=2, MTMSN=0
2024-09-16T23:52:54.883Z,1726530774.883 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-16T23:53:01.397Z,1726530781.397 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0027.lzma
2024-09-16T23:53:02.401Z,1726530782.401 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0027.lzma.bak
2024-09-16T23:53:02.401Z,1726530782.401 [DataOverHttps](INFO): SBD MOMSN=23193711
2024-09-16T23:53:04.207Z,1726530784.207 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:53:04.207Z,1726530784.207 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:53:04.207Z,1726530784.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:54:22.652Z,1726530862.652 [NAL9602](INFO): SBD MO Status=0, MOMSN=21943, MT Status=0, MTMSN=0
2024-09-16T23:54:22.652Z,1726530862.652 [NAL9602](INFO): No messages in MT queue
2024-09-16T23:54:53.351Z,1726530893.351 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-16T23:58:04.887Z,1726531084.887 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T23:58:04.887Z,1726531084.887 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:58:04.887Z,1726531084.887 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:58:04.887Z,1726531084.887 [Default:CheckIn:D] Running Loop=1
2024-09-16T23:58:05.326Z,1726531085.326 [Default:CheckIn:D] Stopped
2024-09-16T23:58:05.326Z,1726531085.326 [Default:CheckIn:E] Running Loop=1
2024-09-16T23:58:05.707Z,1726531085.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.788851 min
2024-09-16T23:58:05.707Z,1726531085.707 [Default:CheckIn:E] Stopped
2024-09-16T23:58:05.707Z,1726531085.707 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T23:58:05.707Z,1726531085.707 [Default:CheckIn] Stopped
2024-09-16T23:58:05.707Z,1726531085.707 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:58:05.708Z,1726531085.708 [Default:CheckIn](INFO): Running loop #8
2024-09-16T23:58:05.708Z,1726531085.708 [Default:CheckIn] Running Loop=8
2024-09-16T23:58:05.708Z,1726531085.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T23:58:05.708Z,1726531085.708 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T23:58:07.705Z,1726531087.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235807.00,A,3648.14209,N,12147.22938,W,0.019,74.03,160924,,,D*46
2024-09-16T23:58:07.708Z,1726531087.708 [NAL9602](INFO): GPS fix at 20240916T235807: (36.802368, -121.787156)
2024-09-16T23:58:07.752Z,1726531087.752 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T23:58:07.752Z,1726531087.752 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T23:58:17.525Z,1726531097.525 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0031.lzma
2024-09-16T23:58:18.528Z,1726531098.528 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0031.lzma.bak
2024-09-16T23:58:18.528Z,1726531098.528 [DataOverHttps](INFO): SBD MOMSN=23193757
2024-09-16T23:58:22.695Z,1726531102.695 [NAL9602](INFO): SBD MO Status=0, MOMSN=21944, MT Status=0, MTMSN=0
2024-09-16T23:58:22.695Z,1726531102.695 [NAL9602](INFO): No messages in MT queue
2024-09-16T23:58:38.037Z,1726531118.037 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240916T231234/Express0032.lzma
2024-09-16T23:58:39.323Z,1726531119.323 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0032.lzma.bak
2024-09-16T23:58:39.323Z,1726531119.323 [DataOverHttps](INFO): SBD MOMSN=23193765
2024-09-16T23:58:41.737Z,1726531121.737 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T23:58:41.746Z,1726531121.746 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T23:58:41.746Z,1726531121.746 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:58:53.375Z,1726531133.375 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:03:42.346Z,1726531422.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:03:42.346Z,1726531422.346 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:03:42.347Z,1726531422.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:03:42.347Z,1726531422.347 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:03:42.745Z,1726531422.745 [Default:CheckIn:D] Stopped
2024-09-17T00:03:42.746Z,1726531422.746 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:03:43.193Z,1726531423.193 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.412512 min
2024-09-17T00:03:43.193Z,1726531423.193 [Default:CheckIn:E] Stopped
2024-09-17T00:03:43.193Z,1726531423.193 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:03:43.193Z,1726531423.193 [Default:CheckIn] Stopped
2024-09-17T00:03:43.193Z,1726531423.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:03:43.198Z,1726531423.198 [Default:CheckIn](INFO): Running loop #9
2024-09-17T00:03:43.198Z,1726531423.198 [Default:CheckIn] Running Loop=9
2024-09-17T00:03:43.199Z,1726531423.199 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:03:43.199Z,1726531423.199 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:03:45.165Z,1726531425.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000344.00,A,3648.14074,N,12147.22912,W,0.039,74.03,170924,,,D*4D
2024-09-17T00:03:45.167Z,1726531425.167 [NAL9602](INFO): GPS fix at 20240917T000344: (36.802346, -121.787152)
2024-09-17T00:03:45.183Z,1726531425.183 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:03:45.183Z,1726531425.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:03:54.290Z,1726531434.290 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0034.lzma
2024-09-17T00:03:55.292Z,1726531435.292 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0034.lzma.bak
2024-09-17T00:03:55.292Z,1726531435.292 [DataOverHttps](INFO): SBD MOMSN=23193778
2024-09-17T00:04:15.769Z,1726531455.769 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0035.lzma
2024-09-17T00:04:16.772Z,1726531456.772 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0035.lzma.bak
2024-09-17T00:04:16.772Z,1726531456.772 [DataOverHttps](INFO): SBD MOMSN=23193785
2024-09-17T00:04:18.921Z,1726531458.921 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:04:18.921Z,1726531458.921 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:04:18.921Z,1726531458.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:04:42.692Z,1726531482.692 [NAL9602](INFO): SBD MO Status=0, MOMSN=21945, MT Status=0, MTMSN=0
2024-09-17T00:04:42.692Z,1726531482.692 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:05:13.395Z,1726531513.395 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:09:19.547Z,1726531759.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:09:19.548Z,1726531759.548 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:09:19.548Z,1726531759.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:09:19.548Z,1726531759.548 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:09:19.993Z,1726531759.993 [Default:CheckIn:D] Stopped
2024-09-17T00:09:19.993Z,1726531759.993 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.032914 min
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn:E] Stopped
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn] Stopped
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn](INFO): Running loop #10
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn] Running Loop=10
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:09:20.356Z,1726531760.356 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:09:22.391Z,1726531762.391 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000921.00,A,3648.14238,N,12147.22902,W,0.019,74.03,170924,,,D*4D
2024-09-17T00:09:22.393Z,1726531762.393 [NAL9602](INFO): GPS fix at 20240917T000921: (36.802373, -121.787150)
2024-09-17T00:09:22.404Z,1726531762.404 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:09:22.404Z,1726531762.404 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:09:31.405Z,1726531771.405 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0037.lzma
2024-09-17T00:09:32.408Z,1726531772.408 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0037.lzma.bak
2024-09-17T00:09:32.408Z,1726531772.408 [DataOverHttps](INFO): SBD MOMSN=23193812
2024-09-17T00:09:54.565Z,1726531794.565 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240916T231234/Express0038.lzma
2024-09-17T00:09:55.568Z,1726531795.568 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0038.lzma.bak
2024-09-17T00:09:55.568Z,1726531795.568 [DataOverHttps](INFO): SBD MOMSN=23193815
2024-09-17T00:09:57.531Z,1726531797.531 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:09:57.531Z,1726531797.531 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:09:57.531Z,1726531797.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:10:01.965Z,1726531801.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=21946, MT Status=2, MTMSN=0
2024-09-17T00:10:01.965Z,1726531801.965 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:10:18.125Z,1726531818.125 [NAL9602](INFO): SBD MO Status=2, MOMSN=21946, MT Status=2, MTMSN=0
2024-09-17T00:10:18.125Z,1726531818.125 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:12:27.857Z,1726531947.857 [NAL9602](INFO): SBD MO Status=0, MOMSN=21946, MT Status=0, MTMSN=0
2024-09-17T00:12:27.857Z,1726531947.857 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:12:58.567Z,1726531978.567 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:14:58.196Z,1726532098.196 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:14:58.196Z,1726532098.196 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:14:58.196Z,1726532098.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:14:58.196Z,1726532098.196 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:14:58.587Z,1726532098.587 [Default:CheckIn:D] Stopped
2024-09-17T00:14:58.588Z,1726532098.588 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.676546 min
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn:E] Stopped
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn] Stopped
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn](INFO): Running loop #11
2024-09-17T00:14:58.979Z,1726532098.979 [Default:CheckIn] Running Loop=11
2024-09-17T00:14:58.980Z,1726532098.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:14:58.980Z,1726532098.980 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:15:00.999Z,1726532100.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001500.00,A,3648.14149,N,12147.22978,W,0.039,74.03,170924,,,D*49
2024-09-17T00:15:01.001Z,1726532101.001 [NAL9602](INFO): GPS fix at 20240917T001500: (36.802358, -121.787163)
2024-09-17T00:15:01.037Z,1726532101.037 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:15:01.037Z,1726532101.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:15:09.345Z,1726532109.345 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0040.lzma
2024-09-17T00:15:09.768Z,1726532109.768 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0040.lzma.bak
2024-09-17T00:15:09.768Z,1726532109.768 [DataOverHttps](INFO): SBD MOMSN=23193828
2024-09-17T00:15:13.921Z,1726532113.921 [NAL9602](INFO): SBD MO Status=0, MOMSN=21947, MT Status=0, MTMSN=0
2024-09-17T00:15:13.921Z,1726532113.921 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:15:25.585Z,1726532125.585 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0041.lzma
2024-09-17T00:15:26.588Z,1726532126.588 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0041.lzma.bak
2024-09-17T00:15:26.588Z,1726532126.588 [DataOverHttps](INFO): SBD MOMSN=23193831
2024-09-17T00:15:28.493Z,1726532128.493 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:15:28.493Z,1726532128.493 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:15:28.493Z,1726532128.493 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:15:44.618Z,1726532144.618 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:20:29.051Z,1726532429.051 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:20:29.051Z,1726532429.051 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:20:29.051Z,1726532429.051 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:20:29.051Z,1726532429.051 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:20:29.475Z,1726532429.475 [Default:CheckIn:D] Stopped
2024-09-17T00:20:29.475Z,1726532429.475 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:20:29.859Z,1726532429.859 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.191341 min
2024-09-17T00:20:29.859Z,1726532429.859 [Default:CheckIn:E] Stopped
2024-09-17T00:20:29.859Z,1726532429.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:20:29.859Z,1726532429.859 [Default:CheckIn] Stopped
2024-09-17T00:20:29.859Z,1726532429.859 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:20:29.859Z,1726532429.859 [Default:CheckIn](INFO): Running loop #12
2024-09-17T00:20:29.860Z,1726532429.860 [Default:CheckIn] Running Loop=12
2024-09-17T00:20:29.860Z,1726532429.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:20:29.860Z,1726532429.860 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:20:31.869Z,1726532431.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002031.00,A,3648.13910,N,12147.23158,W,0.039,74.03,170924,,,D*45
2024-09-17T00:20:31.871Z,1726532431.871 [NAL9602](INFO): GPS fix at 20240917T002031: (36.802318, -121.787193)
2024-09-17T00:20:31.882Z,1726532431.882 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:20:31.882Z,1726532431.882 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:20:39.033Z,1726532439.033 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0043.lzma
2024-09-17T00:20:40.037Z,1726532440.037 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0043.lzma.bak
2024-09-17T00:20:40.037Z,1726532440.037 [DataOverHttps](INFO): SBD MOMSN=23193880
2024-09-17T00:20:55.915Z,1726532455.915 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240916T231234/Express0044.lzma
2024-09-17T00:20:56.916Z,1726532456.916 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0044.lzma.bak
2024-09-17T00:20:56.916Z,1726532456.916 [DataOverHttps](INFO): SBD MOMSN=23193883
2024-09-17T00:20:58.639Z,1726532458.639 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:20:58.639Z,1726532458.639 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:20:58.639Z,1726532458.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:21:33.764Z,1726532493.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=21948, MT Status=0, MTMSN=0
2024-09-17T00:21:33.764Z,1726532493.764 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:22:04.451Z,1726532524.451 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:25:59.185Z,1726532759.185 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:25:59.185Z,1726532759.185 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:25:59.185Z,1726532759.185 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:25:59.185Z,1726532759.185 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:25:59.590Z,1726532759.590 [Default:CheckIn:D] Stopped
2024-09-17T00:25:59.590Z,1726532759.590 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.693245 min
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn:E] Stopped
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn] Stopped
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn](INFO): Running loop #13
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn] Running Loop=13
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:25:59.991Z,1726532759.991 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:26:02.006Z,1726532762.006 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002601.00,A,3648.13724,N,12147.23177,W,0.039,74.03,170924,,,D*44
2024-09-17T00:26:02.008Z,1726532762.008 [NAL9602](INFO): GPS fix at 20240917T002601: (36.802287, -121.787196)
2024-09-17T00:26:02.034Z,1726532762.034 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:26:02.035Z,1726532762.035 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:26:09.493Z,1726532769.493 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0046.lzma
2024-09-17T00:26:10.496Z,1726532770.496 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0046.lzma.bak
2024-09-17T00:26:10.496Z,1726532770.496 [DataOverHttps](INFO): SBD MOMSN=23193931
2024-09-17T00:26:15.333Z,1726532775.333 [NAL9602](INFO): SBD MO Status=0, MOMSN=21949, MT Status=0, MTMSN=0
2024-09-17T00:26:15.333Z,1726532775.333 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:26:26.381Z,1726532786.381 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0047.lzma
2024-09-17T00:26:27.384Z,1726532787.384 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0047.lzma.bak
2024-09-17T00:26:27.384Z,1726532787.384 [DataOverHttps](INFO): SBD MOMSN=23193943
2024-09-17T00:26:30.335Z,1726532790.335 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:26:30.335Z,1726532790.335 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:26:30.335Z,1726532790.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:26:46.043Z,1726532806.043 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:31:30.869Z,1726533090.869 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:31:30.869Z,1726533090.869 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:31:30.870Z,1726533090.870 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:31:30.870Z,1726533090.870 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:31:31.280Z,1726533091.280 [Default:CheckIn:D] Stopped
2024-09-17T00:31:31.280Z,1726533091.280 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.221411 min
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn:E] Stopped
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn] Stopped
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn](INFO): Running loop #14
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn] Running Loop=14
2024-09-17T00:31:31.679Z,1726533091.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:31:31.680Z,1726533091.680 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:31:33.684Z,1726533093.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003133.00,A,3648.14249,N,12147.22841,W,0.019,74.03,170924,,,D*45
2024-09-17T00:31:33.686Z,1726533093.686 [NAL9602](INFO): GPS fix at 20240917T003133: (36.802375, -121.787140)
2024-09-17T00:31:33.697Z,1726533093.697 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:31:33.698Z,1726533093.698 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:31:41.061Z,1726533101.061 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0049.lzma
2024-09-17T00:31:42.064Z,1726533102.064 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0049.lzma.bak
2024-09-17T00:31:42.064Z,1726533102.064 [DataOverHttps](INFO): SBD MOMSN=23193973
2024-09-17T00:31:57.942Z,1726533117.942 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0050.lzma
2024-09-17T00:31:58.944Z,1726533118.944 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0050.lzma.bak
2024-09-17T00:31:58.944Z,1726533118.944 [DataOverHttps](INFO): SBD MOMSN=23193976
2024-09-17T00:32:00.768Z,1726533120.768 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:32:00.768Z,1726533120.768 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:32:00.768Z,1726533120.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:32:12.477Z,1726533132.477 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0
2024-09-17T00:32:12.477Z,1726533132.477 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:32:40.349Z,1726533160.349 [NAL9602](INFO): SBD MO Status=0, MOMSN=21950, MT Status=0, MTMSN=0
2024-09-17T00:32:40.349Z,1726533160.349 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:33:11.063Z,1726533191.063 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:37:01.371Z,1726533421.371 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:37:01.371Z,1726533421.371 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:37:01.371Z,1726533421.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:37:01.371Z,1726533421.371 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:37:01.769Z,1726533421.769 [Default:CheckIn:D] Stopped
2024-09-17T00:37:01.769Z,1726533421.769 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:37:02.161Z,1726533422.161 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.729565 min
2024-09-17T00:37:02.161Z,1726533422.161 [Default:CheckIn:E] Stopped
2024-09-17T00:37:02.161Z,1726533422.161 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:37:02.161Z,1726533422.161 [Default:CheckIn] Stopped
2024-09-17T00:37:02.161Z,1726533422.161 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:37:02.161Z,1726533422.161 [Default:CheckIn](INFO): Running loop #15
2024-09-17T00:37:02.162Z,1726533422.162 [Default:CheckIn] Running Loop=15
2024-09-17T00:37:02.162Z,1726533422.162 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:37:02.162Z,1726533422.162 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:37:04.182Z,1726533424.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003703.00,A,3648.14276,N,12147.22846,W,0.019,74.03,170924,,,D*4B
2024-09-17T00:37:04.184Z,1726533424.184 [NAL9602](INFO): GPS fix at 20240917T003703: (36.802379, -121.787141)
2024-09-17T00:37:04.195Z,1726533424.195 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:37:04.195Z,1726533424.195 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:37:11.621Z,1726533431.621 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0052.lzma
2024-09-17T00:37:12.624Z,1726533432.624 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0052.lzma.bak
2024-09-17T00:37:12.624Z,1726533432.624 [DataOverHttps](INFO): SBD MOMSN=23194052
2024-09-17T00:37:28.430Z,1726533448.430 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240916T231234/Express0053.lzma
2024-09-17T00:37:29.432Z,1726533449.432 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0053.lzma.bak
2024-09-17T00:37:29.432Z,1726533449.432 [DataOverHttps](INFO): SBD MOMSN=23194055
2024-09-17T00:37:31.259Z,1726533451.259 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:37:31.259Z,1726533451.259 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:37:31.259Z,1726533451.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:37:37.305Z,1726533457.305 [NAL9602](INFO): SBD MO Status=2, MOMSN=21951, MT Status=2, MTMSN=0
2024-09-17T00:37:37.305Z,1726533457.305 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:37:58.313Z,1726533478.313 [NAL9602](INFO): SBD MO Status=2, MOMSN=21951, MT Status=2, MTMSN=0
2024-09-17T00:37:58.313Z,1726533478.313 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:39:24.367Z,1726533564.367 [NAL9602](INFO): SBD MO Status=0, MOMSN=21951, MT Status=0, MTMSN=0
2024-09-17T00:39:24.367Z,1726533564.367 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:39:55.067Z,1726533595.067 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:42:31.833Z,1726533751.833 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:42:31.850Z,1726533751.850 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:42:31.850Z,1726533751.850 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:42:31.850Z,1726533751.850 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:42:32.233Z,1726533752.233 [Default:CheckIn:D] Stopped
2024-09-17T00:42:32.233Z,1726533752.233 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:42:32.642Z,1726533752.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.237297 min
2024-09-17T00:42:32.642Z,1726533752.642 [Default:CheckIn:E] Stopped
2024-09-17T00:42:32.643Z,1726533752.643 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:42:32.643Z,1726533752.643 [Default:CheckIn] Stopped
2024-09-17T00:42:32.643Z,1726533752.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:42:32.643Z,1726533752.643 [Default:CheckIn](INFO): Running loop #16
2024-09-17T00:42:32.643Z,1726533752.643 [Default:CheckIn] Running Loop=16
2024-09-17T00:42:32.643Z,1726533752.643 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:42:32.644Z,1726533752.644 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:42:34.657Z,1726533754.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004233.00,A,3648.14234,N,12147.23025,W,0.039,0.00,170924,,,D*72
2024-09-17T00:42:34.667Z,1726533754.667 [NAL9602](INFO): GPS fix at 20240917T004233: (36.802372, -121.787171)
2024-09-17T00:42:34.678Z,1726533754.678 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:42:34.678Z,1726533754.678 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:42:42.301Z,1726533762.301 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0055.lzma
2024-09-17T00:42:43.305Z,1726533763.305 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0055.lzma.bak
2024-09-17T00:42:43.305Z,1726533763.305 [DataOverHttps](INFO): SBD MOMSN=23194065
2024-09-17T00:42:56.065Z,1726533776.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=21952, MT Status=0, MTMSN=0
2024-09-17T00:42:56.065Z,1726533776.065 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:42:59.354Z,1726533779.354 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240916T231234/Express0056.lzma
2024-09-17T00:43:00.356Z,1726533780.356 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0056.lzma.bak
2024-09-17T00:43:00.356Z,1726533780.356 [DataOverHttps](INFO): SBD MOMSN=23194068
2024-09-17T00:43:02.156Z,1726533782.156 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:43:02.156Z,1726533782.156 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:43:02.156Z,1726533782.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:43:26.767Z,1726533806.767 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:48:02.738Z,1726534082.738 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:48:02.739Z,1726534082.739 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:48:02.739Z,1726534082.739 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:48:02.739Z,1726534082.739 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:48:03.156Z,1726534083.156 [Default:CheckIn:D] Stopped
2024-09-17T00:48:03.156Z,1726534083.156 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.752686 min
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn:E] Stopped
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn] Stopped
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn](INFO): Running loop #17
2024-09-17T00:48:03.538Z,1726534083.538 [Default:CheckIn] Running Loop=17
2024-09-17T00:48:03.539Z,1726534083.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:48:03.539Z,1726534083.539 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:48:05.551Z,1726534085.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004804.00,A,3648.14113,N,12147.23008,W,0.039,0.00,170924,,,D*75
2024-09-17T00:48:05.553Z,1726534085.553 [NAL9602](INFO): GPS fix at 20240917T004804: (36.802352, -121.787168)
2024-09-17T00:48:05.564Z,1726534085.564 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:48:05.564Z,1726534085.564 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:48:13.393Z,1726534093.393 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0058.lzma
2024-09-17T00:48:14.396Z,1726534094.396 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0058.lzma.bak
2024-09-17T00:48:14.396Z,1726534094.396 [DataOverHttps](INFO): SBD MOMSN=23194072
2024-09-17T00:48:19.693Z,1726534099.693 [NAL9602](INFO): SBD MO Status=0, MOMSN=21953, MT Status=0, MTMSN=0
2024-09-17T00:48:19.693Z,1726534099.693 [NAL9602](INFO): No messages in MT queue
2024-09-17T00:48:30.469Z,1726534110.469 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0059.lzma
2024-09-17T00:48:31.472Z,1726534111.472 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0059.lzma.bak
2024-09-17T00:48:31.472Z,1726534111.472 [DataOverHttps](INFO): SBD MOMSN=23194075
2024-09-17T00:48:33.464Z,1726534113.464 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:48:33.464Z,1726534113.464 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:48:33.464Z,1726534113.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:48:50.391Z,1726534130.391 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T00:51:39.670Z,1726534299.670 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:51:53.404Z,1726534313.404 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:52:07.555Z,1726534327.555 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:52:21.283Z,1726534341.283 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:52:35.420Z,1726534355.420 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:52:49.157Z,1726534369.157 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:53:03.321Z,1726534383.321 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:53:17.033Z,1726534397.033 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:53:31.175Z,1726534411.175 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:53:34.021Z,1726534414.021 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:53:34.021Z,1726534414.021 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:53:34.021Z,1726534414.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:53:34.022Z,1726534414.022 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:53:34.419Z,1726534414.419 [Default:CheckIn:D] Stopped
2024-09-17T00:53:34.419Z,1726534414.419 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:53:34.838Z,1726534414.838 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.273739 min
2024-09-17T00:53:34.838Z,1726534414.838 [Default:CheckIn:E] Stopped
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn] Stopped
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn](INFO): Running loop #18
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn] Running Loop=18
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:53:34.839Z,1726534414.839 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:53:36.832Z,1726534416.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005336.00,A,3648.14174,N,12147.22724,W,0.019,0.00,170924,,,D*75
2024-09-17T00:53:36.835Z,1726534416.835 [NAL9602](INFO): GPS fix at 20240917T005336: (36.802362, -121.787121)
2024-09-17T00:53:36.847Z,1726534416.847 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:53:36.847Z,1726534416.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:53:44.225Z,1726534424.225 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240916T231234/Courier0061.lzma
2024-09-17T00:53:44.909Z,1726534424.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:53:45.228Z,1726534425.228 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0061.lzma.bak
2024-09-17T00:53:45.228Z,1726534425.228 [DataOverHttps](INFO): SBD MOMSN=23194080
2024-09-17T00:53:59.050Z,1726534439.050 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T00:54:00.669Z,1726534440.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=21954, MT Status=2, MTMSN=0
2024-09-17T00:54:00.669Z,1726534440.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:54:01.121Z,1726534441.121 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0062.lzma
2024-09-17T00:54:02.124Z,1726534442.124 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0062.lzma.bak
2024-09-17T00:54:02.124Z,1726534442.124 [DataOverHttps](INFO): SBD MOMSN=23194083
2024-09-17T00:54:03.920Z,1726534443.920 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:54:03.920Z,1726534443.920 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:54:03.921Z,1726534443.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:58:14.799Z,1726534694.799 [NAL9602](INFO): SBD MO Status=2, MOMSN=21954, MT Status=2, MTMSN=0
2024-09-17T00:58:14.800Z,1726534694.800 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T00:58:39.429Z,1726534719.429 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T00:59:04.493Z,1726534744.493 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T00:59:04.493Z,1726534744.493 [Default:CheckIn:C.Wait] Stopped
2024-09-17T00:59:04.494Z,1726534744.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T00:59:04.494Z,1726534744.494 [Default:CheckIn:D] Running Loop=1
2024-09-17T00:59:04.894Z,1726534744.894 [Default:CheckIn:D] Stopped
2024-09-17T00:59:04.894Z,1726534744.894 [Default:CheckIn:E] Running Loop=1
2024-09-17T00:59:05.319Z,1726534745.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.781649 min
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn:E] Stopped
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn] Stopped
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn](INFO): Running loop #19
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn] Running Loop=19
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T00:59:05.320Z,1726534745.320 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T00:59:07.305Z,1726534747.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005906.00,A,3648.13942,N,12147.23032,W,0.311,172.73,170924,,,D*7C
2024-09-17T00:59:07.325Z,1726534747.325 [NAL9602](INFO): GPS fix at 20240917T005906: (36.802324, -121.787172)
2024-09-17T00:59:07.337Z,1726534747.337 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T00:59:07.337Z,1726534747.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T00:59:14.546Z,1726534754.546 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0064.lzma
2024-09-17T00:59:15.548Z,1726534755.548 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0064.lzma.bak
2024-09-17T00:59:15.548Z,1726534755.548 [DataOverHttps](INFO): SBD MOMSN=23194113
2024-09-17T00:59:31.540Z,1726534771.540 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240916T231234/Express0065.lzma
2024-09-17T00:59:32.540Z,1726534772.540 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0065.lzma.bak
2024-09-17T00:59:32.540Z,1726534772.540 [DataOverHttps](INFO): SBD MOMSN=23194116
2024-09-17T00:59:34.440Z,1726534774.440 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T00:59:34.440Z,1726534774.440 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T00:59:34.440Z,1726534774.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T00:59:39.688Z,1726534779.688 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:01:30.793Z,1726534890.793 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-09-17T01:01:36.020Z,1726534896.020 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:01:50.160Z,1726534910.160 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:02:03.899Z,1726534923.899 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:02:18.037Z,1726534938.037 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:02:31.772Z,1726534951.772 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:02:45.912Z,1726534965.912 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:02:59.649Z,1726534979.649 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:03:13.789Z,1726534993.789 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:03:27.524Z,1726535007.524 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:03:41.664Z,1726535021.664 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:03:55.400Z,1726535035.400 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:04:35.005Z,1726535075.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:04:35.005Z,1726535075.005 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:04:35.005Z,1726535075.005 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:04:35.005Z,1726535075.005 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:04:35.417Z,1726535075.417 [Default:CheckIn:D] Stopped
2024-09-17T01:04:35.417Z,1726535075.417 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:04:35.828Z,1726535075.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.290381 min
2024-09-17T01:04:35.828Z,1726535075.828 [Default:CheckIn:E] Stopped
2024-09-17T01:04:35.828Z,1726535075.828 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:04:35.828Z,1726535075.828 [Default:CheckIn] Stopped
2024-09-17T01:04:35.828Z,1726535075.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:04:35.829Z,1726535075.829 [Default:CheckIn](INFO): Running loop #20
2024-09-17T01:04:35.829Z,1726535075.829 [Default:CheckIn] Running Loop=20
2024-09-17T01:04:35.829Z,1726535075.829 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:04:35.829Z,1726535075.829 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:04:37.824Z,1726535077.824 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010437.00,A,3648.15005,N,12147.22130,W,0.039,66.35,170924,,,D*46
2024-09-17T01:04:37.826Z,1726535077.826 [NAL9602](INFO): GPS fix at 20240917T010437: (36.802501, -121.787022)
2024-09-17T01:04:37.837Z,1726535077.837 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:04:37.837Z,1726535077.837 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:04:45.501Z,1726535085.501 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0067.lzma
2024-09-17T01:04:46.504Z,1726535086.504 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0067.lzma.bak
2024-09-17T01:04:46.504Z,1726535086.504 [DataOverHttps](INFO): SBD MOMSN=23194120
2024-09-17T01:05:02.557Z,1726535102.557 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0068.lzma
2024-09-17T01:05:03.560Z,1726535103.560 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0068.lzma.bak
2024-09-17T01:05:03.560Z,1726535103.560 [DataOverHttps](INFO): SBD MOMSN=23194123
2024-09-17T01:05:05.328Z,1726535105.328 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:05:05.328Z,1726535105.328 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:05:05.328Z,1726535105.328 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:05:08.536Z,1726535108.536 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T01:05:08.610Z,1726535108.610 [NAL9602](ERROR): received:
+CSQ:0
OK954, 2, 0, 0, 0
OK
2024-09-17T01:06:55.229Z,1726535215.229 [NAL9602](INFO): SBD MO Status=0, MOMSN=21954, MT Status=0, MTMSN=0
2024-09-17T01:06:55.229Z,1726535215.229 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:07:25.911Z,1726535245.911 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:10:05.923Z,1726535405.923 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:10:05.923Z,1726535405.923 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:10:05.923Z,1726535405.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:10:05.924Z,1726535405.924 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:10:06.310Z,1726535406.310 [Default:CheckIn:D] Stopped
2024-09-17T01:10:06.310Z,1726535406.310 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:10:06.718Z,1726535406.718 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.805257 min
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn:E] Stopped
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn] Stopped
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn](INFO): Running loop #21
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn] Running Loop=21
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:10:06.719Z,1726535406.719 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:10:08.735Z,1726535408.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011007.00,A,3648.14927,N,12147.21977,W,0.058,66.35,170924,,,D*47
2024-09-17T01:10:08.738Z,1726535408.738 [NAL9602](INFO): GPS fix at 20240917T011007: (36.802488, -121.786996)
2024-09-17T01:10:08.749Z,1726535408.749 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:10:08.749Z,1726535408.749 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:10:17.113Z,1726535417.113 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0070.lzma
2024-09-17T01:10:18.116Z,1726535418.116 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0070.lzma.bak
2024-09-17T01:10:18.116Z,1726535418.116 [DataOverHttps](INFO): SBD MOMSN=23194127
2024-09-17T01:10:34.190Z,1726535434.190 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240916T231234/Express0071.lzma
2024-09-17T01:10:35.192Z,1726535435.192 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0071.lzma.bak
2024-09-17T01:10:35.192Z,1726535435.192 [DataOverHttps](INFO): SBD MOMSN=23194130
2024-09-17T01:10:37.019Z,1726535437.019 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:10:37.019Z,1726535437.019 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:10:37.019Z,1726535437.019 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:10:43.076Z,1726535443.076 [NAL9602](INFO): SBD MO Status=0, MOMSN=21955, MT Status=0, MTMSN=0
2024-09-17T01:10:43.076Z,1726535443.076 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:11:13.769Z,1726535473.769 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:11:23.864Z,1726535483.864 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:11:38.004Z,1726535498.004 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:11:51.746Z,1726535511.746 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:12:05.882Z,1726535525.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:12:19.616Z,1726535539.616 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:12:33.757Z,1726535553.757 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:12:47.499Z,1726535567.499 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:13:01.632Z,1726535581.632 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:13:15.369Z,1726535595.369 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:13:29.508Z,1726535609.508 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:13:43.250Z,1726535623.250 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T01:14:51.522Z,1726535691.522 [BPC1](INFO): Calculating totals. Valid battery stick count: 26. Valid reserve battery stick count: 3.
2024-09-17T01:14:51.524Z,1726535691.524 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-09-17T01:15:37.600Z,1726535737.600 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:15:37.600Z,1726535737.600 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:15:37.600Z,1726535737.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:15:37.600Z,1726535737.600 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:15:37.000Z,1726535738.000 [Default:CheckIn:D] Stopped
2024-09-17T01:15:37.000Z,1726535738.000 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.333415 min
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn:E] Stopped
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn] Stopped
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn](INFO): Running loop #22
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn] Running Loop=22
2024-09-17T01:15:38.412Z,1726535738.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:15:38.413Z,1726535738.413 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:15:40.417Z,1726535740.417 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011539.00,A,3648.14970,N,12147.22071,W,0.000,66.35,170924,,,D*4C
2024-09-17T01:15:40.419Z,1726535740.419 [NAL9602](INFO): GPS fix at 20240917T011539: (36.802495, -121.787012)
2024-09-17T01:15:40.447Z,1726535740.447 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:15:40.447Z,1726535740.447 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:15:49.521Z,1726535749.521 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240916T231234/Courier0073.lzma
2024-09-17T01:15:50.528Z,1726535750.528 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0073.lzma.bak
2024-09-17T01:15:50.528Z,1726535750.528 [DataOverHttps](INFO): SBD MOMSN=23194134
2024-09-17T01:16:06.294Z,1726535766.294 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240916T231234/Express0074.lzma
2024-09-17T01:16:07.296Z,1726535767.296 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0074.lzma.bak
2024-09-17T01:16:07.296Z,1726535767.296 [DataOverHttps](INFO): SBD MOMSN=23194137
2024-09-17T01:16:07.481Z,1726535767.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=21956, MT Status=2, MTMSN=0
2024-09-17T01:16:07.481Z,1726535767.481 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:16:09.112Z,1726535769.112 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:16:09.112Z,1726535769.112 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:16:09.112Z,1726535769.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:16:26.065Z,1726535786.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=21956, MT Status=0, MTMSN=0
2024-09-17T01:16:26.065Z,1726535786.065 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:16:56.779Z,1726535816.779 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:21:09.689Z,1726536069.689 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:21:09.689Z,1726536069.689 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:21:09.689Z,1726536069.689 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:21:09.689Z,1726536069.689 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:21:10.100Z,1726536070.100 [Default:CheckIn:D] Stopped
2024-09-17T01:21:10.100Z,1726536070.100 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:21:10.490Z,1726536070.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.868424 min
2024-09-17T01:21:10.490Z,1726536070.490 [Default:CheckIn:E] Stopped
2024-09-17T01:21:10.490Z,1726536070.490 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:21:10.490Z,1726536070.490 [Default:CheckIn] Stopped
2024-09-17T01:21:10.490Z,1726536070.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:21:10.491Z,1726536070.491 [Default:CheckIn](INFO): Running loop #23
2024-09-17T01:21:10.491Z,1726536070.491 [Default:CheckIn] Running Loop=23
2024-09-17T01:21:10.491Z,1726536070.491 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:21:10.491Z,1726536070.491 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:21:12.497Z,1726536072.497 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012111.00,A,3648.14955,N,12147.22125,W,0.019,66.35,170924,,,D*4E
2024-09-17T01:21:12.499Z,1726536072.499 [NAL9602](INFO): GPS fix at 20240917T012111: (36.802492, -121.787021)
2024-09-17T01:21:12.511Z,1726536072.511 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:21:12.511Z,1726536072.511 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:21:20.041Z,1726536080.041 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0076.lzma
2024-09-17T01:21:21.044Z,1726536081.044 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0076.lzma.bak
2024-09-17T01:21:21.044Z,1726536081.044 [DataOverHttps](INFO): SBD MOMSN=23194145
2024-09-17T01:21:35.525Z,1726536095.525 [NAL9602](INFO): SBD MO Status=0, MOMSN=21957, MT Status=0, MTMSN=0
2024-09-17T01:21:35.525Z,1726536095.525 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:21:37.025Z,1726536097.025 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240916T231234/Express0077.lzma
2024-09-17T01:21:38.028Z,1726536098.028 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0077.lzma.bak
2024-09-17T01:21:38.028Z,1726536098.028 [DataOverHttps](INFO): SBD MOMSN=23194148
2024-09-17T01:21:39.983Z,1726536099.983 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:21:39.983Z,1726536099.983 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:21:39.983Z,1726536099.983 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:22:06.235Z,1726536126.235 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:26:40.553Z,1726536400.553 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:26:40.553Z,1726536400.553 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:26:40.553Z,1726536400.553 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:26:40.553Z,1726536400.553 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:26:40.972Z,1726536400.972 [Default:CheckIn:D] Stopped
2024-09-17T01:26:40.972Z,1726536400.972 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:26:41.372Z,1726536401.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.382959 min
2024-09-17T01:26:41.372Z,1726536401.372 [Default:CheckIn:E] Stopped
2024-09-17T01:26:41.372Z,1726536401.372 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:26:41.372Z,1726536401.372 [Default:CheckIn] Stopped
2024-09-17T01:26:41.372Z,1726536401.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:26:41.373Z,1726536401.373 [Default:CheckIn](INFO): Running loop #24
2024-09-17T01:26:41.373Z,1726536401.373 [Default:CheckIn] Running Loop=24
2024-09-17T01:26:41.373Z,1726536401.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:26:41.373Z,1726536401.373 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:26:43.372Z,1726536403.372 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012642.00,A,3648.15027,N,12147.22234,W,0.019,66.35,170924,,,D*41
2024-09-17T01:26:43.374Z,1726536403.374 [NAL9602](INFO): GPS fix at 20240917T012642: (36.802504, -121.787039)
2024-09-17T01:26:43.385Z,1726536403.385 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:26:43.385Z,1726536403.385 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:26:50.813Z,1726536410.813 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0079.lzma
2024-09-17T01:26:51.816Z,1726536411.816 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0079.lzma.bak
2024-09-17T01:26:51.816Z,1726536411.816 [DataOverHttps](INFO): SBD MOMSN=23194245
2024-09-17T01:27:07.605Z,1726536427.605 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240916T231234/Express0080.lzma
2024-09-17T01:27:08.608Z,1726536428.608 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0080.lzma.bak
2024-09-17T01:27:08.608Z,1726536428.608 [DataOverHttps](INFO): SBD MOMSN=23194250
2024-09-17T01:27:10.491Z,1726536430.491 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:27:10.491Z,1726536430.491 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:27:10.491Z,1726536430.491 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:28:06.209Z,1726536486.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=21958, MT Status=2, MTMSN=0
2024-09-17T01:28:06.209Z,1726536486.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:29:13.273Z,1726536553.273 [NAL9602](INFO): SBD MO Status=2, MOMSN=21958, MT Status=2, MTMSN=0
2024-09-17T01:29:13.273Z,1726536553.273 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:30:40.949Z,1726536640.949 [NAL9602](INFO): SBD MO Status=0, MOMSN=21958, MT Status=0, MTMSN=0
2024-09-17T01:30:40.949Z,1726536640.949 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:31:11.656Z,1726536671.656 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:32:02.468Z,1726536722.468 [DataOverHttps](IMPORTANT): SBD MTMSN=20240917T013201
2024-09-17T01:32:09.850Z,1726536729.850 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;run
2024-09-17T01:32:09.880Z,1726536729.880 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2024-09-17T01:32:09.883Z,1726536729.883 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2024-09-17T01:32:09.883Z,1726536729.883 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2024-09-17T01:32:09.887Z,1726536729.887 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2024-09-17T01:32:10.540Z,1726536730.540 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2024-09-17T01:32:10.546Z,1726536730.546 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2024-09-17T01:32:10.549Z,1726536730.549 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2024-09-17T01:32:10.551Z,1726536730.551 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2024-09-17T01:32:10.554Z,1726536730.554 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2024-09-17T01:32:10.557Z,1726536730.557 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2024-09-17T01:32:10.560Z,1726536730.560 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2024-09-17T01:32:10.562Z,1726536730.562 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2024-09-17T01:32:10.565Z,1726536730.565 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2024-09-17T01:32:10.568Z,1726536730.568 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2024-09-17T01:32:10.571Z,1726536730.571 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2024-09-17T01:32:10.573Z,1726536730.573 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2024-09-17T01:32:10.605Z,1726536730.605 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2024-09-17T01:32:10.609Z,1726536730.609 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2024-09-17T01:32:10.646Z,1726536730.646 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2024-09-17T01:32:10.646Z,1726536730.646 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2024-09-17T01:32:10.647Z,1726536730.647 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2024-09-17T01:32:10.954Z,1726536730.954 [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-17T01:32:10.960Z,1726536730.960 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2024-09-17T01:32:10.979Z,1726536730.979 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2024-09-17T01:32:10.982Z,1726536730.982 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2024-09-17T01:32:10.985Z,1726536730.985 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2024-09-17T01:32:10.988Z,1726536730.988 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2024-09-17T01:32:10.991Z,1726536730.991 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2024-09-17T01:32:10.995Z,1726536730.995 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2024-09-17T01:32:10.998Z,1726536730.998 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2024-09-17T01:32:11.005Z,1726536731.005 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-17T01:32:11.090Z,1726536731.090 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-09-17T01:32:11.090Z,1726536731.090 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-09-17T01:32:11.091Z,1726536731.091 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-09-17T01:32:11.223Z,1726536731.223 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-09-17T01:32:11.232Z,1726536731.232 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2024-09-17T01:32:11.236Z,1726536731.236 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-09-17T01:32:11.247Z,1726536731.247 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2024-09-17T01:32:11.254Z,1726536731.254 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-09-17T01:32:11.256Z,1726536731.256 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-09-17T01:32:11.280Z,1726536731.280 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-09-17T01:32:11.306Z,1726536731.306 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-09-17T01:32:11.326Z,1726536731.326 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-09-17T01:32:11.327Z,1726536731.327 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-09-17T01:32:11.328Z,1726536731.328 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-09-17T01:32:11.374Z,1726536731.374 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-09-17T01:32:11.382Z,1726536731.382 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2024-09-17T01:32:11.385Z,1726536731.385 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-09-17T01:32:11.392Z,1726536731.392 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-09-17T01:32:11.392Z,1726536731.392 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-09-17T01:32:11.393Z,1726536731.393 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-09-17T01:32:11.658Z,1726536731.658 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-09-17T01:32:11.679Z,1726536731.679 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2024-09-17T01:32:11.682Z,1726536731.682 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2024-09-17T01:32:11.685Z,1726536731.685 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2024-09-17T01:32:11.687Z,1726536731.687 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2024-09-17T01:32:11.703Z,1726536731.703 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2024-09-17T01:32:11.710Z,1726536731.710 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-09-17T01:32:11.712Z,1726536731.712 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2024-09-17T01:32:11.732Z,1726536731.732 [transit:MassHold.Pitch](DEBUG): Construct.
2024-09-17T01:32:11.740Z,1726536731.740 [transit:Transit:A.Pitch](DEBUG): Construct.
2024-09-17T01:32:11.746Z,1726536731.746 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2024-09-17T01:32:11.749Z,1726536731.749 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2024-09-17T01:32:11.788Z,1726536731.788 [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-17T01:32:11.789Z,1726536731.789 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2024-09-17T01:32:11.882Z,1726536731.882 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:32:11.882Z,1726536731.882 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:32:11.882Z,1726536731.882 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:32:11.882Z,1726536731.882 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:32:12.274Z,1726536732.274 [Default:CheckIn:D] Stopped
2024-09-17T01:32:12.274Z,1726536732.274 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.904582 min
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn:E] Stopped
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn] Stopped
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn](INFO): Running loop #25
2024-09-17T01:32:12.688Z,1726536732.688 [Default:CheckIn] Running Loop=25
2024-09-17T01:32:12.689Z,1726536732.689 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:32:12.689Z,1726536732.689 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:32:14.673Z,1726536734.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013213.00,A,3648.14934,N,12147.22155,W,0.019,66.35,170924,,,D*4E
2024-09-17T01:32:14.675Z,1726536734.675 [NAL9602](INFO): GPS fix at 20240917T013213: (36.802489, -121.787026)
2024-09-17T01:32:14.719Z,1726536734.719 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:32:14.719Z,1726536734.719 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:32:18.749Z,1726536738.749 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2024-09-17T01:32:18.757Z,1726536738.757 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute
2024-09-17T01:32:18.757Z,1726536738.757 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2024-09-17T01:32:18.758Z,1726536738.758 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2024-09-17T01:32:18.759Z,1726536738.759 [CommandExec](IMPORTANT): got command run
2024-09-17T01:32:18.761Z,1726536738.761 [CommandExec](IMPORTANT): Running
2024-09-17T01:32:18.848Z,1726536738.848 [Default] Stopped
2024-09-17T01:32:18.848Z,1726536738.848 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-17T01:32:18.848Z,1726536738.848 [Default:B.GoToSurface] Stopped
2024-09-17T01:32:18.848Z,1726536738.848 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-17T01:32:18.848Z,1726536738.848 [Default:CheckIn] Stopped
2024-09-17T01:32:18.848Z,1726536738.848 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:32:18.848Z,1726536738.848 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:32:18.848Z,1726536738.848 [MissionManager](IMPORTANT): Started mission transit
2024-09-17T01:32:18.849Z,1726536738.849 [transit] Running Loop=1
2024-09-17T01:32:18.849Z,1726536738.849 [transit](DEBUG): Aggregate::initialize transit
2024-09-17T01:32:18.849Z,1726536738.849 [transit:StandardEnvelopes] Running Loop=1
2024-09-17T01:32:18.849Z,1726536738.849 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2024-09-17T01:32:18.849Z,1726536738.849 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-17T01:32:18.849Z,1726536738.849 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-09-17T01:32:18.849Z,1726536738.849 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-17T01:32:18.849Z,1726536738.849 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-09-17T01:32:18.850Z,1726536738.850 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-17T01:32:18.850Z,1726536738.850 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-09-17T01:32:18.850Z,1726536738.850 [transit:BackseatDriver] Running Loop=1
2024-09-17T01:32:18.850Z,1726536738.850 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2024-09-17T01:32:18.850Z,1726536738.850 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-09-17T01:32:18.850Z,1726536738.850 [transit:PowerOnly] Running Loop=1
2024-09-17T01:32:18.850Z,1726536738.850 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2024-09-17T01:32:18.850Z,1726536738.850 [transit:PowerOnly:A] Running Loop=1
2024-09-17T01:32:18.850Z,1726536738.850 [transit:PowerOnly:B] Running Loop=1
2024-09-17T01:32:18.851Z,1726536738.851 [transit:PowerOnly:C] Running Loop=1
2024-09-17T01:32:18.851Z,1726536738.851 [transit:PowerOnly:D] Running Loop=1
2024-09-17T01:32:18.851Z,1726536738.851 [transit:PowerOnly:E.Wait] Running Loop=1
2024-09-17T01:32:18.851Z,1726536738.851 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:32:18.851Z,1726536738.851 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-17T01:32:18.851Z,1726536738.851 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-09-17T01:32:18.851Z,1726536738.851 [transit:MassHold.Pitch] Running Loop=1
2024-09-17T01:32:18.851Z,1726536738.851 [transit:MassHold.Pitch](DEBUG): Initialize.
2024-09-17T01:32:18.851Z,1726536738.851 [transit:B] Running Loop=1
2024-09-17T01:32:18.852Z,1726536738.852 [transit:MassHold.Pitch] Running Loop=1
2024-09-17T01:32:18.852Z,1726536738.852 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-17T01:32:18.853Z,1726536738.853 [transit:PowerOnly] Running Loop=1
2024-09-17T01:32:18.853Z,1726536738.853 [transit:PowerOnly:D] Stopped
2024-09-17T01:32:18.853Z,1726536738.853 [transit:PowerOnly:C] Stopped
2024-09-17T01:32:18.853Z,1726536738.853 [transit:PowerOnly:B] Stopped
2024-09-17T01:32:18.853Z,1726536738.853 [transit:PowerOnly:A] Stopped
2024-09-17T01:32:18.853Z,1726536738.853 [transit:BackseatDriver] Running Loop=1
2024-09-17T01:32:18.876Z,1726536738.876 [transit:StandardEnvelopes] Running Loop=1
2024-09-17T01:32:18.876Z,1726536738.876 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-17T01:32:18.877Z,1726536738.877 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2024-09-17T01:32:18.889Z,1726536738.889 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-17T01:32:18.894Z,1726536738.894 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-17T01:32:18.895Z,1726536738.895 [transit:B] Stopped
2024-09-17T01:32:18.895Z,1726536738.895 [transit:C] Running Loop=1
2024-09-17T01:32:18.903Z,1726536738.903 [HorizontalControl](DEBUG): kpHeading = 0.400000
2024-09-17T01:32:18.903Z,1726536738.903 [HorizontalControl](DEBUG): kiHeading = 0.001000
2024-09-17T01:32:18.904Z,1726536738.904 [HorizontalControl](DEBUG): kdHeading = 0.050000
2024-09-17T01:32:19.219Z,1726536739.219 [transit:C] Stopped
2024-09-17T01:32:19.219Z,1726536739.219 [transit:E] Running Loop=1
2024-09-17T01:32:19.632Z,1726536739.632 [transit:E] Stopped
2024-09-17T01:32:19.632Z,1726536739.632 [transit:F] Running Loop=1
2024-09-17T01:32:20.041Z,1726536740.041 [transit:F] Stopped
2024-09-17T01:32:20.041Z,1726536740.041 [transit:G] Running Loop=1
2024-09-17T01:32:20.552Z,1726536740.552 [transit:G] Stopped
2024-09-17T01:32:20.552Z,1726536740.552 [transit:Transit] Running Loop=1
2024-09-17T01:32:20.552Z,1726536740.552 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2024-09-17T01:32:20.552Z,1726536740.552 [transit:Transit:A.Pitch] Running Loop=1
2024-09-17T01:32:20.552Z,1726536740.552 [transit:Transit:A.Pitch](DEBUG): Initialize.
2024-09-17T01:32:20.552Z,1726536740.552 [transit:Transit:B.SetSpeed] Running Loop=1
2024-09-17T01:32:20.552Z,1726536740.552 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2024-09-17T01:32:20.553Z,1726536740.553 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2024-09-17T01:32:20.553Z,1726536740.553 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2024-09-17T01:32:20.563Z,1726536740.563 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2024-09-17T01:32:20.579Z,1726536740.579 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2024-09-17T01:32:20.852Z,1726536740.852 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2024-09-17T01:32:20.852Z,1726536740.852 [transit:Transit:B.SetSpeed] Running Loop=1
2024-09-17T01:32:20.853Z,1726536740.853 [transit:Transit:A.Pitch] Running Loop=1
2024-09-17T01:32:24.181Z,1726536744.181 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0082.lzma
2024-09-17T01:32:25.184Z,1726536745.184 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0082.lzma.bak
2024-09-17T01:32:25.184Z,1726536745.184 [DataOverHttps](IMPORTANT): SBD MOMSN=23194264, MTMSN=20240917T013224
2024-09-17T01:32:33.341Z,1726536753.341 [DataOverHttps](INFO): Received command: stop
2024-09-17T01:32:33.391Z,1726536753.391 [CommandExec](IMPORTANT): got command stop
2024-09-17T01:32:33.391Z,1726536753.391 [CommandExec](IMPORTANT): Scheduling is paused
2024-09-17T01:32:33.391Z,1726536753.391 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2024-09-17T01:32:33.876Z,1726536753.876 [MissionManager](INFO): MissionManager is completed.
2024-09-17T01:32:33.876Z,1726536753.876 [MissionManager](INFO): Uninitializing Mission transit
2024-09-17T01:32:33.876Z,1726536753.876 [transit] Stopped
2024-09-17T01:32:33.876Z,1726536753.876 [transit](DEBUG): Aggregate::uninitialize transit
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes] Stopped
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2024-09-17T01:32:33.877Z,1726536753.877 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2024-09-17T01:32:33.877Z,1726536753.877 [transit:BackseatDriver] Stopped
2024-09-17T01:32:33.877Z,1726536753.877 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2024-09-17T01:32:33.877Z,1726536753.877 [transit:BackseatDriver:A.BackseatDriver] Stopped
2024-09-17T01:32:33.877Z,1726536753.877 [transit:PowerOnly] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2024-09-17T01:32:33.892Z,1726536753.892 [transit:PowerOnly:E.Wait] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:32:33.892Z,1726536753.892 [transit:BuoyancyHold.Buoyancy] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-09-17T01:32:33.892Z,1726536753.892 [transit:MassHold.Pitch] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:Transit] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2024-09-17T01:32:33.892Z,1726536753.892 [transit:Transit:A.Pitch] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:Transit:B.SetSpeed] Stopped
2024-09-17T01:32:33.892Z,1726536753.892 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2024-09-17T01:32:33.892Z,1726536753.892 [transit:Transit:Wpt1.Waypoint] Stopped
2024-09-17T01:32:33.893Z,1726536753.893 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2024-09-17T01:32:34.163Z,1726536754.163 [MissionManager](IMPORTANT): Started mission Default
2024-09-17T01:32:34.163Z,1726536754.163 [Default] Running Loop=1
2024-09-17T01:32:34.163Z,1726536754.163 [Default](DEBUG): Aggregate::initialize Default
2024-09-17T01:32:34.163Z,1726536754.163 [Default:B.GoToSurface] Running Loop=1
2024-09-17T01:32:34.163Z,1726536754.163 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-17T01:32:34.164Z,1726536754.164 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-17T01:32:34.164Z,1726536754.164 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-17T01:32:34.164Z,1726536754.164 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-17T01:32:34.165Z,1726536754.165 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-17T01:32:34.165Z,1726536754.165 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-17T01:32:34.165Z,1726536754.165 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-17T01:32:34.165Z,1726536754.165 [Default:A.Wait] Running Loop=1
2024-09-17T01:32:34.166Z,1726536754.166 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:32:43.849Z,1726536763.849 [NAL9602](INFO): SBD MO Status=0, MOMSN=21959, MT Status=0, MTMSN=0
2024-09-17T01:32:43.849Z,1726536763.849 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:32:47.510Z,1726536767.510 [Default:A.Wait](INFO): Done Waiting.
2024-09-17T01:32:47.510Z,1726536767.510 [Default:A.Wait] Stopped
2024-09-17T01:32:47.510Z,1726536767.510 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:32:47.898Z,1726536767.898 [Default:CheckIn] Running Loop=1
2024-09-17T01:32:47.898Z,1726536767.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:32:47.898Z,1726536767.898 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:32:49.915Z,1726536769.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013249.00,A,3648.14979,N,12147.22085,W,0.019,66.35,170924,,,D*44
2024-09-17T01:32:49.918Z,1726536769.918 [NAL9602](INFO): GPS fix at 20240917T013249: (36.802496, -121.787014)
2024-09-17T01:32:49.928Z,1726536769.928 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:32:49.929Z,1726536769.929 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:32:57.025Z,1726536777.025 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0088.lzma
2024-09-17T01:32:58.028Z,1726536778.028 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0088.lzma.bak
2024-09-17T01:32:58.028Z,1726536778.028 [DataOverHttps](INFO): SBD MOMSN=23194269
2024-09-17T01:33:14.174Z,1726536794.174 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20240916T231234/Express0083.lzma
2024-09-17T01:33:15.176Z,1726536795.176 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0083.lzma.bak
2024-09-17T01:33:15.176Z,1726536795.176 [DataOverHttps](INFO): SBD MOMSN=23194272
2024-09-17T01:33:22.627Z,1726536802.627 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:33:32.665Z,1726536812.665 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240916T231234/Express0086.lzma
2024-09-17T01:33:33.668Z,1726536813.668 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0086.lzma.bak
2024-09-17T01:33:33.668Z,1726536813.668 [DataOverHttps](INFO): SBD MOMSN=23194279
2024-09-17T01:33:49.834Z,1726536829.834 [DataOverHttps](INFO): Sending 429 bytes from file Logs/20240916T231234/Express0089.lzma
2024-09-17T01:33:50.836Z,1726536830.836 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0089.lzma.bak
2024-09-17T01:33:50.836Z,1726536830.836 [DataOverHttps](INFO): SBD MOMSN=23194281
2024-09-17T01:33:52.545Z,1726536832.545 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:33:52.545Z,1726536832.545 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:33:52.545Z,1726536832.545 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:38:53.149Z,1726537133.149 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:38:53.149Z,1726537133.149 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:38:53.149Z,1726537133.149 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:38:53.149Z,1726537133.149 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:38:53.529Z,1726537133.529 [Default:CheckIn:D] Stopped
2024-09-17T01:38:53.529Z,1726537133.529 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:38:53.938Z,1726537133.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.322767 min
2024-09-17T01:38:53.938Z,1726537133.938 [Default:CheckIn:E] Stopped
2024-09-17T01:38:53.938Z,1726537133.938 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:38:53.938Z,1726537133.938 [Default:CheckIn] Stopped
2024-09-17T01:38:53.938Z,1726537133.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:38:53.939Z,1726537133.939 [Default:CheckIn](INFO): Running loop #2
2024-09-17T01:38:53.939Z,1726537133.939 [Default:CheckIn] Running Loop=2
2024-09-17T01:38:53.939Z,1726537133.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:38:53.939Z,1726537133.939 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:38:55.959Z,1726537135.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013855.00,A,3648.34891,N,12147.12372,W,5.618,2.15,170924,,,D*7C
2024-09-17T01:38:55.961Z,1726537135.961 [NAL9602](INFO): GPS fix at 20240917T013855: (36.805815, -121.785395)
2024-09-17T01:38:55.972Z,1726537135.972 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:38:55.972Z,1726537135.972 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:39:03.545Z,1726537143.545 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0091.lzma
2024-09-17T01:39:04.548Z,1726537144.548 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0091.lzma.bak
2024-09-17T01:39:04.548Z,1726537144.548 [DataOverHttps](INFO): SBD MOMSN=23194391
2024-09-17T01:39:20.533Z,1726537160.533 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240916T231234/Express0093.lzma
2024-09-17T01:39:21.536Z,1726537161.536 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0093.lzma.bak
2024-09-17T01:39:21.536Z,1726537161.536 [DataOverHttps](INFO): SBD MOMSN=23194394
2024-09-17T01:39:23.874Z,1726537163.874 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:39:23.874Z,1726537163.874 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:39:23.874Z,1726537163.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:39:52.524Z,1726537192.524 [NAL9602](INFO): SBD MO Status=2, MOMSN=21960, MT Status=2, MTMSN=0
2024-09-17T01:39:52.524Z,1726537192.524 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:41:11.371Z,1726537271.371 [NAL9602](INFO): SBD MO Status=2, MOMSN=21960, MT Status=2, MTMSN=0
2024-09-17T01:41:11.371Z,1726537271.371 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:41:28.713Z,1726537288.713 [NAL9602](INFO): SBD MO Status=2, MOMSN=21960, MT Status=2, MTMSN=0
2024-09-17T01:41:28.713Z,1726537288.713 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:42:11.944Z,1726537331.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=21960, MT Status=2, MTMSN=0
2024-09-17T01:42:11.944Z,1726537331.944 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T01:42:31.741Z,1726537351.741 [NAL9602](INFO): SBD MO Status=0, MOMSN=21960, MT Status=0, MTMSN=0
2024-09-17T01:42:31.741Z,1726537351.741 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:43:02.475Z,1726537382.475 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:44:24.540Z,1726537464.540 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:44:24.541Z,1726537464.541 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:44:24.541Z,1726537464.541 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:44:24.548Z,1726537464.548 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:44:24.960Z,1726537464.960 [Default:CheckIn:D] Stopped
2024-09-17T01:44:24.960Z,1726537464.960 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.846614 min
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn:E] Stopped
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn] Stopped
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn](INFO): Running loop #3
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn] Running Loop=3
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:44:25.335Z,1726537465.335 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:44:27.336Z,1726537467.336 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014426.00,A,3648.31503,N,12147.57638,W,22.315,249.23,170924,,,D*4F
2024-09-17T01:44:27.351Z,1726537467.351 [NAL9602](INFO): GPS fix at 20240917T014426: (36.805250, -121.792940)
2024-09-17T01:44:27.377Z,1726537467.377 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:44:27.377Z,1726537467.377 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:44:34.965Z,1726537474.965 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0097.lzma
2024-09-17T01:44:35.970Z,1726537475.970 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0097.lzma.bak
2024-09-17T01:44:35.971Z,1726537475.971 [DataOverHttps](INFO): SBD MOMSN=23194413
2024-09-17T01:44:48.780Z,1726537488.780 [NAL9602](INFO): SBD MO Status=0, MOMSN=21961, MT Status=0, MTMSN=0
2024-09-17T01:44:48.781Z,1726537488.781 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:44:52.845Z,1726537492.845 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240916T231234/Express0098.lzma
2024-09-17T01:44:53.848Z,1726537493.848 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0098.lzma.bak
2024-09-17T01:44:53.848Z,1726537493.848 [DataOverHttps](INFO): SBD MOMSN=23194416
2024-09-17T01:44:55.642Z,1726537495.642 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:44:55.642Z,1726537495.642 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:44:55.642Z,1726537495.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:45:19.523Z,1726537519.523 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:47:15.078Z,1726537635.078 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.954910
2024-09-17T01:47:25.744Z,1726537645.744 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003266
2024-09-17T01:49:56.333Z,1726537796.333 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T01:49:56.333Z,1726537796.333 [Default:CheckIn:C.Wait] Stopped
2024-09-17T01:49:56.333Z,1726537796.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T01:49:56.333Z,1726537796.333 [Default:CheckIn:D] Running Loop=1
2024-09-17T01:49:56.760Z,1726537796.760 [Default:CheckIn:D] Stopped
2024-09-17T01:49:56.760Z,1726537796.760 [Default:CheckIn:E] Running Loop=1
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.376609 min
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn:E] Stopped
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn] Stopped
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn](INFO): Running loop #4
2024-09-17T01:49:57.184Z,1726537797.184 [Default:CheckIn] Running Loop=4
2024-09-17T01:49:57.185Z,1726537797.185 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T01:49:57.185Z,1726537797.185 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T01:49:59.161Z,1726537799.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014958.00,A,3648.23141,N,12148.41991,W,0.836,272.82,170924,,,D*77
2024-09-17T01:49:59.164Z,1726537799.164 [NAL9602](INFO): GPS fix at 20240917T014958: (36.803857, -121.806999)
2024-09-17T01:49:59.175Z,1726537799.175 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T01:49:59.175Z,1726537799.175 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T01:50:11.249Z,1726537811.249 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240916T231234/Courier0100.lzma
2024-09-17T01:50:12.252Z,1726537812.252 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Courier0100.lzma.bak
2024-09-17T01:50:12.252Z,1726537812.252 [DataOverHttps](INFO): SBD MOMSN=23194426
2024-09-17T01:50:12.926Z,1726537812.926 [NAL9602](INFO): SBD MO Status=0, MOMSN=21962, MT Status=0, MTMSN=0
2024-09-17T01:50:12.926Z,1726537812.926 [NAL9602](INFO): No messages in MT queue
2024-09-17T01:50:29.221Z,1726537829.221 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240916T231234/Express0101.lzma
2024-09-17T01:50:30.224Z,1726537830.224 [DataOverHttps](INFO): Moved sent file to Logs/20240916T231234/Express0101.lzma.bak
2024-09-17T01:50:30.224Z,1726537830.224 [DataOverHttps](INFO): SBD MOMSN=23194442
2024-09-17T01:50:32.807Z,1726537832.807 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T01:50:32.807Z,1726537832.807 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T01:50:32.807Z,1726537832.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T01:50:43.639Z,1726537843.639 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T01:51:53.161Z,1726537913.161 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2024-09-17T01:51:55.190Z,1726537915.190 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.40,000.00
2024-09-17T01:51:57.229Z,1726537917.229 [DVL_micro](ERROR): Failed to parse:
:SA,-02.00,+03.98,067.3
2024-09-17T01:53:12.975Z,1726537992.975 [DataOverHttps](IMPORTANT): SBD MTMSN=20240917T015311
2024-09-17T01:53:23.364Z,1726538003.364 [DataOverHttps](INFO): Received command: restart logs