2024-05-15T18:50:31.999Z,1715799031.999 [Supervisor](DEBUG): Initializing supervisor. 2024-05-15T18:50:32.003Z,1715799032.003 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-05-15T18:50:32.004Z,1715799032.004 [SyncHandler](INFO): Protected caller Thread ID is 842 2024-05-15T18:50:32.004Z,1715799032.004 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-05-15T18:50:32.005Z,1715799032.005 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-05-15T18:50:32.005Z,1715799032.005 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 843 2024-05-15T18:50:32.009Z,1715799032.009 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-05-15T18:50:32.027Z,1715799032.027 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-05-15T18:50:32.028Z,1715799032.028 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-05-15T18:50:32.029Z,1715799032.029 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 844 2024-05-15T18:50:32.033Z,1715799032.033 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-05-15T18:50:32.034Z,1715799032.034 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-05-15T18:50:32.034Z,1715799032.034 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 845 2024-05-15T18:50:32.036Z,1715799032.036 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-05-15T18:50:32.037Z,1715799032.037 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-05-15T18:50:32.038Z,1715799032.038 [logger ThreadHandler](INFO): Protected caller Thread ID is 846 2024-05-15T18:50:32.041Z,1715799032.041 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-05-15T18:50:32.042Z,1715799032.042 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-05-15T18:50:32.046Z,1715799032.046 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-05-15T18:50:32.156Z,1715799032.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-05-15T18:50:32.158Z,1715799032.158 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-05-15T18:50:32.982Z,1715799032.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-05-15T18:50:32.984Z,1715799032.984 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-05-15T18:50:33.249Z,1715799033.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-05-15T18:50:33.251Z,1715799033.251 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-05-15T18:50:33.344Z,1715799033.344 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-05-15T18:50:33.491Z,1715799033.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-05-15T18:50:33.493Z,1715799033.493 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-05-15T18:50:33.588Z,1715799033.588 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-05-15T18:50:33.710Z,1715799033.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-05-15T18:50:33.711Z,1715799033.711 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-05-15T18:50:34.178Z,1715799034.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-05-15T18:50:34.180Z,1715799034.180 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-05-15T18:50:34.798Z,1715799034.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-05-15T18:50:34.799Z,1715799034.799 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-05-15T18:50:35.041Z,1715799035.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-05-15T18:50:35.042Z,1715799035.042 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-05-15T18:50:35.256Z,1715799035.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-05-15T18:50:35.256Z,1715799035.256 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-05-15T18:50:36.562Z,1715799036.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-05-15T18:50:36.564Z,1715799036.564 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-05-15T18:50:36.883Z,1715799036.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-05-15T18:50:36.885Z,1715799036.885 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-05-15T18:50:37.141Z,1715799037.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-05-15T18:50:37.142Z,1715799037.142 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-05-15T18:50:37.551Z,1715799037.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-05-15T18:50:37.552Z,1715799037.552 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-05-15T18:50:37.936Z,1715799037.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-05-15T18:50:37.938Z,1715799037.938 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2024-05-15T18:50:37.941Z,1715799037.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2024-05-15T18:50:38.038Z,1715799038.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2024-05-15T18:50:38.222Z,1715799038.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2024-05-15T18:50:38.345Z,1715799038.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2024-05-15T18:50:38.444Z,1715799038.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2024-05-15T18:50:38.554Z,1715799038.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2024-05-15T18:50:38.682Z,1715799038.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2024-05-15T18:50:38.963Z,1715799038.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-05-15T18:50:38.965Z,1715799038.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2024-05-15T18:50:39.074Z,1715799039.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2024-05-15T18:50:39.181Z,1715799039.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2024-05-15T18:50:39.341Z,1715799039.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2024-05-15T18:50:39.455Z,1715799039.455 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-05-15T18:50:39.475Z,1715799039.475 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-05-15T18:50:39.624Z,1715799039.624 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-05-15T18:50:39.626Z,1715799039.626 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-05-15T18:50:39.699Z,1715799039.699 [VerticalControl](DEBUG): Construct VerticalControl. 2024-05-15T18:50:39.761Z,1715799039.761 [VerticalControl] Loaded 2024-05-15T18:50:39.761Z,1715799039.761 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-05-15T18:50:39.764Z,1715799039.764 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-05-15T18:50:39.812Z,1715799039.812 [HorizontalControl] Loaded 2024-05-15T18:50:39.812Z,1715799039.812 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-05-15T18:50:39.815Z,1715799039.815 [SpeedControl](DEBUG): Construct SpeedControl. 2024-05-15T18:50:39.818Z,1715799039.818 [SpeedControl] Loaded 2024-05-15T18:50:39.818Z,1715799039.818 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-05-15T18:50:39.821Z,1715799039.821 [LoopControl](DEBUG): Construct LoopControl. 2024-05-15T18:50:39.821Z,1715799039.821 [LoopControl] Loaded 2024-05-15T18:50:39.822Z,1715799039.822 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-05-15T18:50:39.822Z,1715799039.822 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-05-15T18:50:39.823Z,1715799039.823 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-05-15T18:50:39.893Z,1715799039.893 [DepthRateCalculator] Loaded 2024-05-15T18:50:39.894Z,1715799039.894 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-05-15T18:50:39.898Z,1715799039.898 [PitchRateCalculator] Loaded 2024-05-15T18:50:39.899Z,1715799039.899 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-05-15T18:50:39.909Z,1715799039.909 [SpeedCalculator] Loaded 2024-05-15T18:50:39.909Z,1715799039.909 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-05-15T18:50:39.914Z,1715799039.914 [YawRateCalculator] Loaded 2024-05-15T18:50:39.914Z,1715799039.914 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-05-15T18:50:39.932Z,1715799039.932 [ElevatorOffsetCalculator] Loaded 2024-05-15T18:50:39.932Z,1715799039.932 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-05-15T18:50:39.933Z,1715799039.933 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-05-15T18:50:39.934Z,1715799039.934 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-05-15T18:50:39.978Z,1715799039.978 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-05-15T18:50:39.978Z,1715799039.978 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-05-15T18:50:40.062Z,1715799040.062 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-05-15T18:50:40.063Z,1715799040.063 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-05-15T18:50:40.476Z,1715799040.476 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-05-15T18:50:40.477Z,1715799040.477 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-05-15T18:50:41.074Z,1715799041.074 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-05-15T18:50:41.075Z,1715799041.075 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-05-15T18:50:42.103Z,1715799042.103 [AHRS_M2] Loaded 2024-05-15T18:50:42.103Z,1715799042.103 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-05-15T18:50:42.364Z,1715799042.364 [BackseatComponent] Loaded 2024-05-15T18:50:42.364Z,1715799042.364 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-05-15T18:50:42.366Z,1715799042.366 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C34E0 2024-05-15T18:50:42.366Z,1715799042.366 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 929 2024-05-15T18:50:42.369Z,1715799042.369 [LcmUniversalReporter] Loaded 2024-05-15T18:50:42.369Z,1715799042.369 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-05-15T18:50:43.180Z,1715799043.180 [BPC1] Loaded 2024-05-15T18:50:43.180Z,1715799043.180 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-05-15T18:50:43.322Z,1715799043.322 [DataOverHttps] Loaded 2024-05-15T18:50:43.322Z,1715799043.322 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-05-15T18:50:43.323Z,1715799043.323 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0 2024-05-15T18:50:43.324Z,1715799043.324 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 930 2024-05-15T18:50:43.347Z,1715799043.347 [Depth_Keller] Loaded 2024-05-15T18:50:43.347Z,1715799043.347 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-05-15T18:50:43.412Z,1715799043.412 [NAL9602] Loaded 2024-05-15T18:50:43.412Z,1715799043.412 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-05-15T18:50:43.442Z,1715799043.442 [Onboard] Loaded 2024-05-15T18:50:43.443Z,1715799043.443 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-05-15T18:50:43.444Z,1715799043.444 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0 2024-05-15T18:50:43.444Z,1715799043.444 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 931 2024-05-15T18:50:43.458Z,1715799043.458 [Power24vConverter] Loaded 2024-05-15T18:50:43.458Z,1715799043.458 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-05-15T18:50:43.473Z,1715799043.473 [Radio_Surface] Loaded 2024-05-15T18:50:43.474Z,1715799043.474 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-05-15T18:50:43.475Z,1715799043.475 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0 2024-05-15T18:50:43.475Z,1715799043.475 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 932 2024-05-15T18:50:43.523Z,1715799043.523 [RDI_Pathfinder] Loaded 2024-05-15T18:50:43.523Z,1715799043.523 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2024-05-15T18:50:43.619Z,1715799043.619 [DAT] Loaded 2024-05-15T18:50:43.619Z,1715799043.619 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-05-15T18:50:43.620Z,1715799043.620 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0 2024-05-15T18:50:43.620Z,1715799043.620 [DAT ThreadHandler](INFO): Protected caller Thread ID is 933 2024-05-15T18:50:43.621Z,1715799043.621 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-05-15T18:50:43.621Z,1715799043.621 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-05-15T18:50:43.761Z,1715799043.761 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-05-15T18:50:43.761Z,1715799043.761 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-05-15T18:50:43.775Z,1715799043.775 [NavChart] Loaded 2024-05-15T18:50:43.776Z,1715799043.776 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-05-15T18:50:43.781Z,1715799043.781 [UniversalFixResidualReporter] Loaded 2024-05-15T18:50:43.782Z,1715799043.782 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-05-15T18:50:43.782Z,1715799043.782 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-05-15T18:50:43.783Z,1715799043.783 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-05-15T18:50:43.959Z,1715799043.959 [SBIT](DEBUG): Construct Startup Built In Test. 2024-05-15T18:50:43.969Z,1715799043.969 [SBIT] Loaded 2024-05-15T18:50:43.969Z,1715799043.969 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-05-15T18:50:43.972Z,1715799043.972 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-05-15T18:50:44.014Z,1715799044.014 [IBIT] Loaded 2024-05-15T18:50:44.014Z,1715799044.014 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-05-15T18:50:44.020Z,1715799044.020 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-05-15T18:50:44.186Z,1715799044.186 [CBIT] Loaded 2024-05-15T18:50:44.186Z,1715799044.186 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-05-15T18:50:44.187Z,1715799044.187 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-05-15T18:50:44.187Z,1715799044.187 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-05-15T18:50:44.359Z,1715799044.359 [BuoyancyServo] Loaded 2024-05-15T18:50:44.360Z,1715799044.360 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-05-15T18:50:44.383Z,1715799044.383 [ElevatorServo] Loaded 2024-05-15T18:50:44.383Z,1715799044.383 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-05-15T18:50:44.416Z,1715799044.416 [DockingStepper] Loaded 2024-05-15T18:50:44.416Z,1715799044.416 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2024-05-15T18:50:44.438Z,1715799044.438 [MassServo] Loaded 2024-05-15T18:50:44.438Z,1715799044.438 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-05-15T18:50:44.459Z,1715799044.459 [RudderServo] Loaded 2024-05-15T18:50:44.459Z,1715799044.459 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-05-15T18:50:44.478Z,1715799044.478 [ThrusterServo] Loaded 2024-05-15T18:50:44.478Z,1715799044.478 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2024-05-15T18:50:44.479Z,1715799044.479 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-05-15T18:50:44.480Z,1715799044.480 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-05-15T18:50:44.495Z,1715799044.495 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-05-15T18:50:44.496Z,1715799044.496 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-05-15T18:50:44.727Z,1715799044.727 [CTD_Seabird] Loaded 2024-05-15T18:50:44.727Z,1715799044.727 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-05-15T18:50:44.728Z,1715799044.728 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD84E0 2024-05-15T18:50:44.729Z,1715799044.729 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 935 2024-05-15T18:50:44.765Z,1715799044.765 [ESPComponent] Loaded 2024-05-15T18:50:44.766Z,1715799044.766 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-05-15T18:50:44.788Z,1715799044.788 [PAR_Licor] Loaded 2024-05-15T18:50:44.788Z,1715799044.788 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-05-15T18:50:44.834Z,1715799044.834 [WetLabsBB2FL] Loaded 2024-05-15T18:50:44.835Z,1715799044.835 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-05-15T18:50:44.836Z,1715799044.836 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C084E0 2024-05-15T18:50:44.836Z,1715799044.836 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 936 2024-05-15T18:50:44.858Z,1715799044.858 [WetLabsUBAT] Loaded 2024-05-15T18:50:44.858Z,1715799044.858 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2024-05-15T18:50:44.859Z,1715799044.859 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C384E0 2024-05-15T18:50:44.859Z,1715799044.859 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 937 2024-05-15T18:50:44.860Z,1715799044.860 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-05-15T18:50:44.866Z,1715799044.866 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-05-15T18:50:44.869Z,1715799044.869 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-05-15T18:50:44.881Z,1715799044.881 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-05-15T18:50:44.882Z,1715799044.882 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C684E0 2024-05-15T18:50:44.882Z,1715799044.882 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 938 2024-05-15T18:50:44.886Z,1715799044.886 [Supervisor](INFO): Main Thread ID is 833 2024-05-15T18:50:44.886Z,1715799044.886 [Supervisor](DEBUG): Running supervisor. 2024-05-15T18:50:44.887Z,1715799044.887 [CommandExec ThreadHandler](INFO): Handler Thread ID is 939 2024-05-15T18:50:44.888Z,1715799044.888 [CommandExec](INFO): Initializing the command executive. 2024-05-15T18:50:44.889Z,1715799044.889 [CommandLine ThreadHandler](INFO): Handler Thread ID is 940 2024-05-15T18:50:44.891Z,1715799044.891 [controlThread ThreadHandler](INFO): Handler Thread ID is 941 2024-05-15T18:50:44.892Z,1715799044.892 [controlThread](DEBUG): Initializing ControlThread 2024-05-15T18:50:44.892Z,1715799044.892 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-05-15T18:50:44.894Z,1715799044.894 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-05-15T18:50:44.895Z,1715799044.895 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-05-15T18:50:44.895Z,1715799044.895 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-05-15T18:50:44.896Z,1715799044.896 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-05-15T18:50:44.896Z,1715799044.896 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-05-15T18:50:44.896Z,1715799044.896 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-05-15T18:50:44.897Z,1715799044.897 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-05-15T18:50:44.897Z,1715799044.897 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-05-15T18:50:44.902Z,1715799044.902 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-05-15T18:50:44.903Z,1715799044.903 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-05-15T18:50:44.903Z,1715799044.903 [SBIT](INFO): Initialize SBIT Component. 2024-05-15T18:50:44.903Z,1715799044.903 [SBIT](IMPORTANT): git: 2024-04-25-3-g5d6336dfa 2024-05-15T18:50:44.903Z,1715799044.903 [SBIT](INFO): git hash: 5d6336dfa5cb8e4efa2bf355f819331ef86c015f 2024-05-15T18:50:44.904Z,1715799044.904 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-05-15T18:50:44.905Z,1715799044.905 [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-05-15T18:50:44.906Z,1715799044.906 [SBIT](INFO): Beginning SBIT in 99.000000 seconds. 2024-05-15T18:50:44.907Z,1715799044.907 [IBIT](INFO): Initialize IBIT Component. 2024-05-15T18:50:44.907Z,1715799044.907 [CBIT](DEBUG): Initialize CBIT Component. 2024-05-15T18:50:44.908Z,1715799044.908 [logger ThreadHandler](INFO): Handler Thread ID is 942 2024-05-15T18:50:44.921Z,1715799044.921 [CBIT](DEBUG): Initialized mux pins. 2024-05-15T18:50:44.921Z,1715799044.921 [CBIT](DEBUG): Initializing the watchdog timer. 2024-05-15T18:50:44.926Z,1715799044.926 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 943 2024-05-15T18:50:44.934Z,1715799044.934 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 944 2024-05-15T18:50:44.935Z,1715799044.935 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-05-15T18:50:44.941Z,1715799044.941 [Onboard ThreadHandler](INFO): Handler Thread ID is 945 2024-05-15T18:50:44.946Z,1715799044.946 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-05-15T18:50:44.946Z,1715799044.946 [CBIT](DEBUG): Initializing heartbeat. 2024-05-15T18:50:44.962Z,1715799044.962 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 946 2024-05-15T18:50:44.981Z,1715799044.981 [DAT ThreadHandler](INFO): Handler Thread ID is 947 2024-05-15T18:50:44.982Z,1715799044.982 [DAT](INFO): Powering up 2024-05-15T18:50:44.982Z,1715799044.982 [DAT](DEBUG): Initializing DAT. 2024-05-15T18:50:44.986Z,1715799044.986 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 949 2024-05-15T18:50:44.987Z,1715799044.987 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-15T18:50:44.991Z,1715799044.991 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 951 2024-05-15T18:50:44.994Z,1715799044.994 [WetLabsBB2FL](INFO): Powering up 2024-05-15T18:50:44.995Z,1715799044.995 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 953 2024-05-15T18:50:44.998Z,1715799044.998 [WetLabsUBAT](INFO): Powering up 2024-05-15T18:50:44.999Z,1715799044.999 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 955 2024-05-15T18:50:45.007Z,1715799045.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-05-15T18:50:45.008Z,1715799045.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-05-15T18:50:45.008Z,1715799045.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-05-15T18:50:45.008Z,1715799045.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-05-15T18:50:45.008Z,1715799045.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-05-15T18:50:45.008Z,1715799045.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-05-15T18:50:45.008Z,1715799045.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-05-15T18:50:45.009Z,1715799045.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-05-15T18:50:45.017Z,1715799045.017 [CBIT](DEBUG): Deactivating GF circuits. 2024-05-15T18:50:45.017Z,1715799045.017 [CBIT](DEBUG): Deactivating emergency mode. 2024-05-15T18:50:45.057Z,1715799045.057 [CBIT](DEBUG): Backplane powered. 2024-05-15T18:50:45.062Z,1715799045.062 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-05-15T18:50:45.062Z,1715799045.062 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-05-15T18:50:45.063Z,1715799045.063 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-05-15T18:50:45.069Z,1715799045.069 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-05-15T18:50:45.127Z,1715799045.127 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-05-15T18:50:45.133Z,1715799045.133 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-15T18:50:45.152Z,1715799045.152 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-05-15T18:50:45.177Z,1715799045.177 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-05-15T18:50:45.177Z,1715799045.177 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-05-15T18:50:45.186Z,1715799045.186 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-05-15T18:50:45.365Z,1715799045.365 [Radio_Surface](INFO): Powering up 2024-05-15T18:50:45.497Z,1715799045.497 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-05-15T18:50:45.527Z,1715799045.527 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-05-15T18:50:45.528Z,1715799045.528 [Default:A.Wait](DEBUG): Construct Wait. 2024-05-15T18:50:45.539Z,1715799045.539 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-15T18:50:45.572Z,1715799045.572 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-05-15T18:50:45.591Z,1715799045.591 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-05-15T18:50:45.607Z,1715799045.607 [Default:E.Execute](DEBUG): Construct Execute. 2024-05-15T18:50:45.610Z,1715799045.610 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-05-15T18:50:45.625Z,1715799045.625 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,ESPComponent,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-05-15T18:50:45.637Z,1715799045.637 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-15T18:50:45.799Z,1715799045.799 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m 2024-05-15T18:50:45.799Z,1715799045.799 [Power24vConverter](INFO): Powering up. 2024-05-15T18:50:45.800Z,1715799045.800 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-05-15T18:50:45.831Z,1715799045.831 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-05-15T18:50:45.870Z,1715799045.870 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-05-15T18:50:45.877Z,1715799045.877 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-05-15T18:50:45.878Z,1715799045.878 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-05-15T18:50:45.885Z,1715799045.885 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-05-15T18:50:45.886Z,1715799045.886 [DockingStepper](INFO): Start 2024-05-15T18:50:45.886Z,1715799045.886 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:50:45.893Z,1715799045.893 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:50:45.894Z,1715799045.894 [MassServo](DEBUG): Initializing EZServoServo. 2024-05-15T18:50:45.901Z,1715799045.901 [MassServo](DEBUG): Initializing MassServo. 2024-05-15T18:50:45.902Z,1715799045.902 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-15T18:50:45.909Z,1715799045.909 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-15T18:50:45.910Z,1715799045.910 [ThrusterServo](DEBUG): Initializing EZServoServo. 2024-05-15T18:50:45.917Z,1715799045.917 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2024-05-15T18:50:46.093Z,1715799046.093 [DockingStepper](INFO): Starting 2024-05-15T18:50:46.460Z,1715799046.460 [DockingStepper](INFO): Starting 2024-05-15T18:50:46.985Z,1715799046.985 [DockingStepper](INFO): Starting 2024-05-15T18:50:47.034Z,1715799047.034 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2024-05-15T18:50:47.285Z,1715799047.285 [DockingStepper](INFO): Starting 2024-05-15T18:50:47.605Z,1715799047.605 [WetLabsBB2FL](INFO): Powering down 2024-05-15T18:50:47.691Z,1715799047.691 [DockingStepper](INFO): Starting 2024-05-15T18:50:48.076Z,1715799048.076 [DockingStepper](INFO): Starting 2024-05-15T18:50:48.529Z,1715799048.529 [DockingStepper](INFO): Starting 2024-05-15T18:50:48.554Z,1715799048.554 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:50:49.007Z,1715799049.007 [DockingStepper](INFO): Starting 2024-05-15T18:50:49.425Z,1715799049.425 [DockingStepper](INFO): Commanding negative:9025 2024-05-15T18:50:50.691Z,1715799050.691 [DockingStepper](INFO): Pause 2024-05-15T18:50:54.297Z,1715799054.297 [WetLabsUBAT](INFO): Powering down 2024-05-15T18:50:58.590Z,1715799058.590 [DAT](INFO): DAT read: 2024-05-15T18:50:58.591Z,1715799058.591 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-05-15T18:51:00.102Z,1715799060.102 [DAT](INFO): DAT read: MF Frequency Band 2024-05-15T18:51:00.103Z,1715799060.103 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2024-05-15T18:51:00.104Z,1715799060.104 [DAT](INFO): DAT read: May 15 2024 18:50:00 2024-05-15T18:51:01.364Z,1715799061.364 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-05-15T18:51:01.368Z,1715799061.368 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-05-15T18:51:01.368Z,1715799061.368 [DAT](INFO): commRate: 800 2024-05-15T18:51:03.430Z,1715799063.430 [DAT](INFO): entering command mode 2024-05-15T18:51:03.630Z,1715799063.630 [DAT](INFO): DAT read: 2024-05-15T18:51:03.630Z,1715799063.630 [DAT](INFO): DAT read: user:1> 2024-05-15T18:51:03.630Z,1715799063.630 [DAT](INFO): setting verbose to 3 2024-05-15T18:51:03.882Z,1715799063.882 [DAT](INFO): DAT read: user:1> 2024-05-15T18:51:03.883Z,1715799063.883 [DAT](INFO): DAT read: Verbose | 3 2024-05-15T18:51:03.883Z,1715799063.883 [DAT](INFO): set verbose to 3 2024-05-15T18:51:03.883Z,1715799063.883 [DAT](INFO): setting DatVerbose to 27440 2024-05-15T18:51:04.134Z,1715799064.134 [DAT](INFO): DAT read: user:2> 2024-05-15T18:51:04.135Z,1715799064.135 [DAT](INFO): DAT read: DatVerbose | 27440 2024-05-15T18:51:04.135Z,1715799064.135 [DAT](INFO): set DatVerbose to 27440 2024-05-15T18:51:04.135Z,1715799064.135 [DAT](INFO): setting transmit power to 8 2024-05-15T18:51:04.386Z,1715799064.386 [DAT](INFO): DAT read: user:3> 2024-05-15T18:51:04.387Z,1715799064.387 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-05-15T18:51:04.388Z,1715799064.388 [DAT](INFO): set transmit power to 8 2024-05-15T18:51:04.388Z,1715799064.388 [DAT](INFO): setting local address to 10 2024-05-15T18:51:04.638Z,1715799064.638 [DAT](INFO): DAT read: user:4> 2024-05-15T18:51:04.639Z,1715799064.639 [DAT](INFO): DAT read: LocalAddr | 10 2024-05-15T18:51:04.639Z,1715799064.639 [DAT](INFO): set local address to 10 2024-05-15T18:51:04.640Z,1715799064.640 [DAT](INFO): Setting time to: 18:51:4 And date to:5/15/2024 2024-05-15T18:51:04.890Z,1715799064.890 [DAT](INFO): DAT read: user:5> 2024-05-15T18:51:04.891Z,1715799064.891 [DAT](INFO): DAT read: Wed May 15, 2024 18:51:04 2024-05-15T18:51:04.891Z,1715799064.891 [DAT](INFO): Local DAT time set to Wed May 15, 2024 18:51:04 2024-05-15T18:51:13.642Z,1715799073.642 [NAL9602](INFO): Powering up NAL9602 2024-05-15T18:51:24.550Z,1715799084.550 [NAL9602](INFO): NAL9602 initialized 2024-05-15T18:52:24.360Z,1715799144.360 [SBIT](IMPORTANT): Beginning Startup BIT 2024-05-15T18:52:24.364Z,1715799144.364 [CBIT](IMPORTANT): Beginning ground fault scan 2024-05-15T18:52:35.314Z,1715799155.314 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007975 CHAN A1 (24V): 0.003956 CHAN A2 (12V): -0.005625 CHAN A3 (5V): -0.001186 CHAN B0 (3.3V): 0.000426 CHAN B1 (3.15aV): -0.000426 CHAN B2 (3.15bV): 0.000440 CHAN B3 (GND): 0.002393 OPEN: -0.002201 Full Scale: +/- 1 mA 2024-05-15T18:53:18.106Z,1715799198.106 [SBIT](IMPORTANT): SBIT PASSED 2024-05-15T18:53:18.106Z,1715799198.106 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-05-15T18:53:18.107Z,1715799198.107 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2024-05-15T18:53:18.107Z,1715799198.107 [SBIT](IMPORTANT): DockingStepper.closedValue=16500 count; 2024-05-15T18:53:18.109Z,1715799198.109 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool; 2024-05-15T18:53:18.109Z,1715799198.109 [SBIT](IMPORTANT): DockingStepper.openValue=7500 count; 2024-05-15T18:53:18.110Z,1715799198.110 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool; 2024-05-15T18:53:18.110Z,1715799198.110 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-05-15T18:53:18.110Z,1715799198.110 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2024-05-15T18:53:18.110Z,1715799198.110 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2024-05-15T18:53:18.112Z,1715799198.112 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-05-15T18:53:18.112Z,1715799198.112 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2024-05-15T18:53:18.112Z,1715799198.112 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter; 2024-05-15T18:53:18.113Z,1715799198.113 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool; 2024-05-15T18:53:18.113Z,1715799198.113 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=700 cubic_centimeter; 2024-05-15T18:53:18.113Z,1715799198.113 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.09 radian_per_meter; 2024-05-15T18:53:18.113Z,1715799198.113 [SBIT](IMPORTANT): VerticalControl.kpPitchElevator=0.5 none; 2024-05-15T18:53:18.113Z,1715799198.113 [SBIT](IMPORTANT): VerticalControl.massDefault=-19.999430 millimeter; 2024-05-15T18:53:18.113Z,1715799198.113 [SBIT](IMPORTANT): VerticalControl.stopDepthExcursion=20 meter; 2024-05-15T18:53:18.495Z,1715799198.495 [MissionManager](IMPORTANT): Started mission Startup 2024-05-15T18:53:18.495Z,1715799198.495 [Startup] Running Loop=1 2024-05-15T18:53:18.495Z,1715799198.495 [Startup](DEBUG): Aggregate::initialize Startup 2024-05-15T18:53:18.496Z,1715799198.496 [Startup:A.GoToSurface] Running Loop=1 2024-05-15T18:53:18.496Z,1715799198.496 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-15T18:53:18.496Z,1715799198.496 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-15T18:53:18.497Z,1715799198.497 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-15T18:53:18.517Z,1715799198.517 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-15T18:53:18.518Z,1715799198.518 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-15T18:53:18.518Z,1715799198.518 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-15T18:53:18.520Z,1715799198.520 [Startup:StartupSatComms] Running Loop=1 2024-05-15T18:53:18.520Z,1715799198.520 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-05-15T18:53:18.520Z,1715799198.520 [Startup:StartupSatComms:A] Running Loop=1 2024-05-15T18:53:18.891Z,1715799198.891 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-05-15T18:53:28.574Z,1715799208.574 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:53:36.048Z,1715799216.048 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005065 2024-05-15T18:53:42.307Z,1715799222.307 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:53:45.172Z,1715799225.172 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-05-15T18:53:45.172Z,1715799225.172 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-05-15T18:53:45.183Z,1715799225.183 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-05-15T18:53:45.575Z,1715799225.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-05-15T18:53:45.575Z,1715799225.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-05-15T18:53:54.831Z,1715799234.831 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:54:08.166Z,1715799248.166 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:54:18.686Z,1715799258.686 [Startup:StartupSatComms:A](INFO): Timed out from 2024-05-15T18:53:18.5Z 2024-05-15T18:54:18.686Z,1715799258.686 [Startup:StartupSatComms:A] Stopped 2024-05-15T18:54:18.686Z,1715799258.686 [Startup:StartupSatComms:B] Running Loop=1 2024-05-15T18:54:19.088Z,1715799259.088 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-15T18:54:20.688Z,1715799260.688 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:54:26.487Z,1715799266.487 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240515T182118/Courier0012.lzma 2024-05-15T18:54:27.488Z,1715799267.488 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Courier0012.lzma.bak 2024-05-15T18:54:27.488Z,1715799267.488 [DataOverHttps](INFO): SBD MOMSN=21023233 2024-05-15T18:54:34.043Z,1715799274.043 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:54:43.450Z,1715799283.450 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240515T185031/Courier0000.lzma 2024-05-15T18:54:44.452Z,1715799284.452 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185031/Courier0000.lzma.bak 2024-05-15T18:54:44.452Z,1715799284.452 [DataOverHttps](INFO): SBD MOMSN=21023235 2024-05-15T18:54:46.971Z,1715799286.971 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:55:00.303Z,1715799300.303 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:55:02.355Z,1715799302.355 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20240515T182118/Express0013.lzma 2024-05-15T18:55:03.356Z,1715799303.356 [DataOverHttps](INFO): Moved sent file to Logs/20240515T182118/Express0013.lzma.bak 2024-05-15T18:55:03.356Z,1715799303.356 [DataOverHttps](INFO): SBD MOMSN=21023238 2024-05-15T18:55:13.231Z,1715799313.231 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:55:19.007Z,1715799319.007 [Startup:StartupSatComms:B](INFO): Timed out from 2024-05-15T18:54:18.7Z 2024-05-15T18:55:19.007Z,1715799319.007 [Startup:StartupSatComms:B] Stopped 2024-05-15T18:55:19.007Z,1715799319.007 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-05-15T18:55:19.008Z,1715799319.008 [Startup:StartupSatComms] Stopped 2024-05-15T18:55:19.008Z,1715799319.008 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-05-15T18:55:19.009Z,1715799319.009 [Startup](INFO): Completed Startup 2024-05-15T18:55:19.009Z,1715799319.009 [MissionManager](INFO): Startup is completed. 2024-05-15T18:55:19.009Z,1715799319.009 [MissionManager](INFO): Uninitializing Mission Startup 2024-05-15T18:55:19.009Z,1715799319.009 [Startup] Stopped 2024-05-15T18:55:19.009Z,1715799319.009 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-05-15T18:55:19.009Z,1715799319.009 [Startup:A.GoToSurface] Stopped 2024-05-15T18:55:19.009Z,1715799319.009 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-15T18:55:19.320Z,1715799319.320 [MissionManager](IMPORTANT): Started mission Default 2024-05-15T18:55:19.321Z,1715799319.321 [Default] Running Loop=1 2024-05-15T18:55:19.321Z,1715799319.321 [Default](DEBUG): Aggregate::initialize Default 2024-05-15T18:55:19.321Z,1715799319.321 [Default:B.GoToSurface] Running Loop=1 2024-05-15T18:55:19.321Z,1715799319.321 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-15T18:55:19.321Z,1715799319.321 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-15T18:55:19.321Z,1715799319.321 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-15T18:55:19.322Z,1715799319.322 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-15T18:55:19.322Z,1715799319.322 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-15T18:55:19.348Z,1715799319.348 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-15T18:55:19.348Z,1715799319.348 [Default:A.Wait] Running Loop=1 2024-05-15T18:55:19.348Z,1715799319.348 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:55:21.684Z,1715799321.684 [DataOverHttps](INFO): Sending 1135 bytes from file Logs/20240515T185031/Express0001.lzma 2024-05-15T18:55:22.684Z,1715799322.684 [DataOverHttps](INFO): Moved sent file to Logs/20240515T185031/Express0001.lzma.bak 2024-05-15T18:55:22.685Z,1715799322.685 [DataOverHttps](INFO): SBD MOMSN=21023243 2024-05-15T18:55:26.967Z,1715799326.967 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:55:32.648Z,1715799332.648 [Default:A.Wait](INFO): Done Waiting. 2024-05-15T18:55:32.648Z,1715799332.648 [Default:A.Wait] Stopped 2024-05-15T18:55:32.648Z,1715799332.648 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-15T18:55:33.061Z,1715799333.061 [Default:CheckIn] Running Loop=1 2024-05-15T18:55:33.061Z,1715799333.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-05-15T18:55:33.061Z,1715799333.061 [Default:CheckIn:Read_GPS] Running Loop=1 2024-05-15T18:55:33.466Z,1715799333.466 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-05-15T18:55:40.299Z,1715799340.299 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-15T18:56:14.364Z,1715799374.364 [CommandExec](IMPORTANT): got command docking standby 2024-05-15T18:56:14.364Z,1715799374.364 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-15T18:56:15.066Z,1715799375.066 [DockingStepper](INFO): Resume 2024-05-15T18:56:15.469Z,1715799375.469 [DockingStepper](INFO): Resuming 2024-05-15T18:56:15.873Z,1715799375.873 [DockingStepper](INFO): Start 2024-05-15T18:56:15.873Z,1715799375.873 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-15T18:56:15.987Z,1715799375.987 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-15T18:56:16.308Z,1715799376.308 [DockingStepper](INFO): Starting 2024-05-15T18:56:16.695Z,1715799376.695 [DockingStepper](INFO): Starting 2024-05-15T18:56:17.083Z,1715799377.083 [DockingStepper](INFO): Starting 2024-05-15T18:56:17.131Z,1715799377.131 [DockingStepper](INFO): Init string: /1z14975aE13561N3m50 2024-05-15T18:56:17.490Z,1715799377.490 [DockingStepper](INFO): Starting 2024-05-15T18:56:17.899Z,1715799377.899 [DockingStepper](INFO): Starting 2024-05-15T18:56:18.333Z,1715799378.333 [DockingStepper](INFO): Starting 2024-05-15T18:56:18.728Z,1715799378.728 [DockingStepper](INFO): Starting 2024-05-15T18:56:18.755Z,1715799378.755 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-15T18:56:19.125Z,1715799379.125 [DockingStepper](INFO): Starting 2024-05-15T18:56:27.980Z,1715799387.980 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-15T18:56:29.193Z,1715799389.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185627.00,A,3648.16733,N,12147.27861,W,0.233,0.00,150524,,,A*74 2024-05-15T18:56:29.197Z,1715799389.197 [NAL9602](INFO): GPS fix at 20240515T185627: (36.802789, -121.787977) 2024-05-15T18:56:29.208Z,1715799389.208 [Default:CheckIn:Read_GPS] Stopped 2024-05-15T18:56:29.208Z,1715799389.208 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-05-15T18:56:29.631Z,1715799389.631 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-15T18:56:38.912Z,1715799398.912 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/OnDock.tl 2024-05-15T18:56:38.913Z,1715799398.913 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2024-05-15T18:56:38.913Z,1715799398.913 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2024-05-15T18:56:38.917Z,1715799398.917 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2024-05-15T18:56:40.608Z,1715799400.608 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 30 5 Restarting DHCP... ! /mnt/mmc/LRAUV/Tools/dhcp.sh Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2024-05-15T18:56:40.623Z,1715799400.623 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2024-05-15T18:56:40.632Z,1715799400.632 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 300.000000 s 2024-05-15T18:56:40.640Z,1715799400.640 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2024-05-15T18:56:40.687Z,1715799400.687 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2024-05-15T18:56:40.729Z,1715799400.729 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2024-05-15T18:56:40.763Z,1715799400.763 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count 2024-05-15T18:56:40.772Z,1715799400.772 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2024-05-15T18:56:40.795Z,1715799400.795 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2024-05-15T18:56:40.819Z,1715799400.819 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2024-05-15T18:56:40.822Z,1715799400.822 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2024-05-15T18:56:40.846Z,1715799400.846 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min 2024-05-15T18:56:40.911Z,1715799400.911 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2024-05-15T18:56:40.911Z,1715799400.911 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-05-15T18:56:40.911Z,1715799400.911 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-05-15T18:56:40.912Z,1715799400.912 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-05-15T18:56:41.248Z,1715799401.248 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-05-15T18:56:41.274Z,1715799401.274 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2024-05-15T18:56:41.278Z,1715799401.278 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-05-15T18:56:41.306Z,1715799401.306 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2024-05-15T18:56:41.334Z,1715799401.334 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-05-15T18:56:41.348Z,1715799401.348 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-05-15T18:56:41.370Z,1715799401.370 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-05-15T18:56:41.421Z,1715799401.421 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-05-15T18:56:41.485Z,1715799401.485 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-15T18:56:41.485Z,1715799401.485 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-15T18:56:41.499Z,1715799401.499 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-15T18:56:41.561Z,1715799401.561 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-15T18:56:41.574Z,1715799401.574 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2024-05-15T18:56:41.589Z,1715799401.589 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-15T18:56:41.597Z,1715799401.597 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-15T18:56:41.597Z,1715799401.597 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-15T18:56:41.598Z,1715799401.598 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-15T18:56:42.101Z,1715799402.101 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-15T18:56:42.134Z,1715799402.134 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2024-05-15T18:56:42.161Z,1715799402.161 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2024-05-15T18:56:42.215Z,1715799402.215 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2024-05-15T18:56:42.252Z,1715799402.252 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2024-05-15T18:56:42.267Z,1715799402.267 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-15T18:56:42.297Z,1715799402.297 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-15T18:56:42.458Z,1715799402.458 [OnDock:restartDHCP:B.Execute](DEBUG): Construct Execute. 2024-05-15T18:56:42.501Z,1715799402.501 [OnDock:L.Execute](DEBUG): Construct Execute. 2024-05-15T18:56:42.521Z,1715799402.521 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2024-05-15T18:56:42.535Z,1715799402.535 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2024-05-15T18:56:42.536Z,1715799402.536 [OnDock:O.Docked](DEBUG): Construct. 2024-05-15T18:56:42.537Z,1715799402.537 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-15T18:56:42.652Z,1715799402.652 [OnDock:Detach:B.Undock](DEBUG): Construct. 2024-05-15T18:56:42.652Z,1715799402.652 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-15T18:56:42.738Z,1715799402.738 [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 { """ Wait on dock, communicating when possible """ arguments { DockedTime = 8 hour """ Time to stay on dock. Mission timeout will be five minutes longer. """ DetachedTimeout = Dock:Docked.detachTimeout """ Time duration limit for determining if the vehicle is detached from the dock. """ RangeTimeout = Dock:Docked.rangeTimeout """ Maximum allowed time without acoustic range. """ TrackingUpdatePeriod = 2 minute """ How long to wait between acoustic queries. """ NumberOfPings = 1 count """ Number of pings requested each time. """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ MaxDepth = BIT:CBIT.stopDepth """ Maximum depth while docked. """ MinAltitude = 5 meter """ Minimum altitude """ MinOffshore = 2 km """ Minimum offshore """ StayOnDock = false """ If true, don't detach from dock at end of mission """ IfconfigInterval = 30 minute """ How long to wait between ifconfig runs """ # Mission variables (do not change) MissionTimeout = DockedTime """ Mission timeout (local variable, do not change) """ } timeout duration=MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore # End docking 5 minutes before end of mission to allow clean undock assign in sequence MissionTimeout = DockedTime + 5 minute insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl # Send data when connection exists readData { while ( Sensor:DataOverHttps.connectionStatus == true ) Universal:platform_communications } behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate restartDHCP { run when ( called ) syslog important "Restarting DHCP..." behavior Guidance:Execute { run in sequence set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh" } } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } # Kick DHCP again, in case it took us a while to close the arm in Docked call refId="restartDHCP" aggregate PeriodicCheck { run in parallel aggregate CheckIP { run in sequence behavior Guidance:Execute { run in sequence set command = "! ifconfig" } behavior Guidance:Wait { run in sequence set duration = IfconfigInterval } } } behavior Dock:Docked { run in sequence timeout duration=DockedTime set detachTimeout = DetachedTimeout set rangeTimeout = RangeTimeout } # Undock cleanly, instead of relying on DefaultWithUndock to come next aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Mission ending, breaking off. Range: " + Estimation:TrackAcousticContact.range_to_contact~meter + "." behavior Dock:Undock { run in sequence } # Get our 10.89 IP back once we're on the surface call refId="restartDHCP" } } 2024-05-15T18:56:42.761Z,1715799402.761 [CommandExec](IMPORTANT): Running ./Missions/Engineering/OnDock.tl 2024-05-15T18:56:42.921Z,1715799402.921 [Default] Stopped 2024-05-15T18:56:42.921Z,1715799402.921 [Default](DEBUG): Aggregate::uninitialize Default 2024-05-15T18:56:42.921Z,1715799402.921 [Default:B.GoToSurface] Stopped 2024-05-15T18:56:42.921Z,1715799402.921 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-15T18:56:42.922Z,1715799402.922 [Default:CheckIn] Stopped 2024-05-15T18:56:42.922Z,1715799402.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-05-15T18:56:42.922Z,1715799402.922 [Default:CheckIn:Read_Iridium] Stopped 2024-05-15T18:56:42.922Z,1715799402.922 [MissionManager](IMPORTANT): Started mission OnDock 2024-05-15T18:56:42.934Z,1715799402.934 [OnDock] Running Loop=1 2024-05-15T18:56:42.934Z,1715799402.934 [OnDock](DEBUG): Aggregate::initialize OnDock 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-15T18:56:42.935Z,1715799402.935 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:BackseatDriver] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly:A] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly:B] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly:C] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly:D] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly:E.Wait] Running Loop=1 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:56:42.936Z,1715799402.936 [OnDock:H] Running Loop=1 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:I.] Running Loop=1 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:I.](INFO): Initializing TrackAcousticContact. 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:PeriodicCheck] Running Loop=1 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2024-05-15T18:56:42.937Z,1715799402.937 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2024-05-15T18:56:42.938Z,1715799402.938 [OnDock:B] Running Loop=1 2024-05-15T18:56:42.938Z,1715799402.938 [OnDock:PeriodicCheck] Running Loop=1 2024-05-15T18:56:42.948Z,1715799402.948 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2024-05-15T18:56:42.948Z,1715799402.948 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2024-05-15T18:56:42.948Z,1715799402.948 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2024-05-15T18:56:42.949Z,1715799402.949 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2024-05-15T18:56:42.949Z,1715799402.949 [OnDock:I.] Running Loop=1 2024-05-15T18:56:42.949Z,1715799402.949 [OnDock:H] Running Loop=1 2024-05-15T18:56:42.950Z,1715799402.950 [OnDock:H](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-15T18:56:42.958Z,1715799402.958 [OnDock:PowerOnly] Running Loop=1 2024-05-15T18:56:42.959Z,1715799402.959 [OnDock:PowerOnly:D] Stopped 2024-05-15T18:56:42.959Z,1715799402.959 [OnDock:PowerOnly:C] Stopped 2024-05-15T18:56:42.959Z,1715799402.959 [OnDock:PowerOnly:B] Stopped 2024-05-15T18:56:42.959Z,1715799402.959 [OnDock:PowerOnly:A] Stopped 2024-05-15T18:56:42.959Z,1715799402.959 [OnDock:BackseatDriver] Running Loop=1 2024-05-15T18:56:42.960Z,1715799402.960 [OnDock:B] Stopped 2024-05-15T18:56:42.960Z,1715799402.960 [OnDock:C] Running Loop=1 2024-05-15T18:56:42.960Z,1715799402.960 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-15T18:56:42.960Z,1715799402.960 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-15T18:56:42.961Z,1715799402.961 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-05-15T18:56:42.967Z,1715799402.967 [CommandExec](IMPORTANT): got command ! ifconfig 2024-05-15T18:56:43.009Z,1715799403.009 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-15T18:56:43.023Z,1715799403.023 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-15T18:56:43.041Z,1715799403.041 [HorizontalControl](DEBUG): kpHeading = 0.400000 2024-05-15T18:56:43.041Z,1715799403.041 [HorizontalControl](DEBUG): kiHeading = 0.001000 2024-05-15T18:56:43.042Z,1715799403.042 [HorizontalControl](DEBUG): kdHeading = 0.050000 2024-05-15T18:56:43.178Z,1715799403.178 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:4B inet addr:10.89.10.2 Bcast:10.89.255.255 Mask:255.255.0.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:214 errors:0 dropped:0 overruns:0 frame:0 TX packets:248 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:97983 (95.6 Kb) TX bytes:41085 (40.1 Kb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:352 errors:0 dropped:0 overruns:0 frame:0 TX packets:352 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:26048 (25.4 Kb) TX bytes:26048 (25.4 Kb) 2024-05-15T18:56:43.380Z,1715799403.380 [OnDock:C] Stopped 2024-05-15T18:56:43.381Z,1715799403.381 [OnDock:D] Running Loop=1 2024-05-15T18:56:43.693Z,1715799403.693 [OnDock:D] Stopped 2024-05-15T18:56:43.693Z,1715799403.693 [OnDock:E] Running Loop=1 2024-05-15T18:56:44.097Z,1715799404.097 [OnDock:E] Stopped 2024-05-15T18:56:44.097Z,1715799404.097 [OnDock:K] Running Loop=1 2024-05-15T18:56:44.566Z,1715799404.566 [OnDock:K](IMPORTANT): Restarting logs 2024-05-15T18:56:44.566Z,1715799404.566 [OnDock:K] Stopped 2024-05-15T18:56:44.567Z,1715799404.567 [OnDock:L.Execute] Running Loop=1 2024-05-15T18:56:44.576Z,1715799404.576 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2024-05-15T18:56:44.631Z,1715799404.631 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20240515T185031/Courier0004.lzma 2024-05-15T18:56:45.005Z,1715799405.005 [OnDock:L.Execute](INFO): Executing command restart logs 2024-05-15T18:56:45.006Z,1715799405.006 [OnDock:L.Execute] Stopped 2024-05-15T18:56:45.006Z,1715799405.006 [OnDock:M] Running Loop=1 2024-05-15T18:56:45.006Z,1715799405.006 [OnDock:M](DEBUG): Aggregate::initialize OnDock:M