2025-12-03T17:40:17.968Z,1764783617.968 [Supervisor](DEBUG): Initializing supervisor.
2025-12-03T17:40:17.973Z,1764783617.973 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-12-03T17:40:17.973Z,1764783617.973 [SyncHandler](INFO): Protected caller Thread ID is 829
2025-12-03T17:40:17.974Z,1764783617.974 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-12-03T17:40:17.975Z,1764783617.975 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-12-03T17:40:17.975Z,1764783617.975 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 830
2025-12-03T17:40:17.979Z,1764783617.979 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-12-03T17:40:17.998Z,1764783617.998 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-12-03T17:40:17.999Z,1764783617.999 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-12-03T17:40:17.999Z,1764783617.999 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 831
2025-12-03T17:40:18.003Z,1764783618.003 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-12-03T17:40:18.004Z,1764783618.004 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-12-03T17:40:18.005Z,1764783618.005 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 832
2025-12-03T17:40:18.007Z,1764783618.007 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-12-03T17:40:18.008Z,1764783618.008 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-12-03T17:40:18.008Z,1764783618.008 [logger ThreadHandler](INFO): Protected caller Thread ID is 833
2025-12-03T17:40:18.012Z,1764783618.012 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-12-03T17:40:18.012Z,1764783618.012 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-12-03T17:40:18.018Z,1764783618.018 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-12-03T17:40:18.121Z,1764783618.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-12-03T17:40:18.123Z,1764783618.123 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-12-03T17:40:18.361Z,1764783618.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-12-03T17:40:18.363Z,1764783618.363 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-12-03T17:40:18.624Z,1764783618.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-12-03T17:40:18.625Z,1764783618.625 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-12-03T17:40:18.739Z,1764783618.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-12-03T17:40:18.741Z,1764783618.741 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-12-03T17:40:19.475Z,1764783619.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-12-03T17:40:19.477Z,1764783619.477 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-12-03T17:40:19.983Z,1764783619.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-12-03T17:40:19.984Z,1764783619.984 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-12-03T17:40:20.507Z,1764783620.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-12-03T17:40:20.508Z,1764783620.508 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-12-03T17:40:20.917Z,1764783620.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-12-03T17:40:20.918Z,1764783620.918 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-12-03T17:40:21.259Z,1764783621.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-12-03T17:40:21.750Z,1764783621.750 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-12-03T17:40:22.247Z,1764783622.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-12-03T17:40:22.249Z,1764783622.249 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-12-03T17:40:22.476Z,1764783622.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-12-03T17:40:22.478Z,1764783622.478 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-12-03T17:40:22.629Z,1764783622.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-12-03T17:40:22.629Z,1764783622.629 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-12-03T17:40:22.728Z,1764783622.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-12-03T17:40:22.729Z,1764783622.729 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-12-03T17:40:22.817Z,1764783622.817 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-12-03T17:40:23.001Z,1764783623.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-12-03T17:40:23.003Z,1764783623.003 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-12-03T17:40:23.248Z,1764783623.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-12-03T17:40:23.250Z,1764783623.250 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-12-03T17:40:23.254Z,1764783623.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-12-03T17:40:23.346Z,1764783623.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-12-03T17:40:23.615Z,1764783623.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-12-03T17:40:23.615Z,1764783623.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-12-03T17:40:23.708Z,1764783623.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-12-03T17:40:23.883Z,1764783623.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-12-03T17:40:23.992Z,1764783623.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-12-03T17:40:24.108Z,1764783624.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-12-03T17:40:24.224Z,1764783624.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-12-03T17:40:24.326Z,1764783624.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-12-03T17:40:24.488Z,1764783624.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-12-03T17:40:24.592Z,1764783624.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-12-03T17:40:24.685Z,1764783624.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-12-03T17:40:24.790Z,1764783624.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-12-03T17:40:24.885Z,1764783624.885 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-12-03T17:40:24.895Z,1764783624.895 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-12-03T17:40:25.112Z,1764783625.112 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-12-03T17:40:25.113Z,1764783625.113 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-12-03T17:40:25.305Z,1764783625.305 [BuoyancyServo] Loaded
2025-12-03T17:40:25.305Z,1764783625.305 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-12-03T17:40:25.328Z,1764783625.328 [ElevatorServo] Loaded
2025-12-03T17:40:25.328Z,1764783625.328 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-12-03T17:40:25.350Z,1764783625.350 [MassServo] Loaded
2025-12-03T17:40:25.350Z,1764783625.350 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-12-03T17:40:25.372Z,1764783625.372 [RudderServo] Loaded
2025-12-03T17:40:25.372Z,1764783625.372 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-12-03T17:40:25.390Z,1764783625.390 [ThrusterHE] Loaded
2025-12-03T17:40:25.390Z,1764783625.390 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-12-03T17:40:25.391Z,1764783625.391 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-12-03T17:40:25.391Z,1764783625.391 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-12-03T17:40:25.584Z,1764783625.584 [SBIT](DEBUG): Construct Startup Built In Test.
2025-12-03T17:40:25.599Z,1764783625.599 [SBIT] Loaded
2025-12-03T17:40:25.599Z,1764783625.599 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-12-03T17:40:25.602Z,1764783625.602 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-12-03T17:40:25.622Z,1764783625.622 [IBIT] Loaded
2025-12-03T17:40:25.622Z,1764783625.622 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-12-03T17:40:25.627Z,1764783625.627 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-12-03T17:40:25.817Z,1764783625.817 [CBIT] Loaded
2025-12-03T17:40:25.817Z,1764783625.817 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-12-03T17:40:25.847Z,1764783625.847 [GFScanner] Loaded
2025-12-03T17:40:25.847Z,1764783625.847 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-12-03T17:40:25.848Z,1764783625.848 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-12-03T17:40:25.848Z,1764783625.848 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-12-03T17:40:26.065Z,1764783626.065 [CTD_Seabird] Loaded
2025-12-03T17:40:26.066Z,1764783626.066 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-12-03T17:40:26.067Z,1764783626.067 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0
2025-12-03T17:40:26.067Z,1764783626.067 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 921
2025-12-03T17:40:26.101Z,1764783626.101 [ESPComponent] Loaded
2025-12-03T17:40:26.102Z,1764783626.102 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-12-03T17:40:26.123Z,1764783626.123 [PAR_Licor] Loaded
2025-12-03T17:40:26.123Z,1764783626.123 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-12-03T17:40:26.166Z,1764783626.166 [WetLabsBB2FL] Loaded
2025-12-03T17:40:26.166Z,1764783626.166 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-12-03T17:40:26.167Z,1764783626.167 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0
2025-12-03T17:40:26.168Z,1764783626.168 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 922
2025-12-03T17:40:26.169Z,1764783626.169 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-12-03T17:40:26.169Z,1764783626.169 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-12-03T17:40:26.240Z,1764783626.240 [DepthRateCalculator] Loaded
2025-12-03T17:40:26.240Z,1764783626.240 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-12-03T17:40:26.245Z,1764783626.245 [PitchRateCalculator] Loaded
2025-12-03T17:40:26.245Z,1764783626.245 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-12-03T17:40:26.255Z,1764783626.255 [SpeedCalculator] Loaded
2025-12-03T17:40:26.256Z,1764783626.256 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-12-03T17:40:26.260Z,1764783626.260 [YawRateCalculator] Loaded
2025-12-03T17:40:26.261Z,1764783626.261 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-12-03T17:40:26.280Z,1764783626.280 [ElevatorOffsetCalculator] Loaded
2025-12-03T17:40:26.280Z,1764783626.280 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-12-03T17:40:26.281Z,1764783626.281 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-12-03T17:40:26.281Z,1764783626.281 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-12-03T17:40:26.935Z,1764783626.935 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-12-03T17:40:26.935Z,1764783626.935 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-12-03T17:40:27.042Z,1764783627.042 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-12-03T17:40:27.042Z,1764783627.042 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-12-03T17:40:27.179Z,1764783627.179 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-12-03T17:40:27.180Z,1764783627.180 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-12-03T17:40:27.193Z,1764783627.193 [NavChart] Loaded
2025-12-03T17:40:27.194Z,1764783627.194 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-12-03T17:40:27.199Z,1764783627.199 [UniversalFixResidualReporter] Loaded
2025-12-03T17:40:27.199Z,1764783627.199 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-12-03T17:40:27.200Z,1764783627.200 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-12-03T17:40:27.200Z,1764783627.200 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-12-03T17:40:28.076Z,1764783628.076 [AHRS_M2] Loaded
2025-12-03T17:40:28.076Z,1764783628.076 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-12-03T17:40:28.328Z,1764783628.328 [BackseatComponent] Loaded
2025-12-03T17:40:28.329Z,1764783628.329 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-12-03T17:40:28.330Z,1764783628.330 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2025-12-03T17:40:28.330Z,1764783628.330 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2025-12-03T17:40:28.333Z,1764783628.333 [LcmPublisher] Loaded
2025-12-03T17:40:28.333Z,1764783628.333 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-12-03T17:40:29.063Z,1764783629.063 [BPC1] Loaded
2025-12-03T17:40:29.063Z,1764783629.063 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-12-03T17:40:29.162Z,1764783629.162 [DAT] Loaded
2025-12-03T17:40:29.162Z,1764783629.162 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-12-03T17:40:29.163Z,1764783629.163 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0
2025-12-03T17:40:29.164Z,1764783629.164 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924
2025-12-03T17:40:29.305Z,1764783629.305 [DataOverHttps] Loaded
2025-12-03T17:40:29.305Z,1764783629.305 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-12-03T17:40:29.306Z,1764783629.306 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0
2025-12-03T17:40:29.306Z,1764783629.306 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2025-12-03T17:40:29.329Z,1764783629.329 [Depth_Keller] Loaded
2025-12-03T17:40:29.330Z,1764783629.330 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-12-03T17:40:29.335Z,1764783629.335 [DropWeight] Loaded
2025-12-03T17:40:29.335Z,1764783629.335 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-12-03T17:40:29.375Z,1764783629.375 [DVL_micro] Loaded
2025-12-03T17:40:29.376Z,1764783629.376 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-12-03T17:40:29.437Z,1764783629.437 [NAL9602] Loaded
2025-12-03T17:40:29.437Z,1764783629.437 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-12-03T17:40:29.463Z,1764783629.463 [Onboard] Loaded
2025-12-03T17:40:29.463Z,1764783629.463 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-12-03T17:40:29.464Z,1764783629.464 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0
2025-12-03T17:40:29.465Z,1764783629.465 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2025-12-03T17:40:29.471Z,1764783629.471 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-12-03T17:40:29.484Z,1764783629.484 [PowerOnly] Loaded
2025-12-03T17:40:29.484Z,1764783629.484 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-12-03T17:40:29.498Z,1764783629.498 [Power24vConverter] Loaded
2025-12-03T17:40:29.498Z,1764783629.498 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-12-03T17:40:29.514Z,1764783629.514 [Radio_Surface] Loaded
2025-12-03T17:40:29.514Z,1764783629.514 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-12-03T17:40:29.515Z,1764783629.515 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0
2025-12-03T17:40:29.516Z,1764783629.516 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2025-12-03T17:40:29.531Z,1764783629.531 [Sonardyne_Nano] Loaded
2025-12-03T17:40:29.531Z,1764783629.531 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-12-03T17:40:29.532Z,1764783629.532 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-12-03T17:40:29.533Z,1764783629.533 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-12-03T17:40:29.619Z,1764783629.619 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-12-03T17:40:29.620Z,1764783629.620 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-12-03T17:40:29.693Z,1764783629.693 [VerticalControl](DEBUG): Construct VerticalControl.
2025-12-03T17:40:29.744Z,1764783629.744 [VerticalControl] Loaded
2025-12-03T17:40:29.744Z,1764783629.744 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-12-03T17:40:29.747Z,1764783629.747 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-12-03T17:40:29.790Z,1764783629.790 [HorizontalControl] Loaded
2025-12-03T17:40:29.790Z,1764783629.790 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-12-03T17:40:29.793Z,1764783629.793 [SpeedControl](DEBUG): Construct SpeedControl.
2025-12-03T17:40:29.794Z,1764783629.794 [SpeedControl] Loaded
2025-12-03T17:40:29.795Z,1764783629.795 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-12-03T17:40:29.798Z,1764783629.798 [LoopControl](DEBUG): Construct LoopControl.
2025-12-03T17:40:29.798Z,1764783629.798 [LoopControl] Loaded
2025-12-03T17:40:29.798Z,1764783629.798 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-12-03T17:40:29.799Z,1764783629.799 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-12-03T17:40:29.799Z,1764783629.799 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-12-03T17:40:29.815Z,1764783629.815 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-12-03T17:40:29.816Z,1764783629.816 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-12-03T17:40:30.213Z,1764783630.213 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-12-03T17:40:30.219Z,1764783630.219 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-12-03T17:40:30.222Z,1764783630.222 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-12-03T17:40:30.233Z,1764783630.233 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-12-03T17:40:30.234Z,1764783630.234 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-12-03T17:40:30.234Z,1764783630.234 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2025-12-03T17:40:30.238Z,1764783630.238 [Supervisor](INFO): Main Thread ID is 822
2025-12-03T17:40:30.239Z,1764783630.239 [Supervisor](DEBUG): Running supervisor.
2025-12-03T17:40:30.239Z,1764783630.239 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2025-12-03T17:40:30.240Z,1764783630.240 [CommandExec](INFO): Initializing the command executive.
2025-12-03T17:40:30.241Z,1764783630.241 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2025-12-03T17:40:30.243Z,1764783630.243 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2025-12-03T17:40:30.244Z,1764783630.244 [controlThread](DEBUG): Initializing ControlThread
2025-12-03T17:40:30.246Z,1764783630.246 [SBIT](INFO): Initialize SBIT Component.
2025-12-03T17:40:30.246Z,1764783630.246 [SBIT](IMPORTANT): git: 2025-12-03
2025-12-03T17:40:30.246Z,1764783630.246 [SBIT](INFO): git hash: 4fa2864846f8e2edf0b5649ab047c65ea92327f5
2025-12-03T17:40:30.247Z,1764783630.247 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-12-03T17:40:30.248Z,1764783630.248 [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
2025-12-03T17:40:30.249Z,1764783630.249 [SBIT](INFO): Beginning SBIT in 151.000000 seconds.
2025-12-03T17:40:30.250Z,1764783630.250 [IBIT](INFO): Initialize IBIT Component.
2025-12-03T17:40:30.250Z,1764783630.250 [CBIT](DEBUG): Initialize CBIT Component.
2025-12-03T17:40:30.251Z,1764783630.251 [logger ThreadHandler](INFO): Handler Thread ID is 932
2025-12-03T17:40:30.264Z,1764783630.264 [CBIT](DEBUG): Initialized mux pins.
2025-12-03T17:40:30.264Z,1764783630.264 [CBIT](DEBUG): Initializing the watchdog timer.
2025-12-03T17:40:30.272Z,1764783630.272 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 933
2025-12-03T17:40:30.273Z,1764783630.273 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-12-03T17:40:30.277Z,1764783630.277 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 935
2025-12-03T17:40:30.280Z,1764783630.280 [WetLabsBB2FL](INFO): Powering up
2025-12-03T17:40:30.281Z,1764783630.281 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937
2025-12-03T17:40:30.288Z,1764783630.288 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-12-03T17:40:30.288Z,1764783630.288 [CBIT](DEBUG): Initializing heartbeat.
2025-12-03T17:40:30.289Z,1764783630.289 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2025-12-03T17:40:30.290Z,1764783630.290 [DAT](INFO): Powering up
2025-12-03T17:40:30.290Z,1764783630.290 [DAT](DEBUG): Initializing DAT.
2025-12-03T17:40:30.293Z,1764783630.293 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940
2025-12-03T17:40:30.294Z,1764783630.294 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-12-03T17:40:30.316Z,1764783630.316 [Onboard ThreadHandler](INFO): Handler Thread ID is 941
2025-12-03T17:40:30.333Z,1764783630.333 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942
2025-12-03T17:40:30.354Z,1764783630.354 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2025-12-03T17:40:30.360Z,1764783630.360 [CBIT](DEBUG): Deactivating emergency mode.
2025-12-03T17:40:30.364Z,1764783630.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-12-03T17:40:30.365Z,1764783630.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-12-03T17:40:30.365Z,1764783630.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-12-03T17:40:30.365Z,1764783630.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-12-03T17:40:30.365Z,1764783630.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-12-03T17:40:30.365Z,1764783630.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-12-03T17:40:30.366Z,1764783630.366 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-12-03T17:40:30.366Z,1764783630.366 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-12-03T17:40:30.404Z,1764783630.404 [CBIT](DEBUG): Backplane powered.
2025-12-03T17:40:30.404Z,1764783630.404 [GFScanner](DEBUG): Initializing GFScanner
2025-12-03T17:40:30.404Z,1764783630.404 [GFScanner](DEBUG): Deactivating GF circuits.
2025-12-03T17:40:30.406Z,1764783630.406 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-12-03T17:40:30.407Z,1764783630.407 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-12-03T17:40:30.407Z,1764783630.407 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-12-03T17:40:30.407Z,1764783630.407 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-12-03T17:40:30.412Z,1764783630.412 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-12-03T17:40:30.420Z,1764783630.420 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-12-03T17:40:30.420Z,1764783630.420 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-12-03T17:40:30.424Z,1764783630.424 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-12-03T17:40:30.426Z,1764783630.426 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-12-03T17:40:30.426Z,1764783630.426 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-12-03T17:40:30.427Z,1764783630.427 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-12-03T17:40:30.427Z,1764783630.427 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-12-03T17:40:30.432Z,1764783630.432 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-12-03T17:40:30.432Z,1764783630.432 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-12-03T17:40:30.440Z,1764783630.440 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-12-03T17:40:30.522Z,1764783630.522 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-12-03T17:40:30.543Z,1764783630.543 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-03T17:40:30.583Z,1764783630.583 [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
}
}
}
2025-12-03T17:40:30.595Z,1764783630.595 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-12-03T17:40:30.595Z,1764783630.595 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-12-03T17:40:30.603Z,1764783630.603 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-12-03T17:40:30.740Z,1764783630.740 [Radio_Surface](INFO): Powering up
2025-12-03T17:40:30.934Z,1764783630.934 [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
2025-12-03T17:40:30.950Z,1764783630.950 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-12-03T17:40:30.952Z,1764783630.952 [Default:A.Wait](DEBUG): Construct Wait.
2025-12-03T17:40:30.969Z,1764783630.969 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-03T17:40:30.998Z,1764783630.998 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-12-03T17:40:31.001Z,1764783631.001 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-12-03T17:40:31.019Z,1764783631.019 [Default:E.Execute](DEBUG): Construct Execute.
2025-12-03T17:40:31.022Z,1764783631.022 [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"
}
}
2025-12-03T17:40:31.044Z,1764783631.044 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2025-12-03T17:40:31.073Z,1764783631.073 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-12-03T17:40:31.196Z,1764783631.196 [Depth_Keller](INFO): Initializing.
2025-12-03T17:40:31.205Z,1764783631.205 [Power24vConverter](INFO): Powering up.
2025-12-03T17:40:31.206Z,1764783631.206 [Sonardyne_Nano](INFO): Initializing.
2025-12-03T17:40:31.232Z,1764783631.232 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-03T17:40:31.272Z,1764783631.272 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-12-03T17:40:31.281Z,1764783631.281 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-12-03T17:40:31.282Z,1764783631.282 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-12-03T17:40:31.288Z,1764783631.288 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-12-03T17:40:31.289Z,1764783631.289 [MassServo](DEBUG): Initializing EZServoServo.
2025-12-03T17:40:31.297Z,1764783631.297 [MassServo](DEBUG): Initializing MassServo.
2025-12-03T17:40:31.298Z,1764783631.298 [RudderServo](DEBUG): Initializing EZServoServo.
2025-12-03T17:40:31.304Z,1764783631.304 [RudderServo](DEBUG): Initializing RudderServo.
2025-12-03T17:40:31.305Z,1764783631.305 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-12-03T17:40:31.312Z,1764783631.312 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-12-03T17:40:31.521Z,1764783631.521 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794553 m
2025-12-03T17:40:31.523Z,1764783631.523 [PowerOnly](INFO): Powering up loadControl
2025-12-03T17:40:31.545Z,1764783631.545 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-03T17:40:32.892Z,1764783632.892 [WetLabsBB2FL](INFO): Powering down
2025-12-03T17:40:33.483Z,1764783633.483 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-12-03T17:40:34.722Z,1764783634.722 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-12-03T17:40:36.937Z,1764783636.937 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-12-03T17:40:37.304Z,1764783637.304 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-12-03T17:40:42.649Z,1764783642.649 [DAT](INFO): DAT read:
2025-12-03T17:40:42.650Z,1764783642.650 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-12-03T17:40:44.413Z,1764783644.413 [DAT](INFO): DAT read: MF Frequency Band
2025-12-03T17:40:44.414Z,1764783644.414 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-12-03T17:40:44.415Z,1764783644.415 [DAT](INFO): DAT read: Dec 3 2025 17:40:38
2025-12-03T17:40:45.421Z,1764783645.421 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-12-03T17:40:45.422Z,1764783645.422 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-12-03T17:40:45.423Z,1764783645.423 [DAT](INFO): commRate: 1200
2025-12-03T17:40:47.488Z,1764783647.488 [DAT](INFO): entering command mode
2025-12-03T17:40:47.688Z,1764783647.688 [DAT](INFO): DAT read:
2025-12-03T17:40:47.689Z,1764783647.689 [DAT](INFO): DAT read: user:1>
2025-12-03T17:40:47.689Z,1764783647.689 [DAT](INFO): setting verbose to 3
2025-12-03T17:40:47.941Z,1764783647.941 [DAT](INFO): DAT read: user:1>
2025-12-03T17:40:47.942Z,1764783647.942 [DAT](INFO): DAT read: Verbose | 3
2025-12-03T17:40:47.942Z,1764783647.942 [DAT](INFO): set verbose to 3
2025-12-03T17:40:47.942Z,1764783647.942 [DAT](INFO): setting DatVerbose to 27440
2025-12-03T17:40:48.193Z,1764783648.193 [DAT](INFO): DAT read: user:2>
2025-12-03T17:40:48.194Z,1764783648.194 [DAT](INFO): DAT read: DatVerbose | 27440
2025-12-03T17:40:48.194Z,1764783648.194 [DAT](INFO): set DatVerbose to 27440
2025-12-03T17:40:48.194Z,1764783648.194 [DAT](INFO): setting transmit power to 8
2025-12-03T17:40:48.445Z,1764783648.445 [DAT](INFO): DAT read: user:3>
2025-12-03T17:40:48.446Z,1764783648.446 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-12-03T17:40:48.446Z,1764783648.446 [DAT](INFO): set transmit power to 8
2025-12-03T17:40:48.446Z,1764783648.446 [DAT](INFO): setting local address to 5
2025-12-03T17:40:48.697Z,1764783648.697 [DAT](INFO): DAT read: user:4>
2025-12-03T17:40:48.698Z,1764783648.698 [DAT](INFO): DAT read: LocalAddr | 5
2025-12-03T17:40:48.698Z,1764783648.698 [DAT](INFO): set local address to 5
2025-12-03T17:40:48.699Z,1764783648.699 [DAT](INFO): Setting time to: 17:40:48 And date to:12/3/2025
2025-12-03T17:40:48.949Z,1764783648.949 [DAT](INFO): DAT read: user:5>
2025-12-03T17:40:48.951Z,1764783648.951 [DAT](INFO): DAT read: Wed Dec 3, 2025 17:40:48
2025-12-03T17:40:48.952Z,1764783648.952 [DAT](INFO): Local DAT time set to Wed Dec 3, 2025 17:40:48
2025-12-03T17:40:59.782Z,1764783659.782 [NAL9602](INFO): Powering up NAL9602
2025-12-03T17:41:10.660Z,1764783670.660 [NAL9602](INFO): NAL9602 initialized
2025-12-03T17:41:25.200Z,1764783685.200 [NAL9602](INFO): SBD MO Status=0, MOMSN=24285, MT Status=0, MTMSN=0
2025-12-03T17:41:25.200Z,1764783685.200 [NAL9602](INFO): No messages in MT queue
2025-12-03T17:41:32.491Z,1764783692.491 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174129.00,A,3648.15050,N,12147.22754,W,0.058,0.00,031225,,,A*71
2025-12-03T17:41:32.495Z,1764783692.495 [NAL9602](INFO): GPS fix at 20251203T174129: (36.802508, -121.787126)
2025-12-03T17:42:04.792Z,1764783724.792 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T17:42:33.570Z,1764783753.570 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004423
2025-12-03T17:43:01.805Z,1764783781.805 [SBIT](IMPORTANT): Beginning Startup BIT
2025-12-03T17:43:01.837Z,1764783781.837 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-03T17:43:06.372Z,1764783786.372 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.092566
CHAN A1 (24V): 0.111334
CHAN A2 (12V): -0.002741
CHAN A3 (5V): -0.045086
CHAN B0 (3.3V): -0.029814
CHAN B1 (3.15aV): -0.020943
CHAN B2 (3.15bV): -0.015536
CHAN B3 (GND): -0.037704
OPEN: 0.008119
Full Scale: +/- 1 mA
2025-12-03T17:43:55.622Z,1764783835.622 [SBIT](IMPORTANT): SBIT PASSED
2025-12-03T17:43:55.622Z,1764783835.622 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-12-03T17:43:55.622Z,1764783835.622 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-12-03T17:43:55.623Z,1764783835.623 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2025-12-03T17:43:55.623Z,1764783835.623 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=319.676746 cubic_centimeter;
2025-12-03T17:43:55.623Z,1764783835.623 [SBIT](IMPORTANT): VerticalControl.massDefault=32.893839 millimeter;
2025-12-03T17:43:56.011Z,1764783836.011 [MissionManager](IMPORTANT): Started mission Startup
2025-12-03T17:43:56.011Z,1764783836.011 [Startup] Running Loop=1
2025-12-03T17:43:56.012Z,1764783836.012 [Startup](DEBUG): Aggregate::initialize Startup
2025-12-03T17:43:56.012Z,1764783836.012 [Startup:A.GoToSurface] Running Loop=1
2025-12-03T17:43:56.012Z,1764783836.012 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-03T17:43:56.013Z,1764783836.013 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-03T17:43:56.013Z,1764783836.013 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-03T17:43:56.013Z,1764783836.013 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-03T17:43:56.014Z,1764783836.014 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-03T17:43:56.014Z,1764783836.014 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-03T17:43:56.014Z,1764783836.014 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-03T17:43:56.032Z,1764783836.032 [Startup:StartupSatComms] Running Loop=1
2025-12-03T17:43:56.032Z,1764783836.032 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-12-03T17:43:56.032Z,1764783836.032 [Startup:StartupSatComms:A] Running Loop=1
2025-12-03T17:43:56.419Z,1764783836.419 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-12-03T17:43:58.026Z,1764783838.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174358.00,A,3648.15290,N,12147.22653,W,0.117,0.00,031225,,,D*72
2025-12-03T17:43:58.029Z,1764783838.029 [NAL9602](INFO): GPS fix at 20251203T174358: (36.802548, -121.787109)
2025-12-03T17:43:58.040Z,1764783838.040 [Startup:StartupSatComms:A] Stopped
2025-12-03T17:43:58.040Z,1764783838.040 [Startup:StartupSatComms:B] Running Loop=1
2025-12-03T17:43:58.464Z,1764783838.464 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-03T17:44:04.649Z,1764783844.649 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251203T172607/Courier0007.lzma
2025-12-03T17:44:05.650Z,1764783845.650 [DataOverHttps](INFO): Moved sent file to Logs/20251203T172607/Courier0007.lzma.bak
2025-12-03T17:44:05.650Z,1764783845.650 [DataOverHttps](INFO): SBD MOMSN=26413303
2025-12-03T17:44:07.721Z,1764783847.721 [NAL9602](INFO): SBD MO Status=0, MOMSN=24286, MT Status=0, MTMSN=0
2025-12-03T17:44:07.721Z,1764783847.721 [NAL9602](INFO): No messages in MT queue
2025-12-03T17:44:12.973Z,1764783852.973 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-03T17:44:21.805Z,1764783861.805 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20251203T174017/Courier0000.lzma
2025-12-03T17:44:22.806Z,1764783862.806 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0000.lzma.bak
2025-12-03T17:44:22.806Z,1764783862.806 [DataOverHttps](INFO): SBD MOMSN=26413305
2025-12-03T17:44:38.424Z,1764783878.424 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T17:44:38.909Z,1764783878.909 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20251203T172607/Express0008.lzma
2025-12-03T17:44:39.910Z,1764783879.910 [DataOverHttps](INFO): Moved sent file to Logs/20251203T172607/Express0008.lzma.bak
2025-12-03T17:44:39.910Z,1764783879.910 [DataOverHttps](INFO): SBD MOMSN=26413309
2025-12-03T17:44:56.225Z,1764783896.225 [DataOverHttps](INFO): Sending 1081 bytes from file Logs/20251203T174017/Express0001.lzma
2025-12-03T17:44:57.226Z,1764783897.226 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0001.lzma.bak
2025-12-03T17:44:57.226Z,1764783897.226 [DataOverHttps](INFO): SBD MOMSN=26413313
2025-12-03T17:44:58.291Z,1764783898.291 [Startup:StartupSatComms:B](INFO): Timed out from 2025-12-03T17:43:58.0Z
2025-12-03T17:44:58.291Z,1764783898.291 [Startup:StartupSatComms:B] Stopped
2025-12-03T17:44:58.291Z,1764783898.291 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-12-03T17:44:58.291Z,1764783898.291 [Startup:StartupSatComms] Stopped
2025-12-03T17:44:58.291Z,1764783898.291 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-12-03T17:44:58.293Z,1764783898.293 [Startup](INFO): Completed Startup
2025-12-03T17:44:58.293Z,1764783898.293 [MissionManager](INFO): Startup is completed.
2025-12-03T17:44:58.293Z,1764783898.293 [MissionManager](INFO): Uninitializing Mission Startup
2025-12-03T17:44:58.293Z,1764783898.293 [Startup] Stopped
2025-12-03T17:44:58.293Z,1764783898.293 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-12-03T17:44:58.293Z,1764783898.293 [Startup:A.GoToSurface] Stopped
2025-12-03T17:44:58.293Z,1764783898.293 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-03T17:44:58.669Z,1764783898.669 [MissionManager](IMPORTANT): Started mission Default
2025-12-03T17:44:58.669Z,1764783898.669 [Default] Running Loop=1
2025-12-03T17:44:58.669Z,1764783898.669 [Default](DEBUG): Aggregate::initialize Default
2025-12-03T17:44:58.669Z,1764783898.669 [Default:B.GoToSurface] Running Loop=1
2025-12-03T17:44:58.669Z,1764783898.669 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-03T17:44:58.669Z,1764783898.669 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-03T17:44:58.670Z,1764783898.670 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-03T17:44:58.670Z,1764783898.670 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-03T17:44:58.670Z,1764783898.670 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-03T17:44:58.671Z,1764783898.671 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-03T17:44:58.671Z,1764783898.671 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-03T17:44:58.671Z,1764783898.671 [Default:A.Wait] Running Loop=1
2025-12-03T17:44:58.671Z,1764783898.671 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-12-03T17:45:11.978Z,1764783911.978 [Default:A.Wait](INFO): Done Waiting.
2025-12-03T17:45:11.978Z,1764783911.978 [Default:A.Wait] Stopped
2025-12-03T17:45:11.978Z,1764783911.978 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T17:45:12.436Z,1764783912.436 [Default:CheckIn] Running Loop=1
2025-12-03T17:45:12.436Z,1764783912.436 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T17:45:12.436Z,1764783912.436 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T17:45:12.793Z,1764783912.793 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-03T17:45:14.401Z,1764783914.401 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174514.00,A,3648.15292,N,12147.22626,W,0.058,0.00,031225,,,D*76
2025-12-03T17:45:14.404Z,1764783914.404 [NAL9602](INFO): GPS fix at 20251203T174514: (36.802549, -121.787104)
2025-12-03T17:45:14.414Z,1764783914.414 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T17:45:14.414Z,1764783914.414 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T17:45:14.814Z,1764783914.814 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-03T17:45:22.543Z,1764783922.543 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0004.lzma
2025-12-03T17:45:23.546Z,1764783923.546 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0004.lzma.bak
2025-12-03T17:45:23.547Z,1764783923.547 [DataOverHttps](INFO): SBD MOMSN=26413332
2025-12-03T17:45:24.893Z,1764783924.893 [NAL9602](INFO): SBD MO Status=0, MOMSN=24287, MT Status=0, MTMSN=0
2025-12-03T17:45:24.893Z,1764783924.893 [NAL9602](INFO): No messages in MT queue
2025-12-03T17:45:39.723Z,1764783939.723 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20251203T174017/Express0005.lzma
2025-12-03T17:45:40.726Z,1764783940.726 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0005.lzma.bak
2025-12-03T17:45:40.726Z,1764783940.726 [DataOverHttps](INFO): SBD MOMSN=26413335
2025-12-03T17:45:41.864Z,1764783941.864 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T17:45:41.864Z,1764783941.864 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T17:45:41.864Z,1764783941.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T17:45:55.584Z,1764783955.584 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T17:49:24.313Z,1764784164.313 [DataOverHttps](IMPORTANT): SBD MTMSN=20251203T174923
2025-12-03T17:49:32.048Z,1764784172.048 [DataOverHttps](INFO): Received command: ibit
2025-12-03T17:49:32.057Z,1764784172.057 [CommandExec](IMPORTANT): got command ibit
2025-12-03T17:49:32.169Z,1764784172.169 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-12-03T17:49:32.169Z,1764784172.169 [IBIT](IMPORTANT): Beginning control surface checks.
2025-12-03T17:49:32.177Z,1764784172.177 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-03T17:49:33.049Z,1764784173.049 [DataOverHttps](IMPORTANT): SBD MTMSN=20251203T174932
2025-12-03T17:49:33.758Z,1764784173.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174933.00,A,3648.15050,N,12147.22465,W,0.019,0.00,031225,,,D*73
2025-12-03T17:49:33.760Z,1764784173.760 [NAL9602](INFO): GPS fix at 20251203T174933: (36.802508, -121.787077)
2025-12-03T17:49:36.684Z,1764784176.684 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.079730
CHAN A1 (24V): 0.099852
CHAN A2 (12V): -0.010505
CHAN A3 (5V): -0.033765
CHAN B0 (3.3V): -0.026753
CHAN B1 (3.15aV): -0.021190
CHAN B2 (3.15bV): -0.014797
CHAN B3 (GND): -0.031496
OPEN: 0.007655
Full Scale: +/- 1 mA
2025-12-03T17:49:41.095Z,1764784181.095 [DataOverHttps](INFO): Received command: failc
2025-12-03T17:49:41.149Z,1764784181.149 [CommandExec](IMPORTANT): got command failComponent
2025-12-03T17:49:41.149Z,1764784181.149 [CommandExec](IMPORTANT): Failed components:
2025-12-03T17:49:41.149Z,1764784181.149 [CommandExec](IMPORTANT): No failed Components.
2025-12-03T17:50:00.415Z,1764784200.415 [NAL9602](INFO): SBD MO Status=0, MOMSN=24288, MT Status=0, MTMSN=0
2025-12-03T17:50:00.415Z,1764784200.415 [NAL9602](INFO): No messages in MT queue
2025-12-03T17:50:01.641Z,1764784201.641 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175001.00,A,3648.14972,N,12147.22404,W,0.058,0.00,031225,,,D*70
2025-12-03T17:50:01.643Z,1764784201.643 [NAL9602](INFO): GPS fix at 20251203T175001: (36.802495, -121.787067)
2025-12-03T17:50:04.458Z,1764784204.458 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175004.00,A,3648.14977,N,12147.22403,W,0.039,0.00,031225,,,D*70
2025-12-03T17:50:04.461Z,1764784204.461 [NAL9602](INFO): GPS fix at 20251203T175004: (36.802496, -121.787067)
2025-12-03T17:50:07.283Z,1764784207.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175007.00,A,3648.14981,N,12147.22410,W,0.000,0.00,031225,,,D*72
2025-12-03T17:50:07.285Z,1764784207.285 [NAL9602](INFO): GPS fix at 20251203T175007: (36.802497, -121.787068)
2025-12-03T17:50:10.558Z,1764784210.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175010.00,A,3648.14984,N,12147.22418,W,0.039,0.00,031225,,,D*73
2025-12-03T17:50:10.560Z,1764784210.560 [NAL9602](INFO): GPS fix at 20251203T175010: (36.802497, -121.787070)
2025-12-03T17:50:13.386Z,1764784213.386 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175013.00,A,3648.14985,N,12147.22420,W,0.039,0.00,031225,,,D*7A
2025-12-03T17:50:13.389Z,1764784213.389 [NAL9602](INFO): GPS fix at 20251203T175013: (36.802498, -121.787070)
2025-12-03T17:50:16.618Z,1764784216.618 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175016.00,A,3648.14984,N,12147.22415,W,0.058,0.00,031225,,,D*7F
2025-12-03T17:50:16.621Z,1764784216.621 [NAL9602](INFO): GPS fix at 20251203T175016: (36.802497, -121.787069)
2025-12-03T17:50:18.263Z,1764784218.263 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802498 Longitude: -121.787071
2025-12-03T17:50:18.648Z,1764784218.648 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-12-03T17:50:19.054Z,1764784219.054 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-12-03T17:50:19.054Z,1764784219.054 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-12-03T17:50:19.055Z,1764784219.055 [IBIT](IMPORTANT): Pressure:9.955327 PSI
2025-12-03T17:50:19.055Z,1764784219.055 [IBIT](IMPORTANT): Humidity:9.931632 %
2025-12-03T17:50:19.477Z,1764784219.477 [IBIT](IMPORTANT): Vehicle Pitch:-1.104483 degrees
2025-12-03T17:50:19.477Z,1764784219.477 [IBIT](IMPORTANT): Vehicle Roll:1.061338 degrees
2025-12-03T17:50:19.478Z,1764784219.478 [IBIT](IMPORTANT): Vehicle Heading:5.493685 degrees
2025-12-03T17:50:19.888Z,1764784219.888 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-12-03T17:50:19.889Z,1764784219.889 [IBIT](IMPORTANT): buoyancyNeutral: 319.676758 cc
2025-12-03T17:50:19.889Z,1764784219.889 [IBIT](IMPORTANT): massDefault: 3.289384 cm
2025-12-03T17:50:19.889Z,1764784219.889 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-12-03T17:50:19.890Z,1764784219.890 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-12-03T17:50:19.890Z,1764784219.890 [IBIT](IMPORTANT): IBIT PASSED
2025-12-03T17:50:42.492Z,1764784242.492 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T17:50:42.492Z,1764784242.492 [Default:CheckIn:C.Wait] Stopped
2025-12-03T17:50:42.492Z,1764784242.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T17:50:42.492Z,1764784242.492 [Default:CheckIn:D] Running Loop=1
2025-12-03T17:50:42.883Z,1764784242.883 [Default:CheckIn:D] Stopped
2025-12-03T17:50:42.883Z,1764784242.883 [Default:CheckIn:E] Running Loop=1
2025-12-03T17:50:43.306Z,1764784243.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.736900 min
2025-12-03T17:50:43.306Z,1764784243.306 [Default:CheckIn:E] Stopped
2025-12-03T17:50:43.307Z,1764784243.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T17:50:43.307Z,1764784243.307 [Default:CheckIn] Stopped
2025-12-03T17:50:43.307Z,1764784243.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T17:50:43.307Z,1764784243.307 [Default:CheckIn](INFO): Running loop #2
2025-12-03T17:50:43.307Z,1764784243.307 [Default:CheckIn] Running Loop=2
2025-12-03T17:50:43.307Z,1764784243.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T17:50:43.324Z,1764784243.324 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T17:50:45.304Z,1764784245.304 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175045.00,A,3648.15047,N,12147.22525,W,0.019,0.00,031225,,,D*79
2025-12-03T17:50:45.306Z,1764784245.306 [NAL9602](INFO): GPS fix at 20251203T175045: (36.802508, -121.787087)
2025-12-03T17:50:45.316Z,1764784245.316 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T17:50:45.316Z,1764784245.316 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T17:50:53.447Z,1764784253.447 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0007.lzma
2025-12-03T17:50:54.450Z,1764784254.450 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0007.lzma.bak
2025-12-03T17:50:54.450Z,1764784254.450 [DataOverHttps](INFO): SBD MOMSN=26413379
2025-12-03T17:51:10.375Z,1764784270.375 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251203T174017/Courier0010.lzma
2025-12-03T17:51:11.378Z,1764784271.378 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0010.lzma.bak
2025-12-03T17:51:11.378Z,1764784271.378 [DataOverHttps](INFO): SBD MOMSN=26413384
2025-12-03T17:51:18.034Z,1764784278.034 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T17:51:27.709Z,1764784287.709 [DataOverHttps](INFO): Sending 1028 bytes from file Logs/20251203T174017/Express0008.lzma
2025-12-03T17:51:28.710Z,1764784288.710 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0008.lzma.bak
2025-12-03T17:51:28.710Z,1764784288.710 [DataOverHttps](INFO): SBD MOMSN=26413391
2025-12-03T17:51:44.783Z,1764784304.783 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20251203T174017/Express0011.lzma
2025-12-03T17:51:45.786Z,1764784305.786 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0011.lzma.bak
2025-12-03T17:51:45.786Z,1764784305.786 [DataOverHttps](INFO): SBD MOMSN=26413427
2025-12-03T17:51:47.149Z,1764784307.149 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T17:51:47.149Z,1764784307.149 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T17:51:47.149Z,1764784307.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T17:53:49.973Z,1764784429.973 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-03T17:56:47.763Z,1764784607.763 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T17:56:47.763Z,1764784607.763 [Default:CheckIn:C.Wait] Stopped
2025-12-03T17:56:47.763Z,1764784607.763 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T17:56:47.763Z,1764784607.763 [Default:CheckIn:D] Running Loop=1
2025-12-03T17:56:48.112Z,1764784608.112 [Default:CheckIn:D] Stopped
2025-12-03T17:56:48.112Z,1764784608.112 [Default:CheckIn:E] Running Loop=1
2025-12-03T17:56:48.516Z,1764784608.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.824044 min
2025-12-03T17:56:48.516Z,1764784608.516 [Default:CheckIn:E] Stopped
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn] Stopped
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn](INFO): Running loop #3
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn] Running Loop=3
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T17:56:48.517Z,1764784608.517 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T17:56:50.515Z,1764784610.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175650.00,A,3648.15521,N,12147.22306,W,0.039,41.51,031225,,,D*4A
2025-12-03T17:56:50.517Z,1764784610.517 [NAL9602](INFO): GPS fix at 20251203T175650: (36.802587, -121.787051)
2025-12-03T17:56:50.546Z,1764784610.546 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T17:56:50.546Z,1764784610.546 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T17:56:58.447Z,1764784618.447 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0013.lzma
2025-12-03T17:56:59.450Z,1764784619.450 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0013.lzma.bak
2025-12-03T17:56:59.450Z,1764784619.450 [DataOverHttps](INFO): SBD MOMSN=26413484
2025-12-03T17:57:06.675Z,1764784626.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=24289, MT Status=0, MTMSN=0
2025-12-03T17:57:06.675Z,1764784626.675 [NAL9602](INFO): No messages in MT queue
2025-12-03T17:57:16.095Z,1764784636.095 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20251203T174017/Express0014.lzma
2025-12-03T17:57:17.098Z,1764784637.098 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0014.lzma.bak
2025-12-03T17:57:17.098Z,1764784637.098 [DataOverHttps](INFO): SBD MOMSN=26413487
2025-12-03T17:57:18.404Z,1764784638.404 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T17:57:18.405Z,1764784638.405 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T17:57:18.405Z,1764784638.405 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T17:57:37.390Z,1764784657.390 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:02:18.976Z,1764784938.976 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:02:18.976Z,1764784938.976 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:02:18.976Z,1764784938.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:02:18.976Z,1764784938.976 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:02:19.386Z,1764784939.386 [Default:CheckIn:D] Stopped
2025-12-03T18:02:19.386Z,1764784939.386 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:02:19.782Z,1764784939.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.345288 min
2025-12-03T18:02:19.782Z,1764784939.782 [Default:CheckIn:E] Stopped
2025-12-03T18:02:19.782Z,1764784939.782 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:02:19.782Z,1764784939.782 [Default:CheckIn] Stopped
2025-12-03T18:02:19.782Z,1764784939.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:02:19.783Z,1764784939.783 [Default:CheckIn](INFO): Running loop #4
2025-12-03T18:02:19.783Z,1764784939.783 [Default:CheckIn] Running Loop=4
2025-12-03T18:02:19.783Z,1764784939.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:02:19.783Z,1764784939.783 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:02:21.790Z,1764784941.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180221.00,A,3648.15639,N,12147.22295,W,0.019,41.51,031225,,,A*44
2025-12-03T18:02:21.793Z,1764784941.793 [NAL9602](INFO): GPS fix at 20251203T180221: (36.802607, -121.787049)
2025-12-03T18:02:21.803Z,1764784941.803 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:02:21.803Z,1764784941.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:02:26.288Z,1764784946.288 [NAL9602](INFO): SBD MO Status=0, MOMSN=24290, MT Status=0, MTMSN=0
2025-12-03T18:02:26.288Z,1764784946.288 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:02:29.755Z,1764784949.755 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0016.lzma
2025-12-03T18:02:30.758Z,1764784950.758 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0016.lzma.bak
2025-12-03T18:02:30.758Z,1764784950.758 [DataOverHttps](INFO): SBD MOMSN=26413505
2025-12-03T18:02:46.860Z,1764784966.860 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20251203T174017/Express0017.lzma
2025-12-03T18:02:47.862Z,1764784967.862 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0017.lzma.bak
2025-12-03T18:02:47.862Z,1764784967.862 [DataOverHttps](INFO): SBD MOMSN=26413525
2025-12-03T18:02:48.891Z,1764784968.891 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:02:48.891Z,1764784968.891 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:02:48.891Z,1764784968.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:02:56.945Z,1764784976.945 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:03:27.650Z,1764785007.650 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-03T18:07:49.444Z,1764785269.444 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:07:49.445Z,1764785269.445 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:07:49.445Z,1764785269.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:07:49.445Z,1764785269.445 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:07:49.929Z,1764785269.929 [Default:CheckIn:D] Stopped
2025-12-03T18:07:49.929Z,1764785269.929 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.854331 min
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn:E] Stopped
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn] Stopped
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn](INFO): Running loop #5
2025-12-03T18:07:50.282Z,1764785270.282 [Default:CheckIn] Running Loop=5
2025-12-03T18:07:50.283Z,1764785270.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:07:50.283Z,1764785270.283 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:07:52.266Z,1764785272.266 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180752.00,A,3648.15702,N,12147.22548,W,0.000,41.51,031225,,,D*46
2025-12-03T18:07:52.268Z,1764785272.268 [NAL9602](INFO): GPS fix at 20251203T180752: (36.802617, -121.787091)
2025-12-03T18:07:52.278Z,1764785272.278 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:07:52.278Z,1764785272.278 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:07:59.751Z,1764785279.751 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251203T174017/Courier0019.lzma
2025-12-03T18:08:00.754Z,1764785280.754 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0019.lzma.bak
2025-12-03T18:08:00.754Z,1764785280.754 [DataOverHttps](INFO): SBD MOMSN=26413575
2025-12-03T18:08:10.846Z,1764785290.846 [NAL9602](INFO): SBD MO Status=0, MOMSN=24291, MT Status=0, MTMSN=0
2025-12-03T18:08:10.846Z,1764785290.846 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:08:16.872Z,1764785296.872 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20251203T174017/Express0020.lzma
2025-12-03T18:08:17.874Z,1764785297.874 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0020.lzma.bak
2025-12-03T18:08:17.875Z,1764785297.875 [DataOverHttps](INFO): SBD MOMSN=26413578
2025-12-03T18:08:18.965Z,1764785298.965 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:08:18.965Z,1764785298.965 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:08:18.965Z,1764785298.965 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:08:41.570Z,1764785321.570 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:11:31.269Z,1764785491.269 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-03T18:11:31.272Z,1764785491.272 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-12-03T18:13:19.536Z,1764785599.536 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:13:19.536Z,1764785599.536 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:13:19.537Z,1764785599.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:13:19.537Z,1764785599.537 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:13:19.950Z,1764785599.950 [Default:CheckIn:D] Stopped
2025-12-03T18:13:19.950Z,1764785599.950 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.354688 min
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn:E] Stopped
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn] Stopped
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn](INFO): Running loop #6
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn] Running Loop=6
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:13:20.347Z,1764785600.347 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:13:22.359Z,1764785602.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181322.00,A,3648.15724,N,12147.22446,W,0.019,137.88,031225,,,D*73
2025-12-03T18:13:22.361Z,1764785602.361 [NAL9602](INFO): GPS fix at 20251203T181322: (36.802621, -121.787074)
2025-12-03T18:13:22.372Z,1764785602.372 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:13:22.372Z,1764785602.372 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:13:30.476Z,1764785610.476 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20251203T174017/Courier0022.lzma
2025-12-03T18:13:31.466Z,1764785611.466 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0022.lzma.bak
2025-12-03T18:13:31.466Z,1764785611.466 [DataOverHttps](INFO): SBD MOMSN=26413623
2025-12-03T18:13:47.448Z,1764785627.448 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20251203T174017/Express0023.lzma
2025-12-03T18:13:48.450Z,1764785628.450 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0023.lzma.bak
2025-12-03T18:13:48.450Z,1764785628.450 [DataOverHttps](INFO): SBD MOMSN=26413656
2025-12-03T18:13:49.838Z,1764785629.838 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:13:49.838Z,1764785629.838 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:13:49.838Z,1764785629.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:13:52.655Z,1764785632.655 [NAL9602](INFO): SBD MO Status=2, MOMSN=24292, MT Status=2, MTMSN=0
2025-12-03T18:13:52.655Z,1764785632.655 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-03T18:14:10.430Z,1764785650.430 [NAL9602](INFO): SBD MO Status=0, MOMSN=24292, MT Status=0, MTMSN=0
2025-12-03T18:14:10.430Z,1764785650.430 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:14:41.136Z,1764785681.136 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:15:29.038Z,1764785729.038 [DataOverHttps](IMPORTANT): SBD MTMSN=20251203T181528
2025-12-03T18:15:36.527Z,1764785736.527 [DataOverHttps](INFO): Received command: ibit
2025-12-03T18:15:36.601Z,1764785736.601 [CommandExec](IMPORTANT): got command ibit
2025-12-03T18:15:36.918Z,1764785736.918 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-12-03T18:15:36.919Z,1764785736.919 [IBIT](IMPORTANT): Beginning control surface checks.
2025-12-03T18:15:36.923Z,1764785736.923 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-03T18:15:38.512Z,1764785738.512 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181538.00,A,3648.15776,N,12147.22533,W,0.000,157.99,031225,,,A*71
2025-12-03T18:15:38.515Z,1764785738.515 [NAL9602](INFO): GPS fix at 20251203T181538: (36.802629, -121.787089)
2025-12-03T18:15:41.448Z,1764785741.448 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.074852
CHAN A1 (24V): 0.096261
CHAN A2 (12V): -0.005520
CHAN A3 (5V): -0.027768
CHAN B0 (3.3V): -0.023582
CHAN B1 (3.15aV): -0.019320
CHAN B2 (3.15bV): -0.014160
CHAN B3 (GND): -0.028233
OPEN: 0.007655
Full Scale: +/- 1 mA
2025-12-03T18:15:52.018Z,1764785752.018 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.797676
2025-12-03T18:15:52.018Z,1764785752.018 [IBIT](FAULT): Control surface position failure.
2025-12-03T18:15:56.710Z,1764785756.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=24293, MT Status=0, MTMSN=0
2025-12-03T18:15:56.710Z,1764785756.710 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:15:57.922Z,1764785757.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181557.00,A,3648.15824,N,12147.22553,W,0.156,157.99,031225,,,A*74
2025-12-03T18:15:57.924Z,1764785757.924 [NAL9602](INFO): GPS fix at 20251203T181557: (36.802637, -121.787092)
2025-12-03T18:16:00.347Z,1764785760.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181600.00,A,3648.15812,N,12147.22570,W,0.019,157.99,031225,,,A*7B
2025-12-03T18:16:00.350Z,1764785760.350 [NAL9602](INFO): GPS fix at 20251203T181600: (36.802635, -121.787095)
2025-12-03T18:16:03.629Z,1764785763.629 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181603.00,A,3648.15808,N,12147.22579,W,0.019,157.99,031225,,,A*7A
2025-12-03T18:16:03.631Z,1764785763.631 [NAL9602](INFO): GPS fix at 20251203T181603: (36.802635, -121.787097)
2025-12-03T18:16:06.417Z,1764785766.417 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181606.00,A,3648.15801,N,12147.22593,W,0.039,157.99,031225,,,A*70
2025-12-03T18:16:06.419Z,1764785766.419 [NAL9602](INFO): GPS fix at 20251203T181606: (36.802633, -121.787099)
2025-12-03T18:16:09.634Z,1764785769.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181609.00,A,3648.15798,N,12147.22598,W,0.019,157.99,031225,,,A*79
2025-12-03T18:16:09.636Z,1764785769.636 [NAL9602](INFO): GPS fix at 20251203T181609: (36.802633, -121.787100)
2025-12-03T18:16:12.473Z,1764785772.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181612.00,A,3648.15792,N,12147.22605,W,0.019,157.99,031225,,,A*7E
2025-12-03T18:16:12.475Z,1764785772.475 [NAL9602](INFO): GPS fix at 20251203T181612: (36.802632, -121.787101)
2025-12-03T18:16:15.293Z,1764785775.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181615.00,A,3648.15788,N,12147.22607,W,0.019,157.99,031225,,,A*70
2025-12-03T18:16:15.296Z,1764785775.296 [NAL9602](INFO): GPS fix at 20251203T181615: (36.802631, -121.787101)
2025-12-03T18:16:18.522Z,1764785778.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181618.00,A,3648.15780,N,12147.22614,W,0.019,157.99,031225,,,A*77
2025-12-03T18:16:18.525Z,1764785778.525 [NAL9602](INFO): GPS fix at 20251203T181618: (36.802630, -121.787102)
2025-12-03T18:16:21.353Z,1764785781.353 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181621.00,A,3648.15766,N,12147.22625,W,0.039,157.99,031225,,,A*75
2025-12-03T18:16:21.356Z,1764785781.356 [NAL9602](INFO): GPS fix at 20251203T181621: (36.802628, -121.787104)
2025-12-03T18:16:22.598Z,1764785782.598 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802628 Longitude: -121.787102
2025-12-03T18:16:22.980Z,1764785782.980 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2025-12-03T18:16:22.980Z,1764785782.980 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-12-03T18:16:22.980Z,1764785782.980 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-12-03T18:16:23.402Z,1764785783.402 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-12-03T18:16:23.403Z,1764785783.403 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-12-03T18:16:23.403Z,1764785783.403 [IBIT](IMPORTANT): Pressure:10.005404 PSI
2025-12-03T18:16:23.403Z,1764785783.403 [IBIT](IMPORTANT): Humidity:9.803443 %
2025-12-03T18:16:23.799Z,1764785783.799 [IBIT](IMPORTANT): Vehicle Pitch:3.749446 degrees
2025-12-03T18:16:23.799Z,1764785783.799 [IBIT](IMPORTANT): Vehicle Roll:0.239888 degrees
2025-12-03T18:16:23.800Z,1764785783.800 [IBIT](IMPORTANT): Vehicle Heading:201.030640 degrees
2025-12-03T18:16:24.192Z,1764785784.192 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-12-03T18:16:24.192Z,1764785784.192 [IBIT](IMPORTANT): buoyancyNeutral: 319.676758 cc
2025-12-03T18:16:24.192Z,1764785784.192 [IBIT](IMPORTANT): massDefault: 3.289384 cm
2025-12-03T18:16:24.193Z,1764785784.193 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-12-03T18:16:24.193Z,1764785784.193 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-12-03T18:16:24.193Z,1764785784.193 [IBIT](IMPORTANT): IBIT FAILED
2025-12-03T18:16:54.083Z,1764785814.083 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:18:50.439Z,1764785930.439 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:18:50.439Z,1764785930.439 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:18:50.439Z,1764785930.439 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:18:50.439Z,1764785930.439 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:18:50.850Z,1764785930.850 [Default:CheckIn:D] Stopped
2025-12-03T18:18:50.850Z,1764785930.850 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:18:51.256Z,1764785931.256 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.869686 min
2025-12-03T18:18:51.256Z,1764785931.256 [Default:CheckIn:E] Stopped
2025-12-03T18:18:51.256Z,1764785931.256 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:18:51.256Z,1764785931.256 [Default:CheckIn] Stopped
2025-12-03T18:18:51.256Z,1764785931.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:18:51.257Z,1764785931.257 [Default:CheckIn](INFO): Running loop #7
2025-12-03T18:18:51.257Z,1764785931.257 [Default:CheckIn] Running Loop=7
2025-12-03T18:18:51.257Z,1764785931.257 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:18:51.257Z,1764785931.257 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:18:53.259Z,1764785933.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181853.00,A,3648.15402,N,12147.22176,W,0.058,164.22,031225,,,A*79
2025-12-03T18:18:53.262Z,1764785933.262 [NAL9602](INFO): GPS fix at 20251203T181853: (36.802567, -121.787029)
2025-12-03T18:18:53.293Z,1764785933.293 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:18:53.293Z,1764785933.293 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:19:00.519Z,1764785940.519 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251203T174017/Courier0025.lzma
2025-12-03T18:19:01.522Z,1764785941.522 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0025.lzma.bak
2025-12-03T18:19:01.522Z,1764785941.522 [DataOverHttps](INFO): SBD MOMSN=26413669
2025-12-03T18:19:07.491Z,1764785947.491 [NAL9602](INFO): SBD MO Status=0, MOMSN=24294, MT Status=0, MTMSN=0
2025-12-03T18:19:07.491Z,1764785947.491 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:19:17.545Z,1764785957.545 [DataOverHttps](INFO): Sending 1026 bytes from file Logs/20251203T174017/Express0026.lzma
2025-12-03T18:19:18.546Z,1764785958.546 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0026.lzma.bak
2025-12-03T18:19:18.546Z,1764785958.546 [DataOverHttps](INFO): SBD MOMSN=26413672
2025-12-03T18:19:19.630Z,1764785959.630 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:19:19.630Z,1764785959.630 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:19:19.630Z,1764785959.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:19:38.195Z,1764785978.195 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:24:20.224Z,1764786260.224 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:24:20.224Z,1764786260.224 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:24:20.224Z,1764786260.224 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:24:20.224Z,1764786260.224 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:24:20.600Z,1764786260.600 [Default:CheckIn:D] Stopped
2025-12-03T18:24:20.600Z,1764786260.600 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:24:21.009Z,1764786261.009 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.365523 min
2025-12-03T18:24:21.009Z,1764786261.009 [Default:CheckIn:E] Stopped
2025-12-03T18:24:21.009Z,1764786261.009 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:24:21.009Z,1764786261.009 [Default:CheckIn] Stopped
2025-12-03T18:24:21.009Z,1764786261.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:24:21.010Z,1764786261.010 [Default:CheckIn](INFO): Running loop #8
2025-12-03T18:24:21.010Z,1764786261.010 [Default:CheckIn] Running Loop=8
2025-12-03T18:24:21.010Z,1764786261.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:24:21.010Z,1764786261.010 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:24:23.035Z,1764786263.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182422.00,A,3648.15491,N,12147.22275,W,0.019,164.22,031225,,,A*7F
2025-12-03T18:24:23.043Z,1764786263.043 [NAL9602](INFO): GPS fix at 20251203T182422: (36.802582, -121.787046)
2025-12-03T18:24:23.055Z,1764786263.055 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:24:23.055Z,1764786263.055 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:24:30.339Z,1764786270.339 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0028.lzma
2025-12-03T18:24:31.342Z,1764786271.342 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0028.lzma.bak
2025-12-03T18:24:31.342Z,1764786271.342 [DataOverHttps](INFO): SBD MOMSN=26413766
2025-12-03T18:24:42.810Z,1764786282.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=24295, MT Status=0, MTMSN=0
2025-12-03T18:24:42.810Z,1764786282.810 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:24:49.399Z,1764786289.399 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20251203T174017/Express0032.lzma
2025-12-03T18:24:50.402Z,1764786290.402 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0032.lzma.bak
2025-12-03T18:24:50.402Z,1764786290.402 [DataOverHttps](INFO): SBD MOMSN=26413769
2025-12-03T18:24:51.705Z,1764786291.705 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:24:51.706Z,1764786291.706 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:24:51.706Z,1764786291.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:25:13.538Z,1764786313.538 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:26:00.209Z,1764786360.209 [DataOverHttps](IMPORTANT): SBD MTMSN=20251203T182559
2025-12-03T18:26:07.708Z,1764786367.708 [DataOverHttps](INFO): Received command: ibit
2025-12-03T18:26:07.745Z,1764786367.745 [CommandExec](IMPORTANT): got command ibit
2025-12-03T18:26:08.071Z,1764786368.071 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-12-03T18:26:08.071Z,1764786368.071 [IBIT](IMPORTANT): Beginning control surface checks.
2025-12-03T18:26:08.077Z,1764786368.077 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-03T18:26:09.676Z,1764786369.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182609.00,A,3648.15470,N,12147.22323,W,0.136,164.22,031225,,,A*75
2025-12-03T18:26:09.678Z,1764786369.678 [NAL9602](INFO): GPS fix at 20251203T182609: (36.802578, -121.787054)
2025-12-03T18:26:12.620Z,1764786372.620 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.058737
CHAN A1 (24V): 0.086171
CHAN A2 (12V): 0.003882
CHAN A3 (5V): -0.020990
CHAN B0 (3.3V): -0.019288
CHAN B1 (3.15aV): -0.016454
CHAN B2 (3.15bV): -0.013276
CHAN B3 (GND): -0.025153
OPEN: 0.007692
Full Scale: +/- 1 mA
2025-12-03T18:26:38.397Z,1764786398.397 [NAL9602](INFO): SBD MO Status=0, MOMSN=24296, MT Status=0, MTMSN=0
2025-12-03T18:26:38.398Z,1764786398.398 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:26:39.602Z,1764786399.602 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182639.00,A,3648.15483,N,12147.22265,W,0.078,164.22,031225,,,A*72
2025-12-03T18:26:39.604Z,1764786399.604 [NAL9602](INFO): GPS fix at 20251203T182639: (36.802580, -121.787044)
2025-12-03T18:26:42.429Z,1764786402.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182642.00,A,3648.15478,N,12147.22260,W,0.019,164.22,031225,,,A*78
2025-12-03T18:26:42.433Z,1764786402.433 [NAL9602](INFO): GPS fix at 20251203T182642: (36.802580, -121.787043)
2025-12-03T18:26:45.662Z,1764786405.662 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182645.00,A,3648.15482,N,12147.22267,W,0.039,164.22,031225,,,A*7F
2025-12-03T18:26:45.664Z,1764786405.664 [NAL9602](INFO): GPS fix at 20251203T182645: (36.802580, -121.787044)
2025-12-03T18:26:48.491Z,1764786408.491 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182648.00,A,3648.15479,N,12147.22270,W,0.019,164.22,031225,,,A*72
2025-12-03T18:26:48.503Z,1764786408.503 [NAL9602](INFO): GPS fix at 20251203T182648: (36.802580, -121.787045)
2025-12-03T18:26:51.323Z,1764786411.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182651.00,A,3648.15481,N,12147.22276,W,0.058,164.22,031225,,,A*7E
2025-12-03T18:26:51.327Z,1764786411.327 [NAL9602](INFO): GPS fix at 20251203T182651: (36.802580, -121.787046)
2025-12-03T18:26:54.198Z,1764786414.198 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802582 Longitude: -121.787048
2025-12-03T18:26:54.551Z,1764786414.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182654.00,A,3648.15480,N,12147.22274,W,0.058,164.22,031225,,,A*78
2025-12-03T18:26:54.554Z,1764786414.554 [NAL9602](INFO): GPS fix at 20251203T182654: (36.802580, -121.787046)
2025-12-03T18:26:54.570Z,1764786414.570 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2025-12-03T18:26:54.570Z,1764786414.570 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-12-03T18:26:54.571Z,1764786414.571 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-12-03T18:26:54.993Z,1764786414.993 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-12-03T18:26:54.993Z,1764786414.993 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-12-03T18:26:54.994Z,1764786414.994 [IBIT](IMPORTANT): Pressure:9.967698 PSI
2025-12-03T18:26:54.994Z,1764786414.994 [IBIT](IMPORTANT): Humidity:9.705774 %
2025-12-03T18:26:55.383Z,1764786415.383 [IBIT](IMPORTANT): Vehicle Pitch:0.976382 degrees
2025-12-03T18:26:55.388Z,1764786415.388 [IBIT](IMPORTANT): Vehicle Roll:12.468196 degrees
2025-12-03T18:26:55.388Z,1764786415.388 [IBIT](IMPORTANT): Vehicle Heading:206.583969 degrees
2025-12-03T18:26:55.772Z,1764786415.772 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-12-03T18:26:55.772Z,1764786415.772 [IBIT](IMPORTANT): buoyancyNeutral: 319.676758 cc
2025-12-03T18:26:55.772Z,1764786415.772 [IBIT](IMPORTANT): massDefault: 3.289384 cm
2025-12-03T18:26:55.773Z,1764786415.773 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-12-03T18:26:55.773Z,1764786415.773 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-12-03T18:26:55.773Z,1764786415.773 [IBIT](IMPORTANT): IBIT PASSED
2025-12-03T18:27:26.868Z,1764786446.868 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:29:52.317Z,1764786592.317 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:29:52.317Z,1764786592.317 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:29:52.318Z,1764786592.318 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:29:52.318Z,1764786592.318 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:29:52.724Z,1764786592.724 [Default:CheckIn:D] Stopped
2025-12-03T18:29:52.724Z,1764786592.724 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:29:53.134Z,1764786593.134 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.900920 min
2025-12-03T18:29:53.134Z,1764786593.134 [Default:CheckIn:E] Stopped
2025-12-03T18:29:53.134Z,1764786593.134 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:29:53.134Z,1764786593.134 [Default:CheckIn] Stopped
2025-12-03T18:29:53.134Z,1764786593.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:29:53.135Z,1764786593.135 [Default:CheckIn](INFO): Running loop #9
2025-12-03T18:29:53.135Z,1764786593.135 [Default:CheckIn] Running Loop=9
2025-12-03T18:29:53.135Z,1764786593.135 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:29:53.135Z,1764786593.135 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:29:55.133Z,1764786595.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182954.00,A,3648.15620,N,12147.22274,W,0.078,164.22,031225,,,D*78
2025-12-03T18:29:55.136Z,1764786595.136 [NAL9602](INFO): GPS fix at 20251203T182954: (36.802603, -121.787046)
2025-12-03T18:29:55.164Z,1764786595.164 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:29:55.164Z,1764786595.164 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:30:02.747Z,1764786602.747 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0034.lzma
2025-12-03T18:30:03.750Z,1764786603.750 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0034.lzma.bak
2025-12-03T18:30:03.750Z,1764786603.750 [DataOverHttps](INFO): SBD MOMSN=26413784
2025-12-03T18:30:07.659Z,1764786607.659 [NAL9602](INFO): SBD MO Status=0, MOMSN=24297, MT Status=0, MTMSN=0
2025-12-03T18:30:07.659Z,1764786607.659 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:30:19.842Z,1764786619.842 [DataOverHttps](INFO): Sending 928 bytes from file Logs/20251203T174017/Express0035.lzma
2025-12-03T18:30:20.842Z,1764786620.842 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0035.lzma.bak
2025-12-03T18:30:20.842Z,1764786620.842 [DataOverHttps](INFO): SBD MOMSN=26413787
2025-12-03T18:30:38.287Z,1764786638.287 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20251203T174017/Express0038.lzma
2025-12-03T18:30:38.361Z,1764786638.361 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:30:39.291Z,1764786639.291 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0038.lzma.bak
2025-12-03T18:30:39.291Z,1764786639.291 [DataOverHttps](INFO): SBD MOMSN=26413821
2025-12-03T18:30:40.398Z,1764786640.398 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:30:40.398Z,1764786640.398 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:30:40.398Z,1764786640.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:35:40.966Z,1764786940.966 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:35:40.966Z,1764786940.966 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:35:40.966Z,1764786940.966 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:35:40.966Z,1764786940.966 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:35:41.374Z,1764786941.374 [Default:CheckIn:D] Stopped
2025-12-03T18:35:41.374Z,1764786941.374 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:35:41.775Z,1764786941.775 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.711743 min
2025-12-03T18:35:41.775Z,1764786941.775 [Default:CheckIn:E] Stopped
2025-12-03T18:35:41.775Z,1764786941.775 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:35:41.776Z,1764786941.776 [Default:CheckIn] Stopped
2025-12-03T18:35:41.776Z,1764786941.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:35:41.776Z,1764786941.776 [Default:CheckIn](INFO): Running loop #10
2025-12-03T18:35:41.776Z,1764786941.776 [Default:CheckIn] Running Loop=10
2025-12-03T18:35:41.776Z,1764786941.776 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:35:41.776Z,1764786941.776 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:35:43.802Z,1764786943.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183543.00,A,3648.15516,N,12147.22226,W,0.058,14.15,031225,,,D*42
2025-12-03T18:35:43.804Z,1764786943.804 [NAL9602](INFO): GPS fix at 20251203T183543: (36.802586, -121.787038)
2025-12-03T18:35:43.815Z,1764786943.815 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:35:43.815Z,1764786943.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:35:51.467Z,1764786951.467 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0040.lzma
2025-12-03T18:35:52.470Z,1764786952.470 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0040.lzma.bak
2025-12-03T18:35:52.470Z,1764786952.470 [DataOverHttps](INFO): SBD MOMSN=26413900
2025-12-03T18:35:58.364Z,1764786958.364 [NAL9602](INFO): SBD MO Status=0, MOMSN=24298, MT Status=0, MTMSN=0
2025-12-03T18:35:58.364Z,1764786958.364 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:36:08.460Z,1764786968.460 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20251203T174017/Express0041.lzma
2025-12-03T18:36:09.462Z,1764786969.462 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0041.lzma.bak
2025-12-03T18:36:09.462Z,1764786969.462 [DataOverHttps](INFO): SBD MOMSN=26413903
2025-12-03T18:36:10.504Z,1764786970.504 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:36:10.504Z,1764786970.504 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:36:10.504Z,1764786970.504 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:36:14.576Z,1764786974.576 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-12-03T18:36:14.576Z,1764786974.576 [DVL_micro] Communications Fault, FailCount= 1
2025-12-03T18:36:14.576Z,1764786974.576 [DVL_micro](ERROR): Communications Fault
2025-12-03T18:36:14.576Z,1764786974.576 [DVL_micro](ERROR): Failed to parse:
2025-12-03T18:36:14.681Z,1764786974.681 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-12-03T18:36:15.004Z,1764786975.004 [DVL_micro](INFO): Powering down
2025-12-03T18:36:15.791Z,1764786975.791 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-12-03T18:36:15.791Z,1764786975.791 [DVL_micro] No Fault, FailCount= 1
2025-12-03T18:36:29.051Z,1764786989.051 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:41:11.068Z,1764787271.068 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:41:11.068Z,1764787271.068 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:41:11.068Z,1764787271.068 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:41:11.068Z,1764787271.068 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:41:11.462Z,1764787271.462 [Default:CheckIn:D] Stopped
2025-12-03T18:41:11.462Z,1764787271.462 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:41:11.866Z,1764787271.866 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.213216 min
2025-12-03T18:41:11.866Z,1764787271.866 [Default:CheckIn:E] Stopped
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn] Stopped
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn](INFO): Running loop #11
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn] Running Loop=11
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:41:11.867Z,1764787271.867 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:41:13.881Z,1764787273.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184113.00,A,3648.15374,N,12147.22245,W,0.136,11.36,031225,,,D*4E
2025-12-03T18:41:13.883Z,1764787273.883 [NAL9602](INFO): GPS fix at 20251203T184113: (36.802562, -121.787041)
2025-12-03T18:41:13.893Z,1764787273.893 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:41:13.893Z,1764787273.893 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:41:21.575Z,1764787281.575 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0043.lzma
2025-12-03T18:41:22.579Z,1764787282.579 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0043.lzma.bak
2025-12-03T18:41:22.579Z,1764787282.579 [DataOverHttps](INFO): SBD MOMSN=26413916
2025-12-03T18:41:38.127Z,1764787298.127 [NAL9602](INFO): SBD MO Status=0, MOMSN=24299, MT Status=0, MTMSN=0
2025-12-03T18:41:38.127Z,1764787298.127 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:41:38.548Z,1764787298.548 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20251203T174017/Express0044.lzma
2025-12-03T18:41:39.550Z,1764787299.550 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0044.lzma.bak
2025-12-03T18:41:39.550Z,1764787299.550 [DataOverHttps](INFO): SBD MOMSN=26413919
2025-12-03T18:41:40.959Z,1764787300.959 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:41:40.959Z,1764787300.959 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:41:40.959Z,1764787300.959 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:42:08.840Z,1764787328.840 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:46:41.530Z,1764787601.530 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:46:41.530Z,1764787601.530 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:46:41.530Z,1764787601.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:46:41.530Z,1764787601.530 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:46:41.947Z,1764787601.947 [Default:CheckIn:D] Stopped
2025-12-03T18:46:41.947Z,1764787601.947 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:46:42.353Z,1764787602.353 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.721301 min
2025-12-03T18:46:42.353Z,1764787602.353 [Default:CheckIn:E] Stopped
2025-12-03T18:46:42.353Z,1764787602.353 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:46:42.353Z,1764787602.353 [Default:CheckIn] Stopped
2025-12-03T18:46:42.353Z,1764787602.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:46:42.354Z,1764787602.354 [Default:CheckIn](INFO): Running loop #12
2025-12-03T18:46:42.354Z,1764787602.354 [Default:CheckIn] Running Loop=12
2025-12-03T18:46:42.354Z,1764787602.354 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:46:42.354Z,1764787602.354 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:46:44.350Z,1764787604.350 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184644.00,A,3648.15313,N,12147.22104,W,0.000,285.85,031225,,,D*7F
2025-12-03T18:46:44.352Z,1764787604.352 [NAL9602](INFO): GPS fix at 20251203T184644: (36.802552, -121.787017)
2025-12-03T18:46:44.379Z,1764787604.379 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:46:44.379Z,1764787604.379 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:46:51.795Z,1764787611.795 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251203T174017/Courier0046.lzma
2025-12-03T18:46:52.798Z,1764787612.798 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0046.lzma.bak
2025-12-03T18:46:52.798Z,1764787612.798 [DataOverHttps](INFO): SBD MOMSN=26413989
2025-12-03T18:47:02.127Z,1764787622.127 [NAL9602](INFO): SBD MO Status=0, MOMSN=24300, MT Status=0, MTMSN=0
2025-12-03T18:47:02.127Z,1764787622.127 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:47:08.883Z,1764787628.883 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20251203T174017/Express0047.lzma
2025-12-03T18:47:09.886Z,1764787629.886 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0047.lzma.bak
2025-12-03T18:47:09.886Z,1764787629.886 [DataOverHttps](INFO): SBD MOMSN=26413992
2025-12-03T18:47:11.028Z,1764787631.028 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:47:11.028Z,1764787631.028 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:47:11.028Z,1764787631.028 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:47:32.832Z,1764787652.832 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:52:11.599Z,1764787931.599 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:52:11.599Z,1764787931.599 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:52:11.616Z,1764787931.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:52:11.616Z,1764787931.616 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:52:12.003Z,1764787932.003 [Default:CheckIn:D] Stopped
2025-12-03T18:52:12.003Z,1764787932.003 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.222225 min
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn:E] Stopped
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn] Stopped
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn](INFO): Running loop #13
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn] Running Loop=13
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:52:12.409Z,1764787932.409 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:52:14.420Z,1764787934.420 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185214.00,A,3648.15386,N,12147.22099,W,0.058,285.85,031225,,,A*7E
2025-12-03T18:52:14.422Z,1764787934.422 [NAL9602](INFO): GPS fix at 20251203T185214: (36.802564, -121.787016)
2025-12-03T18:52:14.433Z,1764787934.433 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:52:14.433Z,1764787934.433 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:52:22.199Z,1764787942.199 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0049.lzma
2025-12-03T18:52:23.202Z,1764787943.202 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0049.lzma.bak
2025-12-03T18:52:23.202Z,1764787943.202 [DataOverHttps](INFO): SBD MOMSN=26414003
2025-12-03T18:52:32.637Z,1764787952.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=24301, MT Status=0, MTMSN=0
2025-12-03T18:52:32.637Z,1764787952.637 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:52:39.132Z,1764787959.132 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20251203T174017/Express0050.lzma
2025-12-03T18:52:40.134Z,1764787960.134 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0050.lzma.bak
2025-12-03T18:52:40.134Z,1764787960.134 [DataOverHttps](INFO): SBD MOMSN=26414006
2025-12-03T18:52:41.494Z,1764787961.494 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:52:41.494Z,1764787961.494 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:52:41.494Z,1764787961.494 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:53:03.305Z,1764787983.305 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T18:56:20.866Z,1764788180.866 [DVL_micro](ERROR): Failed to parse:
:SA,+00.64,-12.42,024.2
2025-12-03T18:57:42.070Z,1764788262.070 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T18:57:42.070Z,1764788262.070 [Default:CheckIn:C.Wait] Stopped
2025-12-03T18:57:42.070Z,1764788262.070 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T18:57:42.070Z,1764788262.070 [Default:CheckIn:D] Running Loop=1
2025-12-03T18:57:42.477Z,1764788262.477 [Default:CheckIn:D] Stopped
2025-12-03T18:57:42.477Z,1764788262.477 [Default:CheckIn:E] Running Loop=1
2025-12-03T18:57:42.880Z,1764788262.880 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.730127 min
2025-12-03T18:57:42.880Z,1764788262.880 [Default:CheckIn:E] Stopped
2025-12-03T18:57:42.880Z,1764788262.880 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T18:57:42.880Z,1764788262.880 [Default:CheckIn] Stopped
2025-12-03T18:57:42.881Z,1764788262.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T18:57:42.881Z,1764788262.881 [Default:CheckIn](INFO): Running loop #14
2025-12-03T18:57:42.881Z,1764788262.881 [Default:CheckIn] Running Loop=14
2025-12-03T18:57:42.881Z,1764788262.881 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T18:57:42.881Z,1764788262.881 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T18:57:44.901Z,1764788264.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185744.00,A,3648.15368,N,12147.22077,W,0.000,285.85,031225,,,A*73
2025-12-03T18:57:44.903Z,1764788264.903 [NAL9602](INFO): GPS fix at 20251203T185744: (36.802561, -121.787013)
2025-12-03T18:57:44.914Z,1764788264.914 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T18:57:44.914Z,1764788264.914 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T18:57:52.315Z,1764788272.315 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251203T174017/Courier0052.lzma
2025-12-03T18:57:53.318Z,1764788273.318 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0052.lzma.bak
2025-12-03T18:57:53.318Z,1764788273.318 [DataOverHttps](INFO): SBD MOMSN=26414064
2025-12-03T18:57:53.835Z,1764788273.835 [NAL9602](INFO): SBD MO Status=0, MOMSN=24302, MT Status=0, MTMSN=0
2025-12-03T18:57:53.835Z,1764788273.835 [NAL9602](INFO): No messages in MT queue
2025-12-03T18:58:09.219Z,1764788289.219 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20251203T174017/Express0053.lzma
2025-12-03T18:58:10.222Z,1764788290.222 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0053.lzma.bak
2025-12-03T18:58:10.222Z,1764788290.222 [DataOverHttps](INFO): SBD MOMSN=26414067
2025-12-03T18:58:11.621Z,1764788291.621 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T18:58:11.621Z,1764788291.621 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T18:58:11.621Z,1764788291.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T18:58:24.531Z,1764788304.531 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:03:12.199Z,1764788592.199 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:03:12.199Z,1764788592.199 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:03:12.199Z,1764788592.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:03:12.199Z,1764788592.199 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:03:12.597Z,1764788592.597 [Default:CheckIn:D] Stopped
2025-12-03T19:03:12.597Z,1764788592.597 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:03:12.995Z,1764788592.995 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.232129 min
2025-12-03T19:03:12.995Z,1764788592.995 [Default:CheckIn:E] Stopped
2025-12-03T19:03:12.995Z,1764788592.995 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:03:12.995Z,1764788592.995 [Default:CheckIn] Stopped
2025-12-03T19:03:12.995Z,1764788592.995 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:03:12.995Z,1764788592.995 [Default:CheckIn](INFO): Running loop #15
2025-12-03T19:03:13.012Z,1764788593.012 [Default:CheckIn] Running Loop=15
2025-12-03T19:03:13.012Z,1764788593.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:03:13.012Z,1764788593.012 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:03:15.019Z,1764788595.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190314.00,A,3648.15407,N,12147.21911,W,0.019,306.44,031225,,,D*78
2025-12-03T19:03:15.021Z,1764788595.021 [NAL9602](INFO): GPS fix at 20251203T190314: (36.802568, -121.786985)
2025-12-03T19:03:15.047Z,1764788595.047 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:03:15.053Z,1764788595.053 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:03:22.243Z,1764788602.243 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0055.lzma
2025-12-03T19:03:23.246Z,1764788603.246 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0055.lzma.bak
2025-12-03T19:03:23.246Z,1764788603.246 [DataOverHttps](INFO): SBD MOMSN=26414117
2025-12-03T19:03:39.340Z,1764788619.340 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20251203T174017/Express0056.lzma
2025-12-03T19:03:40.342Z,1764788620.342 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0056.lzma.bak
2025-12-03T19:03:40.342Z,1764788620.342 [DataOverHttps](INFO): SBD MOMSN=26414120
2025-12-03T19:03:41.709Z,1764788621.709 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:03:41.709Z,1764788621.709 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:03:41.709Z,1764788621.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:03:45.307Z,1764788625.307 [NAL9602](INFO): SBD MO Status=2, MOMSN=24303, MT Status=2, MTMSN=0
2025-12-03T19:03:45.307Z,1764788625.307 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-03T19:03:54.198Z,1764788634.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=24303, MT Status=0, MTMSN=0
2025-12-03T19:03:54.198Z,1764788634.198 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:04:24.903Z,1764788664.903 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:08:42.258Z,1764788922.258 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:08:42.258Z,1764788922.258 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:08:42.258Z,1764788922.258 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:08:42.258Z,1764788922.258 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:08:42.662Z,1764788922.662 [Default:CheckIn:D] Stopped
2025-12-03T19:08:42.662Z,1764788922.662 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:08:43.067Z,1764788923.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.733219 min
2025-12-03T19:08:43.067Z,1764788923.067 [Default:CheckIn:E] Stopped
2025-12-03T19:08:43.067Z,1764788923.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:08:43.067Z,1764788923.067 [Default:CheckIn] Stopped
2025-12-03T19:08:43.067Z,1764788923.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:08:43.068Z,1764788923.068 [Default:CheckIn](INFO): Running loop #16
2025-12-03T19:08:43.068Z,1764788923.068 [Default:CheckIn] Running Loop=16
2025-12-03T19:08:43.068Z,1764788923.068 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:08:43.068Z,1764788923.068 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:08:45.082Z,1764788925.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190844.00,A,3648.17110,N,12147.19023,W,1.108,95.47,031225,,,A*4B
2025-12-03T19:08:45.084Z,1764788925.084 [NAL9602](INFO): GPS fix at 20251203T190844: (36.802852, -121.786504)
2025-12-03T19:08:45.095Z,1764788925.095 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:08:45.095Z,1764788925.095 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:08:52.519Z,1764788932.519 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251203T174017/Courier0058.lzma
2025-12-03T19:08:53.522Z,1764788933.522 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0058.lzma.bak
2025-12-03T19:08:53.522Z,1764788933.522 [DataOverHttps](INFO): SBD MOMSN=26414179
2025-12-03T19:09:03.264Z,1764788943.264 [NAL9602](INFO): SBD MO Status=0, MOMSN=24304, MT Status=0, MTMSN=0
2025-12-03T19:09:03.264Z,1764788943.264 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:09:09.660Z,1764788949.660 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20251203T174017/Express0059.lzma
2025-12-03T19:09:10.662Z,1764788950.662 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0059.lzma.bak
2025-12-03T19:09:10.662Z,1764788950.662 [DataOverHttps](INFO): SBD MOMSN=26414182
2025-12-03T19:09:11.746Z,1764788951.746 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:09:11.746Z,1764788951.746 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:09:11.747Z,1764788951.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:09:33.960Z,1764788973.960 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:12:45.859Z,1764789165.859 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0062,-00224,+00000,A
2025-12-03T19:12:45.860Z,1764789165.860 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0062,-00224,+00000,A
2025-12-03T19:14:12.333Z,1764789252.333 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:14:12.333Z,1764789252.333 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:14:12.334Z,1764789252.334 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:14:12.334Z,1764789252.334 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:14:12.731Z,1764789252.731 [Default:CheckIn:D] Stopped
2025-12-03T19:14:12.731Z,1764789252.731 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.234375 min
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn:E] Stopped
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn] Stopped
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn](INFO): Running loop #17
2025-12-03T19:14:13.165Z,1764789253.165 [Default:CheckIn] Running Loop=17
2025-12-03T19:14:13.166Z,1764789253.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:14:13.166Z,1764789253.166 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:14:15.154Z,1764789255.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191415.00,A,3648.48817,N,12147.12199,W,5.812,339.87,031225,,,A*72
2025-12-03T19:14:15.157Z,1764789255.157 [NAL9602](INFO): GPS fix at 20251203T191415: (36.808136, -121.785366)
2025-12-03T19:14:15.167Z,1764789255.167 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:14:15.167Z,1764789255.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:14:23.135Z,1764789263.135 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251203T174017/Courier0061.lzma
2025-12-03T19:14:24.138Z,1764789264.138 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0061.lzma.bak
2025-12-03T19:14:24.138Z,1764789264.138 [DataOverHttps](INFO): SBD MOMSN=26414220
2025-12-03T19:14:32.110Z,1764789272.110 [NAL9602](INFO): SBD MO Status=0, MOMSN=24305, MT Status=0, MTMSN=0
2025-12-03T19:14:32.110Z,1764789272.110 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:14:40.659Z,1764789280.659 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20251203T174017/Express0062.lzma
2025-12-03T19:14:41.662Z,1764789281.662 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0062.lzma.bak
2025-12-03T19:14:41.662Z,1764789281.662 [DataOverHttps](INFO): SBD MOMSN=26414223
2025-12-03T19:14:43.031Z,1764789283.031 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:14:43.032Z,1764789283.032 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:14:43.032Z,1764789283.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:15:02.822Z,1764789302.822 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:17:53.725Z,1764789473.725 [DVL_micro](ERROR): Failed to parse:
:WI,-00177,+00021,-00161,+00000,A
2025-12-03T19:19:43.610Z,1764789583.610 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:19:43.610Z,1764789583.610 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:19:43.611Z,1764789583.611 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:19:43.611Z,1764789583.611 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:19:44.024Z,1764789584.024 [Default:CheckIn:D] Stopped
2025-12-03T19:19:44.025Z,1764789584.025 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:19:44.411Z,1764789584.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.755924 min
2025-12-03T19:19:44.411Z,1764789584.411 [Default:CheckIn:E] Stopped
2025-12-03T19:19:44.411Z,1764789584.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:19:44.411Z,1764789584.411 [Default:CheckIn] Stopped
2025-12-03T19:19:44.411Z,1764789584.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:19:44.412Z,1764789584.412 [Default:CheckIn](INFO): Running loop #18
2025-12-03T19:19:44.412Z,1764789584.412 [Default:CheckIn] Running Loop=18
2025-12-03T19:19:44.412Z,1764789584.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:19:44.412Z,1764789584.412 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:19:46.425Z,1764789586.425 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191946.00,A,3648.27491,N,12148.13227,W,10.847,256.92,031225,,,A*42
2025-12-03T19:19:46.427Z,1764789586.427 [NAL9602](INFO): GPS fix at 20251203T191946: (36.804582, -121.802205)
2025-12-03T19:19:46.438Z,1764789586.438 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:19:46.438Z,1764789586.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:19:54.331Z,1764789594.331 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0064.lzma
2025-12-03T19:19:55.334Z,1764789595.334 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0064.lzma.bak
2025-12-03T19:19:55.334Z,1764789595.334 [DataOverHttps](INFO): SBD MOMSN=26414305
2025-12-03T19:20:05.815Z,1764789605.815 [NAL9602](INFO): SBD MO Status=0, MOMSN=24306, MT Status=0, MTMSN=0
2025-12-03T19:20:05.815Z,1764789605.815 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:20:11.564Z,1764789611.564 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20251203T174017/Express0065.lzma
2025-12-03T19:20:12.566Z,1764789612.566 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0065.lzma.bak
2025-12-03T19:20:12.566Z,1764789612.566 [DataOverHttps](INFO): SBD MOMSN=26414308
2025-12-03T19:20:13.902Z,1764789613.902 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:20:13.903Z,1764789613.903 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:20:13.903Z,1764789613.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:20:36.574Z,1764789636.574 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:25:14.490Z,1764789914.490 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:25:14.490Z,1764789914.490 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:25:14.491Z,1764789914.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:25:14.491Z,1764789914.491 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:25:14.882Z,1764789914.882 [Default:CheckIn:D] Stopped
2025-12-03T19:25:14.882Z,1764789914.882 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:25:15.286Z,1764789915.286 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.270215 min
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn:E] Stopped
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn] Stopped
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn](INFO): Running loop #19
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn] Running Loop=19
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:25:15.287Z,1764789915.287 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:25:17.305Z,1764789917.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192517.00,A,3647.98859,N,12149.84187,W,22.937,255.11,031225,,,A*43
2025-12-03T19:25:17.307Z,1764789917.307 [NAL9602](INFO): GPS fix at 20251203T192517: (36.799810, -121.830698)
2025-12-03T19:25:17.317Z,1764789917.317 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:25:17.317Z,1764789917.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:25:27.959Z,1764789927.959 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251203T174017/Courier0067.lzma
2025-12-03T19:25:28.963Z,1764789928.963 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0067.lzma.bak
2025-12-03T19:25:28.963Z,1764789928.963 [DataOverHttps](INFO): SBD MOMSN=26414325
2025-12-03T19:25:37.924Z,1764789937.924 [NAL9602](INFO): SBD MO Status=2, MOMSN=24307, MT Status=2, MTMSN=0
2025-12-03T19:25:37.925Z,1764789937.925 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-03T19:25:51.129Z,1764789951.129 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20251203T174017/Express0068.lzma
2025-12-03T19:25:52.108Z,1764789952.108 [NAL9602](INFO): SBD MO Status=2, MOMSN=24307, MT Status=2, MTMSN=0
2025-12-03T19:25:52.108Z,1764789952.108 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-03T19:25:52.130Z,1764789952.130 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0068.lzma.bak
2025-12-03T19:25:52.130Z,1764789952.130 [DataOverHttps](INFO): SBD MOMSN=26414328
2025-12-03T19:25:53.337Z,1764789953.337 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:25:53.337Z,1764789953.337 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:25:53.337Z,1764789953.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:26:12.249Z,1764789972.249 [NAL9602](INFO): SBD MO Status=0, MOMSN=24307, MT Status=0, MTMSN=0
2025-12-03T19:26:12.250Z,1764789972.250 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:26:42.944Z,1764790002.944 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:29:35.697Z,1764790175.697 [DataOverHttps](IMPORTANT): SBD MTMSN=20251203T192935
2025-12-03T19:29:43.215Z,1764790183.215 [DataOverHttps](INFO): Received command: ibit
2025-12-03T19:29:43.220Z,1764790183.220 [CommandExec](IMPORTANT): got command ibit
2025-12-03T19:29:43.549Z,1764790183.549 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-12-03T19:29:43.549Z,1764790183.549 [IBIT](IMPORTANT): Beginning control surface checks.
2025-12-03T19:29:43.553Z,1764790183.553 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-03T19:29:45.150Z,1764790185.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192945.00,A,3647.78528,N,12150.80215,W,0.156,138.93,031225,,,A*74
2025-12-03T19:29:45.153Z,1764790185.153 [NAL9602](INFO): GPS fix at 20251203T192945: (36.796421, -121.846703)
2025-12-03T19:29:48.093Z,1764790188.093 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.032503
CHAN A1 (24V): 0.055234
CHAN A2 (12V): 0.009035
CHAN A3 (5V): -0.008089
CHAN B0 (3.3V): -0.009283
CHAN B1 (3.15aV): -0.008183
CHAN B2 (3.15bV): -0.007453
CHAN B3 (GND): -0.013729
OPEN: 0.007206
Full Scale: +/- 1 mA
2025-12-03T19:30:29.627Z,1764790229.627 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.796421 Longitude: -121.846703
2025-12-03T19:30:30.054Z,1764790230.054 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 317.440002
Voltage: 16.532000
2025-12-03T19:30:30.054Z,1764790230.054 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-12-03T19:30:30.054Z,1764790230.054 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-12-03T19:30:30.432Z,1764790230.432 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-12-03T19:30:30.433Z,1764790230.433 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-12-03T19:30:30.433Z,1764790230.433 [IBIT](IMPORTANT): Pressure:9.967698 PSI
2025-12-03T19:30:30.433Z,1764790230.433 [IBIT](IMPORTANT): Humidity:9.602002 %
2025-12-03T19:30:30.837Z,1764790230.837 [IBIT](IMPORTANT): Vehicle Pitch:1.149177 degrees
2025-12-03T19:30:30.837Z,1764790230.837 [IBIT](IMPORTANT): Vehicle Roll:10.667851 degrees
2025-12-03T19:30:30.838Z,1764790230.838 [IBIT](IMPORTANT): Vehicle Heading:153.878174 degrees
2025-12-03T19:30:31.246Z,1764790231.246 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-12-03T19:30:31.246Z,1764790231.246 [IBIT](IMPORTANT): buoyancyNeutral: 319.676758 cc
2025-12-03T19:30:31.246Z,1764790231.246 [IBIT](IMPORTANT): massDefault: 3.289384 cm
2025-12-03T19:30:31.247Z,1764790231.247 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-12-03T19:30:31.247Z,1764790231.247 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-12-03T19:30:31.247Z,1764790231.247 [IBIT](IMPORTANT): IBIT PASSED
2025-12-03T19:30:40.965Z,1764790240.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=24308, MT Status=2, MTMSN=0
2025-12-03T19:30:40.965Z,1764790240.965 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-03T19:30:53.909Z,1764790253.909 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:30:53.909Z,1764790253.909 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:30:53.909Z,1764790253.909 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:30:53.909Z,1764790253.909 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:30:54.272Z,1764790254.272 [Default:CheckIn:D] Stopped
2025-12-03T19:30:54.272Z,1764790254.272 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.926709 min
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn:E] Stopped
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn] Stopped
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn](INFO): Running loop #20
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn] Running Loop=20
2025-12-03T19:30:54.681Z,1764790254.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:30:54.682Z,1764790254.681 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:31:12.464Z,1764790272.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=24308, MT Status=2, MTMSN=0
2025-12-03T19:31:12.464Z,1764790272.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-03T19:31:41.133Z,1764790301.133 [NAL9602](INFO): SBD MO Status=0, MOMSN=24308, MT Status=0, MTMSN=0
2025-12-03T19:31:41.133Z,1764790301.133 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:31:42.380Z,1764790302.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193142.00,A,3647.77928,N,12150.80161,W,0.408,259.56,031225,,,D*7F
2025-12-03T19:31:42.382Z,1764790302.382 [NAL9602](INFO): GPS fix at 20251203T193142: (36.796321, -121.846694)
2025-12-03T19:31:42.437Z,1764790302.437 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:31:42.437Z,1764790302.437 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:31:46.281Z,1764790306.281 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251203T174017/Courier0070.lzma
2025-12-03T19:31:46.926Z,1764790306.926 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Courier0070.lzma.bak
2025-12-03T19:31:46.926Z,1764790306.926 [DataOverHttps](INFO): SBD MOMSN=26414395
2025-12-03T19:32:03.333Z,1764790323.333 [DataOverHttps](INFO): Sending 865 bytes from file Logs/20251203T174017/Express0071.lzma
2025-12-03T19:32:04.334Z,1764790324.334 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0071.lzma.bak
2025-12-03T19:32:04.334Z,1764790324.334 [DataOverHttps](INFO): SBD MOMSN=26414398
2025-12-03T19:32:14.878Z,1764790334.878 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:32:20.591Z,1764790340.591 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20251203T174017/Express0074.lzma
2025-12-03T19:32:21.594Z,1764790341.594 [DataOverHttps](INFO): Moved sent file to Logs/20251203T174017/Express0074.lzma.bak
2025-12-03T19:32:21.595Z,1764790341.595 [DataOverHttps](INFO): SBD MOMSN=26414427
2025-12-03T19:32:22.971Z,1764790342.971 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:32:22.971Z,1764790342.971 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:32:22.971Z,1764790342.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:33:29.614Z,1764790409.614 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-03T19:37:23.568Z,1764790643.568 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-03T19:37:23.568Z,1764790643.568 [Default:CheckIn:C.Wait] Stopped
2025-12-03T19:37:23.568Z,1764790643.568 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-03T19:37:23.568Z,1764790643.568 [Default:CheckIn:D] Running Loop=1
2025-12-03T19:37:24.003Z,1764790644.003 [Default:CheckIn:D] Stopped
2025-12-03T19:37:24.003Z,1764790644.003 [Default:CheckIn:E] Running Loop=1
2025-12-03T19:37:24.370Z,1764790644.370 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.422233 min
2025-12-03T19:37:24.370Z,1764790644.370 [Default:CheckIn:E] Stopped
2025-12-03T19:37:24.370Z,1764790644.370 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-03T19:37:24.370Z,1764790644.370 [Default:CheckIn] Stopped
2025-12-03T19:37:24.371Z,1764790644.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-03T19:37:24.371Z,1764790644.371 [Default:CheckIn](INFO): Running loop #21
2025-12-03T19:37:24.371Z,1764790644.371 [Default:CheckIn] Running Loop=21
2025-12-03T19:37:24.371Z,1764790644.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-03T19:37:24.371Z,1764790644.371 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-03T19:37:26.403Z,1764790646.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193726.00,A,3647.76778,N,12150.81037,W,0.408,289.30,031225,,,D*7F
2025-12-03T19:37:26.405Z,1764790646.405 [NAL9602](INFO): GPS fix at 20251203T193726: (36.796130, -121.846840)
2025-12-03T19:37:26.416Z,1764790646.416 [Default:CheckIn:Read_GPS] Stopped
2025-12-03T19:37:26.416Z,1764790646.416 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-03T19:37:27.218Z,1764790647.218 [Default:CheckIn:Read_Iridium] Stopped
2025-12-03T19:37:27.218Z,1764790647.218 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-03T19:37:27.219Z,1764790647.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-03T19:38:19.306Z,1764790699.306 [NAL9602](INFO): SBD MO Status=0, MOMSN=24309, MT Status=0, MTMSN=0
2025-12-03T19:38:19.306Z,1764790699.306 [NAL9602](INFO): No messages in MT queue
2025-12-03T19:38:50.041Z,1764790730.041 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-03T19:41:09.853Z,1764790869.853 [DataOverHttps](IMPORTANT): SBD MTMSN=20251203T194109
2025-12-03T19:41:16.285Z,1764790876.285 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-03T19:41:17.432Z,1764790877.432 [DataOverHttps](INFO): Received command: restart logs