2025-08-26T19:58:10.611Z,1756238290.611 [Supervisor](DEBUG): Initializing supervisor. 2025-08-26T19:58:10.616Z,1756238290.616 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-08-26T19:58:10.616Z,1756238290.616 [SyncHandler](INFO): Protected caller Thread ID is 1032 2025-08-26T19:58:10.617Z,1756238290.617 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-08-26T19:58:10.618Z,1756238290.618 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-08-26T19:58:10.618Z,1756238290.618 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1033 2025-08-26T19:58:10.622Z,1756238290.622 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-08-26T19:58:10.642Z,1756238290.642 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-08-26T19:58:10.643Z,1756238290.643 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-08-26T19:58:10.644Z,1756238290.644 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1034 2025-08-26T19:58:10.648Z,1756238290.648 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-08-26T19:58:10.649Z,1756238290.649 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-08-26T19:58:10.650Z,1756238290.650 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1035 2025-08-26T19:58:10.652Z,1756238290.652 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-08-26T19:58:10.653Z,1756238290.653 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-08-26T19:58:10.653Z,1756238290.653 [logger ThreadHandler](INFO): Protected caller Thread ID is 1036 2025-08-26T19:58:10.657Z,1756238290.657 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-08-26T19:58:10.658Z,1756238290.658 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-08-26T19:58:10.659Z,1756238290.659 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-08-26T19:58:10.764Z,1756238290.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-08-26T19:58:10.765Z,1756238290.765 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-08-26T19:58:11.512Z,1756238291.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-08-26T19:58:11.513Z,1756238291.513 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-08-26T19:58:11.776Z,1756238291.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-08-26T19:58:11.776Z,1756238291.776 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-08-26T19:58:11.993Z,1756238291.993 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-08-26T19:58:12.146Z,1756238292.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-08-26T19:58:12.146Z,1756238292.146 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-08-26T19:58:12.249Z,1756238292.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-08-26T19:58:12.249Z,1756238292.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-08-26T19:58:12.368Z,1756238292.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-08-26T19:58:12.368Z,1756238292.368 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-08-26T19:58:12.786Z,1756238292.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-08-26T19:58:12.787Z,1756238292.787 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-08-26T19:58:13.332Z,1756238293.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-08-26T19:58:13.332Z,1756238293.332 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-08-26T19:58:13.571Z,1756238293.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-08-26T19:58:13.571Z,1756238293.571 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-08-26T19:58:13.767Z,1756238293.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-08-26T19:58:13.767Z,1756238293.767 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-08-26T19:58:14.338Z,1756238294.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-08-26T19:58:14.339Z,1756238294.339 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-08-26T19:58:14.638Z,1756238294.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-08-26T19:58:14.639Z,1756238294.639 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-08-26T19:58:14.888Z,1756238294.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-08-26T19:58:14.888Z,1756238294.888 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-08-26T19:58:15.289Z,1756238295.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-08-26T19:58:15.289Z,1756238295.289 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-08-26T19:58:15.649Z,1756238295.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-08-26T19:58:15.651Z,1756238295.651 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2025-08-26T19:58:15.652Z,1756238295.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2025-08-26T19:58:15.746Z,1756238295.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2025-08-26T19:58:15.925Z,1756238295.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2025-08-26T19:58:16.042Z,1756238296.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2025-08-26T19:58:16.147Z,1756238296.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2025-08-26T19:58:16.263Z,1756238296.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2025-08-26T19:58:16.357Z,1756238296.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2025-08-26T19:58:16.461Z,1756238296.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2025-08-26T19:58:16.582Z,1756238296.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2025-08-26T19:58:16.916Z,1756238296.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-08-26T19:58:16.917Z,1756238296.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2025-08-26T19:58:17.097Z,1756238297.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2025-08-26T19:58:17.383Z,1756238297.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2025-08-26T19:58:17.747Z,1756238297.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2025-08-26T19:58:17.980Z,1756238297.980 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2025-08-26T19:58:17.981Z,1756238297.981 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-08-26T19:58:17.998Z,1756238297.998 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-08-26T19:58:18.306Z,1756238298.306 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-08-26T19:58:18.307Z,1756238298.307 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-08-26T19:58:18.364Z,1756238298.364 [VerticalControl](DEBUG): Construct VerticalControl. 2025-08-26T19:58:18.427Z,1756238298.427 [VerticalControl] Loaded 2025-08-26T19:58:18.428Z,1756238298.428 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-08-26T19:58:18.431Z,1756238298.431 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-08-26T19:58:18.480Z,1756238298.480 [HorizontalControl] Loaded 2025-08-26T19:58:18.481Z,1756238298.481 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-08-26T19:58:18.483Z,1756238298.483 [SpeedControl](DEBUG): Construct SpeedControl. 2025-08-26T19:58:18.486Z,1756238298.486 [SpeedControl] Loaded 2025-08-26T19:58:18.487Z,1756238298.487 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-08-26T19:58:18.489Z,1756238298.489 [LoopControl](DEBUG): Construct LoopControl. 2025-08-26T19:58:18.490Z,1756238298.490 [LoopControl] Loaded 2025-08-26T19:58:18.490Z,1756238298.490 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-08-26T19:58:18.491Z,1756238298.491 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-08-26T19:58:18.491Z,1756238298.491 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-08-26T19:58:18.544Z,1756238298.544 [DepthRateCalculator] Loaded 2025-08-26T19:58:18.545Z,1756238298.545 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-08-26T19:58:18.550Z,1756238298.550 [PitchRateCalculator] Loaded 2025-08-26T19:58:18.550Z,1756238298.550 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-08-26T19:58:18.561Z,1756238298.561 [SpeedCalculator] Loaded 2025-08-26T19:58:18.561Z,1756238298.561 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-08-26T19:58:18.565Z,1756238298.565 [YawRateCalculator] Loaded 2025-08-26T19:58:18.565Z,1756238298.565 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-08-26T19:58:18.585Z,1756238298.585 [ElevatorOffsetCalculator] Loaded 2025-08-26T19:58:18.585Z,1756238298.585 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-08-26T19:58:18.585Z,1756238298.585 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-08-26T19:58:18.586Z,1756238298.586 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-08-26T19:58:18.617Z,1756238298.617 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-08-26T19:58:18.617Z,1756238298.617 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-08-26T19:58:18.680Z,1756238298.680 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-08-26T19:58:18.681Z,1756238298.681 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-08-26T19:58:19.049Z,1756238299.049 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-08-26T19:58:19.050Z,1756238299.050 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-08-26T19:58:19.240Z,1756238299.240 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-08-26T19:58:19.240Z,1756238299.240 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-08-26T19:58:19.767Z,1756238299.767 [AHRS_M2] Loaded 2025-08-26T19:58:19.767Z,1756238299.767 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-08-26T19:58:19.807Z,1756238299.807 [BackseatComponent] Loaded 2025-08-26T19:58:19.807Z,1756238299.807 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-08-26T19:58:19.808Z,1756238299.808 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0 2025-08-26T19:58:19.809Z,1756238299.809 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1124 2025-08-26T19:58:19.812Z,1756238299.812 [LcmUniversalReporter] Loaded 2025-08-26T19:58:19.812Z,1756238299.812 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-08-26T19:58:20.672Z,1756238300.672 [BPC1] Loaded 2025-08-26T19:58:20.673Z,1756238300.673 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-08-26T19:58:20.773Z,1756238300.773 [DAT] Loaded 2025-08-26T19:58:20.773Z,1756238300.773 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-08-26T19:58:20.775Z,1756238300.775 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0 2025-08-26T19:58:20.775Z,1756238300.775 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1125 2025-08-26T19:58:20.849Z,1756238300.849 [DataOverHttps] Loaded 2025-08-26T19:58:20.849Z,1756238300.849 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-08-26T19:58:20.850Z,1756238300.850 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0 2025-08-26T19:58:20.851Z,1756238300.851 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1126 2025-08-26T19:58:20.875Z,1756238300.875 [Depth_Keller] Loaded 2025-08-26T19:58:20.875Z,1756238300.875 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-08-26T19:58:20.881Z,1756238300.881 [DropWeight] Loaded 2025-08-26T19:58:20.881Z,1756238300.881 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-08-26T19:58:20.948Z,1756238300.948 [NAL9602] Loaded 2025-08-26T19:58:20.948Z,1756238300.948 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-08-26T19:58:20.979Z,1756238300.979 [Onboard] Loaded 2025-08-26T19:58:20.979Z,1756238300.979 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-08-26T19:58:20.980Z,1756238300.980 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0 2025-08-26T19:58:20.980Z,1756238300.980 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1127 2025-08-26T19:58:20.995Z,1756238300.995 [Power24vConverter] Loaded 2025-08-26T19:58:20.995Z,1756238300.995 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-08-26T19:58:21.012Z,1756238301.012 [Radio_Surface] Loaded 2025-08-26T19:58:21.012Z,1756238301.012 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-08-26T19:58:21.013Z,1756238301.013 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0 2025-08-26T19:58:21.013Z,1756238301.013 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1128 2025-08-26T19:58:21.062Z,1756238301.062 [RDI_Pathfinder] Loaded 2025-08-26T19:58:21.062Z,1756238301.062 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-08-26T19:58:21.078Z,1756238301.078 [Sonardyne_Nano] Loaded 2025-08-26T19:58:21.078Z,1756238301.078 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-08-26T19:58:21.079Z,1756238301.079 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-08-26T19:58:21.080Z,1756238301.080 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-08-26T19:58:21.198Z,1756238301.198 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-08-26T19:58:21.199Z,1756238301.199 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-08-26T19:58:21.213Z,1756238301.213 [NavChart] Loaded 2025-08-26T19:58:21.213Z,1756238301.213 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-08-26T19:58:21.219Z,1756238301.219 [UniversalFixResidualReporter] Loaded 2025-08-26T19:58:21.219Z,1756238301.219 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-08-26T19:58:21.220Z,1756238301.220 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-08-26T19:58:21.221Z,1756238301.221 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-08-26T19:58:21.393Z,1756238301.393 [SBIT](DEBUG): Construct Startup Built In Test. 2025-08-26T19:58:21.403Z,1756238301.403 [SBIT] Loaded 2025-08-26T19:58:21.403Z,1756238301.403 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-08-26T19:58:21.406Z,1756238301.406 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-08-26T19:58:21.419Z,1756238301.419 [IBIT] Loaded 2025-08-26T19:58:21.419Z,1756238301.419 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-08-26T19:58:21.425Z,1756238301.425 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-08-26T19:58:21.510Z,1756238301.510 [CBIT] Loaded 2025-08-26T19:58:21.510Z,1756238301.510 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-08-26T19:58:21.544Z,1756238301.544 [GFScanner] Loaded 2025-08-26T19:58:21.544Z,1756238301.544 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-08-26T19:58:21.545Z,1756238301.545 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-08-26T19:58:21.545Z,1756238301.545 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-08-26T19:58:21.691Z,1756238301.691 [BuoyancyServo] Loaded 2025-08-26T19:58:21.691Z,1756238301.691 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-08-26T19:58:21.716Z,1756238301.716 [ElevatorServo] Loaded 2025-08-26T19:58:21.716Z,1756238301.716 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-08-26T19:58:21.746Z,1756238301.746 [DockingStepper] Loaded 2025-08-26T19:58:21.747Z,1756238301.747 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-08-26T19:58:21.770Z,1756238301.770 [MassServo] Loaded 2025-08-26T19:58:21.770Z,1756238301.770 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-08-26T19:58:21.791Z,1756238301.791 [RudderServo] Loaded 2025-08-26T19:58:21.792Z,1756238301.792 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-08-26T19:58:21.812Z,1756238301.812 [ThrusterServo] Loaded 2025-08-26T19:58:21.812Z,1756238301.812 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2025-08-26T19:58:21.813Z,1756238301.813 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-08-26T19:58:21.813Z,1756238301.813 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-08-26T19:58:21.824Z,1756238301.824 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-08-26T19:58:21.824Z,1756238301.824 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-08-26T19:58:22.008Z,1756238302.008 [CTD_Seabird] Loaded 2025-08-26T19:58:22.008Z,1756238302.008 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-08-26T19:58:22.009Z,1756238302.009 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0 2025-08-26T19:58:22.009Z,1756238302.009 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1129 2025-08-26T19:58:22.033Z,1756238302.033 [PAR_Licor] Loaded 2025-08-26T19:58:22.034Z,1756238302.034 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-08-26T19:58:22.081Z,1756238302.081 [WetLabsBB2FL] Loaded 2025-08-26T19:58:22.081Z,1756238302.081 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-08-26T19:58:22.082Z,1756238302.082 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0 2025-08-26T19:58:22.082Z,1756238302.082 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1130 2025-08-26T19:58:22.104Z,1756238302.104 [WetLabsUBAT] Loaded 2025-08-26T19:58:22.105Z,1756238302.105 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2025-08-26T19:58:22.106Z,1756238302.106 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2025-08-26T19:58:22.106Z,1756238302.106 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1131 2025-08-26T19:58:22.107Z,1756238302.107 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-08-26T19:58:22.161Z,1756238302.161 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-08-26T19:58:22.167Z,1756238302.167 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-08-26T19:58:22.179Z,1756238302.179 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-08-26T19:58:22.182Z,1756238302.182 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9D4E0 2025-08-26T19:58:22.183Z,1756238302.183 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1132 2025-08-26T19:58:22.680Z,1756238302.680 [Supervisor](INFO): Main Thread ID is 831 2025-08-26T19:58:22.680Z,1756238302.680 [Supervisor](DEBUG): Running supervisor. 2025-08-26T19:58:22.681Z,1756238302.681 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1133 2025-08-26T19:58:22.682Z,1756238302.682 [CommandExec](INFO): Initializing the command executive. 2025-08-26T19:58:22.683Z,1756238302.683 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1134 2025-08-26T19:58:22.720Z,1756238302.720 [controlThread ThreadHandler](INFO): Handler Thread ID is 1135 2025-08-26T19:58:22.721Z,1756238302.721 [controlThread](DEBUG): Initializing ControlThread 2025-08-26T19:58:22.721Z,1756238302.721 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-08-26T19:58:22.723Z,1756238302.723 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-08-26T19:58:22.724Z,1756238302.724 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-08-26T19:58:22.724Z,1756238302.724 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-08-26T19:58:22.724Z,1756238302.724 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-08-26T19:58:22.725Z,1756238302.725 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-08-26T19:58:22.725Z,1756238302.725 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-08-26T19:58:22.725Z,1756238302.725 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-08-26T19:58:22.726Z,1756238302.726 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-08-26T19:58:22.729Z,1756238302.729 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-08-26T19:58:22.729Z,1756238302.729 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-08-26T19:58:22.730Z,1756238302.730 [SBIT](INFO): Initialize SBIT Component. 2025-08-26T19:58:22.730Z,1756238302.730 [SBIT](IMPORTANT): git: 2025-08-26 2025-08-26T19:58:22.730Z,1756238302.730 [SBIT](INFO): git hash: bb7b608ae095ad33f3a1ba1dc4d6b0b36a605f14 2025-08-26T19:58:22.731Z,1756238302.731 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-08-26T19:58:22.732Z,1756238302.732 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-08-26T19:58:22.733Z,1756238302.733 [SBIT](INFO): Beginning SBIT in 93.000000 seconds. 2025-08-26T19:58:22.733Z,1756238302.733 [IBIT](INFO): Initialize IBIT Component. 2025-08-26T19:58:22.734Z,1756238302.734 [CBIT](DEBUG): Initialize CBIT Component. 2025-08-26T19:58:22.744Z,1756238302.744 [logger ThreadHandler](INFO): Handler Thread ID is 1136 2025-08-26T19:58:22.752Z,1756238302.752 [CBIT](DEBUG): Initialized mux pins. 2025-08-26T19:58:22.752Z,1756238302.752 [CBIT](DEBUG): Initializing the watchdog timer. 2025-08-26T19:58:22.776Z,1756238302.776 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-08-26T19:58:22.776Z,1756238302.776 [CBIT](DEBUG): Initializing heartbeat. 2025-08-26T19:58:22.777Z,1756238302.777 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1137 2025-08-26T19:58:22.779Z,1756238302.779 [DAT ThreadHandler](INFO): Handler Thread ID is 1138 2025-08-26T19:58:22.779Z,1756238302.779 [DAT](INFO): Powering up 2025-08-26T19:58:22.780Z,1756238302.780 [DAT](DEBUG): Initializing DAT. 2025-08-26T19:58:22.786Z,1756238302.786 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1140 2025-08-26T19:58:22.788Z,1756238302.788 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-08-26T19:58:22.796Z,1756238302.796 [Onboard ThreadHandler](INFO): Handler Thread ID is 1141 2025-08-26T19:58:22.814Z,1756238302.814 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1144 2025-08-26T19:58:22.825Z,1756238302.825 [WetLabsBB2FL](INFO): Powering up 2025-08-26T19:58:22.827Z,1756238302.827 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1142 2025-08-26T19:58:22.828Z,1756238302.828 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1146 2025-08-26T19:58:22.830Z,1756238302.830 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1143 2025-08-26T19:58:22.830Z,1756238302.830 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-08-26T19:58:22.833Z,1756238302.833 [WetLabsUBAT](INFO): Powering up 2025-08-26T19:58:22.834Z,1756238302.834 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1149 2025-08-26T19:58:22.837Z,1756238302.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-08-26T19:58:22.837Z,1756238302.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-08-26T19:58:22.838Z,1756238302.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-08-26T19:58:22.838Z,1756238302.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-08-26T19:58:22.838Z,1756238302.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-08-26T19:58:22.838Z,1756238302.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-08-26T19:58:22.838Z,1756238302.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-08-26T19:58:22.838Z,1756238302.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-08-26T19:58:22.848Z,1756238302.848 [CBIT](DEBUG): Deactivating emergency mode. 2025-08-26T19:58:22.888Z,1756238302.888 [CBIT](DEBUG): Backplane powered. 2025-08-26T19:58:22.888Z,1756238302.888 [GFScanner](DEBUG): Initializing GFScanner 2025-08-26T19:58:22.888Z,1756238302.888 [GFScanner](DEBUG): Deactivating GF circuits. 2025-08-26T19:58:22.896Z,1756238302.896 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-08-26T19:58:22.897Z,1756238302.897 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2025-08-26T19:58:22.897Z,1756238302.897 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2025-08-26T19:58:22.899Z,1756238302.899 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2025-08-26T19:58:22.905Z,1756238302.905 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2025-08-26T19:58:22.905Z,1756238302.905 [MissionManager](ERROR): Top node has no child nodes: Mission 2025-08-26T19:58:22.905Z,1756238302.905 [MissionManager](ERROR): Could not load startup mission. 2025-08-26T19:58:22.906Z,1756238302.906 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2025-08-26T19:58:22.906Z,1756238302.906 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2025-08-26T19:58:22.907Z,1756238302.907 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2025-08-26T19:58:23.236Z,1756238303.236 [Radio_Surface](INFO): Powering up 2025-08-26T19:58:23.527Z,1756238303.527 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into: 0 6 10 1 3 30 Timed out while trying to detach from dock at current depth of . Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-08-26T19:58:23.551Z,1756238303.551 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2025-08-26T19:58:23.555Z,1756238303.555 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2025-08-26T19:58:23.579Z,1756238303.579 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2025-08-26T19:58:23.583Z,1756238303.583 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2025-08-26T19:58:23.603Z,1756238303.603 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2025-08-26T19:58:23.607Z,1756238303.607 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2025-08-26T19:58:23.624Z,1756238303.624 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2025-08-26T19:58:23.782Z,1756238303.782 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2025-08-26T19:58:23.782Z,1756238303.782 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-26T19:58:23.852Z,1756238303.852 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-08-26T19:58:23.890Z,1756238303.890 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-08-26T19:58:23.897Z,1756238303.897 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-08-26T19:58:23.915Z,1756238303.915 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2025-08-26T19:58:23.936Z,1756238303.936 [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 DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 6 hour """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 10 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ DriftTimeout = 3 minute """ How long to allow vehicle to drift away from dock before retrying reverse """ } 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 = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run while ( Universal:depth > Control:VerticalControl.surfaceThreshold ) set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence set driftTimeout = DriftTimeout } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used 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=P2H { 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-08-26T19:58:23.942Z,1756238303.942 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-08-26T19:58:23.962Z,1756238303.962 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-08-26T19:58:24.024Z,1756238304.024 [Depth_Keller](INFO): Initializing. 2025-08-26T19:58:24.026Z,1756238304.026 [Power24vConverter](INFO): Powering up. 2025-08-26T19:58:24.026Z,1756238304.026 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-08-26T19:58:24.033Z,1756238304.033 [Sonardyne_Nano](INFO): Initializing. 2025-08-26T19:58:24.077Z,1756238304.077 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-08-26T19:58:24.103Z,1756238304.103 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-08-26T19:58:24.120Z,1756238304.120 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-08-26T19:58:24.121Z,1756238304.121 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-08-26T19:58:24.128Z,1756238304.128 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-08-26T19:58:24.129Z,1756238304.129 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-26T19:58:24.136Z,1756238304.136 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-26T19:58:24.137Z,1756238304.137 [MassServo](DEBUG): Initializing EZServoServo. 2025-08-26T19:58:24.144Z,1756238304.144 [MassServo](DEBUG): Initializing MassServo. 2025-08-26T19:58:24.145Z,1756238304.145 [RudderServo](DEBUG): Initializing EZServoServo. 2025-08-26T19:58:24.152Z,1756238304.152 [RudderServo](DEBUG): Initializing RudderServo. 2025-08-26T19:58:24.153Z,1756238304.153 [ThrusterServo](DEBUG): Initializing EZServoServo. 2025-08-26T19:58:24.160Z,1756238304.160 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2025-08-26T19:58:24.389Z,1756238304.389 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m 2025-08-26T19:58:24.405Z,1756238304.405 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-08-26T19:58:25.432Z,1756238305.432 [WetLabsBB2FL](INFO): Powering down 2025-08-26T19:58:25.967Z,1756238305.967 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-08-26T19:58:27.612Z,1756238307.612 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2025-08-26T19:58:28.492Z,1756238308.492 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-08-26T19:58:28.493Z,1756238308.493 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0 2025-08-26T19:58:30.865Z,1756238310.865 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-08-26T19:58:32.076Z,1756238312.076 [WetLabsUBAT](INFO): Powering down 2025-08-26T19:58:35.129Z,1756238315.129 [DAT](INFO): DAT read: 2025-08-26T19:58:35.130Z,1756238315.130 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-08-26T19:58:40.673Z,1756238320.673 [DAT](INFO): DAT read: MF Frequency Band 2025-08-26T19:58:40.674Z,1756238320.674 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2025-08-26T19:58:40.675Z,1756238320.675 [DAT](INFO): DAT read: Aug 26 2025 19:58:30 2025-08-26T19:58:41.681Z,1756238321.681 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-08-26T19:58:41.682Z,1756238321.682 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-08-26T19:58:41.683Z,1756238321.683 [DAT](INFO): commRate: 600 2025-08-26T19:58:43.748Z,1756238323.748 [DAT](INFO): entering command mode 2025-08-26T19:58:43.949Z,1756238323.949 [DAT](INFO): DAT read: 2025-08-26T19:58:43.949Z,1756238323.949 [DAT](INFO): DAT read: user:1> 2025-08-26T19:58:43.950Z,1756238323.950 [DAT](INFO): setting verbose to 3 2025-08-26T19:58:44.201Z,1756238324.201 [DAT](INFO): DAT read: user:1> 2025-08-26T19:58:44.202Z,1756238324.202 [DAT](INFO): DAT read: Verbose | 3 2025-08-26T19:58:44.202Z,1756238324.202 [DAT](INFO): set verbose to 3 2025-08-26T19:58:44.202Z,1756238324.202 [DAT](INFO): setting DatVerbose to 27440 2025-08-26T19:58:44.453Z,1756238324.453 [DAT](INFO): DAT read: user:2> 2025-08-26T19:58:44.454Z,1756238324.454 [DAT](INFO): DAT read: DatVerbose | 27440 2025-08-26T19:58:44.454Z,1756238324.454 [DAT](INFO): set DatVerbose to 27440 2025-08-26T19:58:44.454Z,1756238324.454 [DAT](INFO): setting transmit power to 8 2025-08-26T19:58:44.705Z,1756238324.705 [DAT](INFO): DAT read: user:3> 2025-08-26T19:58:44.706Z,1756238324.706 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-08-26T19:58:44.706Z,1756238324.706 [DAT](INFO): set transmit power to 8 2025-08-26T19:58:44.706Z,1756238324.706 [DAT](INFO): setting local address to 10 2025-08-26T19:58:44.957Z,1756238324.957 [DAT](INFO): DAT read: user:4> 2025-08-26T19:58:44.958Z,1756238324.958 [DAT](INFO): DAT read: LocalAddr | 10 2025-08-26T19:58:44.958Z,1756238324.958 [DAT](INFO): set local address to 10 2025-08-26T19:58:44.959Z,1756238324.959 [DAT](INFO): Setting time to: 19:58:44 And date to:8/26/2025 2025-08-26T19:58:45.209Z,1756238325.209 [DAT](INFO): DAT read: user:5> 2025-08-26T19:58:45.210Z,1756238325.210 [DAT](INFO): DAT read: Tue Aug 26, 2025 19:58:44 2025-08-26T19:58:45.210Z,1756238325.210 [DAT](INFO): Local DAT time set to Tue Aug 26, 2025 19:58:44 2025-08-26T19:58:51.254Z,1756238331.254 [NAL9602](INFO): Powering up NAL9602 2025-08-26T19:58:53.908Z,1756238333.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T19:59:02.157Z,1756238342.157 [NAL9602](INFO): NAL9602 initialized 2025-08-26T19:59:25.033Z,1756238365.033 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T19:59:43.780Z,1756238383.780 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-3278,V 2025-08-26T19:59:56.168Z,1756238396.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T19:59:56.329Z,1756238396.329 [SBIT](IMPORTANT): Beginning Startup BIT 2025-08-26T19:59:56.333Z,1756238396.333 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-08-26T20:00:00.865Z,1756238400.865 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.009303 CHAN A1 (24V): -0.027925 CHAN A2 (12V): -0.007318 CHAN A3 (5V): -0.002151 CHAN B0 (3.3V): 0.000244 CHAN B1 (3.15aV): 0.000039 CHAN B2 (3.15bV): -0.000199 CHAN B3 (GND): 0.000876 OPEN: 0.004837 Full Scale: +/- 1 mA 2025-08-26T20:00:04.841Z,1756238404.841 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T20:00:27.296Z,1756238427.296 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:00:35.942Z,1756238435.942 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.781546 2025-08-26T20:00:35.942Z,1756238435.942 [SBIT](FAULT): Control surface position failure. 2025-08-26T20:00:49.795Z,1756238449.795 [SBIT](CRITICAL): SBIT FAILED 2025-08-26T20:00:49.795Z,1756238449.795 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-08-26T20:00:49.795Z,1756238449.795 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere; 2025-08-26T20:00:49.796Z,1756238449.796 [SBIT](IMPORTANT): Docked.verbose=0 bool; 2025-08-26T20:00:49.796Z,1756238449.796 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count; 2025-08-26T20:00:49.796Z,1756238449.796 [SBIT](IMPORTANT): DockingStepper.slideValue=10700 count; 2025-08-26T20:00:49.796Z,1756238449.796 [SBIT](IMPORTANT): Express mostRecent WetLabsUBAT.average_bioluminescence; 2025-08-26T20:00:49.796Z,1756238449.796 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-1 ampere_hour; 2025-08-26T20:00:49.796Z,1756238449.796 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=1 minute; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): Normal linearApproximation _.dinoflagellates 10000000000.000000 none; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): RudderServo.offsetAngle=1 degree; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): SpeedControl.rollOptimum=1.025000 m/s; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=415.338195 cubic_centimeter; 2025-08-26T20:00:49.797Z,1756238449.797 [SBIT](IMPORTANT): VerticalControl.massDefault=18.468839 millimeter; 2025-08-26T20:00:49.799Z,1756238449.799 [CommandExec](FAULT): Scheduling is paused 2025-08-26T20:00:49.799Z,1756238449.799 [CBIT](INFO): Critical error at 20250826T200049 2025-08-26T20:00:49.799Z,1756238449.799 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-08-26T20:00:50.178Z,1756238450.178 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-08-26T20:00:50.178Z,1756238450.178 [DefaultWithUndock] Running Loop=1 2025-08-26T20:00:50.178Z,1756238450.178 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-08-26T20:00:50.179Z,1756238450.179 [DefaultWithUndock:A.Wait] Running Loop=1 2025-08-26T20:00:50.179Z,1756238450.179 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:00:58.428Z,1756238458.428 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:01:11.639Z,1756238471.639 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:A.Wait] Stopped 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-08-26T20:01:20.466Z,1756238480.466 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-08-26T20:01:20.467Z,1756238480.467 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-08-26T20:01:20.467Z,1756238480.467 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-26T20:01:20.467Z,1756238480.467 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-26T20:01:20.867Z,1756238480.867 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-26T20:01:20.867Z,1756238480.867 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-26T20:01:20.868Z,1756238480.868 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.256756 m. 2025-08-26T20:01:20.869Z,1756238480.869 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-08-26T20:01:20.869Z,1756238480.869 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-26T20:01:21.276Z,1756238481.276 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-26T20:01:21.714Z,1756238481.714 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-26T20:01:21.833Z,1756238481.833 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-26T20:01:22.909Z,1756238482.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-08-26T20:01:22.909Z,1756238482.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-26T20:01:23.050Z,1756238483.050 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-26T20:01:23.355Z,1756238483.355 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-26T20:01:23.355Z,1756238483.355 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-08-26T20:01:29.561Z,1756238489.561 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:01:41.483Z,1756238501.483 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-08-26T20:01:41.905Z,1756238501.905 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-08-26T20:01:42.335Z,1756238502.335 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-26T20:01:42.335Z,1756238502.335 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-08-26T20:01:42.335Z,1756238502.335 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-08-26T20:01:42.336Z,1756238502.336 [DefaultWithUndock:LeaveDock] Stopped 2025-08-26T20:01:42.336Z,1756238502.336 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-08-26T20:01:42.336Z,1756238502.336 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-08-26T20:01:42.336Z,1756238502.336 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-08-26T20:01:42.336Z,1756238502.336 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-08-26T20:01:42.336Z,1756238502.336 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-26T20:01:42.337Z,1756238502.337 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-26T20:01:42.337Z,1756238502.337 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-26T20:01:42.337Z,1756238502.337 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-26T20:01:42.338Z,1756238502.338 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-26T20:01:42.338Z,1756238502.338 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-26T20:01:42.338Z,1756238502.338 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-26T20:01:42.691Z,1756238502.691 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-08-26T20:01:42.691Z,1756238502.691 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:01:42.691Z,1756238502.691 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:01:43.127Z,1756238503.127 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-08-26T20:02:00.697Z,1756238520.697 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:02:18.434Z,1756238538.434 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004266 2025-08-26T20:02:19.432Z,1756238539.432 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:02:41.116Z,1756238561.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:02:58.732Z,1756238578.732 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:03:18.984Z,1756238598.984 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:03:37.512Z,1756238617.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:03:56.188Z,1756238636.188 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:03:59.101Z,1756238639.101 [CommandExec](IMPORTANT): got command docking arm 2025-08-26T20:03:59.101Z,1756238639.101 [CommandExec](IMPORTANT): Switching docking module to ARM. 2025-08-26T20:04:00.571Z,1756238640.571 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-26T20:04:00.689Z,1756238640.689 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-26T20:04:04.192Z,1756238644.192 [DockingStepper](INFO): Changing to mode: 2 2025-08-26T20:04:14.664Z,1756238654.664 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:04:16.913Z,1756238656.913 [CommandExec](IMPORTANT): got command docking standby 2025-08-26T20:04:16.914Z,1756238656.914 [CommandExec](IMPORTANT): Switching docking module to STANDBY. 2025-08-26T20:04:17.153Z,1756238657.153 [DockingStepper](INFO): Changing to mode: 1 2025-08-26T20:04:24.162Z,1756238664.162 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-08-26T20:04:24.162Z,1756238664.162 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-26T20:04:24.241Z,1756238664.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-26T20:04:24.573Z,1756238664.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-26T20:04:24.573Z,1756238664.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-08-26T20:04:26.468Z,1756238666.468 [CommandExec](IMPORTANT): got command docking unrequest 2025-08-26T20:04:26.468Z,1756238666.468 [CommandExec](IMPORTANT): Setting data request false. 2025-08-26T20:04:33.549Z,1756238673.549 [CommandExec](IMPORTANT): got command strobe off 2025-08-26T20:04:33.549Z,1756238673.549 [CommandExec](IMPORTANT): Deactivating strobe 2025-08-26T20:04:35.620Z,1756238675.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:04:54.205Z,1756238694.205 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:05:14.084Z,1756238714.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:05:32.628Z,1756238732.628 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:05:51.328Z,1756238751.328 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:06:09.872Z,1756238769.872 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:06:28.532Z,1756238788.532 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:06:42.695Z,1756238802.695 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2025-08-26T20:01:42.7Z 2025-08-26T20:06:42.695Z,1756238802.695 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:06:42.695Z,1756238802.695 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:06:43.106Z,1756238803.106 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-08-26T20:06:52.875Z,1756238812.875 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250826T194544/Courier0004.lzma 2025-08-26T20:07:03.918Z,1756238823.918 [DataOverHttps](INFO): Moved sent file to Logs/20250826T194544/Courier0004.lzma.bak 2025-08-26T20:07:03.918Z,1756238823.918 [DataOverHttps](INFO): SBD MOMSN=25895324 2025-08-26T20:07:25.108Z,1756238845.108 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-08-26T20:07:25.108Z,1756238845.108 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-26T20:07:25.119Z,1756238845.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-26T20:07:25.683Z,1756238845.683 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-26T20:07:25.683Z,1756238845.683 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-08-26T20:07:30.121Z,1756238850.121 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20250826T195810/Courier0000.lzma 2025-08-26T20:07:43.170Z,1756238863.170 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0000.lzma.bak 2025-08-26T20:07:43.170Z,1756238863.170 [DataOverHttps](INFO): SBD MOMSN=25895326 2025-08-26T20:08:11.524Z,1756238891.524 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20250826T194544/Express0005.lzma 2025-08-26T20:08:22.574Z,1756238902.574 [DataOverHttps](INFO): Moved sent file to Logs/20250826T194544/Express0005.lzma.bak 2025-08-26T20:08:22.574Z,1756238902.574 [DataOverHttps](INFO): SBD MOMSN=25895330 2025-08-26T20:08:53.387Z,1756238933.387 [DataOverHttps](INFO): Sending 1430 bytes from file Logs/20250826T195810/Express0001.lzma 2025-08-26T20:09:04.167Z,1756238944.167 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-08-26T20:09:04.167Z,1756238944.167 [NAL9602] Data Fault, FailCount= 1 2025-08-26T20:09:04.167Z,1756238944.167 [NAL9602](ERROR): Data Fault 2025-08-26T20:09:04.185Z,1756238944.185 [CBIT](ERROR): Data Fault in component: NAL9602 2025-08-26T20:09:04.422Z,1756238944.422 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0001.lzma.bak 2025-08-26T20:09:04.422Z,1756238944.422 [DataOverHttps](INFO): SBD MOMSN=25895349 2025-08-26T20:09:04.581Z,1756238944.581 [NAL9602](INFO): Powering down 2025-08-26T20:09:05.391Z,1756238945.391 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-26T20:09:05.391Z,1756238945.391 [NAL9602] No Fault, FailCount= 1 2025-08-26T20:09:07.414Z,1756238947.414 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:09:07.414Z,1756238947.414 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:09:07.414Z,1756238947.414 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:09:34.865Z,1756238974.865 [NAL9602](INFO): Powering up NAL9602 2025-08-26T20:09:45.529Z,1756238985.529 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:09:45.771Z,1756238985.771 [NAL9602](INFO): NAL9602 initialized 2025-08-26T20:10:05.644Z,1756239005.644 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:10:24.197Z,1756239024.197 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:10:26.234Z,1756239026.234 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-08-26T20:10:26.234Z,1756239026.234 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-26T20:10:26.276Z,1756239026.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-26T20:10:26.647Z,1756239026.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-26T20:10:26.647Z,1756239026.647 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-08-26T20:10:43.952Z,1756239043.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:10:53.665Z,1756239053.665 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768, 2025-08-26T20:11:02.468Z,1756239062.468 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:11:20.981Z,1756239080.981 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:11:39.504Z,1756239099.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:11:58.052Z,1756239118.052 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:12:16.616Z,1756239136.616 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:12:38.076Z,1756239158.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:12:56.756Z,1756239176.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:13:15.376Z,1756239195.376 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:13:27.064Z,1756239207.064 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-08-26T20:13:27.064Z,1756239207.064 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-26T20:13:27.075Z,1756239207.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-26T20:13:27.545Z,1756239207.545 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-26T20:13:27.545Z,1756239207.545 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-08-26T20:13:33.988Z,1756239213.988 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:13:56.464Z,1756239236.464 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:14:00.999Z,1756239240.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201400.00,A,3648.16833,N,12147.26645,W,3.460,111.16,260825,,,A*73 2025-08-26T20:14:01.002Z,1756239241.002 [NAL9602](INFO): GPS fix at 20250826T201400: (36.802805, -121.787774) 2025-08-26T20:14:07.904Z,1756239247.904 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:14:07.904Z,1756239247.904 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:14:07.904Z,1756239247.904 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:14:07.904Z,1756239247.904 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:14:08.288Z,1756239248.288 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:14:08.288Z,1756239248.288 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:14:08.692Z,1756239248.692 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 13.301830 min 2025-08-26T20:14:08.692Z,1756239248.692 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:14:08.692Z,1756239248.692 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:14:08.692Z,1756239248.692 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:14:08.692Z,1756239248.692 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:14:08.693Z,1756239248.693 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2025-08-26T20:14:08.693Z,1756239248.693 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2025-08-26T20:14:08.693Z,1756239248.693 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:14:08.693Z,1756239248.693 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:14:10.699Z,1756239250.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201410.00,A,3648.16619,N,12147.25664,W,3.188,117.51,260825,,,A*72 2025-08-26T20:14:10.701Z,1756239250.701 [NAL9602](INFO): GPS fix at 20250826T201410: (36.802770, -121.787611) 2025-08-26T20:14:10.734Z,1756239250.734 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:14:10.735Z,1756239250.735 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:14:32.053Z,1756239272.053 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20250826T195810/Courier0004.lzma 2025-08-26T20:14:42.898Z,1756239282.898 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:14:43.106Z,1756239283.106 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0004.lzma.bak 2025-08-26T20:14:43.106Z,1756239283.106 [DataOverHttps](INFO): SBD MOMSN=25895399 2025-08-26T20:15:00.542Z,1756239300.542 [CommandExec](IMPORTANT): got command ibit 2025-08-26T20:15:00.691Z,1756239300.691 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-08-26T20:15:00.691Z,1756239300.691 [IBIT](IMPORTANT): Beginning control surface checks. 2025-08-26T20:15:00.694Z,1756239300.694 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-08-26T20:15:02.283Z,1756239302.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201501.00,A,3648.14200,N,12147.22880,W,3.207,40.82,260825,,,A*47 2025-08-26T20:15:02.293Z,1756239302.293 [NAL9602](INFO): GPS fix at 20250826T201501: (36.802367, -121.787147) 2025-08-26T20:15:05.244Z,1756239305.244 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.024119 CHAN A1 (24V): 0.026172 CHAN A2 (12V): 0.015356 CHAN A3 (5V): 0.001553 CHAN B0 (3.3V): -0.000384 CHAN B1 (3.15aV): -0.001702 CHAN B2 (3.15bV): -0.001571 CHAN B3 (GND): -0.006887 OPEN: 0.006062 Full Scale: +/- 1 mA 2025-08-26T20:15:11.568Z,1756239311.568 [DataOverHttps](INFO): Sending 440 bytes from file Logs/20250826T195810/Express0005.lzma 2025-08-26T20:15:19.728Z,1756239319.728 [NAL9602](INFO): SBD MO Status=0, MOMSN=49612, MT Status=0, MTMSN=0 2025-08-26T20:15:19.729Z,1756239319.729 [NAL9602](INFO): No messages in MT queue 2025-08-26T20:15:20.915Z,1756239320.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201520.00,A,3648.15105,N,12147.22427,W,0.039,33.63,260825,,,A*45 2025-08-26T20:15:20.917Z,1756239320.917 [NAL9602](INFO): GPS fix at 20250826T201520: (36.802518, -121.787071) 2025-08-26T20:15:22.611Z,1756239322.611 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0005.lzma.bak 2025-08-26T20:15:22.611Z,1756239322.611 [DataOverHttps](INFO): SBD MOMSN=25895402 2025-08-26T20:15:23.747Z,1756239323.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201523.00,A,3648.15118,N,12147.22487,W,0.350,33.63,260825,,,A*4C 2025-08-26T20:15:23.749Z,1756239323.749 [NAL9602](INFO): GPS fix at 20250826T201523: (36.802520, -121.787081) 2025-08-26T20:15:27.111Z,1756239327.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201526.00,A,3648.15131,N,12147.22554,W,0.311,33.63,260825,,,A*48 2025-08-26T20:15:27.113Z,1756239327.113 [NAL9602](INFO): GPS fix at 20250826T201526: (36.802522, -121.787092) 2025-08-26T20:15:29.935Z,1756239329.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201529.00,A,3648.15131,N,12147.22574,W,0.117,33.63,260825,,,A*41 2025-08-26T20:15:29.942Z,1756239329.942 [NAL9602](INFO): GPS fix at 20250826T201529: (36.802522, -121.787096) 2025-08-26T20:15:32.743Z,1756239332.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201532.00,A,3648.15127,N,12147.22595,W,0.117,33.63,260825,,,A*43 2025-08-26T20:15:32.745Z,1756239332.745 [NAL9602](INFO): GPS fix at 20250826T201532: (36.802521, -121.787099) 2025-08-26T20:15:35.980Z,1756239335.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201535.00,A,3648.15122,N,12147.22613,W,0.233,33.63,260825,,,A*49 2025-08-26T20:15:35.982Z,1756239335.982 [NAL9602](INFO): GPS fix at 20250826T201535: (36.802520, -121.787102) 2025-08-26T20:15:38.812Z,1756239338.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201538.00,A,3648.15119,N,12147.22601,W,0.019,33.63,260825,,,A*45 2025-08-26T20:15:38.814Z,1756239338.814 [NAL9602](INFO): GPS fix at 20250826T201538: (36.802520, -121.787100) 2025-08-26T20:15:42.051Z,1756239342.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201541.00,A,3648.15115,N,12147.22600,W,0.039,33.63,260825,,,A*44 2025-08-26T20:15:42.053Z,1756239342.053 [NAL9602](INFO): GPS fix at 20250826T201541: (36.802519, -121.787100) 2025-08-26T20:15:44.867Z,1756239344.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201544.00,A,3648.15111,N,12147.22596,W,0.156,33.63,260825,,,A*41 2025-08-26T20:15:44.869Z,1756239344.869 [NAL9602](INFO): GPS fix at 20250826T201544: (36.802518, -121.787099) 2025-08-26T20:15:46.923Z,1756239346.923 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802517 Longitude: -121.787102 2025-08-26T20:15:47.341Z,1756239347.341 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-08-26T20:15:47.707Z,1756239347.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201547.00,A,3648.15106,N,12147.22606,W,0.058,33.63,260825,,,A*41 2025-08-26T20:15:47.713Z,1756239347.713 [NAL9602](INFO): GPS fix at 20250826T201547: (36.802518, -121.787101) 2025-08-26T20:15:47.831Z,1756239347.831 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-08-26T20:15:47.831Z,1756239347.831 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-08-26T20:15:47.840Z,1756239347.840 [IBIT](IMPORTANT): Pressure:10.509007 PSI 2025-08-26T20:15:47.840Z,1756239347.840 [IBIT](IMPORTANT): Humidity:16.560860 % 2025-08-26T20:15:48.153Z,1756239348.153 [IBIT](IMPORTANT): Vehicle Pitch:-1.271063 degrees 2025-08-26T20:15:48.154Z,1756239348.154 [IBIT](IMPORTANT): Vehicle Roll:2.790669 degrees 2025-08-26T20:15:48.154Z,1756239348.154 [IBIT](IMPORTANT): Vehicle Heading:8.638113 degrees 2025-08-26T20:15:48.555Z,1756239348.555 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-08-26T20:15:48.555Z,1756239348.555 [IBIT](IMPORTANT): buoyancyNeutral: 415.338196 cc 2025-08-26T20:15:48.568Z,1756239348.568 [IBIT](IMPORTANT): massDefault: 1.846884 cm 2025-08-26T20:15:48.568Z,1756239348.568 [IBIT](IMPORTANT): stopDepth: 215.000000 m 2025-08-26T20:15:48.569Z,1756239348.569 [IBIT](IMPORTANT): abortDepth: 255.000000 m 2025-08-26T20:15:48.569Z,1756239348.569 [IBIT](IMPORTANT): IBIT PASSED 2025-08-26T20:15:48.961Z,1756239348.961 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:15:48.961Z,1756239348.961 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:15:48.961Z,1756239348.961 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:16:09.688Z,1756239369.688 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:16:20.435Z,1756239380.435 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:16:27.329Z,1756239387.329 [DataOverHttps](IMPORTANT): SBD MTMSN=20250826T201627 2025-08-26T20:16:35.104Z,1756239395.104 [DataOverHttps](INFO): Received command: ibit 2025-08-26T20:16:35.105Z,1756239395.105 [CommandExec](IMPORTANT): got command ibit 2025-08-26T20:16:35.407Z,1756239395.407 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-08-26T20:16:35.412Z,1756239395.412 [IBIT](IMPORTANT): Beginning control surface checks. 2025-08-26T20:16:35.415Z,1756239395.415 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-08-26T20:16:36.979Z,1756239396.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201637.00,A,3648.15187,N,12147.22283,W,0.097,33.63,260825,,,A*46 2025-08-26T20:16:36.982Z,1756239396.982 [NAL9602](INFO): GPS fix at 20250826T201637: (36.802531, -121.787047) 2025-08-26T20:16:40.171Z,1756239400.171 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.023697 CHAN A1 (24V): 0.025989 CHAN A2 (12V): 0.014650 CHAN A3 (5V): 0.001112 CHAN B0 (3.3V): -0.001190 CHAN B1 (3.15aV): -0.001602 CHAN B2 (3.15bV): -0.001421 CHAN B3 (GND): -0.006895 OPEN: 0.005814 Full Scale: +/- 1 mA 2025-08-26T20:16:47.148Z,1756239407.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:17:04.239Z,1756239424.239 [NAL9602](INFO): SBD MO Status=2, MOMSN=49613, MT Status=2, MTMSN=0 2025-08-26T20:17:04.239Z,1756239424.239 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:17:05.840Z,1756239425.840 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:17:21.328Z,1756239441.328 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802532 Longitude: -121.787048 2025-08-26T20:17:21.752Z,1756239441.752 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-08-26T20:17:22.115Z,1756239442.115 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-08-26T20:17:22.116Z,1756239442.116 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-08-26T20:17:22.116Z,1756239442.116 [IBIT](IMPORTANT): Pressure:10.471193 PSI 2025-08-26T20:17:22.117Z,1756239442.117 [IBIT](IMPORTANT): Humidity:16.536442 % 2025-08-26T20:17:22.537Z,1756239442.537 [IBIT](IMPORTANT): Vehicle Pitch:-1.275533 degrees 2025-08-26T20:17:22.538Z,1756239442.538 [IBIT](IMPORTANT): Vehicle Roll:2.793350 degrees 2025-08-26T20:17:22.538Z,1756239442.538 [IBIT](IMPORTANT): Vehicle Heading:8.648659 degrees 2025-08-26T20:17:22.986Z,1756239442.986 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-08-26T20:17:22.986Z,1756239442.986 [IBIT](IMPORTANT): buoyancyNeutral: 415.338196 cc 2025-08-26T20:17:22.986Z,1756239442.986 [IBIT](IMPORTANT): massDefault: 1.846884 cm 2025-08-26T20:17:22.987Z,1756239442.987 [IBIT](IMPORTANT): stopDepth: 215.000000 m 2025-08-26T20:17:22.987Z,1756239442.987 [IBIT](IMPORTANT): abortDepth: 255.000000 m 2025-08-26T20:17:22.987Z,1756239442.987 [IBIT](IMPORTANT): IBIT PASSED 2025-08-26T20:17:24.976Z,1756239444.976 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:17:25.742Z,1756239445.742 [NAL9602](INFO): SBD MO Status=2, MOMSN=49613, MT Status=2, MTMSN=0 2025-08-26T20:17:25.742Z,1756239445.742 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:17:43.948Z,1756239463.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:17:48.766Z,1756239468.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=49613, MT Status=2, MTMSN=0 2025-08-26T20:17:48.767Z,1756239468.767 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:17:49.174Z,1756239469.174 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T20:18:02.896Z,1756239482.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:18:19.610Z,1756239499.610 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:18:21.824Z,1756239501.824 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:18:44.353Z,1756239524.353 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:19:03.176Z,1756239543.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:19:22.172Z,1756239562.172 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:19:41.052Z,1756239581.052 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:19:59.961Z,1756239599.961 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:20:18.984Z,1756239618.984 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:20:41.041Z,1756239641.041 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:20:49.486Z,1756239649.486 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:20:49.487Z,1756239649.487 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:20:49.487Z,1756239649.487 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:20:49.487Z,1756239649.487 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:20:49.897Z,1756239649.897 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:20:49.897Z,1756239649.897 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:20:50.284Z,1756239650.284 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 19.995315 min 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:20:50.285Z,1756239650.285 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:20:52.236Z,1756239652.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202052.00,A,3648.15074,N,12147.22241,W,0.117,33.63,260825,,,D*4F 2025-08-26T20:20:52.238Z,1756239652.238 [NAL9602](INFO): GPS fix at 20250826T202052: (36.802512, -121.787040) 2025-08-26T20:20:52.269Z,1756239652.269 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:20:52.269Z,1756239652.269 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:21:07.507Z,1756239667.507 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250826T195810/Courier0007.lzma 2025-08-26T20:21:18.550Z,1756239678.550 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0007.lzma.bak 2025-08-26T20:21:18.550Z,1756239678.550 [DataOverHttps](INFO): SBD MOMSN=25895427 2025-08-26T20:21:48.470Z,1756239708.470 [DataOverHttps](INFO): Sending 1240 bytes from file Logs/20250826T195810/Express0008.lzma 2025-08-26T20:21:59.510Z,1756239719.510 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0008.lzma.bak 2025-08-26T20:21:59.510Z,1756239719.510 [DataOverHttps](INFO): SBD MOMSN=25895430 2025-08-26T20:22:06.574Z,1756239726.574 [NAL9602](INFO): SBD MO Status=2, MOMSN=49613, MT Status=2, MTMSN=0 2025-08-26T20:22:06.575Z,1756239726.575 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:22:06.977Z,1756239726.977 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T20:22:31.876Z,1756239751.876 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250826T195810/Express0011.lzma 2025-08-26T20:22:37.717Z,1756239757.717 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:22:46.934Z,1756239766.934 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0011.lzma.bak 2025-08-26T20:22:46.934Z,1756239766.934 [DataOverHttps](INFO): SBD MOMSN=25895501 2025-08-26T20:22:50.241Z,1756239770.241 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:22:50.241Z,1756239770.241 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:22:50.241Z,1756239770.241 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:23:28.808Z,1756239808.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:23:47.520Z,1756239827.520 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:24:06.156Z,1756239846.156 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:24:25.121Z,1756239865.121 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:24:44.048Z,1756239884.048 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:25:04.188Z,1756239904.188 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:25:22.928Z,1756239922.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:25:41.700Z,1756239941.700 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:26:00.288Z,1756239960.288 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:26:19.214Z,1756239979.214 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:26:41.636Z,1756240001.636 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:27:00.340Z,1756240020.340 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:27:19.256Z,1756240039.256 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:27:37.996Z,1756240057.996 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:27:50.934Z,1756240070.934 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:27:50.935Z,1756240070.935 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:27:50.935Z,1756240070.935 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:27:50.935Z,1756240070.935 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:27:51.351Z,1756240071.351 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:27:51.351Z,1756240071.351 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 27.019541 min 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4 2025-08-26T20:27:51.770Z,1756240071.770 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:27:51.771Z,1756240071.771 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:27:53.751Z,1756240073.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202754.00,A,3648.14875,N,12147.22331,W,0.019,33.63,260825,,,D*4F 2025-08-26T20:27:53.757Z,1756240073.757 [NAL9602](INFO): GPS fix at 20250826T202754: (36.802479, -121.787055) 2025-08-26T20:27:53.808Z,1756240073.808 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:27:53.808Z,1756240073.808 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:28:03.167Z,1756240083.167 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250826T195810/Courier0013.lzma 2025-08-26T20:28:09.912Z,1756240089.912 [NAL9602](INFO): SBD MO Status=0, MOMSN=49613, MT Status=0, MTMSN=0 2025-08-26T20:28:09.912Z,1756240089.912 [NAL9602](INFO): No messages in MT queue 2025-08-26T20:28:14.509Z,1756240094.509 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0013.lzma.bak 2025-08-26T20:28:14.510Z,1756240094.510 [DataOverHttps](INFO): SBD MOMSN=25895503 2025-08-26T20:28:40.697Z,1756240120.697 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:28:47.564Z,1756240127.564 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250826T195810/Express0014.lzma 2025-08-26T20:29:00.614Z,1756240140.614 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0014.lzma.bak 2025-08-26T20:29:00.614Z,1756240140.614 [DataOverHttps](INFO): SBD MOMSN=25895507 2025-08-26T20:29:03.817Z,1756240143.817 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:29:03.817Z,1756240143.817 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:29:03.817Z,1756240143.817 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:29:22.782Z,1756240162.782 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-08-26T20:29:24.407Z,1756240164.407 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-08-26T20:29:24.410Z,1756240164.410 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-08-26T20:29:42.556Z,1756240182.556 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:29:43.983Z,1756240183.983 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0. +0.00, +0.00, 20.00,1279.41 2025-08-26T20:30:01.446Z,1756240201.446 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:30:20.313Z,1756240220.313 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:30:29.512Z,1756240229.512 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-32768,- 2025-08-26T20:30:42.460Z,1756240242.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:31:01.128Z,1756240261.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:31:20.893Z,1756240280.893 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:31:39.584Z,1756240299.584 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:31:58.440Z,1756240318.440 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:32:17.188Z,1756240337.188 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:32:39.232Z,1756240359.232 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:32:57.924Z,1756240377.924 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:33:17.688Z,1756240397.688 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:33:36.565Z,1756240416.565 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:33:55.418Z,1756240435.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:34:04.499Z,1756240444.499 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:34:04.499Z,1756240444.499 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:34:04.499Z,1756240444.499 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:34:04.500Z,1756240444.500 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:34:04.901Z,1756240444.901 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:34:04.901Z,1756240444.901 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:34:05.312Z,1756240445.312 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 33.245382 min 2025-08-26T20:34:05.312Z,1756240445.312 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:34:05.312Z,1756240445.312 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:34:05.312Z,1756240445.312 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:34:05.313Z,1756240445.313 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:34:05.313Z,1756240445.313 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5 2025-08-26T20:34:05.313Z,1756240445.313 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5 2025-08-26T20:34:05.313Z,1756240445.313 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:34:05.313Z,1756240445.313 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:34:07.319Z,1756240447.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203407.00,A,3648.15071,N,12147.22237,W,0.019,33.63,260825,,,D*41 2025-08-26T20:34:07.321Z,1756240447.321 [NAL9602](INFO): GPS fix at 20250826T203407: (36.802512, -121.787040) 2025-08-26T20:34:07.356Z,1756240447.356 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:34:07.356Z,1756240447.356 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:34:22.784Z,1756240462.784 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250826T195810/Courier0016.lzma 2025-08-26T20:34:27.567Z,1756240467.567 [NAL9602](INFO): SBD MO Status=0, MOMSN=49614, MT Status=0, MTMSN=0 2025-08-26T20:34:27.567Z,1756240467.567 [NAL9602](INFO): No messages in MT queue 2025-08-26T20:34:33.826Z,1756240473.826 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0016.lzma.bak 2025-08-26T20:34:33.826Z,1756240473.826 [DataOverHttps](INFO): SBD MOMSN=25895543 2025-08-26T20:34:58.529Z,1756240498.529 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:35:02.676Z,1756240502.676 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250826T195810/Express0017.lzma 2025-08-26T20:35:14.568Z,1756240514.568 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0017.lzma.bak 2025-08-26T20:35:14.568Z,1756240514.568 [DataOverHttps](INFO): SBD MOMSN=25895547 2025-08-26T20:35:17.147Z,1756240517.147 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:35:17.147Z,1756240517.147 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:35:17.147Z,1756240517.147 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:35:55.628Z,1756240555.628 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:36:15.900Z,1756240575.900 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:36:38.056Z,1756240598.056 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:36:57.021Z,1756240617.021 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:37:15.644Z,1756240635.644 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:37:34.185Z,1756240654.185 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:37:52.756Z,1756240672.756 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:38:11.500Z,1756240691.500 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:38:28.687Z,1756240708.687 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-26T20:38:32.180Z,1756240712.180 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:38:51.240Z,1756240731.240 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:39:09.952Z,1756240749.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:39:28.564Z,1756240768.564 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:39:47.156Z,1756240787.156 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:40:05.928Z,1756240805.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:40:17.821Z,1756240817.821 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:40:17.821Z,1756240817.821 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:40:17.821Z,1756240817.821 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:40:17.821Z,1756240817.821 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:40:18.192Z,1756240818.192 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:40:18.193Z,1756240818.193 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 39.466903 min 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #6 2025-08-26T20:40:18.590Z,1756240818.590 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=6 2025-08-26T20:40:18.591Z,1756240818.591 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:40:18.591Z,1756240818.591 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:40:20.600Z,1756240820.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204021.00,A,3648.15077,N,12147.22146,W,0.019,33.63,260825,,,D*45 2025-08-26T20:40:20.602Z,1756240820.602 [NAL9602](INFO): GPS fix at 20250826T204021: (36.802513, -121.787024) 2025-08-26T20:40:20.670Z,1756240820.670 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:40:20.670Z,1756240820.670 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:40:34.603Z,1756240834.603 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250826T195810/Courier0019.lzma 2025-08-26T20:40:45.646Z,1756240845.646 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0019.lzma.bak 2025-08-26T20:40:45.646Z,1756240845.646 [DataOverHttps](INFO): SBD MOMSN=25895552 2025-08-26T20:40:54.971Z,1756240854.971 [NAL9602](INFO): SBD MO Status=2, MOMSN=49615, MT Status=2, MTMSN=0 2025-08-26T20:40:54.971Z,1756240854.971 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:41:02.638Z,1756240862.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=49615, MT Status=0, MTMSN=0 2025-08-26T20:41:02.639Z,1756240862.639 [NAL9602](INFO): No messages in MT queue 2025-08-26T20:41:15.640Z,1756240875.640 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250826T195810/Express0020.lzma 2025-08-26T20:41:26.682Z,1756240886.682 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0020.lzma.bak 2025-08-26T20:41:26.682Z,1756240886.682 [DataOverHttps](INFO): SBD MOMSN=25895555 2025-08-26T20:41:30.144Z,1756240890.144 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:41:30.144Z,1756240890.144 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:41:30.144Z,1756240890.144 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:41:33.341Z,1756240893.341 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:42:09.748Z,1756240929.748 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:42:28.356Z,1756240948.356 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:42:48.149Z,1756240968.149 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:43:07.120Z,1756240987.120 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:43:25.964Z,1756241005.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:43:44.684Z,1756241024.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:44:03.340Z,1756241043.340 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:44:21.917Z,1756241061.917 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:44:43.580Z,1756241083.580 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:45:02.568Z,1756241102.568 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:45:21.450Z,1756241121.450 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:45:40.199Z,1756241140.199 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:45:58.892Z,1756241158.892 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:46:17.497Z,1756241177.497 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:46:30.792Z,1756241190.792 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:46:30.792Z,1756241190.792 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:46:30.792Z,1756241190.792 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:46:30.792Z,1756241190.792 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:46:31.193Z,1756241191.193 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:46:31.193Z,1756241191.193 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:46:31.615Z,1756241191.615 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 45.683582 min 2025-08-26T20:46:31.615Z,1756241191.615 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:46:31.615Z,1756241191.615 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:46:31.615Z,1756241191.615 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:46:31.615Z,1756241191.615 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:46:31.616Z,1756241191.616 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #7 2025-08-26T20:46:31.617Z,1756241191.617 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=7 2025-08-26T20:46:31.617Z,1756241191.617 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:46:31.617Z,1756241191.617 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:46:33.599Z,1756241193.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204634.00,A,3648.14899,N,12147.22172,W,0.117,33.63,260825,,,D*46 2025-08-26T20:46:33.603Z,1756241193.603 [NAL9602](INFO): GPS fix at 20250826T204634: (36.802483, -121.787029) 2025-08-26T20:46:33.650Z,1756241193.650 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:46:33.650Z,1756241193.650 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:46:48.599Z,1756241208.599 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250826T195810/Courier0022.lzma 2025-08-26T20:46:52.594Z,1756241212.594 [NAL9602](INFO): SBD MO Status=2, MOMSN=49616, MT Status=2, MTMSN=0 2025-08-26T20:46:52.595Z,1756241212.595 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:46:59.642Z,1756241219.642 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0022.lzma.bak 2025-08-26T20:46:59.642Z,1756241219.642 [DataOverHttps](INFO): SBD MOMSN=25895571 2025-08-26T20:47:27.334Z,1756241247.334 [NAL9602](INFO): SBD MO Status=0, MOMSN=49616, MT Status=0, MTMSN=0 2025-08-26T20:47:27.335Z,1756241247.335 [NAL9602](INFO): No messages in MT queue 2025-08-26T20:47:28.312Z,1756241248.312 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250826T195810/Express0023.lzma 2025-08-26T20:47:43.370Z,1756241263.370 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0023.lzma.bak 2025-08-26T20:47:43.370Z,1756241263.370 [DataOverHttps](INFO): SBD MOMSN=25895574 2025-08-26T20:47:46.753Z,1756241266.753 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:47:46.753Z,1756241266.753 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:47:46.753Z,1756241266.753 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:47:58.042Z,1756241278.042 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:48:27.896Z,1756241307.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:48:45.747Z,1756241325.747 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3268,V 2025-08-26T20:48:46.613Z,1756241326.613 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:49:05.450Z,1756241345.450 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:49:24.100Z,1756241364.100 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:49:42.744Z,1756241382.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:50:01.450Z,1756241401.450 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:50:20.292Z,1756241420.292 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:50:42.816Z,1756241442.816 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:51:01.700Z,1756241461.700 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:51:20.330Z,1756241480.330 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:51:39.036Z,1756241499.036 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:51:57.732Z,1756241517.732 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:52:16.652Z,1756241536.652 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:52:38.753Z,1756241558.753 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:52:47.503Z,1756241567.503 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:52:47.503Z,1756241567.503 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:52:47.503Z,1756241567.503 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:52:47.503Z,1756241567.503 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:52:47.883Z,1756241567.883 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:52:47.884Z,1756241567.884 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:52:48.263Z,1756241568.263 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 51.961751 min 2025-08-26T20:52:48.263Z,1756241568.263 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #8 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=8 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:52:48.264Z,1756241568.264 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:52:50.271Z,1756241570.271 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205250.00,A,3648.14949,N,12147.22169,W,0.253,33.63,260825,,,D*44 2025-08-26T20:52:50.281Z,1756241570.281 [NAL9602](INFO): GPS fix at 20250826T205250: (36.802492, -121.787028) 2025-08-26T20:52:50.293Z,1756241570.293 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:52:50.293Z,1756241570.293 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:53:05.303Z,1756241585.303 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250826T195810/Courier0025.lzma 2025-08-26T20:53:08.852Z,1756241588.852 [NAL9602](INFO): SBD MO Status=2, MOMSN=49617, MT Status=2, MTMSN=0 2025-08-26T20:53:08.852Z,1756241588.852 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:53:16.346Z,1756241596.346 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0025.lzma.bak 2025-08-26T20:53:16.346Z,1756241596.346 [DataOverHttps](INFO): SBD MOMSN=25895587 2025-08-26T20:53:46.092Z,1756241626.092 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250826T195810/Express0026.lzma 2025-08-26T20:53:50.919Z,1756241630.919 [NAL9602](INFO): SBD MO Status=0, MOMSN=49617, MT Status=0, MTMSN=0 2025-08-26T20:53:50.919Z,1756241630.919 [NAL9602](INFO): No messages in MT queue 2025-08-26T20:53:57.134Z,1756241637.134 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0026.lzma.bak 2025-08-26T20:53:57.134Z,1756241637.134 [DataOverHttps](INFO): SBD MOMSN=25895593 2025-08-26T20:54:00.240Z,1756241640.240 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T20:54:00.240Z,1756241640.240 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T20:54:00.240Z,1756241640.240 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T20:54:21.628Z,1756241661.628 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T20:54:41.716Z,1756241681.716 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:55:00.404Z,1756241700.404 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:55:19.397Z,1756241719.397 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:55:38.064Z,1756241738.064 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:55:56.920Z,1756241756.920 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:56:15.684Z,1756241775.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:56:37.390Z,1756241797.390 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:56:56.056Z,1756241816.056 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:57:14.744Z,1756241834.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:57:33.644Z,1756241853.644 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:57:52.557Z,1756241872.557 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:58:11.308Z,1756241891.308 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:58:31.068Z,1756241911.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:58:49.760Z,1756241929.760 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T20:59:01.010Z,1756241941.010 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T20:59:01.010Z,1756241941.010 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T20:59:01.010Z,1756241941.010 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T20:59:01.010Z,1756241941.010 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T20:59:01.449Z,1756241941.449 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T20:59:01.449Z,1756241941.449 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T20:59:01.830Z,1756241941.830 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 58.187834 min 2025-08-26T20:59:01.830Z,1756241941.830 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T20:59:01.830Z,1756241941.830 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:59:01.830Z,1756241941.830 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T20:59:01.830Z,1756241941.830 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:59:01.831Z,1756241941.831 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #9 2025-08-26T20:59:01.831Z,1756241941.831 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=9 2025-08-26T20:59:01.831Z,1756241941.831 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T20:59:01.831Z,1756241941.831 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T20:59:03.815Z,1756241943.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205904.00,A,3648.14864,N,12147.22242,W,0.039,56.56,260825,,,D*41 2025-08-26T20:59:03.817Z,1756241943.817 [NAL9602](INFO): GPS fix at 20250826T205904: (36.802477, -121.787040) 2025-08-26T20:59:03.849Z,1756241943.849 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T20:59:03.849Z,1756241943.849 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T20:59:16.339Z,1756241956.339 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250826T195810/Courier0028.lzma 2025-08-26T20:59:27.382Z,1756241967.382 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0028.lzma.bak 2025-08-26T20:59:27.382Z,1756241967.382 [DataOverHttps](INFO): SBD MOMSN=25895604 2025-08-26T20:59:54.710Z,1756241994.710 [NAL9602](INFO): SBD MO Status=2, MOMSN=49618, MT Status=2, MTMSN=0 2025-08-26T20:59:54.711Z,1756241994.711 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T20:59:56.620Z,1756241996.620 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250826T195810/Express0029.lzma 2025-08-26T21:00:06.049Z,1756242006.049 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T21:00:07.702Z,1756242007.702 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0029.lzma.bak 2025-08-26T21:00:07.702Z,1756242007.702 [DataOverHttps](INFO): SBD MOMSN=25895607 2025-08-26T21:00:11.301Z,1756242011.301 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T21:00:11.301Z,1756242011.301 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T21:00:11.301Z,1756242011.301 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T21:00:36.785Z,1756242036.785 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T21:00:54.212Z,1756242054.212 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:01:12.888Z,1756242072.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:01:31.874Z,1756242091.874 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:01:50.601Z,1756242110.601 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:02:09.488Z,1756242129.488 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:02:28.148Z,1756242148.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:02:46.872Z,1756242166.872 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:03:05.561Z,1756242185.561 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:03:24.076Z,1756242204.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:03:42.974Z,1756242222.974 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:04:01.933Z,1756242241.933 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:04:20.673Z,1756242260.673 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:04:42.828Z,1756242282.828 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:05:02.808Z,1756242302.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:05:11.943Z,1756242311.943 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T21:05:11.943Z,1756242311.943 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T21:05:11.943Z,1756242311.943 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T21:05:11.944Z,1756242311.944 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T21:05:12.344Z,1756242312.344 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T21:05:12.345Z,1756242312.345 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T21:05:12.752Z,1756242312.752 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 64.369438 min 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #10 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=10 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:05:12.753Z,1756242312.753 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T21:05:14.768Z,1756242314.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210515.00,A,3648.15002,N,12147.22300,W,0.019,56.56,260825,,,D*45 2025-08-26T21:05:14.770Z,1756242314.770 [NAL9602](INFO): GPS fix at 20250826T210515: (36.802500, -121.787050) 2025-08-26T21:05:14.781Z,1756242314.781 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T21:05:14.782Z,1756242314.782 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T21:05:27.611Z,1756242327.611 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250826T195810/Courier0031.lzma 2025-08-26T21:05:39.658Z,1756242339.658 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0031.lzma.bak 2025-08-26T21:05:39.658Z,1756242339.658 [DataOverHttps](INFO): SBD MOMSN=25895616 2025-08-26T21:05:45.549Z,1756242345.549 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-08-26T21:05:45.632Z,1756242345.632 [NAL9602](ERROR): received: +CSQ:0 OK618, 2, 0, 0, 0 OK 2025-08-26T21:06:08.548Z,1756242368.548 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250826T195810/Express0032.lzma 2025-08-26T21:06:17.486Z,1756242377.486 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T21:06:19.594Z,1756242379.594 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0032.lzma.bak 2025-08-26T21:06:19.594Z,1756242379.594 [DataOverHttps](INFO): SBD MOMSN=25895619 2025-08-26T21:06:24.864Z,1756242384.864 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T21:06:24.864Z,1756242384.864 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T21:06:24.864Z,1756242384.864 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T21:06:48.257Z,1756242408.257 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T21:06:51.511Z,1756242411.511 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3278,V 2025-08-26T21:07:06.684Z,1756242426.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:07:25.391Z,1756242445.391 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:07:44.044Z,1756242464.044 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:08:02.940Z,1756242482.940 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:08:21.680Z,1756242501.680 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:08:43.728Z,1756242523.728 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:09:02.332Z,1756242542.332 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:09:20.964Z,1756242560.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:09:39.636Z,1756242579.636 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:09:58.393Z,1756242598.393 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:10:18.360Z,1756242618.360 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:10:40.276Z,1756242640.276 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:11:00.380Z,1756242660.380 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:11:18.964Z,1756242678.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:11:25.412Z,1756242685.412 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T21:11:25.412Z,1756242685.412 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T21:11:25.413Z,1756242685.413 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T21:11:25.413Z,1756242685.413 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T21:11:25.825Z,1756242685.825 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T21:11:25.825Z,1756242685.825 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T21:11:26.182Z,1756242686.182 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 70.594116 min 2025-08-26T21:11:26.183Z,1756242686.183 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T21:11:26.183Z,1756242686.183 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:11:26.183Z,1756242686.183 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T21:11:26.183Z,1756242686.183 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:11:26.184Z,1756242686.184 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #11 2025-08-26T21:11:26.185Z,1756242686.185 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=11 2025-08-26T21:11:26.185Z,1756242686.185 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:11:26.185Z,1756242686.185 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T21:11:28.191Z,1756242688.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211128.00,A,3648.35465,N,12147.10615,W,4.782,8.76,260825,,,D*71 2025-08-26T21:11:28.204Z,1756242688.204 [NAL9602](INFO): GPS fix at 20250826T211128: (36.805911, -121.785102) 2025-08-26T21:11:28.221Z,1756242688.221 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T21:11:28.221Z,1756242688.221 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T21:11:43.851Z,1756242703.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250826T195810/Courier0034.lzma 2025-08-26T21:11:54.902Z,1756242714.902 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0034.lzma.bak 2025-08-26T21:11:54.902Z,1756242714.902 [DataOverHttps](INFO): SBD MOMSN=25895623 2025-08-26T21:11:58.893Z,1756242718.893 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-08-26T21:11:58.968Z,1756242718.968 [NAL9602](ERROR): received: +CSQ:0 OK618, 2, 0, 0, 0 OK 2025-08-26T21:12:29.844Z,1756242749.844 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250826T195810/Express0035.lzma 2025-08-26T21:12:30.021Z,1756242750.021 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T21:12:40.886Z,1756242760.886 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0035.lzma.bak 2025-08-26T21:12:40.886Z,1756242760.886 [DataOverHttps](INFO): SBD MOMSN=25895626 2025-08-26T21:12:46.229Z,1756242766.229 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T21:12:46.229Z,1756242766.229 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T21:12:46.229Z,1756242766.229 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T21:13:00.725Z,1756242780.725 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T21:13:25.352Z,1756242805.352 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:13:44.164Z,1756242824.164 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:14:03.164Z,1756242843.164 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:14:21.968Z,1756242861.968 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:14:44.144Z,1756242884.144 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:15:02.872Z,1756242902.872 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:15:21.853Z,1756242921.853 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:15:40.588Z,1756242940.588 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:15:59.364Z,1756242959.364 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:16:18.061Z,1756242978.061 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:16:39.648Z,1756242999.648 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:16:58.808Z,1756243018.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:17:17.649Z,1756243037.649 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:17:36.516Z,1756243056.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:17:46.732Z,1756243066.732 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T21:17:46.733Z,1756243066.733 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T21:17:46.733Z,1756243066.733 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T21:17:46.733Z,1756243066.733 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T21:17:47.137Z,1756243067.137 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T21:17:47.137Z,1756243067.137 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T21:17:47.563Z,1756243067.563 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 76.949308 min 2025-08-26T21:17:47.563Z,1756243067.563 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #12 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=12 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:17:47.564Z,1756243067.564 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T21:17:49.559Z,1756243069.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211749.00,A,3647.66291,N,12148.22939,W,26.086,217.50,260825,,,D*40 2025-08-26T21:17:49.562Z,1756243069.562 [NAL9602](INFO): GPS fix at 20250826T211749: (36.794382, -121.803823) 2025-08-26T21:17:49.593Z,1756243069.593 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T21:17:49.593Z,1756243069.593 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T21:18:02.099Z,1756243082.099 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250826T195810/Courier0037.lzma 2025-08-26T21:18:13.142Z,1756243093.142 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Courier0037.lzma.bak 2025-08-26T21:18:13.142Z,1756243093.142 [DataOverHttps](INFO): SBD MOMSN=25895643 2025-08-26T21:18:20.285Z,1756243100.285 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-08-26T21:18:20.372Z,1756243100.372 [NAL9602](ERROR): received: +CSQ:0 OK618, 2, 0, 0, 0 OK 2025-08-26T21:18:45.464Z,1756243125.464 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250826T195810/Express0038.lzma 2025-08-26T21:18:52.243Z,1756243132.243 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-26T21:18:56.522Z,1756243136.522 [DataOverHttps](INFO): Moved sent file to Logs/20250826T195810/Express0038.lzma.bak 2025-08-26T21:18:56.522Z,1756243136.522 [DataOverHttps](INFO): SBD MOMSN=25895646 2025-08-26T21:18:59.945Z,1756243139.945 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T21:18:59.945Z,1756243139.945 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T21:18:59.945Z,1756243139.945 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T21:19:22.937Z,1756243162.937 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T21:19:38.196Z,1756243178.196 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:19:56.888Z,1756243196.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:20:15.560Z,1756243215.560 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:20:42.856Z,1756243242.856 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:21:02.028Z,1756243262.028 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:21:20.648Z,1756243280.648 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:21:39.508Z,1756243299.508 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:21:58.216Z,1756243318.216 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:22:18.064Z,1756243338.064 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:22:39.860Z,1756243359.860 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:23:02.768Z,1756243382.768 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:23:35.633Z,1756243415.633 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:24:00.624Z,1756243440.624 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T21:24:00.624Z,1756243440.624 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T21:24:00.624Z,1756243440.624 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T21:24:00.624Z,1756243440.624 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T21:24:01.035Z,1756243441.035 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T21:24:01.035Z,1756243441.035 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T21:24:01.475Z,1756243441.475 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 83.180949 min 2025-08-26T21:24:01.475Z,1756243441.475 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T21:24:01.475Z,1756243441.475 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:24:01.475Z,1756243441.475 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T21:24:01.475Z,1756243441.475 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:24:01.476Z,1756243441.476 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #13 2025-08-26T21:24:01.476Z,1756243441.476 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=13 2025-08-26T21:24:01.476Z,1756243441.476 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:24:01.476Z,1756243441.476 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T21:24:02.423Z,1756243442.423 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.658047 2025-08-26T21:24:03.425Z,1756243443.425 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:24:03.451Z,1756243443.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212403.00,A,3645.96914,N,12150.46874,W,2.741,79.57,260825,,,D*41 2025-08-26T21:24:03.454Z,1756243443.454 [NAL9602](INFO): GPS fix at 20250826T212403: (36.766152, -121.841146) 2025-08-26T21:24:03.502Z,1756243443.502 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T21:24:03.502Z,1756243443.502 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T21:24:34.146Z,1756243474.146 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2025-08-26T21:24:34.228Z,1756243474.228 [NAL9602](ERROR): received: +CSQ:1 OK618, 2, 0, 0, 0 OK 2025-08-26T21:24:34.556Z,1756243474.556 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:24:53.084Z,1756243493.084 [NAL9602](INFO): SBD MO Status=1, MOMSN=49618, MT Status=0, MTMSN=0 2025-08-26T21:24:53.137Z,1756243493.137 [NAL9602](INFO): Sent 74 bytes from file Logs/20250826T195810/Courier0040.lzma 2025-08-26T21:24:53.137Z,1756243493.137 [NAL9602](INFO): Packets left to send: 0 2025-08-26T21:25:04.680Z,1756243504.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:25:05.684Z,1756243505.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:25:12.552Z,1756243512.552 [NAL9602](INFO): SBD MO Status=1, MOMSN=49619, MT Status=0, MTMSN=0 2025-08-26T21:25:12.600Z,1756243512.600 [NAL9602](INFO): Sent 247 bytes from file Logs/20250826T195810/Express0041.lzma 2025-08-26T21:25:12.600Z,1756243512.600 [NAL9602](INFO): Packets left to send: 0 2025-08-26T21:25:30.410Z,1756243530.410 [NAL9602](INFO): SBD MO Status=2, MOMSN=49620, MT Status=2, MTMSN=0 2025-08-26T21:25:30.410Z,1756243530.410 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-26T21:25:35.804Z,1756243535.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:25:36.808Z,1756243536.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:25:37.078Z,1756243537.078 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-26T21:25:50.894Z,1756243550.894 [NAL9602](INFO): SBD MO Status=0, MOMSN=49620, MT Status=0, MTMSN=0 2025-08-26T21:25:50.999Z,1756243550.999 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T21:25:50.999Z,1756243550.999 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T21:25:50.999Z,1756243550.999 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T21:26:06.928Z,1756243566.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:26:07.932Z,1756243567.932 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:26:21.605Z,1756243581.605 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T21:26:38.052Z,1756243598.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:26:39.056Z,1756243599.056 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:27:09.188Z,1756243629.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:27:10.193Z,1756243630.193 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:27:40.312Z,1756243660.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:27:41.316Z,1756243661.316 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:28:11.444Z,1756243691.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:28:12.448Z,1756243692.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:28:28.453Z,1756243708.453 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-08-26T21:28:28.453Z,1756243708.453 [DropWeight] Hardware Fault, FailCount= 1 2025-08-26T21:28:28.453Z,1756243708.453 [DropWeight](ERROR): Hardware Fault 2025-08-26T21:28:28.501Z,1756243708.501 [CBIT](INFO): Critical error at 20250826T212828 2025-08-26T21:28:28.503Z,1756243708.503 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-08-26T21:28:28.508Z,1756243708.508 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-08-26T21:28:28.898Z,1756243708.898 [CBIT](INFO): Critical error at 20250826T212828 2025-08-26T21:28:42.568Z,1756243722.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:28:43.573Z,1756243723.573 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:29:13.696Z,1756243753.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:29:14.701Z,1756243754.701 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:29:44.828Z,1756243784.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:29:45.832Z,1756243785.832 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:30:15.952Z,1756243815.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:30:16.956Z,1756243816.956 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:30:47.076Z,1756243847.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:30:48.080Z,1756243848.080 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:30:51.522Z,1756243851.522 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-26T21:30:51.522Z,1756243851.522 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-26T21:30:51.523Z,1756243851.523 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-26T21:30:51.523Z,1756243851.523 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-08-26T21:30:51.887Z,1756243851.887 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-08-26T21:30:51.887Z,1756243851.887 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-08-26T21:30:52.288Z,1756243852.288 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 90.028475 min 2025-08-26T21:30:52.288Z,1756243852.288 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-08-26T21:30:52.288Z,1756243852.288 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:30:52.288Z,1756243852.288 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-26T21:30:52.289Z,1756243852.289 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:30:52.289Z,1756243852.289 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #14 2025-08-26T21:30:52.289Z,1756243852.289 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=14 2025-08-26T21:30:52.289Z,1756243852.289 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-26T21:30:52.289Z,1756243852.289 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-26T21:30:54.303Z,1756243854.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213054.00,A,3645.95362,N,12150.43485,W,0.486,189.21,260825,,,D*7C 2025-08-26T21:30:54.306Z,1756243854.306 [NAL9602](INFO): GPS fix at 20250826T213054: (36.765894, -121.840581) 2025-08-26T21:30:54.317Z,1756243854.317 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-26T21:30:54.317Z,1756243854.317 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-26T21:31:12.311Z,1756243872.311 [NAL9602](INFO): SBD MO Status=1, MOMSN=49621, MT Status=0, MTMSN=0 2025-08-26T21:31:12.368Z,1756243872.368 [NAL9602](INFO): Sent 179 bytes from file Logs/20250826T195810/Courier0043.lzma 2025-08-26T21:31:12.368Z,1756243872.368 [NAL9602](INFO): Packets left to send: 0 2025-08-26T21:31:18.208Z,1756243878.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:31:19.212Z,1756243879.212 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:31:19.777Z,1756243879.777 [NAL9602](INFO): SBD MO Status=1, MOMSN=49622, MT Status=0, MTMSN=0 2025-08-26T21:31:19.825Z,1756243879.825 [NAL9602](INFO): Sent 149 bytes from file Logs/20250826T195810/Express0044.lzma 2025-08-26T21:31:19.825Z,1756243879.825 [NAL9602](INFO): Packets left to send: 0 2025-08-26T21:31:27.527Z,1756243887.527 [NAL9602](INFO): SBD MO Status=0, MOMSN=49623, MT Status=0, MTMSN=0 2025-08-26T21:31:27.619Z,1756243887.619 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-26T21:31:27.619Z,1756243887.619 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-26T21:31:27.619Z,1756243887.619 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-26T21:31:49.332Z,1756243909.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-26T21:31:50.336Z,1756243910.336 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:31:53.778Z,1756243913.778 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-26T21:31:58.213Z,1756243918.213 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-26T21:32:05.505Z,1756243925.505 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-26T21:32:21.240Z,1756243941.240 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-08-26T21:32:22.244Z,1756243942.244 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-26T21:32:48.673Z,1756243968.673 [DataOverHttps](IMPORTANT): SBD MTMSN=20250826T213249 2025-08-26T21:32:48.677Z,1756243968.677 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003469 2025-08-26T21:32:57.427Z,1756243977.427 [DataOverHttps](INFO): Received command: restart logs