2024-11-04T22:30:14.291Z,1730759414.291 [Supervisor](DEBUG): Initializing supervisor. 2024-11-04T22:30:14.295Z,1730759414.295 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-11-04T22:30:14.296Z,1730759414.296 [SyncHandler](INFO): Protected caller Thread ID is 1466 2024-11-04T22:30:14.296Z,1730759414.296 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-11-04T22:30:14.297Z,1730759414.297 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-11-04T22:30:14.298Z,1730759414.298 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1467 2024-11-04T22:30:14.301Z,1730759414.301 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-11-04T22:30:14.319Z,1730759414.319 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-11-04T22:30:14.320Z,1730759414.320 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-11-04T22:30:14.320Z,1730759414.320 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1468 2024-11-04T22:30:14.324Z,1730759414.324 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-11-04T22:30:14.325Z,1730759414.325 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-11-04T22:30:14.326Z,1730759414.326 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1469 2024-11-04T22:30:14.328Z,1730759414.328 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-11-04T22:30:14.328Z,1730759414.328 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-11-04T22:30:14.329Z,1730759414.329 [logger ThreadHandler](INFO): Protected caller Thread ID is 1470 2024-11-04T22:30:14.332Z,1730759414.332 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-11-04T22:30:14.333Z,1730759414.333 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-11-04T22:30:14.334Z,1730759414.334 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-11-04T22:30:14.441Z,1730759414.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-11-04T22:30:14.442Z,1730759414.442 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-11-04T22:30:15.485Z,1730759415.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-11-04T22:30:15.486Z,1730759415.486 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-11-04T22:30:15.728Z,1730759415.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-11-04T22:30:15.729Z,1730759415.729 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-11-04T22:30:15.819Z,1730759415.819 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-11-04T22:30:15.960Z,1730759415.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-11-04T22:30:15.961Z,1730759415.961 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-11-04T22:30:16.052Z,1730759416.052 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-11-04T22:30:16.170Z,1730759416.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-11-04T22:30:16.171Z,1730759416.171 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-11-04T22:30:16.585Z,1730759416.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-11-04T22:30:16.586Z,1730759416.586 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-11-04T22:30:17.131Z,1730759417.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-11-04T22:30:17.132Z,1730759417.132 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-11-04T22:30:17.362Z,1730759417.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-11-04T22:30:17.362Z,1730759417.362 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-11-04T22:30:17.568Z,1730759417.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-11-04T22:30:17.568Z,1730759417.568 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-11-04T22:30:18.124Z,1730759418.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-11-04T22:30:18.124Z,1730759418.124 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-11-04T22:30:18.426Z,1730759418.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-11-04T22:30:18.426Z,1730759418.426 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-11-04T22:30:18.668Z,1730759418.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-11-04T22:30:18.669Z,1730759418.669 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-11-04T22:30:19.056Z,1730759419.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-11-04T22:30:19.057Z,1730759419.057 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-11-04T22:30:19.415Z,1730759419.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-11-04T22:30:19.417Z,1730759419.417 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2024-11-04T22:30:19.418Z,1730759419.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2024-11-04T22:30:19.512Z,1730759419.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2024-11-04T22:30:19.692Z,1730759419.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2024-11-04T22:30:19.810Z,1730759419.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2024-11-04T22:30:19.915Z,1730759419.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2024-11-04T22:30:20.034Z,1730759420.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2024-11-04T22:30:20.130Z,1730759420.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2024-11-04T22:30:20.235Z,1730759420.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2024-11-04T22:30:20.358Z,1730759420.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2024-11-04T22:30:20.642Z,1730759420.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-11-04T22:30:20.643Z,1730759420.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2024-11-04T22:30:20.899Z,1730759420.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2024-11-04T22:30:21.056Z,1730759421.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2024-11-04T22:30:21.322Z,1730759421.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2024-11-04T22:30:21.564Z,1730759421.564 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2024-11-04T22:30:21.564Z,1730759421.564 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-11-04T22:30:21.603Z,1730759421.603 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-11-04T22:30:22.191Z,1730759422.191 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-11-04T22:30:22.192Z,1730759422.192 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-11-04T22:30:22.244Z,1730759422.244 [VerticalControl](DEBUG): Construct VerticalControl. 2024-11-04T22:30:22.389Z,1730759422.389 [VerticalControl] Loaded 2024-11-04T22:30:22.389Z,1730759422.389 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-11-04T22:30:22.392Z,1730759422.392 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-11-04T22:30:22.439Z,1730759422.439 [HorizontalControl] Loaded 2024-11-04T22:30:22.439Z,1730759422.439 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-11-04T22:30:22.442Z,1730759422.442 [SpeedControl](DEBUG): Construct SpeedControl. 2024-11-04T22:30:22.445Z,1730759422.445 [SpeedControl] Loaded 2024-11-04T22:30:22.445Z,1730759422.445 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-11-04T22:30:22.448Z,1730759422.448 [LoopControl](DEBUG): Construct LoopControl. 2024-11-04T22:30:22.448Z,1730759422.448 [LoopControl] Loaded 2024-11-04T22:30:22.448Z,1730759422.448 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-11-04T22:30:22.449Z,1730759422.449 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-11-04T22:30:22.449Z,1730759422.449 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-11-04T22:30:22.499Z,1730759422.499 [DepthRateCalculator] Loaded 2024-11-04T22:30:22.499Z,1730759422.499 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-11-04T22:30:22.504Z,1730759422.504 [PitchRateCalculator] Loaded 2024-11-04T22:30:22.504Z,1730759422.504 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-11-04T22:30:22.514Z,1730759422.514 [SpeedCalculator] Loaded 2024-11-04T22:30:22.514Z,1730759422.514 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-11-04T22:30:22.519Z,1730759422.519 [YawRateCalculator] Loaded 2024-11-04T22:30:22.519Z,1730759422.519 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-11-04T22:30:22.537Z,1730759422.537 [ElevatorOffsetCalculator] Loaded 2024-11-04T22:30:22.537Z,1730759422.537 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-11-04T22:30:22.538Z,1730759422.538 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-11-04T22:30:22.538Z,1730759422.538 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-11-04T22:30:22.564Z,1730759422.564 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-11-04T22:30:22.565Z,1730759422.565 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-11-04T22:30:22.622Z,1730759422.622 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-11-04T22:30:22.623Z,1730759422.623 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-11-04T22:30:22.958Z,1730759422.958 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-11-04T22:30:22.959Z,1730759422.959 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-11-04T22:30:23.132Z,1730759423.132 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-11-04T22:30:23.133Z,1730759423.133 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-11-04T22:30:23.611Z,1730759423.611 [AHRS_M2] Loaded 2024-11-04T22:30:23.611Z,1730759423.611 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-11-04T22:30:23.649Z,1730759423.649 [BackseatComponent] Loaded 2024-11-04T22:30:23.650Z,1730759423.650 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-11-04T22:30:23.651Z,1730759423.651 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0 2024-11-04T22:30:23.651Z,1730759423.651 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1558 2024-11-04T22:30:23.654Z,1730759423.654 [LcmUniversalReporter] Loaded 2024-11-04T22:30:23.654Z,1730759423.654 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-11-04T22:30:24.453Z,1730759424.453 [BPC1] Loaded 2024-11-04T22:30:24.453Z,1730759424.453 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-11-04T22:30:24.548Z,1730759424.548 [DAT] Loaded 2024-11-04T22:30:24.548Z,1730759424.548 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-11-04T22:30:24.549Z,1730759424.549 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A0C4E0 2024-11-04T22:30:24.550Z,1730759424.550 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1559 2024-11-04T22:30:24.624Z,1730759424.624 [DataOverHttps] Loaded 2024-11-04T22:30:24.625Z,1730759424.625 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-11-04T22:30:24.626Z,1730759424.626 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A3C4E0 2024-11-04T22:30:24.626Z,1730759424.626 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1560 2024-11-04T22:30:24.649Z,1730759424.649 [Depth_Keller] Loaded 2024-11-04T22:30:24.649Z,1730759424.649 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-11-04T22:30:24.713Z,1730759424.713 [NAL9602] Loaded 2024-11-04T22:30:24.713Z,1730759424.713 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-11-04T22:30:24.742Z,1730759424.742 [Onboard] Loaded 2024-11-04T22:30:24.742Z,1730759424.742 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-11-04T22:30:24.743Z,1730759424.743 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A6C4E0 2024-11-04T22:30:24.744Z,1730759424.744 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1561 2024-11-04T22:30:24.757Z,1730759424.757 [Power24vConverter] Loaded 2024-11-04T22:30:24.758Z,1730759424.758 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-11-04T22:30:24.773Z,1730759424.773 [Radio_Surface] Loaded 2024-11-04T22:30:24.774Z,1730759424.774 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-11-04T22:30:24.775Z,1730759424.775 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2024-11-04T22:30:24.775Z,1730759424.775 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1562 2024-11-04T22:30:24.822Z,1730759424.822 [RDI_Pathfinder] Loaded 2024-11-04T22:30:24.822Z,1730759424.822 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2024-11-04T22:30:24.837Z,1730759424.837 [Sonardyne_Nano] Loaded 2024-11-04T22:30:24.837Z,1730759424.837 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-11-04T22:30:24.894Z,1730759424.894 [TrnDataBridge] Loaded 2024-11-04T22:30:24.895Z,1730759424.895 [ComponentRegistry](DEBUG): Component "TrnDataBridge" handled in its own thread. 2024-11-04T22:30:24.895Z,1730759424.895 [TrnDataBridge ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0 2024-11-04T22:30:24.896Z,1730759424.896 [TrnDataBridge ThreadHandler](INFO): Protected caller Thread ID is 1563 2024-11-04T22:30:24.896Z,1730759424.896 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-11-04T22:30:24.897Z,1730759424.897 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-11-04T22:30:25.008Z,1730759425.008 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-11-04T22:30:25.009Z,1730759425.009 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-11-04T22:30:25.022Z,1730759425.022 [NavChart] Loaded 2024-11-04T22:30:25.023Z,1730759425.023 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-11-04T22:30:25.028Z,1730759425.028 [UniversalFixResidualReporter] Loaded 2024-11-04T22:30:25.028Z,1730759425.028 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-11-04T22:30:25.029Z,1730759425.029 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-11-04T22:30:25.029Z,1730759425.029 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-11-04T22:30:25.170Z,1730759425.170 [SBIT](DEBUG): Construct Startup Built In Test. 2024-11-04T22:30:25.179Z,1730759425.179 [SBIT] Loaded 2024-11-04T22:30:25.179Z,1730759425.179 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-11-04T22:30:25.182Z,1730759425.182 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-11-04T22:30:25.194Z,1730759425.194 [IBIT] Loaded 2024-11-04T22:30:25.195Z,1730759425.195 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-11-04T22:30:25.200Z,1730759425.200 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-11-04T22:30:25.306Z,1730759425.306 [CBIT] Loaded 2024-11-04T22:30:25.306Z,1730759425.306 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-11-04T22:30:25.307Z,1730759425.307 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-11-04T22:30:25.307Z,1730759425.307 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-11-04T22:30:25.441Z,1730759425.441 [BuoyancyServo] Loaded 2024-11-04T22:30:25.441Z,1730759425.441 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-11-04T22:30:25.464Z,1730759425.464 [ElevatorServo] Loaded 2024-11-04T22:30:25.464Z,1730759425.464 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-11-04T22:30:25.495Z,1730759425.495 [DockingStepper] Loaded 2024-11-04T22:30:25.495Z,1730759425.495 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2024-11-04T22:30:25.517Z,1730759425.517 [MassServo] Loaded 2024-11-04T22:30:25.517Z,1730759425.517 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-11-04T22:30:25.538Z,1730759425.538 [RudderServo] Loaded 2024-11-04T22:30:25.538Z,1730759425.538 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-11-04T22:30:25.557Z,1730759425.557 [ThrusterServo] Loaded 2024-11-04T22:30:25.557Z,1730759425.557 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2024-11-04T22:30:25.558Z,1730759425.558 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-11-04T22:30:25.558Z,1730759425.558 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-11-04T22:30:25.568Z,1730759425.568 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-11-04T22:30:25.569Z,1730759425.569 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-11-04T22:30:25.745Z,1730759425.745 [CTD_Seabird] Loaded 2024-11-04T22:30:25.745Z,1730759425.745 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-11-04T22:30:25.746Z,1730759425.746 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C204E0 2024-11-04T22:30:25.746Z,1730759425.746 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1564 2024-11-04T22:30:25.769Z,1730759425.769 [PAR_Licor] Loaded 2024-11-04T22:30:25.770Z,1730759425.770 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-11-04T22:30:25.882Z,1730759425.882 [WetLabsBB2FL] Loaded 2024-11-04T22:30:25.882Z,1730759425.882 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-11-04T22:30:25.883Z,1730759425.883 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C504E0 2024-11-04T22:30:25.883Z,1730759425.883 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1565 2024-11-04T22:30:25.904Z,1730759425.904 [WetLabsUBAT] Loaded 2024-11-04T22:30:25.904Z,1730759425.904 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2024-11-04T22:30:25.905Z,1730759425.905 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C804E0 2024-11-04T22:30:25.906Z,1730759425.906 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1566 2024-11-04T22:30:25.906Z,1730759425.906 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-11-04T22:30:25.913Z,1730759425.913 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-11-04T22:30:25.916Z,1730759425.916 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-11-04T22:30:25.927Z,1730759425.927 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-11-04T22:30:25.928Z,1730759425.928 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40CB04E0 2024-11-04T22:30:25.928Z,1730759425.928 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1567 2024-11-04T22:30:25.933Z,1730759425.933 [Supervisor](INFO): Main Thread ID is 829 2024-11-04T22:30:25.933Z,1730759425.933 [Supervisor](DEBUG): Running supervisor. 2024-11-04T22:30:25.934Z,1730759425.934 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1568 2024-11-04T22:30:25.934Z,1730759425.934 [CommandExec](INFO): Initializing the command executive. 2024-11-04T22:30:25.936Z,1730759425.936 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1569 2024-11-04T22:30:25.939Z,1730759425.939 [controlThread ThreadHandler](INFO): Handler Thread ID is 1570 2024-11-04T22:30:25.939Z,1730759425.939 [controlThread](DEBUG): Initializing ControlThread 2024-11-04T22:30:25.940Z,1730759425.940 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-11-04T22:30:25.942Z,1730759425.942 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-11-04T22:30:25.942Z,1730759425.942 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-11-04T22:30:25.943Z,1730759425.943 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-11-04T22:30:25.943Z,1730759425.943 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-11-04T22:30:25.944Z,1730759425.944 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-11-04T22:30:25.944Z,1730759425.944 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-11-04T22:30:25.944Z,1730759425.944 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-11-04T22:30:25.945Z,1730759425.945 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-11-04T22:30:25.951Z,1730759425.951 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-11-04T22:30:25.951Z,1730759425.951 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-11-04T22:30:25.951Z,1730759425.951 [SBIT](INFO): Initialize SBIT Component. 2024-11-04T22:30:25.952Z,1730759425.952 [SBIT](IMPORTANT): git: 2024-11-04 2024-11-04T22:30:25.952Z,1730759425.952 [SBIT](INFO): git hash: 918dcff84bd5c7d3a4287ae7c22e61012bd6dc2d 2024-11-04T22:30:25.952Z,1730759425.952 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-11-04T22:30:25.954Z,1730759425.954 [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 2024-11-04T22:30:25.955Z,1730759425.955 [SBIT](INFO): Beginning SBIT in 114.000000 seconds. 2024-11-04T22:30:25.955Z,1730759425.955 [IBIT](INFO): Initialize IBIT Component. 2024-11-04T22:30:25.956Z,1730759425.956 [CBIT](DEBUG): Initialize CBIT Component. 2024-11-04T22:30:25.957Z,1730759425.957 [logger ThreadHandler](INFO): Handler Thread ID is 1571 2024-11-04T22:30:25.969Z,1730759425.969 [CBIT](DEBUG): Initialized mux pins. 2024-11-04T22:30:25.970Z,1730759425.970 [CBIT](DEBUG): Initializing the watchdog timer. 2024-11-04T22:30:25.974Z,1730759425.974 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1572 2024-11-04T22:30:25.982Z,1730759425.982 [DAT ThreadHandler](INFO): Handler Thread ID is 1573 2024-11-04T22:30:25.983Z,1730759425.983 [DAT](INFO): Powering up 2024-11-04T22:30:25.983Z,1730759425.983 [DAT](DEBUG): Initializing DAT. 2024-11-04T22:30:25.987Z,1730759425.987 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1575 2024-11-04T22:30:25.988Z,1730759425.988 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-11-04T22:30:25.993Z,1730759425.993 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-11-04T22:30:25.994Z,1730759425.994 [CBIT](DEBUG): Initializing heartbeat. 2024-11-04T22:30:26.002Z,1730759426.002 [Onboard ThreadHandler](INFO): Handler Thread ID is 1576 2024-11-04T22:30:26.019Z,1730759426.019 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1577 2024-11-04T22:30:26.038Z,1730759426.038 [TrnDataBridge ThreadHandler](INFO): Handler Thread ID is 1578 2024-11-04T22:30:26.039Z,1730759426.039 [TrnDataBridge](INFO): Start 2024-11-04T22:30:26.039Z,1730759426.039 [TrnDataBridge](IMPORTANT): LCM Instance1 2024-11-04T22:30:26.065Z,1730759426.065 [CBIT](DEBUG): Deactivating GF circuits. 2024-11-04T22:30:26.065Z,1730759426.065 [CBIT](DEBUG): Deactivating emergency mode. 2024-11-04T22:30:26.071Z,1730759426.071 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1580 2024-11-04T22:30:26.071Z,1730759426.071 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-11-04T22:30:26.074Z,1730759426.074 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1582 2024-11-04T22:30:26.078Z,1730759426.078 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1584 2024-11-04T22:30:26.080Z,1730759426.080 [WetLabsBB2FL](INFO): Powering up 2024-11-04T22:30:26.082Z,1730759426.082 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1586 2024-11-04T22:30:26.085Z,1730759426.085 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-11-04T22:30:26.085Z,1730759426.085 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-11-04T22:30:26.085Z,1730759426.085 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-11-04T22:30:26.085Z,1730759426.085 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-11-04T22:30:26.086Z,1730759426.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-11-04T22:30:26.086Z,1730759426.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-11-04T22:30:26.086Z,1730759426.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-11-04T22:30:26.086Z,1730759426.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-11-04T22:30:26.102Z,1730759426.102 [WetLabsUBAT](INFO): Powering up 2024-11-04T22:30:26.105Z,1730759426.105 [CBIT](DEBUG): Backplane powered. 2024-11-04T22:30:26.110Z,1730759426.110 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-11-04T22:30:26.111Z,1730759426.111 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2024-11-04T22:30:26.111Z,1730759426.111 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2024-11-04T22:30:26.112Z,1730759426.112 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2024-11-04T22:30:26.117Z,1730759426.117 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2024-11-04T22:30:26.118Z,1730759426.118 [MissionManager](ERROR): Top node has no child nodes: Mission 2024-11-04T22:30:26.118Z,1730759426.118 [MissionManager](ERROR): Could not load startup mission. 2024-11-04T22:30:26.118Z,1730759426.118 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2024-11-04T22:30:26.118Z,1730759426.118 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2024-11-04T22:30:26.120Z,1730759426.120 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2024-11-04T22:30:26.425Z,1730759426.425 [Radio_Surface](INFO): Powering up 2024-11-04T22:30:26.685Z,1730759426.685 [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 2024-11-04T22:30:26.692Z,1730759426.692 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2024-11-04T22:30:26.704Z,1730759426.704 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2024-11-04T22:30:26.708Z,1730759426.708 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2024-11-04T22:30:26.732Z,1730759426.732 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2024-11-04T22:30:26.736Z,1730759426.736 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2024-11-04T22:30:26.759Z,1730759426.759 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2024-11-04T22:30:26.760Z,1730759426.760 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2024-11-04T22:30:26.919Z,1730759426.919 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2024-11-04T22:30:26.919Z,1730759426.919 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-11-04T22:30:26.972Z,1730759426.972 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-11-04T22:30:27.013Z,1730759427.013 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-11-04T22:30:27.016Z,1730759427.016 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-11-04T22:30:27.038Z,1730759427.038 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2024-11-04T22:30:27.042Z,1730759427.042 [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" } } 2024-11-04T22:30:27.057Z,1730759427.057 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,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,LcmUniversalReporter,Reporter,LogSplitter, 2024-11-04T22:30:27.070Z,1730759427.070 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-11-04T22:30:27.135Z,1730759427.135 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m 2024-11-04T22:30:27.136Z,1730759427.136 [Power24vConverter](INFO): Powering up. 2024-11-04T22:30:27.137Z,1730759427.137 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-11-04T22:30:27.146Z,1730759427.146 [Sonardyne_Nano](INFO): Initializing. 2024-11-04T22:30:27.194Z,1730759427.194 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-11-04T22:30:27.219Z,1730759427.219 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-11-04T22:30:27.226Z,1730759427.226 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-11-04T22:30:27.227Z,1730759427.227 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-11-04T22:30:27.234Z,1730759427.234 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-11-04T22:30:27.235Z,1730759427.235 [DockingStepper](INFO): Start 2024-11-04T22:30:27.235Z,1730759427.235 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-11-04T22:30:27.243Z,1730759427.243 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-11-04T22:30:27.243Z,1730759427.243 [MassServo](DEBUG): Initializing EZServoServo. 2024-11-04T22:30:27.251Z,1730759427.251 [MassServo](DEBUG): Initializing MassServo. 2024-11-04T22:30:27.252Z,1730759427.252 [RudderServo](DEBUG): Initializing EZServoServo. 2024-11-04T22:30:27.258Z,1730759427.258 [RudderServo](DEBUG): Initializing RudderServo. 2024-11-04T22:30:27.259Z,1730759427.259 [ThrusterServo](DEBUG): Initializing EZServoServo. 2024-11-04T22:30:27.266Z,1730759427.266 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2024-11-04T22:30:27.546Z,1730759427.546 [DockingStepper](INFO): Starting 2024-11-04T22:30:27.893Z,1730759427.893 [DockingStepper](INFO): Starting 2024-11-04T22:30:28.418Z,1730759428.418 [DockingStepper](INFO): Starting 2024-11-04T22:30:28.466Z,1730759428.466 [DockingStepper](INFO): Init string: /1z16337aE13561N3m50 2024-11-04T22:30:28.702Z,1730759428.702 [WetLabsBB2FL](INFO): Powering down 2024-11-04T22:30:28.739Z,1730759428.739 [DockingStepper](INFO): Starting 2024-11-04T22:30:29.112Z,1730759429.112 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-11-04T22:30:29.173Z,1730759429.173 [DockingStepper](INFO): Starting 2024-11-04T22:30:29.533Z,1730759429.533 [DockingStepper](INFO): Starting 2024-11-04T22:30:29.982Z,1730759429.982 [DockingStepper](INFO): Starting 2024-11-04T22:30:30.006Z,1730759430.006 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-11-04T22:30:30.460Z,1730759430.460 [DockingStepper](INFO): Starting 2024-11-04T22:30:30.766Z,1730759430.766 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2024-11-04T22:30:31.244Z,1730759431.244 [DockingStepper](INFO): Pause 2024-11-04T22:30:31.678Z,1730759431.678 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2024-11-04T22:30:31.679Z,1730759431.679 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0 2024-11-04T22:30:35.370Z,1730759435.370 [WetLabsUBAT](INFO): Powering down 2024-11-04T22:30:38.834Z,1730759438.834 [DAT](INFO): DAT read: 2024-11-04T22:30:38.835Z,1730759438.835 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-11-04T22:30:40.850Z,1730759440.850 [DAT](INFO): DAT read: MF Frequency Band 2024-11-04T22:30:40.852Z,1730759440.852 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2024-11-04T22:30:40.852Z,1730759440.852 [DAT](INFO): DAT read: Nov 4 2024 22:22:12 2024-11-04T22:30:41.859Z,1730759441.859 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-11-04T22:30:41.860Z,1730759441.860 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2024-11-04T22:30:41.860Z,1730759441.860 [DAT](INFO): commRate: 600 2024-11-04T22:30:43.925Z,1730759443.925 [DAT](INFO): entering command mode 2024-11-04T22:30:44.126Z,1730759444.126 [DAT](INFO): DAT read: 2024-11-04T22:30:44.126Z,1730759444.126 [DAT](INFO): DAT read: user:1> 2024-11-04T22:30:44.127Z,1730759444.127 [DAT](INFO): setting verbose to 3 2024-11-04T22:30:44.378Z,1730759444.378 [DAT](INFO): DAT read: user:1> 2024-11-04T22:30:44.379Z,1730759444.379 [DAT](INFO): DAT read: Verbose | 3 2024-11-04T22:30:44.380Z,1730759444.380 [DAT](INFO): set verbose to 3 2024-11-04T22:30:44.380Z,1730759444.380 [DAT](INFO): setting DatVerbose to 27440 2024-11-04T22:30:44.632Z,1730759444.632 [DAT](INFO): DAT read: user:2> 2024-11-04T22:30:44.635Z,1730759444.635 [DAT](INFO): DAT read: DatVerbose | 27440 2024-11-04T22:30:44.636Z,1730759444.636 [DAT](INFO): set DatVerbose to 27440 2024-11-04T22:30:44.637Z,1730759444.637 [DAT](INFO): setting transmit power to 8 2024-11-04T22:30:44.882Z,1730759444.882 [DAT](INFO): DAT read: user:3> 2024-11-04T22:30:44.883Z,1730759444.883 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-11-04T22:30:44.884Z,1730759444.884 [DAT](INFO): set transmit power to 8 2024-11-04T22:30:44.884Z,1730759444.884 [DAT](INFO): setting local address to 10 2024-11-04T22:30:45.134Z,1730759445.134 [DAT](INFO): DAT read: user:4> 2024-11-04T22:30:45.135Z,1730759445.135 [DAT](INFO): DAT read: LocalAddr | 10 2024-11-04T22:30:45.135Z,1730759445.135 [DAT](INFO): set local address to 10 2024-11-04T22:30:45.136Z,1730759445.136 [DAT](INFO): Setting time to: 22:30:45 And date to:11/4/2024 2024-11-04T22:30:45.387Z,1730759445.387 [DAT](INFO): DAT read: user:5> 2024-11-04T22:30:45.388Z,1730759445.388 [DAT](INFO): DAT read: Mon Nov 4, 2024 22:30:45 2024-11-04T22:30:45.388Z,1730759445.388 [DAT](INFO): Local DAT time set to Mon Nov 4, 2024 22:30:45 2024-11-04T22:30:55.027Z,1730759455.027 [NAL9602](INFO): Powering up NAL9602 2024-11-04T22:30:57.110Z,1730759457.110 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:31:05.935Z,1730759465.935 [NAL9602](INFO): NAL9602 initialized 2024-11-04T22:31:28.234Z,1730759488.234 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:31:59.394Z,1730759519.394 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:32:01.296Z,1730759521.296 [NAL9602](INFO): SBD MO Status=0, MOMSN=44166, MT Status=0, MTMSN=0 2024-11-04T22:32:01.296Z,1730759521.296 [NAL9602](INFO): No messages in MT queue 2024-11-04T22:32:02.524Z,1730759522.524 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223201.00,A,3648.14311,N,12147.22981,W,0.019,0.00,041124,,,A*78 2024-11-04T22:32:02.528Z,1730759522.528 [NAL9602](INFO): GPS fix at 20241104T223201: (36.802385, -121.787164) 2024-11-04T22:32:20.795Z,1730759540.795 [SBIT](IMPORTANT): Beginning Startup BIT 2024-11-04T22:32:20.807Z,1730759540.807 [CBIT](IMPORTANT): Beginning ground fault scan 2024-11-04T22:32:30.518Z,1730759550.518 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:32:32.229Z,1730759552.229 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.009545 CHAN A1 (24V): -0.000496 CHAN A2 (12V): 0.006125 CHAN A3 (5V): 0.000356 CHAN B0 (3.3V): 0.001400 CHAN B1 (3.15aV): 0.001387 CHAN B2 (3.15bV): 0.000946 CHAN B3 (GND): -0.000109 OPEN: 0.005538 Full Scale: +/- 1 mA 2024-11-04T22:32:34.862Z,1730759554.862 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:33:01.654Z,1730759581.654 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:33:14.530Z,1730759594.530 [SBIT](IMPORTANT): SBIT PASSED 2024-11-04T22:33:14.530Z,1730759594.530 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-11-04T22:33:14.531Z,1730759594.531 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere; 2024-11-04T22:33:14.531Z,1730759594.531 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count; 2024-11-04T22:33:14.531Z,1730759594.531 [SBIT](IMPORTANT): DockingStepper.slideValue=10800 count; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 5.000000 meter; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): Express linearApproximation TrnDataBridge.mmse_lat 0.000200 angular_degree; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): Express linearApproximation TrnDataBridge.mmse_lon 0.000200 angular_degree; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): LineCapture.interceptTimeout=5 second; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): LineCapture.kiHeadingFinalApproach=0.02 reciprocal_second; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): LineCapture.kiHeadingTerminalGuidance=0.02 reciprocal_second; 2024-11-04T22:33:14.532Z,1730759594.532 [SBIT](IMPORTANT): LineCapture.navigationGain=3 none; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): RudderServo.offsetAngle=0.2 degree; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): TrnDataBridge.loadAtStartup=1 bool; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): Undock.reverseThrustTimeout=20 second; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=317.403036 cubic_centimeter; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=1.25 second; 2024-11-04T22:33:14.533Z,1730759594.533 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=1.25 reciprocal_second; 2024-11-04T22:33:14.534Z,1730759594.534 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=1.25 none; 2024-11-04T22:33:14.534Z,1730759594.534 [SBIT](IMPORTANT): VerticalControl.massDeadband=0.05 centimeter; 2024-11-04T22:33:14.534Z,1730759594.534 [SBIT](IMPORTANT): VerticalControl.massDefault=23.583194 millimeter; 2024-11-04T22:33:14.534Z,1730759594.534 [SBIT](IMPORTANT): VerticalControl.massOnlyForceInt=0 bool; 2024-11-04T22:33:14.945Z,1730759594.945 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2024-11-04T22:33:14.946Z,1730759594.945 [DefaultWithUndock] Running Loop=1 2024-11-04T22:33:14.946Z,1730759594.946 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2024-11-04T22:33:14.946Z,1730759594.946 [DefaultWithUndock:A.Wait] Running Loop=1 2024-11-04T22:33:14.946Z,1730759594.946 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:33:32.802Z,1730759612.802 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:33:45.222Z,1730759625.222 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2024-11-04T22:33:45.222Z,1730759625.222 [DefaultWithUndock:A.Wait] Stopped 2024-11-04T22:33:45.222Z,1730759625.222 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock] Running Loop=1 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-11-04T22:33:45.223Z,1730759625.223 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-11-04T22:33:45.633Z,1730759625.633 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-11-04T22:33:45.633Z,1730759625.633 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-11-04T22:33:45.634Z,1730759625.634 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.258709 m. 2024-11-04T22:33:45.635Z,1730759625.635 [DefaultWithUndock:LeaveDock:A.] Stopped 2024-11-04T22:33:45.635Z,1730759625.635 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2024-11-04T22:33:45.640Z,1730759625.640 [DockingStepper](INFO): Resume 2024-11-04T22:33:46.050Z,1730759626.050 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2024-11-04T22:33:46.070Z,1730759626.070 [DockingStepper](INFO): Resuming 2024-11-04T22:33:46.440Z,1730759626.440 [DockingStepper](INFO): Start 2024-11-04T22:33:46.440Z,1730759626.440 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-11-04T22:33:46.558Z,1730759626.558 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-11-04T22:33:46.865Z,1730759626.865 [DockingStepper](INFO): Starting 2024-11-04T22:33:47.256Z,1730759627.256 [DockingStepper](INFO): Starting 2024-11-04T22:33:47.808Z,1730759627.808 [DockingStepper](INFO): Starting 2024-11-04T22:33:47.865Z,1730759627.865 [DockingStepper](INFO): Init string: /1z16336aE13561N3m50 2024-11-04T22:33:48.140Z,1730759628.140 [DockingStepper](INFO): Starting 2024-11-04T22:33:48.582Z,1730759628.582 [DockingStepper](INFO): Starting 2024-11-04T22:33:48.963Z,1730759628.963 [DockingStepper](INFO): Starting 2024-11-04T22:33:49.331Z,1730759629.331 [DockingStepper](INFO): Starting 2024-11-04T22:33:49.358Z,1730759629.358 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-11-04T22:33:49.715Z,1730759629.715 [DockingStepper](INFO): Starting 2024-11-04T22:34:03.934Z,1730759643.934 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:34:06.254Z,1730759646.254 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2024-11-04T22:34:06.707Z,1730759646.707 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:LeaveDock] Stopped 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2024-11-04T22:34:07.092Z,1730759647.092 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-11-04T22:34:07.093Z,1730759647.093 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-11-04T22:34:07.099Z,1730759647.099 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-11-04T22:34:07.099Z,1730759647.099 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-11-04T22:34:07.099Z,1730759647.099 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-11-04T22:34:07.100Z,1730759647.100 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-11-04T22:34:07.100Z,1730759647.100 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-11-04T22:34:07.466Z,1730759647.466 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2024-11-04T22:34:07.466Z,1730759647.466 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:34:07.467Z,1730759647.467 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T22:34:07.606Z,1730759647.606 [DockingStepper](INFO): Pause 2024-11-04T22:34:07.871Z,1730759647.871 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-11-04T22:34:09.452Z,1730759649.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223408.00,A,3648.14294,N,12147.22837,W,0.019,0.00,041124,,,A*77 2024-11-04T22:34:09.463Z,1730759649.463 [NAL9602](INFO): GPS fix at 20241104T223408: (36.802382, -121.787139) 2024-11-04T22:34:09.494Z,1730759649.494 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T22:34:09.494Z,1730759649.494 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T22:34:09.902Z,1730759649.902 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-11-04T22:34:12.011Z,1730759652.011 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005174 2024-11-04T22:34:21.494Z,1730759661.494 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241104T213612/Courier0031.lzma 2024-11-04T22:34:22.476Z,1730759662.476 [DataOverHttps](INFO): Moved sent file to Logs/20241104T213612/Courier0031.lzma.bak 2024-11-04T22:34:22.476Z,1730759662.476 [DataOverHttps](INFO): SBD MOMSN=23370310 2024-11-04T22:34:30.404Z,1730759670.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=44167, MT Status=2, MTMSN=0 2024-11-04T22:34:30.404Z,1730759670.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:34:41.405Z,1730759681.405 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20241104T223014/Courier0000.lzma 2024-11-04T22:34:42.408Z,1730759682.408 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0000.lzma.bak 2024-11-04T22:34:42.408Z,1730759682.408 [DataOverHttps](INFO): SBD MOMSN=23370313 2024-11-04T22:34:59.087Z,1730759699.087 [DataOverHttps](INFO): Sending 1142 bytes from file Logs/20241104T213612/Express0032.lzma 2024-11-04T22:35:00.087Z,1730759700.087 [DataOverHttps](INFO): Moved sent file to Logs/20241104T213612/Express0032.lzma.bak 2024-11-04T22:35:00.087Z,1730759700.087 [DataOverHttps](INFO): SBD MOMSN=23370317 2024-11-04T22:35:01.139Z,1730759701.139 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:35:16.627Z,1730759716.627 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20241104T213612/Express0035.lzma 2024-11-04T22:35:17.620Z,1730759717.620 [DataOverHttps](INFO): Moved sent file to Logs/20241104T213612/Express0035.lzma.bak 2024-11-04T22:35:17.620Z,1730759717.620 [DataOverHttps](INFO): SBD MOMSN=23370377 2024-11-04T22:35:34.373Z,1730759734.373 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20241104T213612/Express0038.lzma 2024-11-04T22:35:35.375Z,1730759735.375 [DataOverHttps](INFO): Moved sent file to Logs/20241104T213612/Express0038.lzma.bak 2024-11-04T22:35:35.375Z,1730759735.375 [DataOverHttps](INFO): SBD MOMSN=23370379 2024-11-04T22:35:52.159Z,1730759752.159 [DataOverHttps](INFO): Sending 1368 bytes from file Logs/20241104T223014/Express0001.lzma 2024-11-04T22:35:53.160Z,1730759753.160 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0001.lzma.bak 2024-11-04T22:35:53.160Z,1730759753.160 [DataOverHttps](IMPORTANT): SBD MOMSN=23370382, MTMSN=20241104T223552 2024-11-04T22:35:54.449Z,1730759754.449 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T22:35:54.449Z,1730759754.449 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T22:35:54.449Z,1730759754.449 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:36:00.806Z,1730759760.806 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.696 degree;set transit.Longitude -121.94146 degree;run 2024-11-04T22:36:00.908Z,1730759760.908 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2024-11-04T22:36:00.908Z,1730759760.908 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2024-11-04T22:36:00.908Z,1730759760.908 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2024-11-04T22:36:00.912Z,1730759760.912 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2024-11-04T22:36:01.932Z,1730759761.932 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2024-11-04T22:36:01.938Z,1730759761.938 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2024-11-04T22:36:01.941Z,1730759761.941 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2024-11-04T22:36:01.944Z,1730759761.944 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2024-11-04T22:36:01.947Z,1730759761.947 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2024-11-04T22:36:01.950Z,1730759761.950 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2024-11-04T22:36:01.953Z,1730759761.953 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2024-11-04T22:36:01.956Z,1730759761.956 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2024-11-04T22:36:01.959Z,1730759761.959 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2024-11-04T22:36:01.962Z,1730759761.962 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2024-11-04T22:36:01.965Z,1730759761.965 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2024-11-04T22:36:01.969Z,1730759761.969 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2024-11-04T22:36:01.997Z,1730759761.997 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2024-11-04T22:36:02.011Z,1730759762.011 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2024-11-04T22:36:02.012Z,1730759762.012 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-11-04T22:36:02.012Z,1730759762.012 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-11-04T22:36:02.018Z,1730759762.018 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-11-04T22:36:02.490Z,1730759762.490 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2024-11-04T22:36:02.495Z,1730759762.495 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2024-11-04T22:36:02.543Z,1730759762.543 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2024-11-04T22:36:02.551Z,1730759762.551 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-11-04T22:36:02.559Z,1730759762.559 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2024-11-04T22:36:02.574Z,1730759762.574 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-11-04T22:36:02.582Z,1730759762.582 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2024-11-04T22:36:02.585Z,1730759762.585 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2024-11-04T22:36:02.604Z,1730759762.604 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2024-11-04T22:36:02.633Z,1730759762.633 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-11-04T22:36:02.724Z,1730759762.724 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-11-04T22:36:02.724Z,1730759762.724 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-11-04T22:36:02.725Z,1730759762.725 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-11-04T22:36:02.810Z,1730759762.810 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:36:02.898Z,1730759762.898 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-11-04T22:36:02.919Z,1730759762.919 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2024-11-04T22:36:02.927Z,1730759762.927 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-11-04T22:36:02.948Z,1730759762.948 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2024-11-04T22:36:02.955Z,1730759762.955 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-11-04T22:36:02.956Z,1730759762.956 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-11-04T22:36:02.988Z,1730759762.988 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-11-04T22:36:03.022Z,1730759763.022 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-11-04T22:36:03.065Z,1730759763.065 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-11-04T22:36:03.065Z,1730759763.065 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-11-04T22:36:03.074Z,1730759763.074 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-11-04T22:36:03.112Z,1730759763.112 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-11-04T22:36:03.121Z,1730759763.121 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2024-11-04T22:36:03.128Z,1730759763.128 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-11-04T22:36:03.132Z,1730759763.132 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-11-04T22:36:03.133Z,1730759763.133 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-11-04T22:36:03.142Z,1730759763.142 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-11-04T22:36:03.375Z,1730759763.375 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-11-04T22:36:03.395Z,1730759763.395 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool 2024-11-04T22:36:03.402Z,1730759763.402 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool 2024-11-04T22:36:03.410Z,1730759763.410 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool 2024-11-04T22:36:03.413Z,1730759763.413 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool 2024-11-04T22:36:03.426Z,1730759763.426 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool 2024-11-04T22:36:03.448Z,1730759763.448 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-11-04T22:36:03.454Z,1730759763.454 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2024-11-04T22:36:03.462Z,1730759763.462 [transit:MassHold.Pitch](DEBUG): Construct. 2024-11-04T22:36:03.479Z,1730759763.479 [transit:Transit:A.Pitch](DEBUG): Construct. 2024-11-04T22:36:03.495Z,1730759763.495 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2024-11-04T22:36:03.502Z,1730759763.502 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2024-11-04T22:36:03.535Z,1730759763.535 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2024-11-04T22:36:03.536Z,1730759763.536 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2024-11-04T22:36:14.508Z,1730759774.508 [DataOverHttps](IMPORTANT): SBD MTMSN=20241104T223614 2024-11-04T22:36:14.951Z,1730759774.951 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute 2024-11-04T22:36:14.951Z,1730759774.951 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute 2024-11-04T22:36:14.952Z,1730759774.952 [CommandExec](IMPORTANT): got command set transit.Latitude 36.696 degree 2024-11-04T22:36:14.953Z,1730759774.953 [CommandExec](IMPORTANT): got command set transit.Longitude -121.94146 degree 2024-11-04T22:36:14.954Z,1730759774.954 [CommandExec](IMPORTANT): got command run 2024-11-04T22:36:14.956Z,1730759774.956 [CommandExec](IMPORTANT): Running 2024-11-04T22:36:15.208Z,1730759775.208 [DefaultWithUndock] Stopped 2024-11-04T22:36:15.208Z,1730759775.208 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault] Stopped 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T22:36:15.209Z,1730759775.209 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:36:15.209Z,1730759775.209 [MissionManager](IMPORTANT): Started mission transit 2024-11-04T22:36:15.210Z,1730759775.210 [transit] Running Loop=1 2024-11-04T22:36:15.210Z,1730759775.210 [transit](DEBUG): Aggregate::initialize transit 2024-11-04T22:36:15.210Z,1730759775.210 [transit:StandardEnvelopes] Running Loop=1 2024-11-04T22:36:15.210Z,1730759775.210 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2024-11-04T22:36:15.210Z,1730759775.210 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-11-04T22:36:15.210Z,1730759775.210 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-11-04T22:36:15.210Z,1730759775.210 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-11-04T22:36:15.211Z,1730759775.211 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-11-04T22:36:15.211Z,1730759775.211 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-11-04T22:36:15.211Z,1730759775.211 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-11-04T22:36:15.211Z,1730759775.211 [transit:BackseatDriver] Running Loop=1 2024-11-04T22:36:15.211Z,1730759775.211 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2024-11-04T22:36:15.211Z,1730759775.211 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-11-04T22:36:15.211Z,1730759775.211 [transit:PowerOnly] Running Loop=1 2024-11-04T22:36:15.211Z,1730759775.211 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly 2024-11-04T22:36:15.211Z,1730759775.211 [transit:PowerOnly:A] Running Loop=1 2024-11-04T22:36:15.211Z,1730759775.211 [transit:PowerOnly:B] Running Loop=1 2024-11-04T22:36:15.212Z,1730759775.212 [transit:PowerOnly:C] Running Loop=1 2024-11-04T22:36:15.212Z,1730759775.212 [transit:PowerOnly:D] Running Loop=1 2024-11-04T22:36:15.212Z,1730759775.212 [transit:PowerOnly:E.Wait] Running Loop=1 2024-11-04T22:36:15.212Z,1730759775.212 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:36:15.212Z,1730759775.212 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2024-11-04T22:36:15.212Z,1730759775.212 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-11-04T22:36:15.212Z,1730759775.212 [transit:MassHold.Pitch] Running Loop=1 2024-11-04T22:36:15.212Z,1730759775.212 [transit:MassHold.Pitch](DEBUG): Initialize. 2024-11-04T22:36:15.213Z,1730759775.213 [transit:B] Running Loop=1 2024-11-04T22:36:15.213Z,1730759775.213 [transit:MassHold.Pitch] Running Loop=1 2024-11-04T22:36:15.214Z,1730759775.214 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2024-11-04T22:36:15.214Z,1730759775.214 [transit:PowerOnly] Running Loop=1 2024-11-04T22:36:15.214Z,1730759775.214 [transit:PowerOnly:D] Stopped 2024-11-04T22:36:15.214Z,1730759775.214 [transit:PowerOnly:C] Stopped 2024-11-04T22:36:15.215Z,1730759775.215 [transit:PowerOnly:B] Stopped 2024-11-04T22:36:15.215Z,1730759775.215 [transit:PowerOnly:A] Stopped 2024-11-04T22:36:15.215Z,1730759775.215 [transit:BackseatDriver] Running Loop=1 2024-11-04T22:36:15.215Z,1730759775.215 [transit:StandardEnvelopes] Running Loop=1 2024-11-04T22:36:15.215Z,1730759775.215 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-11-04T22:36:15.216Z,1730759775.216 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-11-04T22:36:15.224Z,1730759775.224 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-11-04T22:36:15.224Z,1730759775.224 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-11-04T22:36:15.225Z,1730759775.225 [transit:B] Stopped 2024-11-04T22:36:15.225Z,1730759775.225 [transit:C] Running Loop=1 2024-11-04T22:36:15.230Z,1730759775.230 [HorizontalControl](DEBUG): kpHeading = 0.400000 2024-11-04T22:36:15.230Z,1730759775.230 [HorizontalControl](DEBUG): kiHeading = 0.001000 2024-11-04T22:36:15.230Z,1730759775.230 [HorizontalControl](DEBUG): kdHeading = 0.050000 2024-11-04T22:36:15.686Z,1730759775.686 [transit:C] Stopped 2024-11-04T22:36:15.686Z,1730759775.686 [transit:E] Running Loop=1 2024-11-04T22:36:16.011Z,1730759776.011 [transit:E] Stopped 2024-11-04T22:36:16.011Z,1730759776.011 [transit:F] Running Loop=1 2024-11-04T22:36:16.422Z,1730759776.422 [transit:F] Stopped 2024-11-04T22:36:16.422Z,1730759776.422 [transit:G] Running Loop=1 2024-11-04T22:36:16.899Z,1730759776.899 [transit:G] Stopped 2024-11-04T22:36:16.899Z,1730759776.899 [transit:Transit] Running Loop=1 2024-11-04T22:36:16.899Z,1730759776.899 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2024-11-04T22:36:16.899Z,1730759776.899 [transit:Transit:A.Pitch] Running Loop=1 2024-11-04T22:36:16.900Z,1730759776.900 [transit:Transit:A.Pitch](DEBUG): Initialize. 2024-11-04T22:36:16.900Z,1730759776.900 [transit:Transit:B.SetSpeed] Running Loop=1 2024-11-04T22:36:16.900Z,1730759776.900 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2024-11-04T22:36:16.900Z,1730759776.900 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2024-11-04T22:36:16.900Z,1730759776.900 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2024-11-04T22:36:16.938Z,1730759776.938 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.695999,-121.941460 2024-11-04T22:36:16.955Z,1730759776.955 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2024-11-04T22:36:17.227Z,1730759777.227 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.695999,-121.941460 2024-11-04T22:36:17.227Z,1730759777.227 [transit:Transit:B.SetSpeed] Running Loop=1 2024-11-04T22:36:17.227Z,1730759777.227 [transit:Transit:A.Pitch] Running Loop=1 2024-11-04T22:36:27.279Z,1730759787.279 [DataOverHttps](INFO): Received command: stop 2024-11-04T22:36:27.322Z,1730759787.322 [CommandExec](IMPORTANT): got command stop 2024-11-04T22:36:27.323Z,1730759787.323 [CommandExec](IMPORTANT): Scheduling is paused 2024-11-04T22:36:27.323Z,1730759787.323 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-11-04T22:36:27.622Z,1730759787.622 [MissionManager](INFO): MissionManager is completed. 2024-11-04T22:36:27.623Z,1730759787.622 [MissionManager](INFO): Uninitializing Mission transit 2024-11-04T22:36:27.623Z,1730759787.623 [transit] Stopped 2024-11-04T22:36:27.623Z,1730759787.623 [transit](DEBUG): Aggregate::uninitialize transit 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes] Stopped 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-11-04T22:36:27.623Z,1730759787.623 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-11-04T22:36:27.623Z,1730759787.623 [transit:BackseatDriver] Stopped 2024-11-04T22:36:27.623Z,1730759787.623 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2024-11-04T22:36:27.624Z,1730759787.624 [transit:BackseatDriver:A.BackseatDriver] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:PowerOnly] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2024-11-04T22:36:27.624Z,1730759787.624 [transit:PowerOnly:E.Wait] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:36:27.624Z,1730759787.624 [transit:BuoyancyHold.Buoyancy] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-11-04T22:36:27.624Z,1730759787.624 [transit:MassHold.Pitch] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:Transit] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2024-11-04T22:36:27.624Z,1730759787.624 [transit:Transit:A.Pitch] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:Transit:B.SetSpeed] Stopped 2024-11-04T22:36:27.624Z,1730759787.624 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2024-11-04T22:36:27.624Z,1730759787.624 [transit:Transit:Wpt1.Waypoint] Stopped 2024-11-04T22:36:27.625Z,1730759787.625 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2024-11-04T22:36:27.986Z,1730759787.986 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2024-11-04T22:36:27.986Z,1730759787.986 [DefaultWithUndock] Running Loop=1 2024-11-04T22:36:27.986Z,1730759787.986 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2024-11-04T22:36:27.986Z,1730759787.986 [DefaultWithUndock:A.Wait] Running Loop=1 2024-11-04T22:36:27.986Z,1730759787.986 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:36:29.282Z,1730759789.282 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:36:38.058Z,1730759798.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:36:47.186Z,1730759807.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:36:56.050Z,1730759816.050 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:36:58.309Z,1730759818.309 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2024-11-04T22:36:58.309Z,1730759818.309 [DefaultWithUndock:A.Wait] Stopped 2024-11-04T22:36:58.309Z,1730759818.309 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:36:58.317Z,1730759818.317 [DefaultWithUndock:LeaveDock] Running Loop=1 2024-11-04T22:36:58.318Z,1730759818.318 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2024-11-04T22:36:58.318Z,1730759818.318 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-11-04T22:36:58.318Z,1730759818.318 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2024-11-04T22:36:58.318Z,1730759818.318 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2024-11-04T22:36:58.318Z,1730759818.318 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-11-04T22:36:58.318Z,1730759818.318 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-11-04T22:36:58.783Z,1730759818.783 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-11-04T22:36:58.784Z,1730759818.784 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-11-04T22:36:58.784Z,1730759818.784 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.260272 m. 2024-11-04T22:36:58.785Z,1730759818.785 [DefaultWithUndock:LeaveDock:A.] Stopped 2024-11-04T22:36:58.785Z,1730759818.785 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2024-11-04T22:36:58.798Z,1730759818.798 [DockingStepper](INFO): Resume 2024-11-04T22:36:59.135Z,1730759819.135 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2024-11-04T22:36:59.147Z,1730759819.147 [DockingStepper](INFO): Resuming 2024-11-04T22:36:59.520Z,1730759819.520 [DockingStepper](INFO): Start 2024-11-04T22:36:59.520Z,1730759819.520 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-11-04T22:36:59.638Z,1730759819.638 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-11-04T22:36:59.935Z,1730759819.935 [DockingStepper](INFO): Starting 2024-11-04T22:37:00.345Z,1730759820.345 [DockingStepper](INFO): Starting 2024-11-04T22:37:00.903Z,1730759820.903 [DockingStepper](INFO): Starting 2024-11-04T22:37:00.950Z,1730759820.950 [DockingStepper](INFO): Init string: /1z16336aE13561N3m50 2024-11-04T22:37:01.159Z,1730759821.159 [DockingStepper](INFO): Starting 2024-11-04T22:37:01.582Z,1730759821.582 [DockingStepper](INFO): Starting 2024-11-04T22:37:01.987Z,1730759821.987 [DockingStepper](INFO): Starting 2024-11-04T22:37:02.355Z,1730759822.355 [DockingStepper](INFO): Starting 2024-11-04T22:37:02.384Z,1730759822.384 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-11-04T22:37:02.802Z,1730759822.802 [DockingStepper](INFO): Starting 2024-11-04T22:37:05.062Z,1730759825.062 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:37:14.058Z,1730759834.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:37:19.310Z,1730759839.310 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2024-11-04T22:37:19.717Z,1730759839.717 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2024-11-04T22:37:20.108Z,1730759840.108 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2024-11-04T22:37:20.108Z,1730759840.108 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2024-11-04T22:37:20.108Z,1730759840.108 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2024-11-04T22:37:20.109Z,1730759840.109 [DefaultWithUndock:LeaveDock] Stopped 2024-11-04T22:37:20.109Z,1730759840.109 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2024-11-04T22:37:20.109Z,1730759840.109 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2024-11-04T22:37:20.109Z,1730759840.109 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2024-11-04T22:37:20.109Z,1730759840.109 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2024-11-04T22:37:20.109Z,1730759840.109 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-11-04T22:37:20.130Z,1730759840.130 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-11-04T22:37:20.130Z,1730759840.130 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-11-04T22:37:20.130Z,1730759840.130 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-11-04T22:37:20.131Z,1730759840.131 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-11-04T22:37:20.131Z,1730759840.131 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-11-04T22:37:20.131Z,1730759840.131 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-11-04T22:37:20.518Z,1730759840.518 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2024-11-04T22:37:20.519Z,1730759840.519 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:37:20.519Z,1730759840.519 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T22:37:20.667Z,1730759840.667 [DockingStepper](INFO): Pause 2024-11-04T22:37:22.519Z,1730759842.519 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223722.00,A,3648.14365,N,12147.22521,W,0.019,0.00,041124,,,A*79 2024-11-04T22:37:22.521Z,1730759842.521 [NAL9602](INFO): GPS fix at 20241104T223722: (36.802394, -121.787087) 2024-11-04T22:37:22.548Z,1730759842.548 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T22:37:22.549Z,1730759842.549 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T22:37:29.929Z,1730759849.929 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241104T223014/Courier0004.lzma 2024-11-04T22:37:30.931Z,1730759850.931 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0004.lzma.bak 2024-11-04T22:37:30.931Z,1730759850.931 [DataOverHttps](INFO): SBD MOMSN=23370445 2024-11-04T22:37:42.352Z,1730759862.352 [NAL9602](INFO): SBD MO Status=2, MOMSN=44167, MT Status=2, MTMSN=0 2024-11-04T22:37:42.352Z,1730759862.352 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:37:47.546Z,1730759867.546 [DataOverHttps](INFO): Sending 549 bytes from file Logs/20241104T223014/Express0005.lzma 2024-11-04T22:37:48.547Z,1730759868.547 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0005.lzma.bak 2024-11-04T22:37:48.547Z,1730759868.547 [DataOverHttps](INFO): SBD MOMSN=23370448 2024-11-04T22:37:49.650Z,1730759869.650 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T22:37:49.651Z,1730759869.651 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T22:37:49.651Z,1730759869.651 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:37:51.648Z,1730759871.648 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2024-11-04T22:37:51.656Z,1730759871.656 [BPC1](INFO): Received data from all battery sticks. 2024-11-04T22:37:53.256Z,1730759873.256 [NAL9602](INFO): SBD MO Status=1, MOMSN=44167, MT Status=0, MTMSN=0 2024-11-04T22:37:53.256Z,1730759873.256 [NAL9602](INFO): No messages in MT queue 2024-11-04T22:37:58.242Z,1730759878.242 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:38:09.906Z,1730759889.906 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:38:19.694Z,1730759899.694 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:38:24.002Z,1730759904.002 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:38:28.330Z,1730759908.330 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:38:37.062Z,1730759917.062 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:38:45.814Z,1730759925.814 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:38:54.730Z,1730759934.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:03.290Z,1730759943.290 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:12.110Z,1730759952.110 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:20.790Z,1730759960.790 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:29.538Z,1730759969.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:38.094Z,1730759978.094 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:46.906Z,1730759986.906 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:39:55.482Z,1730759995.482 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:40:04.573Z,1730760004.573 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:40:16.714Z,1730760016.714 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:40:25.314Z,1730760025.314 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:40:33.962Z,1730760033.962 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:40:42.639Z,1730760042.639 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:40:51.514Z,1730760051.514 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:00.162Z,1730760060.162 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:08.922Z,1730760068.922 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:17.558Z,1730760077.558 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:26.426Z,1730760086.426 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:35.066Z,1730760095.066 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:43.730Z,1730760103.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:41:52.651Z,1730760112.651 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:01.186Z,1730760121.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:10.046Z,1730760130.046 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:18.674Z,1730760138.674 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:31.054Z,1730760151.054 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:39.546Z,1730760159.546 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:48.354Z,1730760168.354 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:42:50.466Z,1730760170.466 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2024-11-04T22:42:50.466Z,1730760170.466 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T22:42:50.466Z,1730760170.466 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:42:50.468Z,1730760170.468 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2024-11-04T22:42:50.840Z,1730760170.840 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2024-11-04T22:42:50.840Z,1730760170.840 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 6.380898 min 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:42:51.215Z,1730760171.215 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T22:42:53.204Z,1730760173.204 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224252.00,A,3648.14123,N,12147.22833,W,0.019,0.00,041124,,,A*72 2024-11-04T22:42:53.206Z,1730760173.206 [NAL9602](INFO): GPS fix at 20241104T224252: (36.802354, -121.787139) 2024-11-04T22:42:53.254Z,1730760173.254 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T22:42:53.255Z,1730760173.255 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T22:42:55.661Z,1730760175.661 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20241104T223014/Courier0007.lzma 2024-11-04T22:42:56.691Z,1730760176.691 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0007.lzma.bak 2024-11-04T22:42:56.729Z,1730760176.729 [DataOverHttps](INFO): SBD MOMSN=23370469 2024-11-04T22:43:13.525Z,1730760193.525 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20241104T223014/Express0009.lzma 2024-11-04T22:43:14.527Z,1730760194.527 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0009.lzma.bak 2024-11-04T22:43:14.527Z,1730760194.527 [DataOverHttps](INFO): SBD MOMSN=23370472 2024-11-04T22:43:15.918Z,1730760195.918 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T22:43:15.918Z,1730760195.918 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T22:43:15.918Z,1730760195.918 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:43:24.246Z,1730760204.246 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:43:33.186Z,1730760213.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:43:39.333Z,1730760219.333 [NAL9602](INFO): SBD MO Status=2, MOMSN=44168, MT Status=2, MTMSN=0 2024-11-04T22:43:39.333Z,1730760219.333 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:43:41.806Z,1730760221.806 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:43:50.578Z,1730760230.578 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:43:59.202Z,1730760239.202 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:44:07.942Z,1730760247.942 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:44:15.692Z,1730760255.692 [NAL9602](INFO): SBD MO Status=1, MOMSN=44168, MT Status=0, MTMSN=0 2024-11-04T22:44:15.693Z,1730760255.693 [NAL9602](INFO): No messages in MT queue 2024-11-04T22:44:16.615Z,1730760256.615 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:44:28.704Z,1730760268.704 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:44:37.542Z,1730760277.542 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:44:46.198Z,1730760286.198 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:44:46.476Z,1730760286.476 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:44:55.090Z,1730760295.090 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:03.550Z,1730760303.550 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:12.438Z,1730760312.438 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:21.114Z,1730760321.114 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:30.050Z,1730760330.050 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:38.678Z,1730760338.678 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:47.366Z,1730760347.366 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:45:56.074Z,1730760356.074 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:46:04.870Z,1730760364.870 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:46:13.418Z,1730760373.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:46:24.362Z,1730760384.362 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:46:34.702Z,1730760394.702 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:46:43.310Z,1730760403.310 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:46:52.362Z,1730760412.362 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:01.050Z,1730760421.050 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:09.770Z,1730760429.770 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:18.418Z,1730760438.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:27.410Z,1730760447.410 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:36.138Z,1730760456.138 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:44.990Z,1730760464.990 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:47:53.538Z,1730760473.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:48:02.354Z,1730760482.354 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:48:11.062Z,1730760491.062 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:48:16.687Z,1730760496.687 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2024-11-04T22:48:16.687Z,1730760496.687 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T22:48:16.687Z,1730760496.687 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:48:16.687Z,1730760496.687 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2024-11-04T22:48:17.072Z,1730760497.072 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2024-11-04T22:48:17.072Z,1730760497.072 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2024-11-04T22:48:17.422Z,1730760497.422 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 11.818104 min 2024-11-04T22:48:17.422Z,1730760497.422 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:48:17.423Z,1730760497.423 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T22:48:19.424Z,1730760499.424 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224819.00,A,3648.14251,N,12147.22793,W,0.000,0.00,041124,,,A*7C 2024-11-04T22:48:19.426Z,1730760499.426 [NAL9602](INFO): GPS fix at 20241104T224819: (36.802375, -121.787132) 2024-11-04T22:48:19.446Z,1730760499.446 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T22:48:19.446Z,1730760499.446 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T22:48:19.538Z,1730760499.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:48:29.501Z,1730760509.501 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241104T223014/Courier0013.lzma 2024-11-04T22:48:30.503Z,1730760510.503 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0013.lzma.bak 2024-11-04T22:48:30.503Z,1730760510.503 [DataOverHttps](INFO): SBD MOMSN=23370489 2024-11-04T22:48:33.380Z,1730760513.380 [NAL9602](INFO): SBD MO Status=1, MOMSN=44169, MT Status=0, MTMSN=0 2024-11-04T22:48:33.380Z,1730760513.380 [NAL9602](INFO): No messages in MT queue 2024-11-04T22:48:47.081Z,1730760527.081 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241104T223014/Express0014.lzma 2024-11-04T22:48:48.083Z,1730760528.083 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0014.lzma.bak 2024-11-04T22:48:48.083Z,1730760528.083 [DataOverHttps](INFO): SBD MOMSN=23370492 2024-11-04T22:48:49.143Z,1730760529.143 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T22:48:49.143Z,1730760529.143 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T22:48:49.143Z,1730760529.143 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:48:58.066Z,1730760538.066 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:04.086Z,1730760544.086 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:49:06.659Z,1730760546.659 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:15.442Z,1730760555.442 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:24.034Z,1730760564.034 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:32.858Z,1730760572.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:41.347Z,1730760581.347 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:50.046Z,1730760590.046 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:49:58.663Z,1730760598.663 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:50:10.434Z,1730760610.434 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:50:19.802Z,1730760619.802 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:50:28.458Z,1730760628.458 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:50:37.414Z,1730760637.414 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:50:46.042Z,1730760646.042 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:50:54.926Z,1730760654.926 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:03.418Z,1730760663.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:12.138Z,1730760672.138 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:20.782Z,1730760680.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:29.442Z,1730760689.442 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:38.074Z,1730760698.074 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:46.948Z,1730760706.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:51:55.690Z,1730760715.690 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:04.430Z,1730760724.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:16.643Z,1730760736.643 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:25.150Z,1730760745.150 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:34.014Z,1730760754.014 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:42.643Z,1730760762.643 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:51.310Z,1730760771.310 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:52:59.894Z,1730760779.894 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:08.627Z,1730760788.627 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:17.222Z,1730760797.222 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:26.058Z,1730760806.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:34.714Z,1730760814.714 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:43.454Z,1730760823.454 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:49.844Z,1730760829.844 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2024-11-04T22:53:49.844Z,1730760829.844 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T22:53:49.844Z,1730760829.844 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:53:49.844Z,1730760829.844 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2024-11-04T22:53:50.260Z,1730760830.260 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2024-11-04T22:53:50.261Z,1730760830.261 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2024-11-04T22:53:50.699Z,1730760830.699 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 17.371240 min 2024-11-04T22:53:50.699Z,1730760830.699 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2024-11-04T22:53:50.699Z,1730760830.699 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:53:50.699Z,1730760830.699 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T22:53:50.699Z,1730760830.699 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:53:50.699Z,1730760830.699 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4 2024-11-04T22:53:50.700Z,1730760830.700 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4 2024-11-04T22:53:50.700Z,1730760830.700 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:53:50.700Z,1730760830.700 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T22:53:52.103Z,1730760832.103 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:53:52.674Z,1730760832.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225352.00,A,3648.14965,N,12147.22303,W,0.019,24.55,041124,,,A*46 2024-11-04T22:53:52.676Z,1730760832.676 [NAL9602](INFO): GPS fix at 20241104T225352: (36.802494, -121.787051) 2024-11-04T22:53:52.724Z,1730760832.724 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T22:53:52.724Z,1730760832.724 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T22:53:53.482Z,1730760833.482 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T22:53:53.482Z,1730760833.482 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T22:53:53.482Z,1730760833.482 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T22:54:09.594Z,1730760849.594 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:54:16.087Z,1730760856.087 [NAL9602](INFO): SBD MO Status=2, MOMSN=44170, MT Status=2, MTMSN=0 2024-11-04T22:54:16.087Z,1730760856.087 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:54:18.406Z,1730760858.406 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:54:30.350Z,1730760870.350 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:54:33.532Z,1730760873.532 [NAL9602](INFO): SBD MO Status=2, MOMSN=44170, MT Status=2, MTMSN=0 2024-11-04T22:54:33.532Z,1730760873.532 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:54:39.118Z,1730760879.118 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:54:48.042Z,1730760888.042 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:54:56.842Z,1730760896.842 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:01.408Z,1730760901.408 [NAL9602](INFO): SBD MO Status=2, MOMSN=44170, MT Status=2, MTMSN=0 2024-11-04T22:55:01.408Z,1730760901.408 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:55:05.822Z,1730760905.822 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:14.438Z,1730760914.438 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:23.210Z,1730760923.210 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:25.263Z,1730760925.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=44170, MT Status=2, MTMSN=0 2024-11-04T22:55:25.264Z,1730760925.264 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T22:55:31.850Z,1730760931.850 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:40.611Z,1730760940.611 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:49.194Z,1730760949.194 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:55:54.348Z,1730760954.348 [NAL9602](INFO): SBD MO Status=1, MOMSN=44170, MT Status=0, MTMSN=0 2024-11-04T22:55:54.348Z,1730760954.348 [NAL9602](INFO): No messages in MT queue 2024-11-04T22:55:58.134Z,1730760958.134 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:56:09.774Z,1730760969.774 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:56:19.430Z,1730760979.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:56:25.098Z,1730760985.098 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:56:28.410Z,1730760988.410 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:56:36.978Z,1730760996.978 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:56:45.906Z,1730761005.906 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:56:54.607Z,1730761014.607 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:03.370Z,1730761023.370 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:12.018Z,1730761032.018 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:20.838Z,1730761040.838 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:29.398Z,1730761049.398 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:38.206Z,1730761058.206 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:46.906Z,1730761066.906 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:57:55.623Z,1730761075.623 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:04.214Z,1730761084.214 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:16.126Z,1730761096.126 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:25.034Z,1730761105.034 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:33.598Z,1730761113.598 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:42.566Z,1730761122.566 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:51.169Z,1730761131.169 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T22:58:54.282Z,1730761134.282 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2024-11-04T22:58:54.282Z,1730761134.282 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T22:58:54.282Z,1730761134.282 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T22:58:54.283Z,1730761134.283 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2024-11-04T22:58:54.750Z,1730761134.750 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2024-11-04T22:58:54.750Z,1730761134.750 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2024-11-04T22:58:55.104Z,1730761135.104 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 22.446063 min 2024-11-04T22:58:55.104Z,1730761135.104 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2024-11-04T22:58:55.105Z,1730761135.105 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:58:55.105Z,1730761135.105 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T22:58:55.105Z,1730761135.105 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:58:55.105Z,1730761135.105 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5 2024-11-04T22:58:55.105Z,1730761135.105 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5 2024-11-04T22:58:55.109Z,1730761135.109 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T22:58:55.109Z,1730761135.109 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T22:58:57.080Z,1730761137.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225856.00,A,3648.17366,N,12147.18147,W,6.045,53.13,041124,,,D*40 2024-11-04T22:58:57.082Z,1730761137.082 [NAL9602](INFO): GPS fix at 20241104T225856: (36.802894, -121.786358) 2024-11-04T22:58:57.098Z,1730761137.098 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T22:58:57.098Z,1730761137.098 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T22:58:57.980Z,1730761137.980 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241104T223014/Courier0016.lzma 2024-11-04T22:58:58.963Z,1730761138.963 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0016.lzma.bak 2024-11-04T22:58:58.963Z,1730761138.963 [DataOverHttps](INFO): SBD MOMSN=23370512 2024-11-04T22:59:08.397Z,1730761148.397 [NAL9602](INFO): SBD MO Status=1, MOMSN=44171, MT Status=0, MTMSN=0 2024-11-04T22:59:08.397Z,1730761148.397 [NAL9602](INFO): No messages in MT queue 2024-11-04T22:59:15.317Z,1730761155.317 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241104T223014/Courier0019.lzma 2024-11-04T22:59:16.319Z,1730761156.319 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0019.lzma.bak 2024-11-04T22:59:16.319Z,1730761156.319 [DataOverHttps](INFO): SBD MOMSN=23370515 2024-11-04T22:59:32.977Z,1730761172.977 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241104T223014/Express0017.lzma 2024-11-04T22:59:33.979Z,1730761173.979 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0017.lzma.bak 2024-11-04T22:59:33.979Z,1730761173.979 [DataOverHttps](INFO): SBD MOMSN=23370520 2024-11-04T22:59:39.094Z,1730761179.094 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T22:59:50.445Z,1730761190.445 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241104T223014/Express0020.lzma 2024-11-04T22:59:51.447Z,1730761191.447 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0020.lzma.bak 2024-11-04T22:59:51.448Z,1730761191.448 [DataOverHttps](INFO): SBD MOMSN=23370523 2024-11-04T22:59:52.482Z,1730761192.482 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T22:59:52.482Z,1730761192.482 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T22:59:52.482Z,1730761192.482 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T23:00:01.074Z,1730761201.074 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:00:09.866Z,1730761209.866 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:00:18.538Z,1730761218.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:00:30.810Z,1730761230.810 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:00:39.514Z,1730761239.514 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:00:48.310Z,1730761248.310 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:00:57.010Z,1730761257.010 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:05.870Z,1730761265.870 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:14.486Z,1730761274.486 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:23.302Z,1730761283.302 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:32.198Z,1730761292.198 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:40.846Z,1730761300.846 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:49.566Z,1730761309.566 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:01:58.194Z,1730761318.194 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:02:09.422Z,1730761329.422 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:02:19.382Z,1730761339.382 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:02:28.126Z,1730761348.126 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:02:36.898Z,1730761356.898 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:02:45.459Z,1730761365.459 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:02:54.350Z,1730761374.350 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:02.958Z,1730761382.958 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:11.766Z,1730761391.766 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:20.458Z,1730761400.458 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:29.186Z,1730761409.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:37.714Z,1730761417.714 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:46.454Z,1730761426.454 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:03:55.054Z,1730761435.054 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:03.794Z,1730761443.794 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:15.770Z,1730761455.770 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:24.438Z,1730761464.438 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:33.338Z,1730761473.338 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:41.974Z,1730761481.974 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:50.782Z,1730761490.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:04:52.891Z,1730761492.891 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2024-11-04T23:04:52.891Z,1730761492.891 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T23:04:52.891Z,1730761492.891 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T23:04:52.891Z,1730761492.891 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2024-11-04T23:04:53.214Z,1730761493.214 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2024-11-04T23:04:53.214Z,1730761493.214 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2024-11-04T23:04:53.623Z,1730761493.623 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 28.420457 min 2024-11-04T23:04:53.623Z,1730761493.623 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2024-11-04T23:04:53.623Z,1730761493.623 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T23:04:53.623Z,1730761493.623 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T23:04:53.624Z,1730761493.624 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T23:04:53.624Z,1730761493.624 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #6 2024-11-04T23:04:53.624Z,1730761493.624 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=6 2024-11-04T23:04:53.624Z,1730761493.624 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T23:04:53.624Z,1730761493.624 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T23:04:55.612Z,1730761495.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230455.00,A,3648.43918,N,12147.11050,W,6.279,351.56,041124,,,D*7A 2024-11-04T23:04:55.614Z,1730761495.614 [NAL9602](INFO): GPS fix at 20241104T230455: (36.807320, -121.785175) 2024-11-04T23:04:55.661Z,1730761495.661 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T23:04:55.662Z,1730761495.662 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T23:04:57.036Z,1730761497.036 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241104T223014/Courier0022.lzma 2024-11-04T23:04:57.951Z,1730761497.951 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0022.lzma.bak 2024-11-04T23:04:57.951Z,1730761497.951 [DataOverHttps](INFO): SBD MOMSN=23370526 2024-11-04T23:05:14.785Z,1730761514.785 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241104T223014/Express0023.lzma 2024-11-04T23:05:15.456Z,1730761515.456 [NAL9602](INFO): SBD MO Status=2, MOMSN=44172, MT Status=2, MTMSN=0 2024-11-04T23:05:15.457Z,1730761515.457 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-11-04T23:05:15.787Z,1730761515.787 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0023.lzma.bak 2024-11-04T23:05:15.787Z,1730761515.787 [DataOverHttps](INFO): SBD MOMSN=23370529 2024-11-04T23:05:17.081Z,1730761517.081 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T23:05:17.081Z,1730761517.081 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T23:05:17.081Z,1730761517.081 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T23:05:24.349Z,1730761524.349 [NAL9602](INFO): SBD MO Status=1, MOMSN=44172, MT Status=0, MTMSN=0 2024-11-04T23:05:24.350Z,1730761524.350 [NAL9602](INFO): No messages in MT queue 2024-11-04T23:05:25.262Z,1730761525.262 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:05:34.118Z,1730761534.118 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:05:42.774Z,1730761542.774 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:05:51.914Z,1730761551.914 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:05:55.042Z,1730761555.042 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T23:06:00.535Z,1730761560.535 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:06:09.270Z,1730761569.270 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:06:17.823Z,1730761577.823 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:06:29.694Z,1730761589.694 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:06:38.578Z,1730761598.578 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:06:47.222Z,1730761607.222 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:06:56.126Z,1730761616.126 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:04.710Z,1730761624.710 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:13.514Z,1730761633.514 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:22.162Z,1730761642.162 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:30.826Z,1730761650.826 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:39.410Z,1730761659.410 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:48.146Z,1730761668.146 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:07:56.794Z,1730761676.794 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:08:05.482Z,1730761685.482 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:08:14.126Z,1730761694.126 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:08:25.546Z,1730761705.546 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:08:35.258Z,1730761715.258 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:08:43.782Z,1730761723.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:08:52.639Z,1730761732.639 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:01.298Z,1730761741.298 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:09.986Z,1730761749.986 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:18.615Z,1730761758.615 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:27.538Z,1730761767.538 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:36.174Z,1730761776.174 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:44.966Z,1730761784.966 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:09:53.534Z,1730761793.534 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:10:02.330Z,1730761802.330 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:10:10.938Z,1730761810.938 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:10:17.773Z,1730761817.773 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2024-11-04T23:10:17.773Z,1730761817.773 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2024-11-04T23:10:17.773Z,1730761817.773 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-11-04T23:10:17.774Z,1730761817.774 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2024-11-04T23:10:18.226Z,1730761818.226 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2024-11-04T23:10:18.226Z,1730761818.226 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2024-11-04T23:10:18.660Z,1730761818.660 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 33.837329 min 2024-11-04T23:10:18.660Z,1730761818.660 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2024-11-04T23:10:18.660Z,1730761818.660 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T23:10:18.661Z,1730761818.661 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-11-04T23:10:18.661Z,1730761818.661 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T23:10:18.661Z,1730761818.661 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #7 2024-11-04T23:10:18.661Z,1730761818.661 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=7 2024-11-04T23:10:18.661Z,1730761818.661 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-11-04T23:10:18.661Z,1730761818.661 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-11-04T23:10:19.654Z,1730761819.654 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:10:20.632Z,1730761820.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231020.00,A,3648.22288,N,12148.22625,W,16.911,264.29,041124,,,D*48 2024-11-04T23:10:20.655Z,1730761820.655 [NAL9602](INFO): GPS fix at 20241104T231020: (36.803715, -121.803771) 2024-11-04T23:10:20.746Z,1730761820.746 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-11-04T23:10:20.746Z,1730761820.746 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2024-11-04T23:10:30.341Z,1730761830.341 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241104T223014/Courier0025.lzma 2024-11-04T23:10:31.343Z,1730761831.343 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Courier0025.lzma.bak 2024-11-04T23:10:31.343Z,1730761831.343 [DataOverHttps](INFO): SBD MOMSN=23370546 2024-11-04T23:10:35.600Z,1730761835.600 [NAL9602](INFO): SBD MO Status=1, MOMSN=44173, MT Status=0, MTMSN=0 2024-11-04T23:10:35.600Z,1730761835.600 [NAL9602](INFO): No messages in MT queue 2024-11-04T23:10:47.870Z,1730761847.870 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20241104T223014/Express0026.lzma 2024-11-04T23:10:48.871Z,1730761848.871 [DataOverHttps](INFO): Moved sent file to Logs/20241104T223014/Express0026.lzma.bak 2024-11-04T23:10:48.871Z,1730761848.871 [DataOverHttps](INFO): SBD MOMSN=23370549 2024-11-04T23:10:50.156Z,1730761850.156 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2024-11-04T23:10:50.156Z,1730761850.156 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2024-11-04T23:10:50.157Z,1730761850.157 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-11-04T23:10:58.526Z,1730761858.526 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:11:06.306Z,1730761866.306 [NAL9602](INFO): Not Powering down - fast GPS 2024-11-04T23:11:07.342Z,1730761867.342 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:11:16.050Z,1730761876.050 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:11:24.834Z,1730761884.834 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:11:33.755Z,1730761893.755 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:11:44.290Z,1730761904.290 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:11:57.106Z,1730761917.106 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:12:06.070Z,1730761926.070 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:12:14.730Z,1730761934.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:12:32.706Z,1730761952.706 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:12:41.754Z,1730761961.754 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:13:07.454Z,1730761987.454 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:13:16.311Z,1730761996.311 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:13:24.971Z,1730762004.971 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:13:34.694Z,1730762014.694 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:13:43.230Z,1730762023.230 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:13:52.014Z,1730762032.014 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:14:00.854Z,1730762040.854 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-11-04T23:14:11.383Z,1730762051.383 [DataOverHttps](IMPORTANT): SBD MTMSN=20241104T231410 2024-11-04T23:14:19.093Z,1730762059.093 [DataOverHttps](INFO): Received command: restart logs