2024-09-05T21:41:16.132Z,1725572476.132 [Supervisor](DEBUG): Initializing supervisor.
2024-09-05T21:41:16.137Z,1725572476.137 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-05T21:41:16.137Z,1725572476.137 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-09-05T21:41:16.138Z,1725572476.138 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-05T21:41:16.139Z,1725572476.139 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-05T21:41:16.139Z,1725572476.139 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-09-05T21:41:16.143Z,1725572476.143 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-05T21:41:16.160Z,1725572476.160 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-05T21:41:16.161Z,1725572476.161 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-05T21:41:16.161Z,1725572476.161 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-09-05T21:41:16.165Z,1725572476.165 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-05T21:41:16.166Z,1725572476.166 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-05T21:41:16.167Z,1725572476.167 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-09-05T21:41:16.169Z,1725572476.169 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-05T21:41:16.170Z,1725572476.170 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-05T21:41:16.170Z,1725572476.170 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-09-05T21:41:16.174Z,1725572476.174 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-05T21:41:16.174Z,1725572476.174 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-05T21:41:16.178Z,1725572476.178 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-05T21:41:16.463Z,1725572476.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-05T21:41:16.464Z,1725572476.464 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-05T21:41:16.559Z,1725572476.559 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-05T21:41:17.123Z,1725572477.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-05T21:41:17.125Z,1725572477.125 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-05T21:41:17.495Z,1725572477.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-05T21:41:17.497Z,1725572477.497 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-05T21:41:17.605Z,1725572477.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-05T21:41:17.606Z,1725572477.606 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-05T21:41:18.058Z,1725572478.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-05T21:41:18.060Z,1725572478.060 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-05T21:41:18.291Z,1725572478.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-05T21:41:18.293Z,1725572478.293 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-05T21:41:18.861Z,1725572478.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-05T21:41:18.862Z,1725572478.862 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-05T21:41:19.011Z,1725572479.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-05T21:41:19.013Z,1725572479.013 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-05T21:41:19.106Z,1725572479.106 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-05T21:41:19.989Z,1725572479.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-05T21:41:19.990Z,1725572479.990 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-05T21:41:21.290Z,1725572481.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-05T21:41:21.292Z,1725572481.292 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-05T21:41:21.504Z,1725572481.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-05T21:41:21.505Z,1725572481.505 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-05T21:41:21.629Z,1725572481.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-05T21:41:21.629Z,1725572481.629 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-05T21:41:21.874Z,1725572481.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-05T21:41:21.874Z,1725572481.874 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-05T21:41:22.144Z,1725572482.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-05T21:41:22.146Z,1725572482.146 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-09-05T21:41:22.148Z,1725572482.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-09-05T21:41:22.252Z,1725572482.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-09-05T21:41:22.350Z,1725572482.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-09-05T21:41:22.467Z,1725572482.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-09-05T21:41:22.563Z,1725572482.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-09-05T21:41:22.679Z,1725572482.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-09-05T21:41:22.785Z,1725572482.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-09-05T21:41:22.915Z,1725572482.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-09-05T21:41:23.084Z,1725572483.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-09-05T21:41:23.249Z,1725572483.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-09-05T21:41:23.373Z,1725572483.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-09-05T21:41:23.653Z,1725572483.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-05T21:41:23.654Z,1725572483.654 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-05T21:41:23.671Z,1725572483.671 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-05T21:41:23.751Z,1725572483.751 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-05T21:41:23.811Z,1725572483.811 [VerticalControl] Loaded
2024-09-05T21:41:23.812Z,1725572483.812 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-05T21:41:23.814Z,1725572483.814 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-05T21:41:23.861Z,1725572483.861 [HorizontalControl] Loaded
2024-09-05T21:41:23.861Z,1725572483.861 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-05T21:41:23.864Z,1725572483.864 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-05T21:41:23.867Z,1725572483.867 [SpeedControl] Loaded
2024-09-05T21:41:23.867Z,1725572483.867 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-05T21:41:23.870Z,1725572483.870 [LoopControl](DEBUG): Construct LoopControl.
2024-09-05T21:41:23.870Z,1725572483.870 [LoopControl] Loaded
2024-09-05T21:41:23.871Z,1725572483.871 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-05T21:41:23.871Z,1725572483.871 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-05T21:41:23.873Z,1725572483.873 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-05T21:41:23.888Z,1725572483.888 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-05T21:41:23.889Z,1725572483.889 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-05T21:41:24.100Z,1725572484.100 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-05T21:41:24.100Z,1725572484.100 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-05T21:41:24.285Z,1725572484.285 [BuoyancyServo] Loaded
2024-09-05T21:41:24.285Z,1725572484.285 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-05T21:41:24.307Z,1725572484.307 [ElevatorServo] Loaded
2024-09-05T21:41:24.307Z,1725572484.307 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-05T21:41:24.328Z,1725572484.328 [MassServo] Loaded
2024-09-05T21:41:24.328Z,1725572484.328 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-05T21:41:24.348Z,1725572484.348 [RudderServo] Loaded
2024-09-05T21:41:24.349Z,1725572484.349 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-05T21:41:24.365Z,1725572484.365 [ThrusterHE] Loaded
2024-09-05T21:41:24.366Z,1725572484.366 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-05T21:41:24.366Z,1725572484.366 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-05T21:41:24.367Z,1725572484.367 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-05T21:41:24.503Z,1725572484.503 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-05T21:41:24.503Z,1725572484.503 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-05T21:41:24.517Z,1725572484.517 [NavChart] Loaded
2024-09-05T21:41:24.517Z,1725572484.517 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-05T21:41:24.523Z,1725572484.523 [UniversalFixResidualReporter] Loaded
2024-09-05T21:41:24.523Z,1725572484.523 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-05T21:41:24.523Z,1725572484.523 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-05T21:41:24.524Z,1725572484.524 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-05T21:41:24.623Z,1725572484.623 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-05T21:41:24.624Z,1725572484.624 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-05T21:41:25.840Z,1725572485.840 [AHRS_M2] Loaded
2024-09-05T21:41:25.840Z,1725572485.840 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-05T21:41:26.667Z,1725572486.667 [BPC1] Loaded
2024-09-05T21:41:26.668Z,1725572486.668 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-05T21:41:26.756Z,1725572486.756 [DATMMP] Loaded
2024-09-05T21:41:26.756Z,1725572486.756 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-05T21:41:26.757Z,1725572486.757 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4094C4E0
2024-09-05T21:41:26.758Z,1725572486.758 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 923
2024-09-05T21:41:26.897Z,1725572486.897 [DataOverHttps] Loaded
2024-09-05T21:41:26.897Z,1725572486.897 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-05T21:41:26.898Z,1725572486.898 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097C4E0
2024-09-05T21:41:26.899Z,1725572486.899 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2024-09-05T21:41:26.922Z,1725572486.922 [Depth_Keller] Loaded
2024-09-05T21:41:26.922Z,1725572486.922 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-05T21:41:26.927Z,1725572486.927 [DropWeight] Loaded
2024-09-05T21:41:26.927Z,1725572486.927 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-05T21:41:26.991Z,1725572486.991 [NAL9602] Loaded
2024-09-05T21:41:26.991Z,1725572486.991 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-05T21:41:27.064Z,1725572487.064 [Onboard] Loaded
2024-09-05T21:41:27.065Z,1725572487.065 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-05T21:41:27.066Z,1725572487.066 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AC4E0
2024-09-05T21:41:27.066Z,1725572487.066 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2024-09-05T21:41:27.080Z,1725572487.080 [Power24vConverter] Loaded
2024-09-05T21:41:27.080Z,1725572487.080 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-05T21:41:27.095Z,1725572487.095 [Radio_Surface] Loaded
2024-09-05T21:41:27.096Z,1725572487.096 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-05T21:41:27.097Z,1725572487.097 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0
2024-09-05T21:41:27.097Z,1725572487.097 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2024-09-05T21:41:27.098Z,1725572487.098 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-05T21:41:27.099Z,1725572487.099 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-05T21:41:27.172Z,1725572487.172 [DepthRateCalculator] Loaded
2024-09-05T21:41:27.172Z,1725572487.172 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-05T21:41:27.177Z,1725572487.177 [PitchRateCalculator] Loaded
2024-09-05T21:41:27.177Z,1725572487.177 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-05T21:41:27.186Z,1725572487.186 [SpeedCalculator] Loaded
2024-09-05T21:41:27.187Z,1725572487.187 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-05T21:41:27.191Z,1725572487.191 [YawRateCalculator] Loaded
2024-09-05T21:41:27.191Z,1725572487.191 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-05T21:41:27.210Z,1725572487.210 [ElevatorOffsetCalculator] Loaded
2024-09-05T21:41:27.210Z,1725572487.210 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-05T21:41:27.210Z,1725572487.210 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-05T21:41:27.211Z,1725572487.211 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-05T21:41:27.435Z,1725572487.435 [CTD_Seabird] Loaded
2024-09-05T21:41:27.435Z,1725572487.435 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-05T21:41:27.436Z,1725572487.436 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A984E0
2024-09-05T21:41:27.436Z,1725572487.436 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2024-09-05T21:41:27.473Z,1725572487.473 [ESPComponent] Loaded
2024-09-05T21:41:27.473Z,1725572487.473 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-05T21:41:27.493Z,1725572487.493 [PAR_Licor] Loaded
2024-09-05T21:41:27.493Z,1725572487.493 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-05T21:41:27.538Z,1725572487.538 [WetLabsBB2FL] Loaded
2024-09-05T21:41:27.538Z,1725572487.538 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-05T21:41:27.539Z,1725572487.539 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC84E0
2024-09-05T21:41:27.539Z,1725572487.539 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2024-09-05T21:41:27.540Z,1725572487.540 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-05T21:41:27.541Z,1725572487.541 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-05T21:41:27.584Z,1725572487.584 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-05T21:41:27.585Z,1725572487.585 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-05T21:41:27.978Z,1725572487.978 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-05T21:41:27.980Z,1725572487.980 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-05T21:41:28.157Z,1725572488.157 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-05T21:41:28.167Z,1725572488.167 [SBIT] Loaded
2024-09-05T21:41:28.167Z,1725572488.167 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-05T21:41:28.170Z,1725572488.170 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-05T21:41:28.182Z,1725572488.182 [IBIT] Loaded
2024-09-05T21:41:28.183Z,1725572488.183 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-05T21:41:28.189Z,1725572488.189 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-05T21:41:28.296Z,1725572488.296 [CBIT] Loaded
2024-09-05T21:41:28.296Z,1725572488.296 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-05T21:41:28.297Z,1725572488.297 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-05T21:41:28.297Z,1725572488.297 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-05T21:41:28.436Z,1725572488.436 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-05T21:41:28.442Z,1725572488.442 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-05T21:41:28.445Z,1725572488.445 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-05T21:41:28.456Z,1725572488.456 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-05T21:41:28.458Z,1725572488.458 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0
2024-09-05T21:41:28.458Z,1725572488.458 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2024-09-05T21:41:28.462Z,1725572488.462 [Supervisor](INFO): Main Thread ID is 830
2024-09-05T21:41:28.462Z,1725572488.462 [Supervisor](DEBUG): Running supervisor.
2024-09-05T21:41:28.463Z,1725572488.463 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2024-09-05T21:41:28.463Z,1725572488.463 [CommandExec](INFO): Initializing the command executive.
2024-09-05T21:41:28.465Z,1725572488.465 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2024-09-05T21:41:28.467Z,1725572488.467 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2024-09-05T21:41:28.467Z,1725572488.467 [controlThread](DEBUG): Initializing ControlThread
2024-09-05T21:41:28.468Z,1725572488.468 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-05T21:41:28.470Z,1725572488.470 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-05T21:41:28.471Z,1725572488.471 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-05T21:41:28.471Z,1725572488.471 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-05T21:41:28.473Z,1725572488.473 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-05T21:41:28.473Z,1725572488.473 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-05T21:41:28.479Z,1725572488.479 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-05T21:41:28.479Z,1725572488.479 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-05T21:41:28.479Z,1725572488.479 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-05T21:41:28.480Z,1725572488.480 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-05T21:41:28.480Z,1725572488.480 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-05T21:41:28.485Z,1725572488.485 [SBIT](INFO): Initialize SBIT Component.
2024-09-05T21:41:28.486Z,1725572488.486 [SBIT](IMPORTANT): git: 2024-09-05
2024-09-05T21:41:28.486Z,1725572488.486 [SBIT](INFO): git hash: dce0dc62070cf863068ea7da7140e449252762ed
2024-09-05T21:41:28.486Z,1725572488.486 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-05T21:41:28.487Z,1725572488.487 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-09-05T21:41:28.488Z,1725572488.488 [SBIT](INFO): Beginning SBIT in 70.000000 seconds.
2024-09-05T21:41:28.489Z,1725572488.489 [IBIT](INFO): Initialize IBIT Component.
2024-09-05T21:41:28.490Z,1725572488.490 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-05T21:41:28.491Z,1725572488.491 [logger ThreadHandler](INFO): Handler Thread ID is 934
2024-09-05T21:41:28.501Z,1725572488.501 [CBIT](DEBUG): Initialized mux pins.
2024-09-05T21:41:28.501Z,1725572488.501 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-05T21:41:28.509Z,1725572488.509 [DATMMP ThreadHandler](INFO): Handler Thread ID is 935
2024-09-05T21:41:28.510Z,1725572488.510 [DATMMP](INFO): Start
2024-09-05T21:41:28.510Z,1725572488.510 [DATMMP](INFO): Powering up
2024-09-05T21:41:28.510Z,1725572488.510 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-05T21:41:28.515Z,1725572488.515 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2024-09-05T21:41:28.516Z,1725572488.516 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-05T21:41:28.525Z,1725572488.525 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-05T21:41:28.526Z,1725572488.526 [CBIT](DEBUG): Initializing heartbeat.
2024-09-05T21:41:28.526Z,1725572488.526 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2024-09-05T21:41:28.546Z,1725572488.546 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2024-09-05T21:41:28.562Z,1725572488.562 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2024-09-05T21:41:28.562Z,1725572488.562 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-05T21:41:28.567Z,1725572488.567 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2024-09-05T21:41:28.569Z,1725572488.569 [WetLabsBB2FL](INFO): Powering up
2024-09-05T21:41:28.571Z,1725572488.571 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2024-09-05T21:41:28.579Z,1725572488.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-09-05T21:41:28.579Z,1725572488.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-09-05T21:41:28.579Z,1725572488.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-09-05T21:41:28.579Z,1725572488.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-09-05T21:41:28.579Z,1725572488.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-09-05T21:41:28.579Z,1725572488.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-09-05T21:41:28.580Z,1725572488.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-09-05T21:41:28.580Z,1725572488.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-09-05T21:41:28.597Z,1725572488.597 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-05T21:41:28.597Z,1725572488.597 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-05T21:41:28.637Z,1725572488.637 [CBIT](DEBUG): Backplane powered.
2024-09-05T21:41:28.638Z,1725572488.638 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-05T21:41:28.638Z,1725572488.638 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-05T21:41:28.639Z,1725572488.639 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-05T21:41:28.646Z,1725572488.646 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-05T21:41:28.688Z,1725572488.688 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-05T21:41:28.708Z,1725572488.708 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-05T21:41:28.728Z,1725572488.728 [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-05T21:41:28.753Z,1725572488.753 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-05T21:41:28.753Z,1725572488.753 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-05T21:41:28.757Z,1725572488.757 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-05T21:41:28.761Z,1725572488.761 [DATMMP](INFO): Starting
2024-09-05T21:41:28.949Z,1725572488.949 [Radio_Surface](INFO): Powering up
2024-09-05T21:41:29.013Z,1725572489.013 [DATMMP](INFO): Starting
2024-09-05T21:41:29.044Z,1725572489.044 [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-05T21:41:29.073Z,1725572489.073 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-05T21:41:29.074Z,1725572489.074 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-05T21:41:29.076Z,1725572489.076 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-05T21:41:29.108Z,1725572489.108 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-05T21:41:29.111Z,1725572489.111 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-05T21:41:29.145Z,1725572489.145 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-05T21:41:29.148Z,1725572489.148 [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-05T21:41:29.168Z,1725572489.168 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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,Reporter,LogSplitter,
2024-09-05T21:41:29.185Z,1725572489.185 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-05T21:41:29.265Z,1725572489.265 [DATMMP](INFO): Starting
2024-09-05T21:41:29.351Z,1725572489.351 [Power24vConverter](INFO): Powering up.
2024-09-05T21:41:29.390Z,1725572489.390 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-05T21:41:29.398Z,1725572489.398 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-05T21:41:29.399Z,1725572489.399 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-05T21:41:29.405Z,1725572489.405 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-05T21:41:29.406Z,1725572489.406 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-05T21:41:29.413Z,1725572489.413 [MassServo](DEBUG): Initializing MassServo.
2024-09-05T21:41:29.414Z,1725572489.414 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-05T21:41:29.421Z,1725572489.421 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-05T21:41:29.422Z,1725572489.422 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-05T21:41:29.429Z,1725572489.429 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-05T21:41:29.517Z,1725572489.517 [DATMMP](INFO): Starting
2024-09-05T21:41:29.769Z,1725572489.769 [DATMMP](INFO): Starting
2024-09-05T21:41:30.021Z,1725572490.021 [DATMMP](INFO): Starting
2024-09-05T21:41:30.273Z,1725572490.273 [DATMMP](INFO): Starting
2024-09-05T21:41:30.525Z,1725572490.525 [DATMMP](INFO): Starting
2024-09-05T21:41:30.777Z,1725572490.777 [DATMMP](INFO): Starting
2024-09-05T21:41:31.029Z,1725572491.029 [DATMMP](INFO): Starting
2024-09-05T21:41:31.181Z,1725572491.181 [WetLabsBB2FL](INFO): Powering down
2024-09-05T21:41:31.281Z,1725572491.281 [DATMMP](INFO): Starting
2024-09-05T21:41:31.533Z,1725572491.533 [DATMMP](INFO): Starting
2024-09-05T21:41:31.785Z,1725572491.785 [DATMMP](INFO): Starting
2024-09-05T21:41:32.037Z,1725572492.037 [DATMMP](INFO): Starting
2024-09-05T21:41:32.289Z,1725572492.289 [DATMMP](INFO): Starting
2024-09-05T21:41:32.541Z,1725572492.541 [DATMMP](INFO): Starting
2024-09-05T21:41:32.793Z,1725572492.793 [DATMMP](INFO): Starting
2024-09-05T21:41:33.045Z,1725572493.045 [DATMMP](INFO): Starting
2024-09-05T21:41:33.297Z,1725572493.297 [DATMMP](INFO): Starting
2024-09-05T21:41:33.549Z,1725572493.549 [DATMMP](INFO): Starting
2024-09-05T21:41:33.802Z,1725572493.802 [DATMMP](INFO): Starting
2024-09-05T21:41:34.057Z,1725572494.057 [DATMMP](INFO): Starting
2024-09-05T21:41:34.309Z,1725572494.309 [DATMMP](INFO): Starting
2024-09-05T21:41:34.561Z,1725572494.561 [DATMMP](INFO): Starting
2024-09-05T21:41:34.813Z,1725572494.813 [DATMMP](INFO): Starting
2024-09-05T21:41:34.910Z,1725572494.910 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-05T21:41:35.065Z,1725572495.065 [DATMMP](INFO): Starting
2024-09-05T21:41:35.317Z,1725572495.317 [DATMMP](INFO): Starting
2024-09-05T21:41:35.569Z,1725572495.569 [DATMMP](INFO): Starting
2024-09-05T21:41:35.597Z,1725572495.597 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-09-05T21:41:35.821Z,1725572495.821 [DATMMP](INFO): Starting
2024-09-05T21:41:36.077Z,1725572496.077 [DATMMP](INFO): Starting
2024-09-05T21:41:36.329Z,1725572496.329 [DATMMP](INFO): Starting
2024-09-05T21:41:36.581Z,1725572496.581 [DATMMP](INFO): Starting
2024-09-05T21:41:36.833Z,1725572496.833 [DATMMP](INFO): Starting
2024-09-05T21:41:37.085Z,1725572497.085 [DATMMP](INFO): Starting
2024-09-05T21:41:37.337Z,1725572497.337 [DATMMP](INFO): Starting
2024-09-05T21:41:37.589Z,1725572497.589 [DATMMP](INFO): Starting
2024-09-05T21:41:37.841Z,1725572497.841 [DATMMP](INFO): Starting
2024-09-05T21:41:38.093Z,1725572498.093 [DATMMP](INFO): Starting
2024-09-05T21:41:38.345Z,1725572498.345 [DATMMP](INFO): Starting
2024-09-05T21:41:38.597Z,1725572498.597 [DATMMP](INFO): Starting
2024-09-05T21:41:38.849Z,1725572498.849 [DATMMP](INFO): Starting
2024-09-05T21:41:39.101Z,1725572499.101 [DATMMP](INFO): Starting
2024-09-05T21:41:39.353Z,1725572499.353 [DATMMP](INFO): Starting
2024-09-05T21:41:39.605Z,1725572499.605 [DATMMP](INFO): Starting
2024-09-05T21:41:39.857Z,1725572499.857 [DATMMP](INFO): Starting
2024-09-05T21:41:40.109Z,1725572500.109 [DATMMP](INFO): Starting
2024-09-05T21:41:40.110Z,1725572500.110 [DATMMP](INFO): DAT read:
2024-09-05T21:41:40.110Z,1725572500.110 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-05T21:41:40.362Z,1725572500.362 [DATMMP](INFO): Starting
2024-09-05T21:41:40.613Z,1725572500.613 [DATMMP](INFO): Starting
2024-09-05T21:41:40.865Z,1725572500.865 [DATMMP](INFO): Starting
2024-09-05T21:41:41.117Z,1725572501.117 [DATMMP](INFO): Starting
2024-09-05T21:41:41.370Z,1725572501.370 [DATMMP](INFO): Starting
2024-09-05T21:41:41.621Z,1725572501.621 [DATMMP](INFO): Starting
2024-09-05T21:41:41.622Z,1725572501.622 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-05T21:41:41.623Z,1725572501.623 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-05T21:41:41.624Z,1725572501.624 [DATMMP](INFO): DAT read: Aug 5 2000 21:41:36
2024-09-05T21:41:41.873Z,1725572501.873 [DATMMP](INFO): Starting
2024-09-05T21:41:42.125Z,1725572502.125 [DATMMP](INFO): Starting
2024-09-05T21:41:42.377Z,1725572502.377 [DATMMP](INFO): Starting
2024-09-05T21:41:42.630Z,1725572502.630 [DATMMP](INFO): Starting
2024-09-05T21:41:42.882Z,1725572502.882 [DATMMP](INFO): Starting
2024-09-05T21:41:42.884Z,1725572502.884 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-05T21:41:42.885Z,1725572502.885 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-09-05T21:41:42.886Z,1725572502.886 [DATMMP](INFO): commRate: 1200
2024-09-05T21:41:43.133Z,1725572503.133 [DATMMP](INFO): Starting
2024-09-05T21:41:43.386Z,1725572503.386 [DATMMP](INFO): Starting
2024-09-05T21:41:43.637Z,1725572503.637 [DATMMP](INFO): Starting
2024-09-05T21:41:43.889Z,1725572503.889 [DATMMP](INFO): Starting
2024-09-05T21:41:44.141Z,1725572504.141 [DATMMP](INFO): Starting
2024-09-05T21:41:44.393Z,1725572504.393 [DATMMP](INFO): Starting
2024-09-05T21:41:44.645Z,1725572504.645 [DATMMP](INFO): Starting
2024-09-05T21:41:44.897Z,1725572504.897 [DATMMP](INFO): Starting
2024-09-05T21:41:44.949Z,1725572504.949 [DATMMP](INFO): entering command mode
2024-09-05T21:41:45.149Z,1725572505.149 [DATMMP](INFO): Starting
2024-09-05T21:41:45.150Z,1725572505.150 [DATMMP](INFO): DAT read:
2024-09-05T21:41:45.150Z,1725572505.150 [DATMMP](INFO): DAT read: user:1>
2024-09-05T21:41:45.150Z,1725572505.150 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-05T21:41:45.151Z,1725572505.151 [DATMMP](INFO): entering MMP mode
2024-09-05T21:41:45.401Z,1725572505.401 [DATMMP](INFO): Starting
2024-09-05T21:41:45.402Z,1725572505.402 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-05T21:41:45.404Z,1725572505.404 [DATMMP](INFO): status rx: x1
2024-09-05T21:41:45.405Z,1725572505.405 [DATMMP](INFO): MMP status: 1: started
2024-09-05T21:41:45.405Z,1725572505.405 [DATMMP](INFO): Received message type: status
2024-09-05T21:41:45.405Z,1725572505.405 [DATMMP](INFO): Handled
2024-09-05T21:41:45.653Z,1725572505.653 [DATMMP](INFO): Starting
2024-09-05T21:41:45.654Z,1725572505.654 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-05T21:41:45.655Z,1725572505.655 [DATMMP](INFO): Setting local address: 8
2024-09-05T21:41:45.664Z,1725572505.664 [DATMMP](INFO): Setting time to: 21:41:45 And date to:9/5/2024
2024-09-05T21:41:45.905Z,1725572505.905 [DATMMP](INFO): Starting
2024-09-05T21:41:45.906Z,1725572505.906 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-05T21:41:45.906Z,1725572505.906 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-05T21:41:45.907Z,1725572505.907 [DATMMP](INFO): Received message type: mdmpower
2024-09-05T21:41:45.907Z,1725572505.907 [DATMMP](INFO): Handled
2024-09-05T21:41:46.157Z,1725572506.157 [DATMMP](INFO): Starting
2024-09-05T21:41:46.157Z,1725572506.157 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-05T21:41:46.158Z,1725572506.158 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-05T21:41:46.158Z,1725572506.158 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-05T21:41:46.158Z,1725572506.158 [DATMMP](INFO): Match for : sreg_txpower
2024-09-05T21:41:46.159Z,1725572506.159 [DATMMP](INFO): ACK
2024-09-05T21:41:46.409Z,1725572506.409 [DATMMP](INFO): Starting
2024-09-05T21:41:46.410Z,1725572506.410 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-05T21:41:46.410Z,1725572506.410 [DATMMP](INFO): mdmlocal rx: x8 x0 x0 x0
2024-09-05T21:41:46.411Z,1725572506.411 [DATMMP](INFO): Received message type: mdmlocal
2024-09-05T21:41:46.411Z,1725572506.411 [DATMMP](INFO): Handled
2024-09-05T21:41:46.661Z,1725572506.661 [DATMMP](INFO): Starting
2024-09-05T21:41:46.662Z,1725572506.662 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-05T21:41:46.662Z,1725572506.662 [DATMMP](INFO): sreg_locaddr rx: x8
2024-09-05T21:41:46.662Z,1725572506.662 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-05T21:41:46.663Z,1725572506.663 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-05T21:41:46.663Z,1725572506.663 [DATMMP](INFO): ACK
2024-09-05T21:41:46.913Z,1725572506.913 [DATMMP](INFO): Starting
2024-09-05T21:41:46.914Z,1725572506.914 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-05T21:41:46.915Z,1725572506.915 [DATMMP](INFO): timedate rx: x0 x15 x29 x2D x8 x5 x7C x0
2024-09-05T21:41:46.915Z,1725572506.915 [DATMMP](INFO): Received message type: timedate
2024-09-05T21:41:46.915Z,1725572506.915 [DATMMP](INFO): Match for : timedate
2024-09-05T21:41:46.915Z,1725572506.915 [DATMMP](INFO): ACK
2024-09-05T21:41:47.165Z,1725572507.165 [DATMMP](INFO): Starting
2024-09-05T21:41:47.166Z,1725572507.166 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-05T21:41:47.238Z,1725572507.238 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-05T21:41:47.238Z,1725572507.238 [DATMMP](INFO): Received message type: sys_verb
2024-09-05T21:41:47.238Z,1725572507.238 [DATMMP](INFO): Handled
2024-09-05T21:41:47.417Z,1725572507.417 [DATMMP](INFO): Starting
2024-09-05T21:41:47.418Z,1725572507.418 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-05T21:41:47.419Z,1725572507.419 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-05T21:41:47.419Z,1725572507.419 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-05T21:41:47.419Z,1725572507.419 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-05T21:41:47.419Z,1725572507.419 [DATMMP](INFO): ACK
2024-09-05T21:41:47.669Z,1725572507.669 [DATMMP](INFO): Starting
2024-09-05T21:41:47.670Z,1725572507.670 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-05T21:41:47.670Z,1725572507.670 [DATMMP](INFO): version rx: x2 x5
2024-09-05T21:41:47.671Z,1725572507.671 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-05T21:41:47.671Z,1725572507.671 [DATMMP](INFO): Received message type: version
2024-09-05T21:41:47.671Z,1725572507.671 [DATMMP](INFO): Match for : version
2024-09-05T21:41:47.671Z,1725572507.671 [DATMMP](INFO): ACK
2024-09-05T21:41:47.671Z,1725572507.671 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-05T21:41:57.067Z,1725572517.067 [NAL9602](INFO): Powering up NAL9602
2024-09-05T21:42:01.030Z,1725572521.030 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-05T21:42:01.031Z,1725572521.031 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-05T21:42:01.031Z,1725572521.031 [DATMMP](INFO): Received message type: timesync
2024-09-05T21:42:01.031Z,1725572521.031 [DATMMP](INFO): Handled
2024-09-05T21:42:07.979Z,1725572527.979 [NAL9602](INFO): NAL9602 initialized
2024-09-05T21:42:10.866Z,1725572530.866 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-05T21:42:10.867Z,1725572530.867 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-05T21:42:10.867Z,1725572530.867 [DATMMP](INFO): Received message type: timesync
2024-09-05T21:42:10.867Z,1725572530.867 [DATMMP](INFO): Handled
2024-09-05T21:42:34.634Z,1725572554.634 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:42:39.098Z,1725572559.098 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-05T21:42:39.102Z,1725572559.102 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T21:42:39.897Z,1725572559.897 [NAL9602](INFO): SBD MO Status=2, MOMSN=56407, MT Status=2, MTMSN=0
2024-09-05T21:42:39.897Z,1725572559.897 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T21:42:50.082Z,1725572570.082 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-09-05T21:42:50.490Z,1725572570.490 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T21:43:01.411Z,1725572581.411 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-09-05T21:43:01.775Z,1725572581.775 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T21:43:12.635Z,1725572592.635 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-09-05T21:43:12.635Z,1725572592.635 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-09-05T21:43:33.236Z,1725572613.236 [SBIT](IMPORTANT): SBIT PASSED
2024-09-05T21:43:33.236Z,1725572613.236 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-05T21:43:33.237Z,1725572613.237 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-09-05T21:43:33.237Z,1725572613.237 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool;
2024-09-05T21:43:33.237Z,1725572613.237 [SBIT](IMPORTANT): CANONSampler.simulateHardware=0 bool;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): Express none _.predict_plankton_data_pct_dinos_sqerr;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): Normal all WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=243.573504 cubic_centimeter;
2024-09-05T21:43:33.238Z,1725572613.238 [SBIT](IMPORTANT): VerticalControl.massDefault=1.25 centimeter;
2024-09-05T21:43:33.672Z,1725572613.672 [MissionManager](IMPORTANT): Started mission Startup
2024-09-05T21:43:33.672Z,1725572613.672 [Startup] Running Loop=1
2024-09-05T21:43:33.672Z,1725572613.672 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-05T21:43:33.672Z,1725572613.672 [Startup:A.GoToSurface] Running Loop=1
2024-09-05T21:43:33.672Z,1725572613.672 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-05T21:43:33.673Z,1725572613.673 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-05T21:43:33.674Z,1725572613.674 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-05T21:43:33.674Z,1725572613.674 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-05T21:43:33.674Z,1725572613.674 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-05T21:43:33.674Z,1725572613.674 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-05T21:43:33.675Z,1725572613.675 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-05T21:43:33.676Z,1725572613.676 [Startup:StartupSatComms] Running Loop=1
2024-09-05T21:43:33.677Z,1725572613.677 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-05T21:43:33.677Z,1725572613.677 [Startup:StartupSatComms:A] Running Loop=1
2024-09-05T21:43:34.040Z,1725572614.040 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-05T21:44:28.577Z,1725572668.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-05T21:44:28.577Z,1725572668.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-05T21:44:28.649Z,1725572668.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-05T21:44:29.065Z,1725572669.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-05T21:44:29.065Z,1725572669.065 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-05T21:44:33.831Z,1725572673.831 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-05T21:43:33.7Z
2024-09-05T21:44:33.831Z,1725572673.831 [Startup:StartupSatComms:A] Stopped
2024-09-05T21:44:33.831Z,1725572673.831 [Startup:StartupSatComms:B] Running Loop=1
2024-09-05T21:44:34.241Z,1725572674.241 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-05T21:45:17.058Z,1725572717.058 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:45:24.860Z,1725572724.860 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004439
2024-09-05T21:45:30.381Z,1725572730.381 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:45:32.414Z,1725572732.414 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T200630/Courier0037.lzma
2024-09-05T21:45:33.411Z,1725572733.411 [DataOverHttps](INFO): Moved sent file to Logs/20240905T200630/Courier0037.lzma.bak
2024-09-05T21:45:33.411Z,1725572733.411 [DataOverHttps](INFO): SBD MOMSN=23132237
2024-09-05T21:45:34.033Z,1725572734.033 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-05T21:44:33.8Z
2024-09-05T21:45:34.033Z,1725572734.033 [Startup:StartupSatComms:B] Stopped
2024-09-05T21:45:34.034Z,1725572734.034 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-05T21:45:34.034Z,1725572734.034 [Startup:StartupSatComms] Stopped
2024-09-05T21:45:34.034Z,1725572734.034 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-05T21:45:34.035Z,1725572734.035 [Startup](INFO): Completed Startup
2024-09-05T21:45:34.035Z,1725572734.035 [MissionManager](INFO): Startup is completed.
2024-09-05T21:45:34.035Z,1725572734.035 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-05T21:45:34.035Z,1725572734.035 [Startup] Stopped
2024-09-05T21:45:34.035Z,1725572734.035 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-05T21:45:34.035Z,1725572734.035 [Startup:A.GoToSurface] Stopped
2024-09-05T21:45:34.035Z,1725572734.035 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-05T21:45:34.435Z,1725572734.435 [MissionManager](IMPORTANT): Started mission Default
2024-09-05T21:45:34.435Z,1725572734.435 [Default] Running Loop=1
2024-09-05T21:45:34.435Z,1725572734.435 [Default](DEBUG): Aggregate::initialize Default
2024-09-05T21:45:34.435Z,1725572734.435 [Default:B.GoToSurface] Running Loop=1
2024-09-05T21:45:34.435Z,1725572734.435 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-05T21:45:34.436Z,1725572734.436 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-05T21:45:34.436Z,1725572734.436 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-05T21:45:34.436Z,1725572734.436 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-05T21:45:34.437Z,1725572734.437 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-05T21:45:34.461Z,1725572734.461 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-05T21:45:34.461Z,1725572734.461 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-05T21:45:34.462Z,1725572734.462 [Default:A.Wait] Running Loop=1
2024-09-05T21:45:34.462Z,1725572734.462 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-05T21:45:43.717Z,1725572743.717 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:45:47.774Z,1725572747.774 [Default:A.Wait](INFO): Done Waiting.
2024-09-05T21:45:47.774Z,1725572747.774 [Default:A.Wait] Stopped
2024-09-05T21:45:47.774Z,1725572747.774 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T21:45:48.160Z,1725572748.160 [Default:CheckIn] Running Loop=1
2024-09-05T21:45:48.161Z,1725572748.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T21:45:48.161Z,1725572748.161 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T21:45:48.571Z,1725572748.571 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-05T21:45:57.453Z,1725572757.453 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:46:11.191Z,1725572771.191 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:46:24.925Z,1725572784.925 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:46:39.463Z,1725572799.463 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:46:54.009Z,1725572814.009 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:47:07.334Z,1725572827.334 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:47:11.783Z,1725572831.783 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-05T21:47:20.666Z,1725572840.666 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-09-05T21:47:29.569Z,1725572849.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-05T21:47:29.569Z,1725572849.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-05T21:47:29.635Z,1725572849.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-05T21:47:30.069Z,1725572850.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-05T21:47:30.069Z,1725572850.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-05T21:47:30.773Z,1725572850.773 [BPC1](ERROR): Battery stick #27 (s/n: 02AB) reported OVER_TEMP_ALARM. Status code: 0x1717.
2024-09-05T21:47:30.774Z,1725572850.774 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 5.
2024-09-05T21:47:30.777Z,1725572850.777 [BPC1](INFO): Received data from all battery sticks.
2024-09-05T21:49:33.592Z,1725572973.592 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214932.00,A,3648.16401,N,12147.27929,W,0.019,116.64,050924,,,A*78
2024-09-05T21:49:33.595Z,1725572973.595 [NAL9602](INFO): GPS fix at 20240905T214932: (36.802734, -121.787988)
2024-09-05T21:49:33.627Z,1725572973.627 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T21:49:33.627Z,1725572973.627 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T21:49:34.029Z,1725572974.029 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-05T21:49:43.785Z,1725572983.785 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240905T214116/Courier0000.lzma
2024-09-05T21:49:45.489Z,1725572985.489 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0000.lzma.bak
2024-09-05T21:49:45.494Z,1725572985.494 [DataOverHttps](INFO): SBD MOMSN=23132288
2024-09-05T21:50:02.581Z,1725573002.581 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20240905T214116/Courier0004.lzma
2024-09-05T21:50:03.583Z,1725573003.583 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0004.lzma.bak
2024-09-05T21:50:03.583Z,1725573003.583 [DataOverHttps](INFO): SBD MOMSN=23132291
2024-09-05T21:50:05.985Z,1725573005.985 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T21:50:21.875Z,1725573021.875 [DataOverHttps](INFO): Sending 456 bytes from file Logs/20240905T200630/Express0035.lzma
2024-09-05T21:50:22.863Z,1725573022.863 [DataOverHttps](INFO): Moved sent file to Logs/20240905T200630/Express0035.lzma.bak
2024-09-05T21:50:22.863Z,1725573022.863 [DataOverHttps](INFO): SBD MOMSN=23132294
2024-09-05T21:50:41.214Z,1725573041.214 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20240905T200630/Express0038.lzma
2024-09-05T21:50:42.207Z,1725573042.207 [DataOverHttps](INFO): Moved sent file to Logs/20240905T200630/Express0038.lzma.bak
2024-09-05T21:50:42.207Z,1725573042.207 [DataOverHttps](INFO): SBD MOMSN=23132304
2024-09-05T21:51:02.122Z,1725573062.122 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240905T200630/Express0041.lzma
2024-09-05T21:51:03.123Z,1725573063.123 [DataOverHttps](INFO): Moved sent file to Logs/20240905T200630/Express0041.lzma.bak
2024-09-05T21:51:03.123Z,1725573063.123 [DataOverHttps](INFO): SBD MOMSN=23132308
2024-09-05T21:51:09.595Z,1725573069.595 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position
2024-09-05T21:51:09.992Z,1725573069.992 [Reporter](INFO): platform_buoyancy_position 945.730591 cc
2024-09-05T21:51:17.427Z,1725573077.427 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 150 cubic_centimeter
2024-09-05T21:51:17.431Z,1725573077.431 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2024-09-05T21:51:17.635Z,1725573077.635 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-09-05T21:51:20.131Z,1725573080.131 [Reporter](INFO): platform_buoyancy_position 952.177002 cc
2024-09-05T21:51:20.451Z,1725573080.451 [Reporter](INFO): platform_buoyancy_position 952.311279 cc
2024-09-05T21:51:20.863Z,1725573080.863 [Reporter](INFO): platform_buoyancy_position 950.162476 cc
2024-09-05T21:51:21.292Z,1725573081.292 [Reporter](INFO): platform_buoyancy_position 945.864868 cc
2024-09-05T21:51:21.652Z,1725573081.652 [Reporter](INFO): platform_buoyancy_position 943.313110 cc
2024-09-05T21:51:21.942Z,1725573081.942 [DataOverHttps](INFO): Sending 853 bytes from file Logs/20240905T214116/Express0001.lzma
2024-09-05T21:51:22.071Z,1725573082.071 [Reporter](INFO): platform_buoyancy_position 937.269653 cc
2024-09-05T21:51:22.495Z,1725573082.495 [Reporter](INFO): platform_buoyancy_position 932.837769 cc
2024-09-05T21:51:22.895Z,1725573082.895 [Reporter](INFO): platform_buoyancy_position 926.391357 cc
2024-09-05T21:51:22.943Z,1725573082.943 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0001.lzma.bak
2024-09-05T21:51:22.943Z,1725573082.943 [DataOverHttps](INFO): SBD MOMSN=23132310
2024-09-05T21:51:23.279Z,1725573083.279 [Reporter](INFO): platform_buoyancy_position 922.228027 cc
2024-09-05T21:51:23.699Z,1725573083.699 [Reporter](INFO): platform_buoyancy_position 915.781677 cc
2024-09-05T21:51:24.171Z,1725573084.171 [Reporter](INFO): platform_buoyancy_position 910.409668 cc
2024-09-05T21:51:24.507Z,1725573084.507 [Reporter](INFO): platform_buoyancy_position 905.037659 cc
2024-09-05T21:51:24.914Z,1725573084.914 [Reporter](INFO): platform_buoyancy_position 900.605774 cc
2024-09-05T21:51:25.299Z,1725573085.299 [Reporter](INFO): platform_buoyancy_position 896.308167 cc
2024-09-05T21:51:25.699Z,1725573085.699 [Reporter](INFO): platform_buoyancy_position 889.861755 cc
2024-09-05T21:51:26.147Z,1725573086.147 [Reporter](INFO): platform_buoyancy_position 883.683960 cc
2024-09-05T21:51:26.557Z,1725573086.557 [Reporter](INFO): platform_buoyancy_position 879.252075 cc
2024-09-05T21:51:26.935Z,1725573086.935 [Reporter](INFO): platform_buoyancy_position 874.820129 cc
2024-09-05T21:51:27.353Z,1725573087.353 [Reporter](INFO): platform_buoyancy_position 868.508057 cc
2024-09-05T21:51:27.767Z,1725573087.767 [Reporter](INFO): platform_buoyancy_position 864.076172 cc
2024-09-05T21:51:28.135Z,1725573088.135 [Reporter](INFO): platform_buoyancy_position 859.912842 cc
2024-09-05T21:51:28.577Z,1725573088.577 [Reporter](INFO): platform_buoyancy_position 853.466431 cc
2024-09-05T21:51:28.975Z,1725573088.975 [Reporter](INFO): platform_buoyancy_position 847.422913 cc
2024-09-05T21:51:29.343Z,1725573089.343 [Reporter](INFO): platform_buoyancy_position 842.588135 cc
2024-09-05T21:51:29.739Z,1725573089.739 [Reporter](INFO): platform_buoyancy_position 838.290527 cc
2024-09-05T21:51:30.131Z,1725573090.131 [Reporter](INFO): platform_buoyancy_position 831.844116 cc
2024-09-05T21:51:30.543Z,1725573090.543 [Reporter](INFO): platform_buoyancy_position 827.546570 cc
2024-09-05T21:51:30.968Z,1725573090.968 [Reporter](INFO): platform_buoyancy_position 821.368713 cc
2024-09-05T21:51:31.355Z,1725573091.355 [Reporter](INFO): platform_buoyancy_position 816.802551 cc
2024-09-05T21:51:31.771Z,1725573091.771 [Reporter](INFO): platform_buoyancy_position 810.356140 cc
2024-09-05T21:51:32.172Z,1725573092.172 [Reporter](INFO): platform_buoyancy_position 806.192810 cc
2024-09-05T21:51:32.571Z,1725573092.571 [Reporter](INFO): platform_buoyancy_position 799.746460 cc
2024-09-05T21:51:32.972Z,1725573092.972 [Reporter](INFO): platform_buoyancy_position 795.314514 cc
2024-09-05T21:51:33.398Z,1725573093.398 [Reporter](INFO): platform_buoyancy_position 789.002441 cc
2024-09-05T21:51:33.778Z,1725573093.778 [Reporter](INFO): platform_buoyancy_position 784.570557 cc
2024-09-05T21:51:34.191Z,1725573094.191 [Reporter](INFO): platform_buoyancy_position 778.392761 cc
2024-09-05T21:51:34.682Z,1725573094.682 [Reporter](INFO): platform_buoyancy_position 773.423645 cc
2024-09-05T21:51:35.019Z,1725573095.019 [Reporter](INFO): platform_buoyancy_position 767.514465 cc
2024-09-05T21:51:35.414Z,1725573095.414 [Reporter](INFO): platform_buoyancy_position 763.216858 cc
2024-09-05T21:51:35.830Z,1725573095.830 [Reporter](INFO): platform_buoyancy_position 757.844849 cc
2024-09-05T21:51:36.236Z,1725573096.236 [Reporter](INFO): platform_buoyancy_position 752.338562 cc
2024-09-05T21:51:36.611Z,1725573096.611 [Reporter](INFO): platform_buoyancy_position 748.040955 cc
2024-09-05T21:51:37.041Z,1725573097.041 [Reporter](INFO): platform_buoyancy_position 741.594543 cc
2024-09-05T21:51:37.451Z,1725573097.451 [Reporter](INFO): platform_buoyancy_position 737.431213 cc
2024-09-05T21:51:37.816Z,1725573097.816 [Reporter](INFO): platform_buoyancy_position 730.984863 cc
2024-09-05T21:51:38.246Z,1725573098.246 [Reporter](INFO): platform_buoyancy_position 726.687256 cc
2024-09-05T21:51:38.635Z,1725573098.635 [Reporter](INFO): platform_buoyancy_position 720.240845 cc
2024-09-05T21:51:39.024Z,1725573099.024 [Reporter](INFO): platform_buoyancy_position 715.808960 cc
2024-09-05T21:51:39.279Z,1725573099.279 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20240905T214116/Express0005.lzma
2024-09-05T21:51:39.449Z,1725573099.449 [Reporter](INFO): platform_buoyancy_position 710.705505 cc
2024-09-05T21:51:39.855Z,1725573099.855 [Reporter](INFO): platform_buoyancy_position 705.064941 cc
2024-09-05T21:51:40.247Z,1725573100.247 [Reporter](INFO): platform_buoyancy_position 700.633057 cc
2024-09-05T21:51:40.279Z,1725573100.279 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0005.lzma.bak
2024-09-05T21:51:40.279Z,1725573100.279 [DataOverHttps](INFO): SBD MOMSN=23132336
2024-09-05T21:51:40.635Z,1725573100.635 [Reporter](INFO): platform_buoyancy_position 694.455261 cc
2024-09-05T21:51:41.054Z,1725573101.054 [Reporter](INFO): platform_buoyancy_position 690.023315 cc
2024-09-05T21:51:41.471Z,1725573101.471 [Reporter](INFO): platform_buoyancy_position 683.711243 cc
2024-09-05T21:51:41.820Z,1725573101.820 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T21:51:41.820Z,1725573101.820 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-05T21:51:41.820Z,1725573101.820 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-05T21:51:41.886Z,1725573101.886 [Reporter](INFO): platform_buoyancy_position 678.607849 cc
2024-09-05T21:51:42.259Z,1725573102.259 [Reporter](INFO): platform_buoyancy_position 672.832947 cc
2024-09-05T21:51:42.675Z,1725573102.675 [Reporter](INFO): platform_buoyancy_position 668.535339 cc
2024-09-05T21:51:43.071Z,1725573103.071 [Reporter](INFO): platform_buoyancy_position 662.088928 cc
2024-09-05T21:51:43.503Z,1725573103.503 [Reporter](INFO): platform_buoyancy_position 657.657043 cc
2024-09-05T21:51:43.874Z,1725573103.874 [Reporter](INFO): platform_buoyancy_position 651.479248 cc
2024-09-05T21:51:44.307Z,1725573104.307 [Reporter](INFO): platform_buoyancy_position 646.644409 cc
2024-09-05T21:51:44.727Z,1725573104.727 [Reporter](INFO): platform_buoyancy_position 640.600952 cc
2024-09-05T21:51:45.123Z,1725573105.123 [Reporter](INFO): platform_buoyancy_position 636.303345 cc
2024-09-05T21:51:45.570Z,1725573105.570 [Reporter](INFO): platform_buoyancy_position 629.856934 cc
2024-09-05T21:51:45.947Z,1725573105.947 [Reporter](INFO): platform_buoyancy_position 625.425049 cc
2024-09-05T21:51:46.323Z,1725573106.323 [Reporter](INFO): platform_buoyancy_position 619.247253 cc
2024-09-05T21:51:46.737Z,1725573106.737 [Reporter](INFO): platform_buoyancy_position 614.949646 cc
2024-09-05T21:51:47.115Z,1725573107.115 [Reporter](INFO): platform_buoyancy_position 610.517700 cc
2024-09-05T21:51:47.531Z,1725573107.531 [Reporter](INFO): platform_buoyancy_position 604.205627 cc
2024-09-05T21:51:47.942Z,1725573107.942 [Reporter](INFO): platform_buoyancy_position 599.102234 cc
2024-09-05T21:51:48.315Z,1725573108.315 [Reporter](INFO): platform_buoyancy_position 593.461609 cc
2024-09-05T21:51:48.739Z,1725573108.739 [Reporter](INFO): platform_buoyancy_position 589.164001 cc
2024-09-05T21:51:49.154Z,1725573109.154 [Reporter](INFO): platform_buoyancy_position 584.732117 cc
2024-09-05T21:51:49.543Z,1725573109.543 [Reporter](INFO): platform_buoyancy_position 578.285706 cc
2024-09-05T21:51:49.935Z,1725573109.935 [Reporter](INFO): platform_buoyancy_position 571.973633 cc
2024-09-05T21:51:50.360Z,1725573110.360 [Reporter](INFO): platform_buoyancy_position 567.541748 cc
2024-09-05T21:51:50.763Z,1725573110.763 [Reporter](INFO): platform_buoyancy_position 561.229614 cc
2024-09-05T21:51:51.151Z,1725573111.151 [Reporter](INFO): platform_buoyancy_position 556.797729 cc
2024-09-05T21:51:51.566Z,1725573111.566 [Reporter](INFO): platform_buoyancy_position 550.485596 cc
2024-09-05T21:51:51.974Z,1725573111.974 [Reporter](INFO): platform_buoyancy_position 546.053711 cc
2024-09-05T21:51:52.364Z,1725573112.364 [Reporter](INFO): platform_buoyancy_position 539.875916 cc
2024-09-05T21:51:52.787Z,1725573112.787 [Reporter](INFO): platform_buoyancy_position 535.309692 cc
2024-09-05T21:51:53.167Z,1725573113.167 [Reporter](INFO): platform_buoyancy_position 531.012146 cc
2024-09-05T21:51:53.583Z,1725573113.583 [Reporter](INFO): platform_buoyancy_position 524.700012 cc
2024-09-05T21:51:53.998Z,1725573113.998 [Reporter](INFO): platform_buoyancy_position 518.253601 cc
2024-09-05T21:51:54.404Z,1725573114.404 [Reporter](INFO): platform_buoyancy_position 513.821716 cc
2024-09-05T21:51:54.779Z,1725573114.779 [Reporter](INFO): platform_buoyancy_position 507.778259 cc
2024-09-05T21:51:55.202Z,1725573115.202 [Reporter](INFO): platform_buoyancy_position 503.077759 cc
2024-09-05T21:51:55.595Z,1725573115.595 [Reporter](INFO): platform_buoyancy_position 498.780121 cc
2024-09-05T21:51:56.011Z,1725573116.011 [Reporter](INFO): platform_buoyancy_position 492.468079 cc
2024-09-05T21:51:56.403Z,1725573116.403 [Reporter](INFO): platform_buoyancy_position 488.170441 cc
2024-09-05T21:51:56.822Z,1725573116.822 [Reporter](INFO): platform_buoyancy_position 481.724030 cc
2024-09-05T21:51:57.207Z,1725573117.207 [Reporter](INFO): platform_buoyancy_position 477.292145 cc
2024-09-05T21:51:57.615Z,1725573117.615 [Reporter](INFO): platform_buoyancy_position 471.248627 cc
2024-09-05T21:51:58.031Z,1725573118.031 [Reporter](INFO): platform_buoyancy_position 466.682465 cc
2024-09-05T21:51:58.411Z,1725573118.411 [Reporter](INFO): platform_buoyancy_position 462.250519 cc
2024-09-05T21:51:58.827Z,1725573118.827 [Reporter](INFO): platform_buoyancy_position 455.938446 cc
2024-09-05T21:51:59.246Z,1725573119.246 [Reporter](INFO): platform_buoyancy_position 451.506561 cc
2024-09-05T21:51:59.635Z,1725573119.635 [Reporter](INFO): platform_buoyancy_position 447.074615 cc
2024-09-05T21:52:00.059Z,1725573120.059 [Reporter](INFO): platform_buoyancy_position 440.762543 cc
2024-09-05T21:52:00.439Z,1725573120.439 [Reporter](INFO): platform_buoyancy_position 436.464935 cc
2024-09-05T21:52:00.855Z,1725573120.855 [Reporter](INFO): platform_buoyancy_position 430.152863 cc
2024-09-05T21:52:01.270Z,1725573121.270 [Reporter](INFO): platform_buoyancy_position 423.572113 cc
2024-09-05T21:52:01.663Z,1725573121.663 [Reporter](INFO): platform_buoyancy_position 419.274567 cc
2024-09-05T21:52:02.051Z,1725573122.051 [Reporter](INFO): platform_buoyancy_position 414.574066 cc
2024-09-05T21:52:02.485Z,1725573122.485 [Reporter](INFO): platform_buoyancy_position 408.530548 cc
2024-09-05T21:52:02.883Z,1725573122.883 [Reporter](INFO): platform_buoyancy_position 402.218445 cc
2024-09-05T21:52:03.259Z,1725573123.259 [Reporter](INFO): platform_buoyancy_position 397.786530 cc
2024-09-05T21:52:03.685Z,1725573123.685 [Reporter](INFO): platform_buoyancy_position 392.951752 cc
2024-09-05T21:52:04.094Z,1725573124.094 [Reporter](INFO): platform_buoyancy_position 387.042542 cc
2024-09-05T21:52:04.499Z,1725573124.499 [Reporter](INFO): platform_buoyancy_position 382.476349 cc
2024-09-05T21:52:04.879Z,1725573124.879 [Reporter](INFO): platform_buoyancy_position 376.432831 cc
2024-09-05T21:52:05.288Z,1725573125.288 [Reporter](INFO): platform_buoyancy_position 371.732330 cc
2024-09-05T21:52:05.707Z,1725573125.707 [Reporter](INFO): platform_buoyancy_position 365.688843 cc
2024-09-05T21:52:06.099Z,1725573126.099 [Reporter](INFO): platform_buoyancy_position 361.256927 cc
2024-09-05T21:52:06.511Z,1725573126.511 [Reporter](INFO): platform_buoyancy_position 354.944824 cc
2024-09-05T21:52:06.903Z,1725573126.903 [Reporter](INFO): platform_buoyancy_position 350.512939 cc
2024-09-05T21:52:07.327Z,1725573127.327 [Reporter](INFO): platform_buoyancy_position 344.200836 cc
2024-09-05T21:52:07.731Z,1725573127.731 [Reporter](INFO): platform_buoyancy_position 339.768951 cc
2024-09-05T21:52:08.104Z,1725573128.104 [Reporter](INFO): platform_buoyancy_position 333.322540 cc
2024-09-05T21:52:08.530Z,1725573128.530 [Reporter](INFO): platform_buoyancy_position 329.024933 cc
2024-09-05T21:52:08.939Z,1725573128.939 [Reporter](INFO): platform_buoyancy_position 322.712830 cc
2024-09-05T21:52:09.331Z,1725573129.331 [Reporter](INFO): platform_buoyancy_position 318.280945 cc
2024-09-05T21:52:09.770Z,1725573129.770 [Reporter](INFO): platform_buoyancy_position 313.983337 cc
2024-09-05T21:52:10.139Z,1725573130.139 [Reporter](INFO): platform_buoyancy_position 307.805542 cc
2024-09-05T21:52:10.556Z,1725573130.556 [Reporter](INFO): platform_buoyancy_position 303.239319 cc
2024-09-05T21:52:10.947Z,1725573130.947 [Reporter](INFO): platform_buoyancy_position 297.061523 cc
2024-09-05T21:52:11.370Z,1725573131.370 [Reporter](INFO): platform_buoyancy_position 292.226746 cc
2024-09-05T21:52:11.747Z,1725573131.747 [Reporter](INFO): platform_buoyancy_position 286.183228 cc
2024-09-05T21:52:12.183Z,1725573132.183 [Reporter](INFO): platform_buoyancy_position 281.617035 cc
2024-09-05T21:52:12.587Z,1725573132.587 [Reporter](INFO): platform_buoyancy_position 275.304932 cc
2024-09-05T21:52:12.955Z,1725573132.955 [Reporter](INFO): platform_buoyancy_position 271.141632 cc
2024-09-05T21:52:13.379Z,1725573133.379 [Reporter](INFO): platform_buoyancy_position 264.695221 cc
2024-09-05T21:52:13.783Z,1725573133.783 [Reporter](INFO): platform_buoyancy_position 260.263336 cc
2024-09-05T21:52:14.206Z,1725573134.206 [Reporter](INFO): platform_buoyancy_position 253.816925 cc
2024-09-05T21:52:14.643Z,1725573134.643 [Reporter](INFO): platform_buoyancy_position 248.982101 cc
2024-09-05T21:52:14.999Z,1725573134.999 [Reporter](INFO): platform_buoyancy_position 243.207214 cc
2024-09-05T21:52:15.415Z,1725573135.415 [Reporter](INFO): platform_buoyancy_position 238.775330 cc
2024-09-05T21:52:15.827Z,1725573135.827 [Reporter](INFO): platform_buoyancy_position 232.463226 cc
2024-09-05T21:52:16.215Z,1725573136.215 [Reporter](INFO): platform_buoyancy_position 228.031342 cc
2024-09-05T21:52:16.634Z,1725573136.634 [Reporter](INFO): platform_buoyancy_position 221.719238 cc
2024-09-05T21:52:17.053Z,1725573137.053 [Reporter](INFO): platform_buoyancy_position 217.287323 cc
2024-09-05T21:52:17.467Z,1725573137.467 [Reporter](INFO): platform_buoyancy_position 210.840912 cc
2024-09-05T21:52:17.927Z,1725573137.927 [Reporter](INFO): platform_buoyancy_position 205.200317 cc
2024-09-05T21:52:18.283Z,1725573138.283 [Reporter](INFO): platform_buoyancy_position 200.096924 cc
2024-09-05T21:52:18.667Z,1725573138.667 [Reporter](INFO): platform_buoyancy_position 195.799316 cc
2024-09-05T21:52:19.036Z,1725573139.036 [Reporter](INFO): platform_buoyancy_position 191.501740 cc
2024-09-05T21:52:19.459Z,1725573139.459 [Reporter](INFO): platform_buoyancy_position 185.189636 cc
2024-09-05T21:52:19.868Z,1725573139.868 [Reporter](INFO): platform_buoyancy_position 178.877533 cc
2024-09-05T21:52:20.256Z,1725573140.256 [Reporter](INFO): platform_buoyancy_position 174.579926 cc
2024-09-05T21:52:20.671Z,1725573140.671 [Reporter](INFO): platform_buoyancy_position 170.013733 cc
2024-09-05T21:52:21.079Z,1725573141.079 [Reporter](INFO): platform_buoyancy_position 163.701630 cc
2024-09-05T21:52:21.508Z,1725573141.508 [Reporter](INFO): platform_buoyancy_position 159.135437 cc
2024-09-05T21:53:27.474Z,1725573207.474 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter
2024-09-05T21:53:27.475Z,1725573207.475 [CommandExec](IMPORTANT): platform_mass_position 1.250068 cm
2024-09-05T21:53:38.734Z,1725573218.734 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.5 centimeter persist
2024-09-05T21:53:51.362Z,1725573231.362 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 125 cubic_centimeter persist
2024-09-05T21:55:15.049Z,1725573315.049 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-05T21:56:42.528Z,1725573402.528 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-05T21:56:42.528Z,1725573402.528 [Default:CheckIn:C.Wait] Stopped
2024-09-05T21:56:42.528Z,1725573402.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T21:56:42.528Z,1725573402.528 [Default:CheckIn:D] Running Loop=1
2024-09-05T21:56:42.936Z,1725573402.936 [Default:CheckIn:D] Stopped
2024-09-05T21:56:42.936Z,1725573402.936 [Default:CheckIn:E] Running Loop=1
2024-09-05T21:56:43.326Z,1725573403.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.141685 min
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn:E] Stopped
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn] Stopped
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn](INFO): Running loop #2
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn] Running Loop=2
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T21:56:43.327Z,1725573403.327 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T21:56:45.348Z,1725573405.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215645.00,A,3648.16937,N,12147.28756,W,0.214,237.93,050924,,,A*70
2024-09-05T21:56:45.350Z,1725573405.350 [NAL9602](INFO): GPS fix at 20240905T215645: (36.802823, -121.788126)
2024-09-05T21:56:45.361Z,1725573405.361 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T21:56:45.362Z,1725573405.362 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T21:56:51.357Z,1725573411.357 [CommandExec](IMPORTANT): got command maintain clear
2024-09-05T21:56:51.570Z,1725573411.570 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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,Reporter,LogSplitter,
2024-09-05T21:56:54.038Z,1725573414.038 [Reporter](INFO): platform_buoyancy_position 150.674530 cc
2024-09-05T21:56:54.835Z,1725573414.835 [Reporter](INFO): platform_buoyancy_position 152.823303 cc
2024-09-05T21:56:55.176Z,1725573415.176 [Reporter](INFO): platform_buoyancy_position 155.106415 cc
2024-09-05T21:56:55.578Z,1725573415.578 [Reporter](INFO): platform_buoyancy_position 159.404022 cc
2024-09-05T21:56:55.960Z,1725573415.960 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0007.lzma
2024-09-05T21:56:56.016Z,1725573416.016 [Reporter](INFO): platform_buoyancy_position 163.835938 cc
2024-09-05T21:56:56.380Z,1725573416.380 [Reporter](INFO): platform_buoyancy_position 170.013733 cc
2024-09-05T21:56:56.787Z,1725573416.787 [Reporter](INFO): platform_buoyancy_position 174.445618 cc
2024-09-05T21:56:56.964Z,1725573416.964 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0007.lzma.bak
2024-09-05T21:56:56.964Z,1725573416.964 [DataOverHttps](INFO): SBD MOMSN=23132347
2024-09-05T21:56:57.207Z,1725573417.207 [Reporter](INFO): platform_buoyancy_position 180.757721 cc
2024-09-05T21:56:57.596Z,1725573417.596 [Reporter](INFO): platform_buoyancy_position 185.055328 cc
2024-09-05T21:56:58.036Z,1725573418.036 [Reporter](INFO): platform_buoyancy_position 191.501740 cc
2024-09-05T21:56:58.428Z,1725573418.428 [Reporter](INFO): platform_buoyancy_position 195.933624 cc
2024-09-05T21:56:58.841Z,1725573418.841 [Reporter](INFO): platform_buoyancy_position 202.245728 cc
2024-09-05T21:56:59.195Z,1725573419.195 [Reporter](INFO): platform_buoyancy_position 206.677612 cc
2024-09-05T21:56:59.629Z,1725573419.629 [Reporter](INFO): platform_buoyancy_position 210.975220 cc
2024-09-05T21:57:00.031Z,1725573420.031 [Reporter](INFO): platform_buoyancy_position 217.287323 cc
2024-09-05T21:57:00.411Z,1725573420.411 [Reporter](INFO): platform_buoyancy_position 221.719238 cc
2024-09-05T21:57:00.820Z,1725573420.820 [Reporter](INFO): platform_buoyancy_position 228.031342 cc
2024-09-05T21:57:01.248Z,1725573421.248 [Reporter](INFO): platform_buoyancy_position 232.731842 cc
2024-09-05T21:57:01.655Z,1725573421.655 [Reporter](INFO): platform_buoyancy_position 238.775330 cc
2024-09-05T21:57:02.111Z,1725573422.111 [Reporter](INFO): platform_buoyancy_position 245.221741 cc
2024-09-05T21:57:02.538Z,1725573422.538 [Reporter](INFO): platform_buoyancy_position 249.653625 cc
2024-09-05T21:57:02.927Z,1725573422.927 [Reporter](INFO): platform_buoyancy_position 255.965714 cc
2024-09-05T21:57:03.334Z,1725573423.334 [Reporter](INFO): platform_buoyancy_position 260.397644 cc
2024-09-05T21:57:03.715Z,1725573423.715 [Reporter](INFO): platform_buoyancy_position 264.695221 cc
2024-09-05T21:57:04.168Z,1725573424.168 [Reporter](INFO): platform_buoyancy_position 271.007324 cc
2024-09-05T21:57:04.575Z,1725573424.575 [Reporter](INFO): platform_buoyancy_position 277.453735 cc
2024-09-05T21:57:04.923Z,1725573424.923 [Reporter](INFO): platform_buoyancy_position 281.885620 cc
2024-09-05T21:57:05.395Z,1725573425.395 [Reporter](INFO): platform_buoyancy_position 288.332031 cc
2024-09-05T21:57:05.788Z,1725573425.788 [Reporter](INFO): platform_buoyancy_position 292.495331 cc
2024-09-05T21:57:06.211Z,1725573426.211 [Reporter](INFO): platform_buoyancy_position 298.941742 cc
2024-09-05T21:57:06.604Z,1725573426.604 [Reporter](INFO): platform_buoyancy_position 303.373627 cc
2024-09-05T21:57:07.046Z,1725573427.046 [Reporter](INFO): platform_buoyancy_position 309.820038 cc
2024-09-05T21:57:07.387Z,1725573427.387 [Reporter](INFO): platform_buoyancy_position 313.983337 cc
2024-09-05T21:57:07.819Z,1725573427.819 [Reporter](INFO): platform_buoyancy_position 318.415222 cc
2024-09-05T21:57:08.184Z,1725573428.184 [Reporter](INFO): platform_buoyancy_position 324.458740 cc
2024-09-05T21:57:08.575Z,1725573428.575 [Reporter](INFO): platform_buoyancy_position 329.024933 cc
2024-09-05T21:57:08.972Z,1725573428.972 [Reporter](INFO): platform_buoyancy_position 333.322540 cc
2024-09-05T21:57:09.439Z,1725573429.439 [Reporter](INFO): platform_buoyancy_position 339.903229 cc
2024-09-05T21:57:09.779Z,1725573429.779 [Reporter](INFO): platform_buoyancy_position 344.200836 cc
2024-09-05T21:57:10.185Z,1725573430.185 [Reporter](INFO): platform_buoyancy_position 350.512939 cc
2024-09-05T21:57:10.591Z,1725573430.591 [Reporter](INFO): platform_buoyancy_position 355.079132 cc
2024-09-05T21:57:10.987Z,1725573430.987 [Reporter](INFO): platform_buoyancy_position 361.256927 cc
2024-09-05T21:57:11.391Z,1725573431.391 [Reporter](INFO): platform_buoyancy_position 365.688843 cc
2024-09-05T21:57:11.819Z,1725573431.819 [Reporter](INFO): platform_buoyancy_position 372.000946 cc
2024-09-05T21:57:12.204Z,1725573432.204 [Reporter](INFO): platform_buoyancy_position 376.432831 cc
2024-09-05T21:57:12.641Z,1725573432.641 [Reporter](INFO): platform_buoyancy_position 382.879242 cc
2024-09-05T21:57:13.051Z,1725573433.051 [Reporter](INFO): platform_buoyancy_position 387.176849 cc
2024-09-05T21:57:13.460Z,1725573433.460 [Reporter](INFO): platform_buoyancy_position 393.488953 cc
2024-09-05T21:57:13.839Z,1725573433.839 [Reporter](INFO): platform_buoyancy_position 397.920837 cc
2024-09-05T21:57:14.255Z,1725573434.255 [Reporter](INFO): platform_buoyancy_position 404.367249 cc
2024-09-05T21:57:14.647Z,1725573434.647 [Reporter](INFO): platform_buoyancy_position 408.664856 cc
2024-09-05T21:57:15.043Z,1725573435.043 [Reporter](INFO): platform_buoyancy_position 414.976959 cc
2024-09-05T21:57:15.471Z,1725573435.471 [Reporter](INFO): platform_buoyancy_position 419.274567 cc
2024-09-05T21:57:15.788Z,1725573435.788 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-05T21:57:15.865Z,1725573435.865 [NAL9602](ERROR): received:
+CSQ:0
OK407, 2, 0, 0, 0
OK
2024-09-05T21:57:15.947Z,1725573435.947 [Reporter](INFO): platform_buoyancy_position 425.855255 cc
2024-09-05T21:57:16.255Z,1725573436.255 [Reporter](INFO): platform_buoyancy_position 430.018524 cc
2024-09-05T21:57:16.676Z,1725573436.676 [Reporter](INFO): platform_buoyancy_position 435.390533 cc
2024-09-05T21:57:17.087Z,1725573437.087 [Reporter](INFO): platform_buoyancy_position 440.762543 cc
2024-09-05T21:57:17.460Z,1725573437.460 [Reporter](INFO): platform_buoyancy_position 445.194427 cc
2024-09-05T21:57:17.855Z,1725573437.855 [Reporter](INFO): platform_buoyancy_position 450.297821 cc
2024-09-05T21:57:17.937Z,1725573437.937 [DataOverHttps](INFO): Sending 371 bytes from file Logs/20240905T214116/Express0008.lzma
2024-09-05T21:57:18.255Z,1725573438.255 [Reporter](INFO): platform_buoyancy_position 455.938446 cc
2024-09-05T21:57:18.655Z,1725573438.655 [Reporter](INFO): platform_buoyancy_position 460.236053 cc
2024-09-05T21:57:18.939Z,1725573438.939 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0008.lzma.bak
2024-09-05T21:57:18.939Z,1725573438.939 [DataOverHttps](INFO): SBD MOMSN=23132353
2024-09-05T21:57:19.082Z,1725573439.082 [Reporter](INFO): platform_buoyancy_position 466.682465 cc
2024-09-05T21:57:19.483Z,1725573439.483 [Reporter](INFO): platform_buoyancy_position 472.994537 cc
2024-09-05T21:57:19.919Z,1725573439.919 [Reporter](INFO): platform_buoyancy_position 477.426422 cc
2024-09-05T21:57:20.343Z,1725573440.343 [Reporter](INFO): platform_buoyancy_position 483.872833 cc
2024-09-05T21:57:20.703Z,1725573440.703 [Reporter](INFO): platform_buoyancy_position 488.170441 cc
2024-09-05T21:57:21.077Z,1725573441.077 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T21:57:21.078Z,1725573441.078 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-05T21:57:21.078Z,1725573441.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-05T21:57:21.130Z,1725573441.130 [Reporter](INFO): platform_buoyancy_position 494.482574 cc
2024-09-05T21:57:21.499Z,1725573441.499 [Reporter](INFO): platform_buoyancy_position 498.914459 cc
2024-09-05T21:57:21.910Z,1725573441.910 [Reporter](INFO): platform_buoyancy_position 505.226562 cc
2024-09-05T21:57:22.331Z,1725573442.331 [Reporter](INFO): platform_buoyancy_position 509.658478 cc
2024-09-05T21:57:22.728Z,1725573442.728 [Reporter](INFO): platform_buoyancy_position 515.970520 cc
2024-09-05T21:57:23.116Z,1725573443.116 [Reporter](INFO): platform_buoyancy_position 520.402405 cc
2024-09-05T21:57:23.520Z,1725573443.520 [Reporter](INFO): platform_buoyancy_position 525.102905 cc
2024-09-05T21:57:23.942Z,1725573443.942 [Reporter](INFO): platform_buoyancy_position 531.146423 cc
2024-09-05T21:57:24.343Z,1725573444.343 [Reporter](INFO): platform_buoyancy_position 537.324219 cc
2024-09-05T21:57:24.731Z,1725573444.731 [Reporter](INFO): platform_buoyancy_position 541.756104 cc
2024-09-05T21:57:25.150Z,1725573445.150 [Reporter](INFO): platform_buoyancy_position 546.188049 cc
2024-09-05T21:57:25.555Z,1725573445.555 [Reporter](INFO): platform_buoyancy_position 552.634399 cc
2024-09-05T21:57:25.971Z,1725573445.971 [Reporter](INFO): platform_buoyancy_position 557.334900 cc
2024-09-05T21:57:26.359Z,1725573446.359 [Reporter](INFO): platform_buoyancy_position 563.244141 cc
2024-09-05T21:57:26.771Z,1725573446.771 [Reporter](INFO): platform_buoyancy_position 567.676025 cc
2024-09-05T21:57:27.179Z,1725573447.179 [Reporter](INFO): platform_buoyancy_position 573.988098 cc
2024-09-05T21:57:27.552Z,1725573447.552 [Reporter](INFO): platform_buoyancy_position 578.420044 cc
2024-09-05T21:57:27.975Z,1725573447.975 [Reporter](INFO): platform_buoyancy_position 584.732117 cc
2024-09-05T21:57:28.359Z,1725573448.359 [Reporter](INFO): platform_buoyancy_position 589.029724 cc
2024-09-05T21:57:28.756Z,1725573448.756 [Reporter](INFO): platform_buoyancy_position 593.327332 cc
2024-09-05T21:57:29.175Z,1725573449.175 [Reporter](INFO): platform_buoyancy_position 599.773743 cc
2024-09-05T21:57:29.563Z,1725573449.563 [Reporter](INFO): platform_buoyancy_position 604.205627 cc
2024-09-05T21:57:29.992Z,1725573449.992 [Reporter](INFO): platform_buoyancy_position 610.517700 cc
2024-09-05T21:57:30.372Z,1725573450.372 [Reporter](INFO): platform_buoyancy_position 614.949646 cc
2024-09-05T21:57:30.788Z,1725573450.788 [Reporter](INFO): platform_buoyancy_position 621.261719 cc
2024-09-05T21:57:31.212Z,1725573451.212 [Reporter](INFO): platform_buoyancy_position 625.962219 cc
2024-09-05T21:57:31.620Z,1725573451.620 [Reporter](INFO): platform_buoyancy_position 632.140015 cc
2024-09-05T21:57:32.027Z,1725573452.027 [Reporter](INFO): platform_buoyancy_position 636.437622 cc
2024-09-05T21:57:32.446Z,1725573452.446 [Reporter](INFO): platform_buoyancy_position 642.884033 cc
2024-09-05T21:57:32.827Z,1725573452.827 [Reporter](INFO): platform_buoyancy_position 647.047302 cc
2024-09-05T21:57:33.224Z,1725573453.224 [Reporter](INFO): platform_buoyancy_position 653.493713 cc
2024-09-05T21:57:33.640Z,1725573453.640 [Reporter](INFO): platform_buoyancy_position 657.925659 cc
2024-09-05T21:57:34.032Z,1725573454.032 [Reporter](INFO): platform_buoyancy_position 662.626160 cc
2024-09-05T21:57:34.443Z,1725573454.443 [Reporter](INFO): platform_buoyancy_position 668.803955 cc
2024-09-05T21:57:34.811Z,1725573454.811 [Reporter](INFO): platform_buoyancy_position 672.967224 cc
2024-09-05T21:57:35.219Z,1725573455.219 [Reporter](INFO): platform_buoyancy_position 679.279358 cc
2024-09-05T21:57:35.635Z,1725573455.635 [Reporter](INFO): platform_buoyancy_position 683.711243 cc
2024-09-05T21:57:36.031Z,1725573456.031 [Reporter](INFO): platform_buoyancy_position 690.023315 cc
2024-09-05T21:57:36.471Z,1725573456.471 [Reporter](INFO): platform_buoyancy_position 694.455261 cc
2024-09-05T21:57:36.859Z,1725573456.859 [Reporter](INFO): platform_buoyancy_position 700.767334 cc
2024-09-05T21:57:37.275Z,1725573457.275 [Reporter](INFO): platform_buoyancy_position 705.199219 cc
2024-09-05T21:57:37.686Z,1725573457.686 [Reporter](INFO): platform_buoyancy_position 711.511353 cc
2024-09-05T21:57:38.043Z,1725573458.043 [Reporter](INFO): platform_buoyancy_position 715.808960 cc
2024-09-05T21:57:38.463Z,1725573458.463 [Reporter](INFO): platform_buoyancy_position 720.240845 cc
2024-09-05T21:57:38.879Z,1725573458.879 [Reporter](INFO): platform_buoyancy_position 726.687256 cc
2024-09-05T21:57:39.251Z,1725573459.251 [Reporter](INFO): platform_buoyancy_position 730.850525 cc
2024-09-05T21:57:39.663Z,1725573459.663 [Reporter](INFO): platform_buoyancy_position 737.296936 cc
2024-09-05T21:57:40.080Z,1725573460.080 [Reporter](INFO): platform_buoyancy_position 741.997437 cc
2024-09-05T21:57:40.479Z,1725573460.479 [Reporter](INFO): platform_buoyancy_position 748.175232 cc
2024-09-05T21:57:40.875Z,1725573460.875 [Reporter](INFO): platform_buoyancy_position 752.338562 cc
2024-09-05T21:57:41.279Z,1725573461.279 [Reporter](INFO): platform_buoyancy_position 758.784912 cc
2024-09-05T21:57:41.711Z,1725573461.711 [Reporter](INFO): platform_buoyancy_position 763.216858 cc
2024-09-05T21:57:42.119Z,1725573462.119 [Reporter](INFO): platform_buoyancy_position 769.528931 cc
2024-09-05T21:57:42.531Z,1725573462.531 [Reporter](INFO): platform_buoyancy_position 774.095154 cc
2024-09-05T21:57:42.927Z,1725573462.927 [Reporter](INFO): platform_buoyancy_position 780.272949 cc
2024-09-05T21:57:43.291Z,1725573463.291 [Reporter](INFO): platform_buoyancy_position 784.704834 cc
2024-09-05T21:57:43.716Z,1725573463.716 [Reporter](INFO): platform_buoyancy_position 791.151245 cc
2024-09-05T21:57:44.103Z,1725573464.103 [Reporter](INFO): platform_buoyancy_position 795.314514 cc
2024-09-05T21:57:44.511Z,1725573464.511 [Reporter](INFO): platform_buoyancy_position 799.880737 cc
2024-09-05T21:57:44.931Z,1725573464.931 [Reporter](INFO): platform_buoyancy_position 806.058533 cc
2024-09-05T21:57:45.339Z,1725573465.339 [Reporter](INFO): platform_buoyancy_position 812.370667 cc
2024-09-05T21:57:45.712Z,1725573465.712 [Reporter](INFO): platform_buoyancy_position 816.802551 cc
2024-09-05T21:57:46.128Z,1725573466.128 [Reporter](INFO): platform_buoyancy_position 821.234436 cc
2024-09-05T21:57:46.547Z,1725573466.547 [Reporter](INFO): platform_buoyancy_position 827.546570 cc
2024-09-05T21:57:46.970Z,1725573466.970 [Reporter](INFO): platform_buoyancy_position 832.247070 cc
2024-09-05T21:57:47.371Z,1725573467.371 [Reporter](INFO): platform_buoyancy_position 838.424866 cc
2024-09-05T21:57:47.775Z,1725573467.775 [Reporter](INFO): platform_buoyancy_position 842.722473 cc
2024-09-05T21:57:48.167Z,1725573468.167 [Reporter](INFO): platform_buoyancy_position 849.034546 cc
2024-09-05T21:57:48.571Z,1725573468.571 [Reporter](INFO): platform_buoyancy_position 853.466431 cc
2024-09-05T21:57:48.967Z,1725573468.967 [Reporter](INFO): platform_buoyancy_position 859.778564 cc
2024-09-05T21:57:49.359Z,1725573469.359 [Reporter](INFO): platform_buoyancy_position 864.076172 cc
2024-09-05T21:57:49.788Z,1725573469.788 [Reporter](INFO): platform_buoyancy_position 868.642334 cc
2024-09-05T21:57:50.188Z,1725573470.188 [Reporter](INFO): platform_buoyancy_position 874.820129 cc
2024-09-05T21:57:50.563Z,1725573470.563 [Reporter](INFO): platform_buoyancy_position 879.252075 cc
2024-09-05T21:57:50.982Z,1725573470.982 [Reporter](INFO): platform_buoyancy_position 885.564148 cc
2024-09-05T21:57:51.395Z,1725573471.395 [Reporter](INFO): platform_buoyancy_position 889.996033 cc
2024-09-05T21:57:51.803Z,1725573471.803 [Reporter](INFO): platform_buoyancy_position 896.442444 cc
2024-09-05T21:57:52.227Z,1725573472.227 [Reporter](INFO): platform_buoyancy_position 900.740051 cc
2024-09-05T21:57:52.623Z,1725573472.623 [Reporter](INFO): platform_buoyancy_position 907.186462 cc
2024-09-05T21:57:53.007Z,1725573473.007 [Reporter](INFO): platform_buoyancy_position 911.484070 cc
2024-09-05T21:57:53.423Z,1725573473.423 [Reporter](INFO): platform_buoyancy_position 917.796143 cc
2024-09-05T21:57:53.815Z,1725573473.815 [Reporter](INFO): platform_buoyancy_position 922.765259 cc
2024-09-05T21:57:54.203Z,1725573474.203 [Reporter](INFO): platform_buoyancy_position 928.674438 cc
2024-09-05T21:57:54.627Z,1725573474.627 [Reporter](INFO): platform_buoyancy_position 932.972046 cc
2024-09-05T21:57:55.051Z,1725573475.051 [Reporter](INFO): platform_buoyancy_position 939.284180 cc
2024-09-05T21:57:55.472Z,1725573475.472 [Reporter](INFO): platform_buoyancy_position 943.716064 cc
2024-09-05T21:57:55.875Z,1725573475.875 [Reporter](INFO): platform_buoyancy_position 947.879395 cc
2024-09-05T22:00:37.371Z,1725573637.371 [NAL9602](INFO): SBD MO Status=0, MOMSN=56407, MT Status=0, MTMSN=0
2024-09-05T22:00:37.372Z,1725573637.372 [NAL9602](INFO): No messages in MT queue
2024-09-05T22:01:08.074Z,1725573668.074 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T22:02:10.598Z,1725573730.598 [DataOverHttps](IMPORTANT): SBD MTMSN=20240905T220210
2024-09-05T22:02:18.853Z,1725573738.853 [DataOverHttps](INFO): Received command: schedule resume
2024-09-05T22:02:18.863Z,1725573738.863 [CommandExec](IMPORTANT): got command schedule resume
2024-09-05T22:02:19.855Z,1725573739.855 [DataOverHttps](IMPORTANT): SBD MTMSN=20240905T220219
2024-09-05T22:02:21.616Z,1725573741.616 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-05T22:02:21.616Z,1725573741.616 [Default:CheckIn:C.Wait] Stopped
2024-09-05T22:02:21.616Z,1725573741.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:02:21.616Z,1725573741.616 [Default:CheckIn:D] Running Loop=1
2024-09-05T22:02:22.027Z,1725573742.027 [Default:CheckIn:D] Stopped
2024-09-05T22:02:22.027Z,1725573742.027 [Default:CheckIn:E] Running Loop=1
2024-09-05T22:02:22.434Z,1725573742.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.793192 min
2024-09-05T22:02:22.434Z,1725573742.434 [Default:CheckIn:E] Stopped
2024-09-05T22:02:22.434Z,1725573742.434 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-05T22:02:22.435Z,1725573742.435 [Default:CheckIn] Stopped
2024-09-05T22:02:22.435Z,1725573742.435 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T22:02:22.435Z,1725573742.435 [Default:CheckIn](INFO): Running loop #3
2024-09-05T22:02:22.435Z,1725573742.435 [Default:CheckIn] Running Loop=3
2024-09-05T22:02:22.435Z,1725573742.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T22:02:22.435Z,1725573742.435 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T22:02:24.452Z,1725573744.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220224.00,A,3648.14128,N,12147.22749,W,0.039,62.82,050924,,,D*4F
2024-09-05T22:02:24.455Z,1725573744.455 [NAL9602](INFO): GPS fix at 20240905T220224: (36.802355, -121.787125)
2024-09-05T22:02:24.521Z,1725573744.521 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T22:02:24.521Z,1725573744.521 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T22:02:29.753Z,1725573749.753 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0010.lzma
2024-09-05T22:02:29.756Z,1725573749.756 [DataOverHttps](INFO): Received command: ibit
2024-09-05T22:02:29.826Z,1725573749.826 [CommandExec](IMPORTANT): got command ibit
2024-09-05T22:02:30.201Z,1725573750.201 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-05T22:02:30.201Z,1725573750.201 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-05T22:02:30.204Z,1725573750.204 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T22:02:30.351Z,1725573750.351 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0010.lzma.bak
2024-09-05T22:02:30.351Z,1725573750.351 [DataOverHttps](INFO): SBD MOMSN=23132381
2024-09-05T22:02:41.094Z,1725573761.094 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-09-05T22:02:41.518Z,1725573761.518 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T22:02:46.541Z,1725573766.541 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T214116/Courier0016.lzma
2024-09-05T22:02:47.546Z,1725573767.546 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0016.lzma.bak
2024-09-05T22:02:47.546Z,1725573767.546 [DataOverHttps](INFO): SBD MOMSN=23132385
2024-09-05T22:02:52.460Z,1725573772.460 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-09-05T22:02:52.930Z,1725573772.930 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T22:02:53.588Z,1725573773.588 [NAL9602](INFO): SBD MO Status=2, MOMSN=56408, MT Status=2, MTMSN=0
2024-09-05T22:02:53.588Z,1725573773.588 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T22:03:03.797Z,1725573783.797 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002163
CHAN A1 (24V): 0.000274
CHAN A2 (12V): -0.002413
CHAN A3 (5V): -0.001610
CHAN B0 (3.3V): 0.000381
CHAN B1 (3.15aV): 0.000096
CHAN B2 (3.15bV): -0.000772
CHAN B3 (GND): 0.000190
OPEN: -0.000439
Full Scale: +/- 1 mA
2024-09-05T22:03:06.505Z,1725573786.505 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20240905T214116/Express0011.lzma
2024-09-05T22:03:07.507Z,1725573787.507 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0011.lzma.bak
2024-09-05T22:03:07.507Z,1725573787.507 [DataOverHttps](INFO): SBD MOMSN=23132387
2024-09-05T22:03:23.412Z,1725573803.412 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240905T214116/Express0014.lzma
2024-09-05T22:03:24.415Z,1725573804.415 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0014.lzma.bak
2024-09-05T22:03:24.415Z,1725573804.415 [DataOverHttps](INFO): SBD MOMSN=23132394
2024-09-05T22:03:32.763Z,1725573812.763 [DataOverHttps](IMPORTANT): SBD MTMSN=20240905T220332
2024-09-05T22:03:40.149Z,1725573820.149 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20240905T214116/Express0017.lzma
2024-09-05T22:03:40.152Z,1725573820.152 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;run
2024-09-05T22:03:40.254Z,1725573820.254 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2024-09-05T22:03:40.255Z,1725573820.255 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2024-09-05T22:03:40.255Z,1725573820.255 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2024-09-05T22:03:40.257Z,1725573820.257 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2024-09-05T22:03:40.933Z,1725573820.933 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2024-09-05T22:03:40.944Z,1725573820.944 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2024-09-05T22:03:40.957Z,1725573820.957 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2024-09-05T22:03:40.963Z,1725573820.963 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2024-09-05T22:03:40.966Z,1725573820.966 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2024-09-05T22:03:40.968Z,1725573820.968 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2024-09-05T22:03:40.975Z,1725573820.975 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2024-09-05T22:03:40.979Z,1725573820.979 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2024-09-05T22:03:40.990Z,1725573820.990 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2024-09-05T22:03:40.993Z,1725573820.993 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2024-09-05T22:03:40.000Z,1725573821.000 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2024-09-05T22:03:41.003Z,1725573821.003 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2024-09-05T22:03:41.013Z,1725573821.013 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2024-09-05T22:03:41.016Z,1725573821.016 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2024-09-05T22:03:41.016Z,1725573821.016 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2024-09-05T22:03:41.017Z,1725573821.017 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2024-09-05T22:03:41.022Z,1725573821.022 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2024-09-05T22:03:41.498Z,1725573821.498 [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-05T22:03:41.503Z,1725573821.503 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2024-09-05T22:03:41.540Z,1725573821.540 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2024-09-05T22:03:41.543Z,1725573821.543 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2024-09-05T22:03:41.545Z,1725573821.545 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2024-09-05T22:03:41.548Z,1725573821.548 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2024-09-05T22:03:41.563Z,1725573821.563 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2024-09-05T22:03:41.589Z,1725573821.589 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2024-09-05T22:03:41.592Z,1725573821.592 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2024-09-05T22:03:41.614Z,1725573821.614 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-05T22:03:41.618Z,1725573821.618 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0017.lzma.bak
2024-09-05T22:03:41.618Z,1725573821.618 [DataOverHttps](INFO): SBD MOMSN=23132400
2024-09-05T22:03:41.714Z,1725573821.714 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-09-05T22:03:41.714Z,1725573821.714 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-09-05T22:03:41.715Z,1725573821.715 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-09-05T22:03:41.837Z,1725573821.837 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-09-05T22:03:41.849Z,1725573821.849 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2024-09-05T22:03:41.852Z,1725573821.852 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-09-05T22:03:41.859Z,1725573821.859 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2024-09-05T22:03:41.863Z,1725573821.863 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-09-05T22:03:41.864Z,1725573821.864 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-09-05T22:03:41.880Z,1725573821.880 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-09-05T22:03:41.900Z,1725573821.900 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-09-05T22:03:41.916Z,1725573821.916 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-09-05T22:03:41.916Z,1725573821.916 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-09-05T22:03:41.937Z,1725573821.937 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-09-05T22:03:41.966Z,1725573821.966 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-09-05T22:03:41.988Z,1725573821.988 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2024-09-05T22:03:42.452Z,1725573822.452 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-09-05T22:03:42.464Z,1725573822.464 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-09-05T22:03:42.464Z,1725573822.464 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-09-05T22:03:42.497Z,1725573822.497 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-09-05T22:03:42.755Z,1725573822.755 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-09-05T22:03:42.759Z,1725573822.759 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2024-09-05T22:03:42.778Z,1725573822.778 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2024-09-05T22:03:42.781Z,1725573822.781 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2024-09-05T22:03:42.784Z,1725573822.784 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2024-09-05T22:03:42.787Z,1725573822.787 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2024-09-05T22:03:42.794Z,1725573822.794 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-09-05T22:03:42.796Z,1725573822.796 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2024-09-05T22:03:42.800Z,1725573822.800 [transit:MassHold.Pitch](DEBUG): Construct.
2024-09-05T22:03:42.808Z,1725573822.808 [transit:Transit:A.Pitch](DEBUG): Construct.
2024-09-05T22:03:42.862Z,1725573822.862 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2024-09-05T22:03:42.865Z,1725573822.865 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2024-09-05T22:03:42.920Z,1725573822.920 [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-05T22:03:42.920Z,1725573822.920 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2024-09-05T22:03:49.907Z,1725573829.907 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute
2024-09-05T22:03:49.908Z,1725573829.908 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2024-09-05T22:03:49.909Z,1725573829.909 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2024-09-05T22:03:49.949Z,1725573829.949 [CommandExec](IMPORTANT): got command run
2024-09-05T22:03:49.951Z,1725573829.951 [CommandExec](IMPORTANT): Running
2024-09-05T22:04:01.392Z,1725573841.392 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240905T214116/Courier0019.lzma
2024-09-05T22:04:02.395Z,1725573842.395 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0019.lzma.bak
2024-09-05T22:04:02.395Z,1725573842.395 [DataOverHttps](INFO): SBD MOMSN=23132414
2024-09-05T22:04:05.394Z,1725573845.394 [CommandExec](IMPORTANT): got command stop
2024-09-05T22:04:05.394Z,1725573845.394 [CommandExec](IMPORTANT): Scheduling is paused
2024-09-05T22:04:05.394Z,1725573845.394 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2024-09-05T22:04:08.203Z,1725573848.203 [CommandExec](IMPORTANT): got command schedule resume
2024-09-05T22:04:08.203Z,1725573848.203 [CommandExec](IMPORTANT): Scheduling is resumed
2024-09-05T22:04:18.982Z,1725573858.982 [DataOverHttps](INFO): Sending 338 bytes from file Logs/20240905T214116/Express0020.lzma
2024-09-05T22:04:19.983Z,1725573859.983 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0020.lzma.bak
2024-09-05T22:04:19.983Z,1725573859.983 [DataOverHttps](INFO): SBD MOMSN=23132419
2024-09-05T22:04:21.382Z,1725573861.382 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-09-05T22:04:21.751Z,1725573861.751 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-09-05T22:04:21.752Z,1725573861.752 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-09-05T22:04:21.752Z,1725573861.752 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-09-05T22:04:22.189Z,1725573862.189 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-05T22:04:22.209Z,1725573862.209 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-05T22:04:22.210Z,1725573862.210 [IBIT](IMPORTANT): Pressure:10.321842 PSI
2024-09-05T22:04:22.210Z,1725573862.210 [IBIT](IMPORTANT): Humidity:15.645221 %
2024-09-05T22:04:22.595Z,1725573862.595 [IBIT](IMPORTANT): Vehicle Pitch:-0.987074 degrees
2024-09-05T22:04:22.596Z,1725573862.596 [IBIT](IMPORTANT): Vehicle Roll:-0.757273 degrees
2024-09-05T22:04:22.596Z,1725573862.596 [IBIT](IMPORTANT): Vehicle Heading:16.336405 degrees
2024-09-05T22:04:22.992Z,1725573862.992 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-05T22:04:22.992Z,1725573862.992 [IBIT](IMPORTANT): buoyancyNeutral: 125.000008 cc
2024-09-05T22:04:22.993Z,1725573862.993 [IBIT](IMPORTANT): massDefault: 1.500000 cm
2024-09-05T22:04:23.017Z,1725573863.017 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-09-05T22:04:23.017Z,1725573863.017 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-09-05T22:04:23.018Z,1725573863.018 [IBIT](IMPORTANT): IBIT FAILED
2024-09-05T22:04:23.404Z,1725573863.404 [Default] Stopped
2024-09-05T22:04:23.404Z,1725573863.404 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-05T22:04:23.404Z,1725573863.404 [Default:B.GoToSurface] Stopped
2024-09-05T22:04:23.404Z,1725573863.404 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-05T22:04:23.404Z,1725573863.404 [Default:CheckIn] Stopped
2024-09-05T22:04:23.404Z,1725573863.404 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T22:04:23.404Z,1725573863.404 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T22:04:23.405Z,1725573863.405 [MissionManager](IMPORTANT): Started mission transit
2024-09-05T22:04:23.409Z,1725573863.409 [transit] Running Loop=1
2024-09-05T22:04:23.409Z,1725573863.409 [transit](DEBUG): Aggregate::initialize transit
2024-09-05T22:04:23.409Z,1725573863.409 [transit:StandardEnvelopes] Running Loop=1
2024-09-05T22:04:23.409Z,1725573863.409 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2024-09-05T22:04:23.409Z,1725573863.409 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-05T22:04:23.410Z,1725573863.410 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-09-05T22:04:23.410Z,1725573863.410 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-05T22:04:23.410Z,1725573863.410 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-09-05T22:04:23.410Z,1725573863.410 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-05T22:04:23.410Z,1725573863.410 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-09-05T22:04:23.410Z,1725573863.410 [transit:BackseatDriver] Running Loop=1
2024-09-05T22:04:23.410Z,1725573863.410 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2024-09-05T22:04:23.411Z,1725573863.411 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly:A] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly:B] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly:C] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly:D] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly:E.Wait] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-09-05T22:04:23.411Z,1725573863.411 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-09-05T22:04:23.411Z,1725573863.411 [transit:MassHold.Pitch] Running Loop=1
2024-09-05T22:04:23.411Z,1725573863.411 [transit:MassHold.Pitch](DEBUG): Initialize.
2024-09-05T22:04:23.412Z,1725573863.412 [transit:B] Running Loop=1
2024-09-05T22:04:23.412Z,1725573863.412 [transit:MassHold.Pitch] Running Loop=1
2024-09-05T22:04:23.425Z,1725573863.425 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-09-05T22:04:23.425Z,1725573863.425 [transit:PowerOnly] Running Loop=1
2024-09-05T22:04:23.426Z,1725573863.426 [transit:PowerOnly:D] Stopped
2024-09-05T22:04:23.426Z,1725573863.426 [transit:PowerOnly:C] Stopped
2024-09-05T22:04:23.426Z,1725573863.426 [transit:PowerOnly:B] Stopped
2024-09-05T22:04:23.426Z,1725573863.426 [transit:PowerOnly:A] Stopped
2024-09-05T22:04:23.426Z,1725573863.426 [transit:BackseatDriver] Running Loop=1
2024-09-05T22:04:23.426Z,1725573863.426 [transit:StandardEnvelopes] Running Loop=1
2024-09-05T22:04:23.426Z,1725573863.426 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-09-05T22:04:23.427Z,1725573863.427 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2024-09-05T22:04:23.504Z,1725573863.504 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-09-05T22:04:23.504Z,1725573863.504 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-09-05T22:04:23.521Z,1725573863.521 [transit:B] Stopped
2024-09-05T22:04:23.521Z,1725573863.521 [transit:C] Running Loop=1
2024-09-05T22:04:23.522Z,1725573863.522 [MissionManager](INFO): MissionManager is completed.
2024-09-05T22:04:23.522Z,1725573863.522 [MissionManager](INFO): Uninitializing Mission transit
2024-09-05T22:04:23.522Z,1725573863.522 [transit] Stopped
2024-09-05T22:04:23.522Z,1725573863.522 [transit](DEBUG): Aggregate::uninitialize transit
2024-09-05T22:04:23.522Z,1725573863.522 [transit:C] Stopped
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes] Stopped
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2024-09-05T22:04:23.522Z,1725573863.522 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2024-09-05T22:04:23.523Z,1725573863.523 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2024-09-05T22:04:23.523Z,1725573863.523 [transit:BackseatDriver] Stopped
2024-09-05T22:04:23.523Z,1725573863.523 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2024-09-05T22:04:23.523Z,1725573863.523 [transit:BackseatDriver:A.BackseatDriver] Stopped
2024-09-05T22:04:23.523Z,1725573863.523 [transit:PowerOnly] Stopped
2024-09-05T22:04:23.523Z,1725573863.523 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2024-09-05T22:04:23.523Z,1725573863.523 [transit:PowerOnly:E.Wait] Stopped
2024-09-05T22:04:23.523Z,1725573863.523 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:04:23.523Z,1725573863.523 [transit:BuoyancyHold.Buoyancy] Stopped
2024-09-05T22:04:23.523Z,1725573863.523 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-09-05T22:04:23.523Z,1725573863.523 [transit:MassHold.Pitch] Stopped
2024-09-05T22:04:23.794Z,1725573863.794 [MissionManager](IMPORTANT): Started mission Default
2024-09-05T22:04:23.794Z,1725573863.794 [Default] Running Loop=1
2024-09-05T22:04:23.794Z,1725573863.794 [Default](DEBUG): Aggregate::initialize Default
2024-09-05T22:04:23.794Z,1725573863.794 [Default:B.GoToSurface] Running Loop=1
2024-09-05T22:04:23.794Z,1725573863.794 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-05T22:04:23.794Z,1725573863.794 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-05T22:04:23.795Z,1725573863.795 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-05T22:04:23.795Z,1725573863.795 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-05T22:04:23.795Z,1725573863.795 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-05T22:04:23.795Z,1725573863.795 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-05T22:04:23.796Z,1725573863.796 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-05T22:04:23.796Z,1725573863.796 [Default:A.Wait] Running Loop=1
2024-09-05T22:04:23.796Z,1725573863.796 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-05T22:04:37.153Z,1725573877.153 [Default:A.Wait](INFO): Done Waiting.
2024-09-05T22:04:37.157Z,1725573877.157 [Default:A.Wait] Stopped
2024-09-05T22:04:37.157Z,1725573877.157 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:04:37.574Z,1725573877.574 [Default:CheckIn] Running Loop=1
2024-09-05T22:04:37.574Z,1725573877.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T22:04:37.574Z,1725573877.574 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T22:07:27.194Z,1725574047.194 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-05T22:07:31.641Z,1725574051.641 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220731.00,A,3648.15075,N,12147.22376,W,0.039,23.86,050924,,,D*4F
2024-09-05T22:07:31.643Z,1725574051.643 [NAL9602](INFO): GPS fix at 20240905T220731: (36.802512, -121.787063)
2024-09-05T22:07:31.653Z,1725574051.653 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T22:07:31.653Z,1725574051.653 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T22:07:39.424Z,1725574059.424 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0022.lzma
2024-09-05T22:07:40.427Z,1725574060.427 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0022.lzma.bak
2024-09-05T22:07:40.427Z,1725574060.427 [DataOverHttps](INFO): SBD MOMSN=23132453
2024-09-05T22:07:57.026Z,1725574077.026 [DataOverHttps](INFO): Sending 793 bytes from file Logs/20240905T214116/Express0023.lzma
2024-09-05T22:07:58.027Z,1725574078.027 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0023.lzma.bak
2024-09-05T22:07:58.027Z,1725574078.027 [DataOverHttps](INFO): SBD MOMSN=23132459
2024-09-05T22:07:59.540Z,1725574079.540 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T22:07:59.540Z,1725574079.540 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-05T22:07:59.540Z,1725574079.540 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-05T22:08:03.958Z,1725574083.958 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T22:09:08.162Z,1725574148.162 [DataOverHttps](IMPORTANT): SBD MTMSN=20240905T220907
2024-09-05T22:09:15.521Z,1725574155.521 [DataOverHttps](INFO): Received command: ibit
2024-09-05T22:09:15.558Z,1725574155.558 [CommandExec](IMPORTANT): got command ibit
2024-09-05T22:09:15.896Z,1725574155.896 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-09-05T22:09:15.896Z,1725574155.896 [IBIT](IMPORTANT): Beginning control surface checks.
2024-09-05T22:09:15.900Z,1725574155.900 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-05T22:09:17.492Z,1725574157.492 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220917.00,A,3648.15051,N,12147.22287,W,0.039,23.86,050924,,,D*4C
2024-09-05T22:09:17.494Z,1725574157.494 [NAL9602](INFO): GPS fix at 20240905T220917: (36.802509, -121.787048)
2024-09-05T22:09:26.814Z,1725574166.814 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002619
CHAN A1 (24V): 0.000872
CHAN A2 (12V): -0.002351
CHAN A3 (5V): -0.001356
CHAN B0 (3.3V): 0.000149
CHAN B1 (3.15aV): -0.000205
CHAN B2 (3.15bV): -0.000604
CHAN B3 (GND): 0.000364
OPEN: -0.000576
Full Scale: +/- 1 mA
2024-09-05T22:09:48.189Z,1725574188.189 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-05T22:09:48.269Z,1725574188.269 [NAL9602](ERROR): received:
+CSQ:0
OK408, 2, 0, 0, 0
OK
2024-09-05T22:10:30.655Z,1725574230.655 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802509 Longitude: -121.787048
2024-09-05T22:10:31.057Z,1725574231.057 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-09-05T22:10:31.058Z,1725574231.058 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-09-05T22:10:31.058Z,1725574231.058 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-09-05T22:10:31.433Z,1725574231.433 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-09-05T22:10:31.433Z,1725574231.433 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-09-05T22:10:31.434Z,1725574231.434 [IBIT](IMPORTANT): Pressure:10.325343 PSI
2024-09-05T22:10:31.434Z,1725574231.434 [IBIT](IMPORTANT): Humidity:15.443780 %
2024-09-05T22:10:31.842Z,1725574231.842 [IBIT](IMPORTANT): Vehicle Pitch:1.470576 degrees
2024-09-05T22:10:31.843Z,1725574231.843 [IBIT](IMPORTANT): Vehicle Roll:13.447589 degrees
2024-09-05T22:10:31.843Z,1725574231.843 [IBIT](IMPORTANT): Vehicle Heading:217.227325 degrees
2024-09-05T22:10:32.252Z,1725574232.252 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-09-05T22:10:32.253Z,1725574232.253 [IBIT](IMPORTANT): buoyancyNeutral: 125.000008 cc
2024-09-05T22:10:32.253Z,1725574232.253 [IBIT](IMPORTANT): massDefault: 1.500000 cm
2024-09-05T22:10:32.253Z,1725574232.253 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-09-05T22:10:32.254Z,1725574232.254 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-09-05T22:10:32.254Z,1725574232.254 [IBIT](IMPORTANT): IBIT PASSED
2024-09-05T22:12:08.783Z,1725574328.783 [NAL9602](INFO): SBD MO Status=0, MOMSN=56408, MT Status=0, MTMSN=0
2024-09-05T22:12:08.784Z,1725574328.784 [NAL9602](INFO): No messages in MT queue
2024-09-05T22:12:39.514Z,1725574359.514 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T22:13:00.186Z,1725574380.186 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-05T22:13:00.186Z,1725574380.186 [Default:CheckIn:C.Wait] Stopped
2024-09-05T22:13:00.186Z,1725574380.186 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:13:00.187Z,1725574380.187 [Default:CheckIn:D] Running Loop=1
2024-09-05T22:13:00.579Z,1725574380.579 [Default:CheckIn:D] Stopped
2024-09-05T22:13:00.580Z,1725574380.580 [Default:CheckIn:E] Running Loop=1
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.613093 min
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn:E] Stopped
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn] Stopped
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn](INFO): Running loop #2
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn] Running Loop=2
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T22:13:00.984Z,1725574380.984 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T22:13:02.987Z,1725574382.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221302.00,A,3648.15095,N,12147.22281,W,0.019,23.86,050924,,,D*4F
2024-09-05T22:13:02.990Z,1725574382.990 [NAL9602](INFO): GPS fix at 20240905T221302: (36.802516, -121.787047)
2024-09-05T22:13:03.023Z,1725574383.023 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T22:13:03.023Z,1725574383.023 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T22:13:10.016Z,1725574390.016 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0025.lzma
2024-09-05T22:13:11.019Z,1725574391.019 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0025.lzma.bak
2024-09-05T22:13:11.019Z,1725574391.019 [DataOverHttps](INFO): SBD MOMSN=23132507
2024-09-05T22:13:27.170Z,1725574407.170 [DataOverHttps](INFO): Sending 777 bytes from file Logs/20240905T214116/Express0026.lzma
2024-09-05T22:13:28.171Z,1725574408.171 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0026.lzma.bak
2024-09-05T22:13:28.171Z,1725574408.171 [DataOverHttps](INFO): SBD MOMSN=23132510
2024-09-05T22:13:30.063Z,1725574410.063 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T22:13:30.063Z,1725574410.063 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-05T22:13:30.063Z,1725574410.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-05T22:13:30.459Z,1725574410.459 [NAL9602](INFO): SBD MO Status=2, MOMSN=56409, MT Status=2, MTMSN=0
2024-09-05T22:13:30.460Z,1725574410.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T22:13:47.023Z,1725574427.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=56409, MT Status=2, MTMSN=0
2024-09-05T22:13:47.023Z,1725574427.023 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T22:14:05.230Z,1725574445.230 [NAL9602](INFO): SBD MO Status=0, MOMSN=56409, MT Status=0, MTMSN=0
2024-09-05T22:14:05.230Z,1725574445.230 [NAL9602](INFO): No messages in MT queue
2024-09-05T22:14:35.904Z,1725574475.904 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T22:18:30.656Z,1725574710.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-05T22:18:30.656Z,1725574710.656 [Default:CheckIn:C.Wait] Stopped
2024-09-05T22:18:30.656Z,1725574710.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:18:30.657Z,1725574710.657 [Default:CheckIn:D] Running Loop=1
2024-09-05T22:18:31.055Z,1725574711.055 [Default:CheckIn:D] Stopped
2024-09-05T22:18:31.056Z,1725574711.056 [Default:CheckIn:E] Running Loop=1
2024-09-05T22:18:31.452Z,1725574711.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.121027 min
2024-09-05T22:18:31.452Z,1725574711.452 [Default:CheckIn:E] Stopped
2024-09-05T22:18:31.452Z,1725574711.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-05T22:18:31.452Z,1725574711.452 [Default:CheckIn] Stopped
2024-09-05T22:18:31.452Z,1725574711.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T22:18:31.453Z,1725574711.453 [Default:CheckIn](INFO): Running loop #3
2024-09-05T22:18:31.453Z,1725574711.453 [Default:CheckIn] Running Loop=3
2024-09-05T22:18:31.453Z,1725574711.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T22:18:31.453Z,1725574711.453 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T22:18:33.464Z,1725574713.464 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221833.00,A,3648.52631,N,12147.16638,W,6.978,279.25,050924,,,D*70
2024-09-05T22:18:33.466Z,1725574713.466 [NAL9602](INFO): GPS fix at 20240905T221833: (36.808772, -121.786106)
2024-09-05T22:18:33.476Z,1725574713.476 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T22:18:33.476Z,1725574713.476 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T22:18:40.224Z,1725574720.224 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0028.lzma
2024-09-05T22:18:41.227Z,1725574721.227 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0028.lzma.bak
2024-09-05T22:18:41.227Z,1725574721.227 [DataOverHttps](INFO): SBD MOMSN=23132549
2024-09-05T22:18:53.676Z,1725574733.676 [NAL9602](INFO): SBD MO Status=2, MOMSN=56410, MT Status=2, MTMSN=0
2024-09-05T22:18:53.676Z,1725574733.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T22:19:00.411Z,1725574740.411 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240905T214116/Express0030.lzma
2024-09-05T22:19:01.319Z,1725574741.319 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0030.lzma.bak
2024-09-05T22:19:01.319Z,1725574741.319 [DataOverHttps](INFO): SBD MOMSN=23132559
2024-09-05T22:19:03.005Z,1725574743.005 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T22:19:03.005Z,1725574743.005 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-05T22:19:03.005Z,1725574743.005 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-05T22:19:12.283Z,1725574752.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=56410, MT Status=2, MTMSN=0
2024-09-05T22:19:12.284Z,1725574752.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T22:20:16.536Z,1725574816.536 [NAL9602](INFO): SBD MO Status=2, MOMSN=56410, MT Status=2, MTMSN=0
2024-09-05T22:20:16.536Z,1725574816.536 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-05T22:20:55.305Z,1725574855.305 [NAL9602](INFO): SBD MO Status=0, MOMSN=56410, MT Status=0, MTMSN=0
2024-09-05T22:20:55.305Z,1725574855.305 [NAL9602](INFO): No messages in MT queue
2024-09-05T22:21:26.002Z,1725574886.002 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T22:24:03.592Z,1725575043.592 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-05T22:24:03.592Z,1725575043.592 [Default:CheckIn:C.Wait] Stopped
2024-09-05T22:24:03.592Z,1725575043.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:24:03.592Z,1725575043.592 [Default:CheckIn:D] Running Loop=1
2024-09-05T22:24:03.995Z,1725575043.995 [Default:CheckIn:D] Stopped
2024-09-05T22:24:03.995Z,1725575043.995 [Default:CheckIn:E] Running Loop=1
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.670020 min
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn:E] Stopped
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn] Stopped
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn](INFO): Running loop #4
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn] Running Loop=4
2024-09-05T22:24:04.395Z,1725575044.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T22:24:04.396Z,1725575044.396 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T22:24:06.403Z,1725575046.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222406.00,A,3648.31955,N,12148.35127,W,15.726,266.07,050924,,,D*4F
2024-09-05T22:24:06.405Z,1725575046.405 [NAL9602](INFO): GPS fix at 20240905T222406: (36.805326, -121.805854)
2024-09-05T22:24:06.415Z,1725575046.415 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T22:24:06.415Z,1725575046.415 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T22:24:14.056Z,1725575054.056 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0034.lzma
2024-09-05T22:24:15.059Z,1725575055.059 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0034.lzma.bak
2024-09-05T22:24:15.059Z,1725575055.059 [DataOverHttps](INFO): SBD MOMSN=23132569
2024-09-05T22:24:16.907Z,1725575056.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=56411, MT Status=0, MTMSN=0
2024-09-05T22:24:16.908Z,1725575056.908 [NAL9602](INFO): No messages in MT queue
2024-09-05T22:24:34.437Z,1725575074.437 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240905T214116/Express0035.lzma
2024-09-05T22:24:35.439Z,1725575075.439 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Express0035.lzma.bak
2024-09-05T22:24:35.439Z,1725575075.439 [DataOverHttps](INFO): SBD MOMSN=23132572
2024-09-05T22:24:37.168Z,1725575077.168 [Default:CheckIn:Read_Iridium] Stopped
2024-09-05T22:24:37.168Z,1725575077.168 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-05T22:24:37.169Z,1725575077.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-05T22:24:47.658Z,1725575087.658 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-05T22:29:37.760Z,1725575377.760 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-05T22:29:37.760Z,1725575377.760 [Default:CheckIn:C.Wait] Stopped
2024-09-05T22:29:37.760Z,1725575377.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-05T22:29:37.761Z,1725575377.761 [Default:CheckIn:D] Running Loop=1
2024-09-05T22:29:38.158Z,1725575378.158 [Default:CheckIn:D] Stopped
2024-09-05T22:29:38.158Z,1725575378.158 [Default:CheckIn:E] Running Loop=1
2024-09-05T22:29:38.554Z,1725575378.554 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.239398 min
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn:E] Stopped
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn] Stopped
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn](INFO): Running loop #5
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn] Running Loop=5
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-05T22:29:38.555Z,1725575378.555 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-05T22:29:40.572Z,1725575380.572 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222940.00,A,3648.17403,N,12148.93835,W,0.564,137.12,050924,,,D*7F
2024-09-05T22:29:40.575Z,1725575380.575 [NAL9602](INFO): GPS fix at 20240905T222940: (36.802900, -121.815639)
2024-09-05T22:29:40.585Z,1725575380.585 [Default:CheckIn:Read_GPS] Stopped
2024-09-05T22:29:40.585Z,1725575380.585 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-05T22:29:47.433Z,1725575387.433 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240905T214116/Courier0037.lzma
2024-09-05T22:29:48.436Z,1725575388.436 [DataOverHttps](INFO): Moved sent file to Logs/20240905T214116/Courier0037.lzma.bak
2024-09-05T22:29:48.436Z,1725575388.436 [DataOverHttps](IMPORTANT): SBD MOMSN=23132596, MTMSN=20240905T222948
2024-09-05T22:29:55.817Z,1725575395.817 [DataOverHttps](INFO): Received command: restart logs