2023-09-19T18:47:13.488Z,1695149233.488 [Supervisor](DEBUG): Initializing supervisor. 2023-09-19T18:47:13.492Z,1695149233.492 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-09-19T18:47:13.492Z,1695149233.492 [SyncHandler](INFO): Protected caller Thread ID is 836 2023-09-19T18:47:13.493Z,1695149233.493 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-09-19T18:47:13.494Z,1695149233.494 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-09-19T18:47:13.494Z,1695149233.494 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2023-09-19T18:47:13.498Z,1695149233.498 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-09-19T18:47:13.515Z,1695149233.515 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-09-19T18:47:13.516Z,1695149233.516 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-09-19T18:47:13.517Z,1695149233.517 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838 2023-09-19T18:47:13.521Z,1695149233.521 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-09-19T18:47:13.522Z,1695149233.522 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-09-19T18:47:13.522Z,1695149233.522 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839 2023-09-19T18:47:13.524Z,1695149233.524 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-09-19T18:47:13.525Z,1695149233.525 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-09-19T18:47:13.526Z,1695149233.526 [logger ThreadHandler](INFO): Protected caller Thread ID is 840 2023-09-19T18:47:13.529Z,1695149233.529 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-09-19T18:47:13.530Z,1695149233.530 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-09-19T18:47:13.534Z,1695149233.534 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-09-19T18:47:13.748Z,1695149233.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-09-19T18:47:13.750Z,1695149233.750 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-09-19T18:47:13.855Z,1695149233.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-09-19T18:47:13.857Z,1695149233.857 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-09-19T18:47:13.950Z,1695149233.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-09-19T18:47:13.952Z,1695149233.952 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-09-19T18:47:14.404Z,1695149234.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-09-19T18:47:14.406Z,1695149234.406 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-09-19T18:47:14.737Z,1695149234.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-09-19T18:47:14.738Z,1695149234.738 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-09-19T18:47:15.051Z,1695149235.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-09-19T18:47:15.051Z,1695149235.051 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-09-19T18:47:15.229Z,1695149235.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-09-19T18:47:15.230Z,1695149235.230 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-09-19T18:47:15.310Z,1695149235.310 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-09-19T18:47:15.390Z,1695149235.390 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-09-19T18:47:15.789Z,1695149235.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-09-19T18:47:15.790Z,1695149235.790 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-09-19T18:47:16.057Z,1695149236.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-09-19T18:47:16.058Z,1695149236.058 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-09-19T18:47:16.559Z,1695149236.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-09-19T18:47:17.023Z,1695149237.023 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-09-19T18:47:17.508Z,1695149237.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-09-19T18:47:17.509Z,1695149237.509 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-09-19T18:47:17.733Z,1695149237.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-09-19T18:47:17.735Z,1695149237.735 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-09-19T18:47:17.873Z,1695149237.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-09-19T18:47:17.873Z,1695149237.873 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-09-19T18:47:18.656Z,1695149238.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-09-19T18:47:18.658Z,1695149238.658 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-09-19T18:47:18.661Z,1695149238.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-09-19T18:47:18.745Z,1695149238.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-09-19T18:47:18.873Z,1695149238.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-09-19T18:47:19.137Z,1695149239.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-09-19T18:47:19.137Z,1695149239.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-09-19T18:47:19.258Z,1695149239.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-09-19T18:47:19.357Z,1695149239.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-09-19T18:47:19.449Z,1695149239.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-09-19T18:47:19.554Z,1695149239.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-09-19T18:47:19.662Z,1695149239.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-09-19T18:47:19.747Z,1695149239.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-09-19T18:47:19.845Z,1695149239.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-09-19T18:47:20.003Z,1695149240.003 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-09-19T18:47:20.026Z,1695149240.026 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-09-19T18:47:20.096Z,1695149240.096 [DepthRateCalculator] Loaded 2023-09-19T18:47:20.096Z,1695149240.096 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-09-19T18:47:20.102Z,1695149240.102 [PitchRateCalculator] Loaded 2023-09-19T18:47:20.102Z,1695149240.102 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-09-19T18:47:20.112Z,1695149240.112 [SpeedCalculator] Loaded 2023-09-19T18:47:20.113Z,1695149240.113 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-09-19T18:47:20.117Z,1695149240.117 [YawRateCalculator] Loaded 2023-09-19T18:47:20.118Z,1695149240.118 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-09-19T18:47:20.139Z,1695149240.139 [ElevatorOffsetCalculator] Loaded 2023-09-19T18:47:20.139Z,1695149240.139 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-09-19T18:47:20.140Z,1695149240.140 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-09-19T18:47:20.142Z,1695149240.142 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-09-19T18:47:20.184Z,1695149240.184 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-09-19T18:47:20.185Z,1695149240.185 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-09-19T18:47:20.199Z,1695149240.199 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-09-19T18:47:20.200Z,1695149240.200 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-09-19T18:47:20.958Z,1695149240.958 [AHRS_M2] Loaded 2023-09-19T18:47:20.958Z,1695149240.958 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-09-19T18:47:21.207Z,1695149241.207 [BackseatComponent] Loaded 2023-09-19T18:47:21.207Z,1695149241.207 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-09-19T18:47:21.208Z,1695149241.208 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2023-09-19T18:47:21.209Z,1695149241.209 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922 2023-09-19T18:47:21.212Z,1695149241.212 [LcmUniversalReporter] Loaded 2023-09-19T18:47:21.212Z,1695149241.212 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-09-19T18:47:22.106Z,1695149242.106 [BPC1] Loaded 2023-09-19T18:47:22.107Z,1695149242.107 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-09-19T18:47:22.461Z,1695149242.461 [DataOverHttps] Loaded 2023-09-19T18:47:22.462Z,1695149242.462 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-09-19T18:47:22.463Z,1695149242.463 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2023-09-19T18:47:22.463Z,1695149242.463 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923 2023-09-19T18:47:22.516Z,1695149242.516 [Depth_Keller] Loaded 2023-09-19T18:47:22.516Z,1695149242.516 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-09-19T18:47:22.521Z,1695149242.521 [DropWeight] Loaded 2023-09-19T18:47:22.521Z,1695149242.521 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-09-19T18:47:22.540Z,1695149242.540 [MultiRay] Loaded 2023-09-19T18:47:22.540Z,1695149242.540 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-09-19T18:47:22.601Z,1695149242.601 [NAL9602] Loaded 2023-09-19T18:47:22.601Z,1695149242.601 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-09-19T18:47:22.663Z,1695149242.663 [Onboard] Loaded 2023-09-19T18:47:22.672Z,1695149242.672 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-09-19T18:47:22.673Z,1695149242.673 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2023-09-19T18:47:22.673Z,1695149242.673 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924 2023-09-19T18:47:22.679Z,1695149242.679 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-09-19T18:47:22.758Z,1695149242.758 [PowerOnly] Loaded 2023-09-19T18:47:22.758Z,1695149242.758 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-09-19T18:47:22.778Z,1695149242.778 [Power24vConverter] Loaded 2023-09-19T18:47:22.778Z,1695149242.778 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-09-19T18:47:22.824Z,1695149242.824 [Radio_Surface] Loaded 2023-09-19T18:47:22.824Z,1695149242.824 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-09-19T18:47:22.825Z,1695149242.825 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2023-09-19T18:47:22.825Z,1695149242.825 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925 2023-09-19T18:47:22.826Z,1695149242.826 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-09-19T18:47:22.827Z,1695149242.827 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-09-19T18:47:22.963Z,1695149242.963 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-09-19T18:47:22.964Z,1695149242.964 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-09-19T18:47:23.005Z,1695149243.005 [DeadReckonUsingSpeedCalculator] Loaded 2023-09-19T18:47:23.006Z,1695149243.006 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-09-19T18:47:23.019Z,1695149243.019 [NavChart] Loaded 2023-09-19T18:47:23.020Z,1695149243.020 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-09-19T18:47:23.025Z,1695149243.025 [UniversalFixResidualReporter] Loaded 2023-09-19T18:47:23.026Z,1695149243.026 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-09-19T18:47:23.026Z,1695149243.026 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-09-19T18:47:23.027Z,1695149243.027 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-09-19T18:47:23.193Z,1695149243.193 [SBIT](DEBUG): Construct Startup Built In Test. 2023-09-19T18:47:23.207Z,1695149243.207 [SBIT] Loaded 2023-09-19T18:47:23.207Z,1695149243.207 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-09-19T18:47:23.210Z,1695149243.210 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-09-19T18:47:23.222Z,1695149243.222 [IBIT] Loaded 2023-09-19T18:47:23.223Z,1695149243.223 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-09-19T18:47:23.228Z,1695149243.228 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-09-19T18:47:23.331Z,1695149243.331 [CBIT] Loaded 2023-09-19T18:47:23.331Z,1695149243.331 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-09-19T18:47:23.331Z,1695149243.331 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-09-19T18:47:23.332Z,1695149243.332 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-09-19T18:47:23.550Z,1695149243.550 [CTD_Seabird] Loaded 2023-09-19T18:47:23.550Z,1695149243.550 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-09-19T18:47:23.551Z,1695149243.551 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0 2023-09-19T18:47:23.552Z,1695149243.552 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926 2023-09-19T18:47:23.571Z,1695149243.571 [PAR_Licor] Loaded 2023-09-19T18:47:23.572Z,1695149243.572 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-09-19T18:47:23.612Z,1695149243.612 [WetLabsBB2FL] Loaded 2023-09-19T18:47:23.612Z,1695149243.612 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-09-19T18:47:23.613Z,1695149243.613 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0 2023-09-19T18:47:23.614Z,1695149243.614 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927 2023-09-19T18:47:23.614Z,1695149243.614 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-09-19T18:47:23.615Z,1695149243.615 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-09-19T18:47:23.775Z,1695149243.775 [BuoyancyServo] Loaded 2023-09-19T18:47:23.775Z,1695149243.775 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-09-19T18:47:23.797Z,1695149243.797 [ElevatorServo] Loaded 2023-09-19T18:47:23.797Z,1695149243.797 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-09-19T18:47:23.819Z,1695149243.819 [MassServo] Loaded 2023-09-19T18:47:23.819Z,1695149243.819 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-09-19T18:47:23.839Z,1695149243.839 [RudderServo] Loaded 2023-09-19T18:47:23.839Z,1695149243.839 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-09-19T18:47:23.855Z,1695149243.855 [ThrusterHE] Loaded 2023-09-19T18:47:23.855Z,1695149243.855 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-09-19T18:47:23.856Z,1695149243.856 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-09-19T18:47:23.856Z,1695149243.856 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-09-19T18:47:23.923Z,1695149243.923 [VerticalControl](DEBUG): Construct VerticalControl. 2023-09-19T18:47:23.974Z,1695149243.974 [VerticalControl] Loaded 2023-09-19T18:47:23.974Z,1695149243.974 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-09-19T18:47:23.977Z,1695149243.977 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-09-19T18:47:24.014Z,1695149244.014 [HorizontalControl] Loaded 2023-09-19T18:47:24.014Z,1695149244.014 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-09-19T18:47:24.017Z,1695149244.017 [SpeedControl](DEBUG): Construct SpeedControl. 2023-09-19T18:47:24.018Z,1695149244.018 [SpeedControl] Loaded 2023-09-19T18:47:24.019Z,1695149244.019 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-09-19T18:47:24.022Z,1695149244.022 [LoopControl](DEBUG): Construct LoopControl. 2023-09-19T18:47:24.022Z,1695149244.022 [LoopControl] Loaded 2023-09-19T18:47:24.022Z,1695149244.022 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-09-19T18:47:24.023Z,1695149244.023 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-09-19T18:47:24.023Z,1695149244.023 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-09-19T18:47:24.150Z,1695149244.150 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-09-19T18:47:24.150Z,1695149244.150 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-09-19T18:47:24.330Z,1695149244.330 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-09-19T18:47:24.331Z,1695149244.331 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-09-19T18:47:24.683Z,1695149244.683 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-09-19T18:47:24.684Z,1695149244.684 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-09-19T18:47:24.782Z,1695149244.782 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC 2023-09-19T18:47:24.782Z,1695149244.782 [StratificationFrontDetector](DEBUG): (re)initializing 2023-09-19T18:47:24.783Z,1695149244.783 [StratificationFrontDetector] Loaded 2023-09-19T18:47:24.783Z,1695149244.783 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2023-09-19T18:47:24.783Z,1695149244.783 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-09-19T18:47:24.790Z,1695149244.790 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-09-19T18:47:24.792Z,1695149244.792 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-09-19T18:47:24.803Z,1695149244.803 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-09-19T18:47:24.804Z,1695149244.804 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-09-19T18:47:24.805Z,1695149244.805 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2023-09-19T18:47:24.809Z,1695149244.809 [Supervisor](INFO): Main Thread ID is 829 2023-09-19T18:47:24.809Z,1695149244.809 [Supervisor](DEBUG): Running supervisor. 2023-09-19T18:47:24.810Z,1695149244.810 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2023-09-19T18:47:24.810Z,1695149244.810 [CommandExec](INFO): Initializing the command executive. 2023-09-19T18:47:24.812Z,1695149244.812 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2023-09-19T18:47:24.814Z,1695149244.814 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2023-09-19T18:47:24.814Z,1695149244.814 [controlThread](DEBUG): Initializing ControlThread 2023-09-19T18:47:24.815Z,1695149244.815 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-09-19T18:47:24.816Z,1695149244.816 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-09-19T18:47:24.816Z,1695149244.816 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-09-19T18:47:24.816Z,1695149244.816 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-09-19T18:47:24.817Z,1695149244.817 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-09-19T18:47:24.823Z,1695149244.823 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-19T18:47:24.823Z,1695149244.823 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-09-19T18:47:24.823Z,1695149244.823 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-09-19T18:47:24.824Z,1695149244.824 [SBIT](INFO): Initialize SBIT Component. 2023-09-19T18:47:24.824Z,1695149244.824 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de 2023-09-19T18:47:24.825Z,1695149244.825 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4 2023-09-19T18:47:24.825Z,1695149244.825 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-09-19T18:47:24.826Z,1695149244.826 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-09-19T18:47:24.827Z,1695149244.827 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2023-09-19T18:47:24.827Z,1695149244.827 [IBIT](INFO): Initialize IBIT Component. 2023-09-19T18:47:24.829Z,1695149244.829 [CBIT](DEBUG): Initialize CBIT Component. 2023-09-19T18:47:24.829Z,1695149244.829 [logger ThreadHandler](INFO): Handler Thread ID is 932 2023-09-19T18:47:24.840Z,1695149244.840 [CBIT](DEBUG): Initialized mux pins. 2023-09-19T18:47:24.840Z,1695149244.840 [CBIT](DEBUG): Initializing the watchdog timer. 2023-09-19T18:47:24.848Z,1695149244.848 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933 2023-09-19T18:47:24.860Z,1695149244.860 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934 2023-09-19T18:47:24.861Z,1695149244.861 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-09-19T18:47:24.864Z,1695149244.864 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-09-19T18:47:24.864Z,1695149244.864 [CBIT](DEBUG): Initializing heartbeat. 2023-09-19T18:47:24.872Z,1695149244.872 [Onboard ThreadHandler](INFO): Handler Thread ID is 935 2023-09-19T18:47:24.889Z,1695149244.889 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936 2023-09-19T18:47:24.913Z,1695149244.913 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937 2023-09-19T18:47:24.913Z,1695149244.913 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-09-19T18:47:24.917Z,1695149244.917 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939 2023-09-19T18:47:24.920Z,1695149244.920 [WetLabsBB2FL](INFO): Powering up 2023-09-19T18:47:24.922Z,1695149244.922 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941 2023-09-19T18:47:24.929Z,1695149244.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-09-19T18:47:24.929Z,1695149244.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-09-19T18:47:24.929Z,1695149244.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-09-19T18:47:24.929Z,1695149244.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-09-19T18:47:24.929Z,1695149244.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-09-19T18:47:24.929Z,1695149244.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-09-19T18:47:24.930Z,1695149244.930 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-09-19T18:47:24.930Z,1695149244.930 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-09-19T18:47:24.936Z,1695149244.936 [CBIT](DEBUG): Deactivating GF circuits. 2023-09-19T18:47:24.936Z,1695149244.936 [CBIT](DEBUG): Deactivating emergency mode. 2023-09-19T18:47:24.976Z,1695149244.976 [CBIT](DEBUG): Backplane powered. 2023-09-19T18:47:24.981Z,1695149244.981 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-09-19T18:47:24.983Z,1695149244.983 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-09-19T18:47:24.984Z,1695149244.984 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-09-19T18:47:24.984Z,1695149244.984 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-09-19T18:47:24.986Z,1695149244.986 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-09-19T18:47:25.015Z,1695149245.015 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-19T18:47:25.048Z,1695149245.048 [MissionManager](DEBUG): 2023-09-19T18:47:25.049Z,1695149245.049 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-09-19T18:47:25.110Z,1695149245.110 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-09-19T18:47:25.111Z,1695149245.111 [Default:A.Wait](DEBUG): Construct Wait. 2023-09-19T18:47:25.122Z,1695149245.122 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-19T18:47:25.148Z,1695149245.148 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-09-19T18:47:25.151Z,1695149245.151 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-09-19T18:47:25.173Z,1695149245.173 [Default:E.Execute](DEBUG): Construct Execute. 2023-09-19T18:47:25.176Z,1695149245.176 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-09-19T18:47:25.181Z,1695149245.181 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-09-19T18:47:25.189Z,1695149245.189 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-09-19T18:47:25.296Z,1695149245.296 [Radio_Surface](INFO): Powering up 2023-09-19T18:47:25.326Z,1695149245.326 [MultiRay](INFO): Powering up MultiRay Lights 2023-09-19T18:47:25.369Z,1695149245.369 [Power24vConverter](INFO): Powering up. 2023-09-19T18:47:25.414Z,1695149245.414 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-09-19T18:47:25.421Z,1695149245.421 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-09-19T18:47:25.422Z,1695149245.422 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-09-19T18:47:25.428Z,1695149245.428 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-09-19T18:47:25.429Z,1695149245.429 [MassServo](DEBUG): Initializing EZServoServo. 2023-09-19T18:47:25.437Z,1695149245.437 [MassServo](DEBUG): Initializing MassServo. 2023-09-19T18:47:25.437Z,1695149245.437 [RudderServo](DEBUG): Initializing EZServoServo. 2023-09-19T18:47:25.444Z,1695149245.444 [RudderServo](DEBUG): Initializing RudderServo. 2023-09-19T18:47:25.445Z,1695149245.445 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-09-19T18:47:25.452Z,1695149245.452 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-09-19T18:47:25.641Z,1695149245.641 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-09-19T18:47:25.641Z,1695149245.641 [DropWeight] Hardware Fault, FailCount= 1 2023-09-19T18:47:25.642Z,1695149245.642 [DropWeight](ERROR): Hardware Fault 2023-09-19T18:47:25.699Z,1695149245.699 [CommandExec](FAULT): Scheduling is paused 2023-09-19T18:47:25.704Z,1695149245.704 [CBIT](INFO): Critical error at 20230919T184725 2023-09-19T18:47:25.704Z,1695149245.704 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-09-19T18:47:25.706Z,1695149245.706 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-09-19T18:47:25.707Z,1695149245.707 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-09-19T18:47:26.030Z,1695149246.030 [CBIT](INFO): Critical error at 20230919T184725 2023-09-19T18:47:27.532Z,1695149247.532 [WetLabsBB2FL](INFO): Powering down 2023-09-19T18:47:30.985Z,1695149250.985 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-09-19T18:47:31.944Z,1695149251.944 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-09-19T18:47:52.739Z,1695149272.739 [NAL9602](INFO): Powering up NAL9602 2023-09-19T18:48:03.638Z,1695149283.638 [NAL9602](INFO): NAL9602 initialized 2023-09-19T18:48:24.256Z,1695149304.256 [SBIT](IMPORTANT): Beginning Startup BIT 2023-09-19T18:48:24.261Z,1695149304.261 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-19T18:48:35.227Z,1695149315.227 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006568 CHAN A1 (24V): 0.001560 CHAN A2 (12V): -0.005709 CHAN A3 (5V): -0.002458 CHAN B0 (3.3V): -0.001111 CHAN B1 (3.15aV): -0.001535 CHAN B2 (3.15bV): -0.001359 CHAN B3 (GND): -0.000395 OPEN: 0.003934 Full Scale: +/- 1 mA 2023-09-19T18:48:52.139Z,1695149332.139 [CommandExec](IMPORTANT): got command strobe off 2023-09-19T18:48:52.139Z,1695149332.139 [CommandExec](IMPORTANT): Deactivating strobe 2023-09-19T18:49:18.399Z,1695149358.399 [SBIT](IMPORTANT): SBIT PASSED 2023-09-19T18:49:18.400Z,1695149358.400 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-09-19T18:49:18.401Z,1695149358.401 [SBIT](IMPORTANT): BackseatComponent.missionCritical=1 bool; 2023-09-19T18:49:18.401Z,1695149358.401 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour; 2023-09-19T18:49:18.401Z,1695149358.401 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-09-19T18:49:18.402Z,1695149358.402 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-09-19T18:49:18.403Z,1695149358.403 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2023-09-19T18:49:18.403Z,1695149358.403 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-09-19T18:49:18.403Z,1695149358.403 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-09-19T18:49:18.405Z,1695149358.405 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2023-09-19T18:49:18.406Z,1695149358.406 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter; 2023-09-19T18:49:18.407Z,1695149358.407 [SBIT](IMPORTANT): VerticalControl.massDeadband=1 millimeter; 2023-09-19T18:49:18.407Z,1695149358.407 [SBIT](IMPORTANT): VerticalControl.massDefault=1 centimeter; 2023-09-19T18:49:18.407Z,1695149358.407 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-09-19T18:49:18.407Z,1695149358.407 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-09-19T18:49:18.808Z,1695149358.808 [MissionManager](IMPORTANT): Started mission Startup 2023-09-19T18:49:18.809Z,1695149358.809 [Startup] Running Loop=1 2023-09-19T18:49:18.809Z,1695149358.809 [Startup](DEBUG): Aggregate::initialize Startup 2023-09-19T18:49:18.809Z,1695149358.809 [Startup:A.GoToSurface] Running Loop=1 2023-09-19T18:49:18.809Z,1695149358.809 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-19T18:49:18.809Z,1695149358.809 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-19T18:49:18.810Z,1695149358.810 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-19T18:49:18.810Z,1695149358.810 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-19T18:49:18.811Z,1695149358.811 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-19T18:49:18.811Z,1695149358.811 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-19T18:49:18.813Z,1695149358.813 [Startup:StartupSatComms] Running Loop=1 2023-09-19T18:49:18.813Z,1695149358.813 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-09-19T18:49:18.813Z,1695149358.813 [Startup:StartupSatComms:A] Running Loop=1 2023-09-19T18:49:19.204Z,1695149359.204 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-09-19T18:49:53.647Z,1695149393.647 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004958 2023-09-19T18:50:19.115Z,1695149419.115 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-19T18:49:18.8Z 2023-09-19T18:50:19.115Z,1695149419.115 [Startup:StartupSatComms:A] Stopped 2023-09-19T18:50:19.115Z,1695149419.115 [Startup:StartupSatComms:B] Running Loop=1 2023-09-19T18:50:19.515Z,1695149419.515 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-19T18:50:24.967Z,1695149424.967 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-09-19T18:50:24.971Z,1695149424.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-19T18:50:24.972Z,1695149424.972 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-09-19T18:50:24.972Z,1695149424.972 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-09-19T18:50:24.991Z,1695149424.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-19T18:50:24.992Z,1695149424.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-09-19T18:50:25.340Z,1695149425.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-19T18:50:25.340Z,1695149425.340 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-09-19T18:50:25.341Z,1695149425.341 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-09-19T18:50:25.341Z,1695149425.341 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-09-19T18:50:25.712Z,1695149425.712 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-19T18:50:27.975Z,1695149427.975 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230919T184225/Courier0004.lzma 2023-09-19T18:50:28.973Z,1695149428.973 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184225/Courier0004.lzma.bak 2023-09-19T18:50:28.973Z,1695149428.973 [DataOverHttps](INFO): SBD MOMSN=18796915 2023-09-19T18:50:45.583Z,1695149445.583 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230919T184713/Courier0000.lzma 2023-09-19T18:50:46.585Z,1695149446.585 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Courier0000.lzma.bak 2023-09-19T18:50:46.585Z,1695149446.585 [DataOverHttps](INFO): SBD MOMSN=18796919 2023-09-19T18:50:50.344Z,1695149450.344 [CommandExec](IMPORTANT): got command show stack 2023-09-19T18:50:50.344Z,1695149450.344 [CommandExec](IMPORTANT): Behavior Stack: 2023-09-19T18:50:50.345Z,1695149450.345 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface 2023-09-19T18:50:50.345Z,1695149450.345 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B 2023-09-19T18:51:06.905Z,1695149466.905 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20230919T173108/Express0011.lzma 2023-09-19T18:51:07.905Z,1695149467.905 [DataOverHttps](INFO): Moved sent file to Logs/20230919T173108/Express0011.lzma.bak 2023-09-19T18:51:07.905Z,1695149467.905 [DataOverHttps](INFO): SBD MOMSN=18796931 2023-09-19T18:51:19.144Z,1695149479.144 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-19T18:50:19.1Z 2023-09-19T18:51:19.144Z,1695149479.144 [Startup:StartupSatComms:B] Stopped 2023-09-19T18:51:19.144Z,1695149479.144 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-09-19T18:51:19.144Z,1695149479.144 [Startup:StartupSatComms] Stopped 2023-09-19T18:51:19.144Z,1695149479.144 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-09-19T18:51:19.145Z,1695149479.145 [Startup](INFO): Completed Startup 2023-09-19T18:51:19.145Z,1695149479.145 [MissionManager](INFO): Startup is completed. 2023-09-19T18:51:19.145Z,1695149479.145 [MissionManager](INFO): Uninitializing Mission Startup 2023-09-19T18:51:19.146Z,1695149479.146 [Startup] Stopped 2023-09-19T18:51:19.146Z,1695149479.146 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-09-19T18:51:19.146Z,1695149479.146 [Startup:A.GoToSurface] Stopped 2023-09-19T18:51:19.146Z,1695149479.146 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-19T18:51:19.522Z,1695149479.522 [MissionManager](IMPORTANT): Started mission Default 2023-09-19T18:51:19.522Z,1695149479.522 [Default] Running Loop=1 2023-09-19T18:51:19.522Z,1695149479.522 [Default](DEBUG): Aggregate::initialize Default 2023-09-19T18:51:19.522Z,1695149479.522 [Default:B.GoToSurface] Running Loop=1 2023-09-19T18:51:19.523Z,1695149479.523 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-19T18:51:19.531Z,1695149479.531 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-19T18:51:19.531Z,1695149479.531 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-19T18:51:19.532Z,1695149479.532 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-19T18:51:19.532Z,1695149479.532 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-19T18:51:19.532Z,1695149479.532 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-19T18:51:19.533Z,1695149479.533 [Default:A.Wait] Running Loop=1 2023-09-19T18:51:19.533Z,1695149479.533 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-19T18:51:32.901Z,1695149492.901 [Default:A.Wait](INFO): Done Waiting. 2023-09-19T18:51:32.901Z,1695149492.901 [Default:A.Wait] Stopped 2023-09-19T18:51:32.901Z,1695149492.901 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T18:51:33.311Z,1695149493.311 [Default:CheckIn] Running Loop=1 2023-09-19T18:51:33.312Z,1695149493.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-19T18:51:33.312Z,1695149493.312 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-19T18:51:33.597Z,1695149493.597 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-09-19T18:51:38.165Z,1695149498.165 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230919T173108/Express0014.lzma 2023-09-19T18:51:39.165Z,1695149499.165 [DataOverHttps](INFO): Moved sent file to Logs/20230919T173108/Express0014.lzma.bak 2023-09-19T18:51:39.165Z,1695149499.165 [DataOverHttps](INFO): SBD MOMSN=18797016 2023-09-19T18:52:41.989Z,1695149561.989 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=62.824623 2023-09-19T18:53:06.917Z,1695149586.917 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-19T18:53:19.303Z,1695149599.303 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002680 2023-09-19T18:53:25.921Z,1695149605.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-09-19T18:53:25.921Z,1695149605.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-19T18:53:25.922Z,1695149605.922 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-09-19T18:53:25.922Z,1695149605.922 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-09-19T18:53:25.933Z,1695149605.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-19T18:53:25.933Z,1695149605.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-09-19T18:53:26.328Z,1695149606.328 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-19T18:53:26.328Z,1695149606.328 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-09-19T18:53:26.329Z,1695149606.329 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-09-19T18:53:26.329Z,1695149606.329 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-09-19T18:53:26.708Z,1695149606.708 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-19T18:54:26.905Z,1695149666.905 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-19T18:54:26.909Z,1695149666.909 [BPC1](INFO): Received data from all battery sticks. 2023-09-19T18:56:26.900Z,1695149786.900 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-09-19T18:56:26.900Z,1695149786.900 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-19T18:56:26.901Z,1695149786.901 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-09-19T18:56:26.901Z,1695149786.901 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-09-19T18:56:26.944Z,1695149786.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-19T18:56:26.944Z,1695149786.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-09-19T18:56:27.349Z,1695149787.349 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-19T18:56:27.349Z,1695149787.349 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-09-19T18:56:27.350Z,1695149787.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-09-19T18:56:27.350Z,1695149787.350 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-09-19T18:56:27.728Z,1695149787.728 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-19T18:56:33.387Z,1695149793.387 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T18:51:33.3Z 2023-09-19T18:56:33.387Z,1695149793.387 [Default:CheckIn:Read_GPS] Stopped 2023-09-19T18:56:33.387Z,1695149793.387 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-19T18:56:33.797Z,1695149793.797 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-19T18:57:01.911Z,1695149821.911 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230919T184713/Courier0004.lzma 2023-09-19T18:57:02.913Z,1695149822.913 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Courier0004.lzma.bak 2023-09-19T18:57:02.913Z,1695149822.913 [DataOverHttps](INFO): SBD MOMSN=18797018 2023-09-19T18:57:24.352Z,1695149844.352 [DataOverHttps](INFO): Sending 663 bytes from file Logs/20230919T184225/Express0001.lzma 2023-09-19T18:57:25.353Z,1695149845.353 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184225/Express0001.lzma.bak 2023-09-19T18:57:25.353Z,1695149845.353 [DataOverHttps](INFO): SBD MOMSN=18797020 2023-09-19T18:57:26.281Z,1695149846.281 [Power24vConverter](INFO): Powering down. 2023-09-19T18:57:42.826Z,1695149862.826 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20230919T184225/Express0005.lzma 2023-09-19T18:57:43.829Z,1695149863.829 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184225/Express0005.lzma.bak 2023-09-19T18:57:43.829Z,1695149863.829 [DataOverHttps](INFO): SBD MOMSN=18797033 2023-09-19T18:58:01.233Z,1695149881.233 [DataOverHttps](INFO): Sending 1113 bytes from file Logs/20230919T184713/Express0001.lzma 2023-09-19T18:58:02.233Z,1695149882.233 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Express0001.lzma.bak 2023-09-19T18:58:02.233Z,1695149882.233 [DataOverHttps](INFO): SBD MOMSN=18797036 2023-09-19T18:58:06.277Z,1695149886.277 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-19T18:58:06.277Z,1695149886.277 [NAL9602] Data Fault, FailCount= 1 2023-09-19T18:58:06.277Z,1695149886.277 [NAL9602](ERROR): Data Fault 2023-09-19T18:58:06.298Z,1695149886.298 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-19T18:58:06.682Z,1695149886.682 [NAL9602](INFO): Powering down 2023-09-19T18:58:07.552Z,1695149887.552 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-19T18:58:07.552Z,1695149887.552 [NAL9602] No Fault, FailCount= 1 2023-09-19T18:58:19.579Z,1695149899.579 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20230919T184713/Express0005.lzma 2023-09-19T18:58:20.581Z,1695149900.581 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Express0005.lzma.bak 2023-09-19T18:58:20.581Z,1695149900.581 [DataOverHttps](INFO): SBD MOMSN=18797070 2023-09-19T18:58:23.688Z,1695149903.688 [Default:CheckIn:Read_Iridium] Stopped 2023-09-19T18:58:23.688Z,1695149903.688 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-19T18:58:23.688Z,1695149903.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-19T18:58:36.977Z,1695149916.977 [NAL9602](INFO): Powering up NAL9602 2023-09-19T18:58:47.882Z,1695149927.882 [NAL9602](INFO): NAL9602 initialized 2023-09-19T18:59:27.892Z,1695149967.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-09-19T18:59:27.892Z,1695149967.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-19T18:59:27.893Z,1695149967.893 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-09-19T18:59:27.893Z,1695149967.893 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-09-19T18:59:27.945Z,1695149967.945 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-19T18:59:27.945Z,1695149967.945 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-09-19T18:59:28.305Z,1695149968.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-19T18:59:28.305Z,1695149968.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-09-19T18:59:28.306Z,1695149968.306 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-09-19T18:59:28.306Z,1695149968.306 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-09-19T18:59:28.700Z,1695149968.700 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-19T19:02:25.684Z,1695150145.684 [CBIT](INFO): Clearing failed state for component DropWeight 2023-09-19T19:02:25.684Z,1695150145.684 [DropWeight] No Fault, FailCount= 1 2023-09-19T19:02:28.905Z,1695150148.905 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-09-19T19:02:28.905Z,1695150148.905 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-19T19:02:28.906Z,1695150148.906 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-09-19T19:02:28.906Z,1695150148.906 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-09-19T19:02:28.917Z,1695150148.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-19T19:02:28.917Z,1695150148.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-09-19T19:02:29.323Z,1695150149.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-19T19:02:29.323Z,1695150149.323 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-09-19T19:02:29.323Z,1695150149.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-09-19T19:02:29.323Z,1695150149.323 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-09-19T19:02:29.684Z,1695150149.684 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-19T19:02:34.944Z,1695150154.944 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190233.00,A,3648.16544,N,12147.27798,W,0.039,0.00,190923,,,A*75 2023-09-19T19:02:34.952Z,1695150154.952 [NAL9602](INFO): GPS fix at 20230919T190233: (36.802757, -121.787966) 2023-09-19T19:03:07.260Z,1695150187.260 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-19T19:03:24.234Z,1695150204.234 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-19T19:03:24.234Z,1695150204.234 [Default:CheckIn:C.Wait] Stopped 2023-09-19T19:03:24.234Z,1695150204.234 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:03:24.234Z,1695150204.234 [Default:CheckIn:D] Running Loop=1 2023-09-19T19:03:24.639Z,1695150204.639 [Default:CheckIn:D] Stopped 2023-09-19T19:03:24.639Z,1695150204.639 [Default:CheckIn:E] Running Loop=1 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.085272 min 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn:E] Stopped 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn] Stopped 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn](INFO): Running loop #2 2023-09-19T19:03:25.048Z,1695150205.048 [Default:CheckIn] Running Loop=2 2023-09-19T19:03:25.049Z,1695150205.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-19T19:03:25.049Z,1695150205.049 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-19T19:03:27.055Z,1695150207.055 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190325.00,A,3648.16523,N,12147.28326,W,0.175,0.00,190923,,,A*75 2023-09-19T19:03:27.057Z,1695150207.057 [NAL9602](INFO): GPS fix at 20230919T190325: (36.802754, -121.788054) 2023-09-19T19:03:27.088Z,1695150207.088 [Default:CheckIn:Read_GPS] Stopped 2023-09-19T19:03:27.089Z,1695150207.089 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-19T19:03:35.311Z,1695150215.311 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230919T184713/Courier0007.lzma 2023-09-19T19:03:36.313Z,1695150216.313 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Courier0007.lzma.bak 2023-09-19T19:03:36.313Z,1695150216.313 [DataOverHttps](INFO): SBD MOMSN=18797169 2023-09-19T19:03:54.827Z,1695150234.827 [DataOverHttps](INFO): Sending 323 bytes from file Logs/20230919T184713/Express0008.lzma 2023-09-19T19:03:55.829Z,1695150235.829 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Express0008.lzma.bak 2023-09-19T19:03:55.829Z,1695150235.829 [DataOverHttps](INFO): SBD MOMSN=18797172 2023-09-19T19:03:59.384Z,1695150239.384 [Default:CheckIn:Read_Iridium] Stopped 2023-09-19T19:03:59.384Z,1695150239.384 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-19T19:03:59.384Z,1695150239.384 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-19T19:08:29.249Z,1695150509.249 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-19T19:08:59.947Z,1695150539.947 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-19T19:08:59.960Z,1695150539.960 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-19T19:08:59.960Z,1695150539.960 [Default:CheckIn:C.Wait] Stopped 2023-09-19T19:08:59.960Z,1695150539.960 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:08:59.960Z,1695150539.960 [Default:CheckIn:D] Running Loop=1 2023-09-19T19:09:00.364Z,1695150540.364 [Default:CheckIn:D] Stopped 2023-09-19T19:09:00.364Z,1695150540.364 [Default:CheckIn:E] Running Loop=1 2023-09-19T19:09:00.779Z,1695150540.779 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.680695 min 2023-09-19T19:09:00.779Z,1695150540.779 [Default:CheckIn:E] Stopped 2023-09-19T19:09:00.779Z,1695150540.779 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-19T19:09:00.779Z,1695150540.779 [Default:CheckIn] Stopped 2023-09-19T19:09:00.780Z,1695150540.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-19T19:09:00.780Z,1695150540.780 [Default:CheckIn](INFO): Running loop #3 2023-09-19T19:09:00.780Z,1695150540.780 [Default:CheckIn] Running Loop=3 2023-09-19T19:09:00.780Z,1695150540.780 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-19T19:09:00.780Z,1695150540.780 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-19T19:09:02.809Z,1695150542.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190901.00,A,3648.16972,N,12147.27991,W,0.583,10.30,190923,,,A*47 2023-09-19T19:09:02.815Z,1695150542.815 [NAL9602](INFO): GPS fix at 20230919T190901: (36.802829, -121.787999) 2023-09-19T19:09:02.848Z,1695150542.848 [Default:CheckIn:Read_GPS] Stopped 2023-09-19T19:09:02.848Z,1695150542.848 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-19T19:09:14.035Z,1695150554.035 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230919T184713/Courier0010.lzma 2023-09-19T19:09:15.037Z,1695150555.037 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Courier0010.lzma.bak 2023-09-19T19:09:15.037Z,1695150555.037 [DataOverHttps](INFO): SBD MOMSN=18797197 2023-09-19T19:09:33.513Z,1695150573.513 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-19T19:09:33.587Z,1695150573.587 [NAL9602](ERROR): received: +CSQ:0 OK 2023-09-19T19:09:35.203Z,1695150575.203 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230919T184713/Express0011.lzma 2023-09-19T19:09:36.205Z,1695150576.205 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Express0011.lzma.bak 2023-09-19T19:09:36.205Z,1695150576.205 [DataOverHttps](INFO): SBD MOMSN=18797200 2023-09-19T19:09:39.601Z,1695150579.601 [Default:CheckIn:Read_Iridium] Stopped 2023-09-19T19:09:39.601Z,1695150579.601 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-19T19:09:39.601Z,1695150579.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-19T19:12:57.198Z,1695150777.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=13557, MT Status=0, MTMSN=0 2023-09-19T19:12:57.198Z,1695150777.198 [NAL9602](INFO): No messages in MT queue 2023-09-19T19:13:27.900Z,1695150807.900 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-19T19:14:40.231Z,1695150880.231 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-19T19:14:40.232Z,1695150880.232 [Default:CheckIn:C.Wait] Stopped 2023-09-19T19:14:40.232Z,1695150880.232 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:14:40.232Z,1695150880.232 [Default:CheckIn:D] Running Loop=1 2023-09-19T19:14:40.633Z,1695150880.633 [Default:CheckIn:D] Stopped 2023-09-19T19:14:40.633Z,1695150880.633 [Default:CheckIn:E] Running Loop=1 2023-09-19T19:14:41.048Z,1695150881.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.351841 min 2023-09-19T19:14:41.048Z,1695150881.048 [Default:CheckIn:E] Stopped 2023-09-19T19:14:41.048Z,1695150881.048 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-19T19:14:41.048Z,1695150881.048 [Default:CheckIn] Stopped 2023-09-19T19:14:41.049Z,1695150881.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-19T19:14:41.049Z,1695150881.049 [Default:CheckIn](INFO): Running loop #4 2023-09-19T19:14:41.049Z,1695150881.049 [Default:CheckIn] Running Loop=4 2023-09-19T19:14:41.049Z,1695150881.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-19T19:14:41.049Z,1695150881.049 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-19T19:14:43.078Z,1695150883.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191442.00,A,3648.16346,N,12147.28080,W,0.603,10.30,190923,,,A*4C 2023-09-19T19:14:43.082Z,1695150883.082 [NAL9602](INFO): GPS fix at 20230919T191442: (36.802724, -121.788013) 2023-09-19T19:14:43.094Z,1695150883.094 [Default:CheckIn:Read_GPS] Stopped 2023-09-19T19:14:43.094Z,1695150883.094 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-19T19:14:51.210Z,1695150891.210 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20230919T184713/Courier0013.lzma 2023-09-19T19:14:52.213Z,1695150892.213 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Courier0013.lzma.bak 2023-09-19T19:14:52.213Z,1695150892.213 [DataOverHttps](INFO): SBD MOMSN=18797336 2023-09-19T19:15:10.059Z,1695150910.059 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230919T184713/Express0014.lzma 2023-09-19T19:15:11.061Z,1695150911.061 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Express0014.lzma.bak 2023-09-19T19:15:11.061Z,1695150911.061 [DataOverHttps](INFO): SBD MOMSN=18797339 2023-09-19T19:15:15.404Z,1695150915.404 [Default:CheckIn:Read_Iridium] Stopped 2023-09-19T19:15:15.404Z,1695150915.404 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-19T19:15:15.404Z,1695150915.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-19T19:19:39.953Z,1695151179.953 [DataOverHttps](IMPORTANT): SBD MTMSN=20230919T191939 2023-09-19T19:19:45.301Z,1695151185.301 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-19T19:19:49.699Z,1695151189.699 [DataOverHttps](INFO): Received command: load Maintenance/tank_ballast_and_trim.tl;set ballast_and_trim.Depth1 5 m;set ballast_and_trim.SettleTime 120 min;set ballast_and_trim:BackseatDriver.EnableBackseat 1 bool;run 2023-09-19T19:19:49.854Z,1695151189.854 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-09-19T19:19:49.854Z,1695151189.854 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-09-19T19:19:49.854Z,1695151189.854 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx 2023-09-19T19:19:49.985Z,1695151189.985 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min 2023-09-19T19:19:49.987Z,1695151189.987 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2023-09-19T19:19:49.990Z,1695151189.990 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m 2023-09-19T19:19:49.993Z,1695151189.993 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2023-09-19T19:19:50.004Z,1695151190.004 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2023-09-19T19:19:50.007Z,1695151190.007 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m 2023-09-19T19:19:50.010Z,1695151190.010 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min 2023-09-19T19:19:50.021Z,1695151190.021 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2023-09-19T19:19:50.024Z,1695151190.024 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2023-09-19T19:19:50.026Z,1695151190.026 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2023-09-19T19:19:50.029Z,1695151190.029 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2023-09-19T19:19:50.096Z,1695151190.096 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2023-09-19T19:19:50.111Z,1695151190.111 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 % 2023-09-19T19:19:50.114Z,1695151190.114 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min 2023-09-19T19:19:50.115Z,1695151190.115 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2023-09-19T19:19:50.115Z,1695151190.115 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx 2023-09-19T19:19:50.867Z,1695151190.867 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2023-09-19T19:19:50.869Z,1695151190.869 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2023-09-19T19:19:50.872Z,1695151190.872 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2023-09-19T19:19:50.875Z,1695151190.875 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool 2023-09-19T19:19:50.877Z,1695151190.877 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool 2023-09-19T19:19:50.888Z,1695151190.888 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2023-09-19T19:19:50.890Z,1695151190.890 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool 2023-09-19T19:19:50.893Z,1695151190.893 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2023-09-19T19:19:50.896Z,1695151190.896 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2023-09-19T19:19:50.899Z,1695151190.899 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2023-09-19T19:19:50.902Z,1695151190.902 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2023-09-19T19:19:50.912Z,1695151190.912 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2023-09-19T19:19:51.027Z,1695151191.027 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2023-09-19T19:19:51.030Z,1695151191.030 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool 2023-09-19T19:19:51.037Z,1695151191.037 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2023-09-19T19:19:51.041Z,1695151191.041 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool 2023-09-19T19:19:51.096Z,1695151191.096 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool 2023-09-19T19:19:51.100Z,1695151191.100 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool 2023-09-19T19:19:51.125Z,1695151191.125 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool 2023-09-19T19:19:51.129Z,1695151191.129 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2023-09-19T19:19:51.158Z,1695151191.158 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool 2023-09-19T19:19:51.161Z,1695151191.161 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2023-09-19T19:19:51.196Z,1695151191.196 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2023-09-19T19:19:51.199Z,1695151191.199 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2023-09-19T19:19:51.201Z,1695151191.201 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2023-09-19T19:19:51.220Z,1695151191.220 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2023-09-19T19:19:51.223Z,1695151191.223 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool 2023-09-19T19:19:51.226Z,1695151191.226 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l 2023-09-19T19:19:51.249Z,1695151191.249 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l 2023-09-19T19:19:51.252Z,1695151191.252 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count 2023-09-19T19:19:51.268Z,1695151191.268 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l 2023-09-19T19:19:51.271Z,1695151191.271 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m 2023-09-19T19:19:51.274Z,1695151191.274 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC 2023-09-19T19:19:51.293Z,1695151191.293 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2023-09-19T19:19:51.296Z,1695151191.296 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2023-09-19T19:19:51.319Z,1695151191.319 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l 2023-09-19T19:19:51.322Z,1695151191.322 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m 2023-09-19T19:19:51.325Z,1695151191.325 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2023-09-19T19:19:51.337Z,1695151191.337 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2023-09-19T19:19:51.361Z,1695151191.361 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3 2023-09-19T19:19:51.364Z,1695151191.364 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m 2023-09-19T19:19:51.367Z,1695151191.367 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2023-09-19T19:19:51.376Z,1695151191.376 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2023-09-19T19:19:51.379Z,1695151191.379 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb 2023-09-19T19:19:51.442Z,1695151191.442 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m 2023-09-19T19:19:51.461Z,1695151191.461 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC 2023-09-19T19:19:51.469Z,1695151191.469 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2023-09-19T19:19:51.488Z,1695151191.488 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2023-09-19T19:19:51.503Z,1695151191.503 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m 2023-09-19T19:19:51.506Z,1695151191.506 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu 2023-09-19T19:19:51.518Z,1695151191.518 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m 2023-09-19T19:19:51.529Z,1695151191.529 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC 2023-09-19T19:19:51.559Z,1695151191.559 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg 2023-09-19T19:19:51.562Z,1695151191.562 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg 2023-09-19T19:19:51.578Z,1695151191.578 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l 2023-09-19T19:19:51.600Z,1695151191.600 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m 2023-09-19T19:19:51.603Z,1695151191.603 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2023-09-19T19:19:51.606Z,1695151191.606 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2023-09-19T19:19:51.613Z,1695151191.613 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2023-09-19T19:19:51.629Z,1695151191.629 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu 2023-09-19T19:19:51.636Z,1695151191.636 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m 2023-09-19T19:19:51.652Z,1695151191.652 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg 2023-09-19T19:19:51.655Z,1695151191.655 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg 2023-09-19T19:19:51.662Z,1695151191.662 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m 2023-09-19T19:19:51.674Z,1695151191.674 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu 2023-09-19T19:19:51.685Z,1695151191.685 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m 2023-09-19T19:19:51.701Z,1695151191.701 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg 2023-09-19T19:19:51.708Z,1695151191.708 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg 2023-09-19T19:19:51.728Z,1695151191.728 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m 2023-09-19T19:19:51.730Z,1695151191.730 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool 2023-09-19T19:19:51.746Z,1695151191.746 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l 2023-09-19T19:19:51.938Z,1695151191.938 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-09-19T19:19:51.991Z,1695151191.991 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-09-19T19:19:52.046Z,1695151192.046 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-09-19T19:19:52.105Z,1695151192.105 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-09-19T19:19:52.136Z,1695151192.136 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-09-19T19:19:52.180Z,1695151192.180 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-09-19T19:19:52.271Z,1695151192.271 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-09-19T19:19:52.313Z,1695151192.313 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-09-19T19:19:52.385Z,1695151192.385 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2023-09-19T19:19:52.407Z,1695151192.407 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2023-09-19T19:19:52.408Z,1695151192.408 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx 2023-09-19T19:19:52.449Z,1695151192.449 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool 2023-09-19T19:19:52.466Z,1695151192.466 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2023-09-19T19:19:52.477Z,1695151192.477 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2023-09-19T19:19:52.478Z,1695151192.478 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx 2023-09-19T19:19:52.873Z,1695151192.873 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2023-09-19T19:19:52.880Z,1695151192.880 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2023-09-19T19:19:52.896Z,1695151192.896 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2023-09-19T19:19:52.903Z,1695151192.903 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2023-09-19T19:19:52.906Z,1695151192.906 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2023-09-19T19:19:52.921Z,1695151192.921 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2023-09-19T19:19:52.926Z,1695151192.926 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2023-09-19T19:19:52.956Z,1695151192.956 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2023-09-19T19:19:52.959Z,1695151192.959 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2023-09-19T19:19:52.974Z,1695151192.974 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2023-09-19T19:19:52.994Z,1695151192.994 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2023-09-19T19:19:53.005Z,1695151193.005 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 % 2023-09-19T19:19:53.065Z,1695151193.065 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2023-09-19T19:19:53.088Z,1695151193.088 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2023-09-19T19:19:53.169Z,1695151193.169 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2023-09-19T19:19:53.207Z,1695151193.207 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2023-09-19T19:19:53.215Z,1695151193.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2023-09-19T19:19:53.241Z,1695151193.241 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2023-09-19T19:19:53.257Z,1695151193.257 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2023-09-19T19:19:53.260Z,1695151193.260 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2023-09-19T19:19:53.284Z,1695151193.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2023-09-19T19:19:53.336Z,1695151193.336 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2023-09-19T19:19:53.340Z,1695151193.340 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2023-09-19T19:19:53.363Z,1695151193.363 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2023-09-19T19:19:53.365Z,1695151193.365 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2023-09-19T19:19:53.387Z,1695151193.387 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2023-09-19T19:19:53.475Z,1695151193.475 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2023-09-19T19:19:53.477Z,1695151193.477 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface 2023-09-19T19:19:53.479Z,1695151193.479 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2023-09-19T19:19:53.501Z,1695151193.501 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2023-09-19T19:19:53.503Z,1695151193.503 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2023-09-19T19:19:53.504Z,1695151193.504 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2023-09-19T19:19:53.506Z,1695151193.506 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2023-09-19T19:19:53.528Z,1695151193.528 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2023-09-19T19:19:53.557Z,1695151193.557 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2023-09-19T19:19:53.600Z,1695151193.600 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait. 2023-09-19T19:19:53.678Z,1695151193.678 [MissionManager](DEBUG): mission ballast_and_trim { arguments { MissionTimeout = 120 minute """ Maximum duration of mission """ SkipComms = false """ Skip communications at start of mission. """ Depth1 = 7 meter Depth2 = NaN meter DepthDeadband = 0.1 meter """ How much vertical drift from the specified depth is allowed durnig the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth. """ MaxWaitOnSurface = 20 minute """ Maximum wait time for the vehicle to go under the surface (i.e., reach the surface threshold depth). """ SettleTime = 10 minute """ How long to wait after reaching target depth before starting ballast and trim. """ EstimationTimeout = 45 minute """ Timeout for ballast and trim estimation. """ MinEstimationTime = 10 minute """ Minimum time to run ballast and trim estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.25 millimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 15 cubic_centimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 99.9 percent """ Confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ WaitToFloat = 5 minute } timeout duration=MissionTimeout insert Insert/Science.tl insert Insert/BackseatDriver.tl aggregate RunBallastAndTrim { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = MaxWaitOnSurface redefineArg InsertSettleTime = SettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate Depth1 { run in sequence break if ( isNaN ( Depth1 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } aggregate Depth2 { run in sequence break if ( isNaN ( Depth2 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } } aggregate Float_Up { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < Control:VerticalControl.surfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } } 2023-09-19T19:19:53.679Z,1695151193.679 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-09-19T19:20:04.535Z,1695151204.535 [CommandExec](IMPORTANT): got command set ballast_and_trim.Depth1 5 meter 2023-09-19T19:20:04.536Z,1695151204.536 [CommandExec](IMPORTANT): got command set ballast_and_trim.SettleTime 120 minute 2023-09-19T19:20:04.537Z,1695151204.537 [CommandExec](IMPORTANT): got command set ballast_and_trim:BackseatDriver.EnableBackseat 1 bool 2023-09-19T19:20:04.537Z,1695151204.537 [CommandExec](IMPORTANT): got command run 2023-09-19T19:20:04.541Z,1695151204.541 [CommandExec](IMPORTANT): Running 2023-09-19T19:20:04.813Z,1695151204.813 [Default] Stopped 2023-09-19T19:20:04.813Z,1695151204.813 [Default](DEBUG): Aggregate::uninitialize Default 2023-09-19T19:20:04.813Z,1695151204.813 [Default:B.GoToSurface] Stopped 2023-09-19T19:20:04.813Z,1695151204.813 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-19T19:20:04.813Z,1695151204.813 [Default:CheckIn] Stopped 2023-09-19T19:20:04.813Z,1695151204.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-19T19:20:04.813Z,1695151204.813 [Default:CheckIn:C.Wait] Stopped 2023-09-19T19:20:04.813Z,1695151204.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:20:04.813Z,1695151204.813 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:A] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:B] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:C] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:D] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:E] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:F] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2023-09-19T19:20:04.814Z,1695151204.814 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2023-09-19T19:20:04.815Z,1695151204.815 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2023-09-19T19:20:04.815Z,1695151204.815 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-09-19T19:20:04.817Z,1695151204.817 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2023-09-19T19:20:04.817Z,1695151204.817 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2023-09-19T19:20:04.817Z,1695151204.817 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-09-19T19:20:04.817Z,1695151204.817 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-09-19T19:20:04.818Z,1695151204.818 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2023-09-19T19:20:04.818Z,1695151204.818 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2023-09-19T19:20:04.818Z,1695151204.818 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2023-09-19T19:20:04.818Z,1695151204.818 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-09-19T19:20:04.820Z,1695151204.820 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2023-09-19T19:20:04.820Z,1695151204.820 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2023-09-19T19:20:04.821Z,1695151204.821 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2023-09-19T19:20:04.821Z,1695151204.821 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-09-19T19:20:04.822Z,1695151204.822 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1 2023-09-19T19:20:04.823Z,1695151204.823 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport 2023-09-19T19:20:04.823Z,1695151204.823 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-09-19T19:20:04.823Z,1695151204.823 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-09-19T19:20:04.823Z,1695151204.823 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2023-09-19T19:20:04.823Z,1695151204.823 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2023-09-19T19:20:04.824Z,1695151204.824 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2023-09-19T19:20:04.824Z,1695151204.824 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-09-19T19:20:04.825Z,1695151204.825 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1 2023-09-19T19:20:04.825Z,1695151204.825 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity 2023-09-19T19:20:04.825Z,1695151204.825 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2023-09-19T19:20:04.825Z,1695151204.825 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-09-19T19:20:04.827Z,1695151204.827 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1 2023-09-19T19:20:04.827Z,1695151204.827 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport 2023-09-19T19:20:04.828Z,1695151204.828 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-09-19T19:20:04.828Z,1695151204.828 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-09-19T19:20:04.828Z,1695151204.828 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1 2023-09-19T19:20:04.828Z,1695151204.828 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:Science:OceanCurrent] Running Loop=1 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2023-09-19T19:20:04.829Z,1695151204.829 [ballast_and_trim:BackseatDriver] Running Loop=1 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-09-19T19:20:04.830Z,1695151204.830 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1 2023-09-19T19:20:04.831Z,1695151204.831 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped 2023-09-19T19:20:04.831Z,1695151204.831 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1 2023-09-19T19:20:04.831Z,1695151204.831 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-09-19T19:20:04.831Z,1695151204.831 [ballast_and_trim:BackseatDriver] Running Loop=1 2023-09-19T19:20:04.832Z,1695151204.832 [ballast_and_trim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2023-09-19T19:20:04.925Z,1695151204.925 [ballast_and_trim:Science] Running Loop=1 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:ChlThreshTrigger] Stopped 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:PeakDetectSalinity] Stopped 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity 2023-09-19T19:20:04.926Z,1695151204.926 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:HighestOilPeakReport] Stopped 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2023-09-19T19:20:04.927Z,1695151204.927 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectOil] Stopped 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-09-19T19:20:04.928Z,1695151204.928 [ballast_and_trim:Science:PeakDetectChl] Stopped 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:Read_Oil] Stopped 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:F] Stopped 2023-09-19T19:20:04.929Z,1695151204.929 [ballast_and_trim:Science:E] Running Loop=1 2023-09-19T19:20:04.930Z,1695151204.930 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2023-09-19T19:20:04.930Z,1695151204.930 [ballast_and_trim:Science:D] Stopped 2023-09-19T19:20:04.930Z,1695151204.930 [ballast_and_trim:Science:C] Running Loop=1 2023-09-19T19:20:04.931Z,1695151204.931 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2023-09-19T19:20:04.932Z,1695151204.932 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2023-09-19T19:20:04.932Z,1695151204.932 [ballast_and_trim:Science:B] Running Loop=1 2023-09-19T19:20:04.933Z,1695151204.933 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2023-09-19T19:20:04.933Z,1695151204.933 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2023-09-19T19:20:04.933Z,1695151204.933 [ballast_and_trim:Science:A] Stopped 2023-09-19T19:20:04.939Z,1695151204.939 [BackseatComponent](INFO): Powering up 2023-09-19T19:20:04.940Z,1695151204.940 [BackseatComponent](INFO): Subscribing to LCM channels. 2023-09-19T19:20:05.193Z,1695151205.193 [WetLabsBB2FL](INFO): Powering up 2023-09-19T19:20:05.209Z,1695151205.209 [Power24vConverter](INFO): Powering up. 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2023-09-19T19:20:05.221Z,1695151205.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2023-09-19T19:20:05.247Z,1695151205.247 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2023-09-19T19:20:05.248Z,1695151205.248 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-09-19T19:20:05.249Z,1695151205.249 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2023-09-19T19:20:05.249Z,1695151205.249 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2023-09-19T19:20:05.249Z,1695151205.249 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2023-09-19T19:20:05.249Z,1695151205.249 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-09-19T19:20:05.249Z,1695151205.249 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2023-09-19T19:20:05.249Z,1695151205.249 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2023-09-19T19:20:05.250Z,1695151205.250 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2023-09-19T19:20:05.250Z,1695151205.250 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2023-09-19T19:20:05.250Z,1695151205.250 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2023-09-19T19:20:05.250Z,1695151205.250 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2023-09-19T19:20:05.623Z,1695151205.623 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2023-09-19T19:20:05.623Z,1695151205.623 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2023-09-19T19:20:05.623Z,1695151205.623 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2023-09-19T19:20:05.623Z,1695151205.623 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2023-09-19T19:20:05.624Z,1695151205.624 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2023-09-19T19:20:06.031Z,1695151206.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2023-09-19T19:20:06.031Z,1695151206.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2023-09-19T19:20:06.031Z,1695151206.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:20:06.031Z,1695151206.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-09-19T19:20:06.031Z,1695151206.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2023-09-19T19:20:06.032Z,1695151206.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-09-19T19:20:06.032Z,1695151206.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2023-09-19T19:20:06.032Z,1695151206.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-09-19T19:20:06.032Z,1695151206.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2023-09-19T19:20:06.032Z,1695151206.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2023-09-19T19:20:06.032Z,1695151206.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2023-09-19T19:20:06.449Z,1695151206.449 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2023-09-19T19:20:06.450Z,1695151206.450 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 5.000000 m 2023-09-19T19:20:06.450Z,1695151206.450 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2023-09-19T19:20:06.450Z,1695151206.450 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2023-09-19T19:20:06.450Z,1695151206.450 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2023-09-19T19:20:08.025Z,1695151208.025 [CTD_Seabird](ERROR): Salinity reading out of range: 59.508701 psu 2023-09-19T19:20:08.027Z,1695151208.027 [CTD_Seabird](INFO): some bad data, not updating bins 2023-09-19T19:20:16.113Z,1695151216.113 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-19T19:21:00.198Z,1695151260.198 [BackseatComponent](IMPORTANT): ikshana: running backseat application. 2023-09-19T19:21:02.910Z,1695151262.910 [BackseatComponent](INFO): Created universal data reader for depth with code: 6 2023-09-19T19:21:02.910Z,1695151262.910 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1 2023-09-19T19:22:54.014Z,1695151374.014 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2023-09-19T19:22:54.014Z,1695151374.014 [CommandExec](IMPORTANT): platform_buoyancy_position 77.866730 cc 2023-09-19T19:31:00.569Z,1695151860.569 [ballast_and_trim:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.data_dir_logging_rate with code: 1979 2023-09-19T19:31:29.563Z,1695151889.563 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:31:31.572Z,1695151891.572 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251114 2023-09-19T19:32:04.704Z,1695151924.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:32:08.179Z,1695151928.179 [Radio_Surface](INFO): Powering down 2023-09-19T19:32:08.719Z,1695151928.719 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-09-19T19:32:12.865Z,1695151932.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2023-09-19T19:32:12.865Z,1695151932.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-09-19T19:32:12.865Z,1695151932.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-09-19T19:32:12.866Z,1695151932.866 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2023-09-19T19:32:12.867Z,1695151932.867 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2023-09-19T19:32:13.274Z,1695151933.274 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 5.035931 m 2023-09-19T19:32:13.274Z,1695151933.274 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2023-09-19T19:32:13.274Z,1695151933.274 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2023-09-19T19:32:13.274Z,1695151933.274 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2023-09-19T19:32:13.274Z,1695151933.274 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2023-09-19T19:32:13.274Z,1695151933.274 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2023-09-19T19:32:27.008Z,1695151947.008 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-09-19T19:32:27.008Z,1695151947.008 [DropWeight] Hardware Fault, FailCount= 1 2023-09-19T19:32:27.008Z,1695151947.008 [DropWeight](ERROR): Hardware Fault 2023-09-19T19:32:27.101Z,1695151947.101 [CBIT](INFO): Critical error at 20230919T193227 2023-09-19T19:32:27.101Z,1695151947.101 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-09-19T19:32:27.104Z,1695151947.104 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-09-19T19:32:27.104Z,1695151947.104 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-09-19T19:32:27.440Z,1695151947.440 [MissionManager](INFO): MissionManager is completed. 2023-09-19T19:32:27.440Z,1695151947.440 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science:B] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science:C] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science:E] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science:OceanCurrent] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:BackseatDriver] Stopped 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver 2023-09-19T19:32:27.441Z,1695151947.441 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2023-09-19T19:32:27.442Z,1695151947.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2023-09-19T19:32:27.443Z,1695151947.443 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2023-09-19T19:32:27.443Z,1695151947.443 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2023-09-19T19:32:27.443Z,1695151947.443 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:32:27.443Z,1695151947.443 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2023-09-19T19:32:27.447Z,1695151947.447 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-09-19T19:32:27.447Z,1695151947.447 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped 2023-09-19T19:32:27.447Z,1695151947.447 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-09-19T19:32:27.583Z,1695151947.583 [WetLabsBB2FL](INFO): Powering down 2023-09-19T19:32:27.662Z,1695151947.662 [CBIT](INFO): Critical error at 20230919T193227 2023-09-19T19:32:27.812Z,1695151947.812 [MissionManager](IMPORTANT): Started mission Default 2023-09-19T19:32:27.812Z,1695151947.812 [Default] Running Loop=1 2023-09-19T19:32:27.812Z,1695151947.812 [Default](DEBUG): Aggregate::initialize Default 2023-09-19T19:32:27.812Z,1695151947.812 [Default:B.GoToSurface] Running Loop=1 2023-09-19T19:32:27.812Z,1695151947.812 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-19T19:32:27.813Z,1695151947.813 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-19T19:32:27.813Z,1695151947.813 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-19T19:32:27.813Z,1695151947.813 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-19T19:32:27.814Z,1695151947.814 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-19T19:32:27.814Z,1695151947.814 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-19T19:32:27.814Z,1695151947.814 [Default:A.Wait] Running Loop=1 2023-09-19T19:32:27.814Z,1695151947.814 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-19T19:32:27.967Z,1695151947.967 [BackseatComponent](INFO): Sent LCM shutdown request. 2023-09-19T19:32:27.967Z,1695151947.967 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2023-09-19T19:32:28.217Z,1695151948.217 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -27.00 mm (1 active estimators). 2023-09-19T19:32:28.379Z,1695151948.379 [Radio_Surface](INFO): Powering up 2023-09-19T19:32:33.831Z,1695151953.831 [DataOverHttps](INFO): Radio surface powered ON. 2023-09-19T19:32:41.144Z,1695151961.144 [Default:A.Wait](INFO): Done Waiting. 2023-09-19T19:32:41.144Z,1695151961.144 [Default:A.Wait] Stopped 2023-09-19T19:32:41.145Z,1695151961.145 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-19T19:32:46.131Z,1695151966.131 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2023-09-19T19:32:46.132Z,1695151966.132 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2023-09-19T19:32:46.132Z,1695151966.132 [LcmUniversalReporter](INFO): Deactivating messaging. 2023-09-19T19:33:03.963Z,1695151983.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:33:39.115Z,1695152019.115 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:34:14.256Z,1695152054.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:34:49.395Z,1695152089.395 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:35:24.551Z,1695152124.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:35:53.873Z,1695152153.873 [Default:CheckIn] Running Loop=1 2023-09-19T19:35:53.873Z,1695152153.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-19T19:35:53.873Z,1695152153.873 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-19T19:35:59.691Z,1695152159.691 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:36:26.583Z,1695152186.583 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-19T19:36:26.663Z,1695152186.663 [NAL9602](ERROR): received: +CSQ:0 OK557, 0, 0, 0, 0 OK 2023-09-19T19:36:34.835Z,1695152194.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-19T19:36:47.428Z,1695152207.428 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002999 2023-09-19T19:37:19.764Z,1695152239.764 [CommandExec](IMPORTANT): got command configSet DropWeight.loadAtStartup 0 bool persist 2023-09-19T19:37:19.765Z,1695152239.765 [CommandExec](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect. 2023-09-19T19:37:19.770Z,1695152239.770 [CommandExec](IMPORTANT): got command restart application 2023-09-19T19:37:20.771Z,1695152240.771 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:20.771Z,1695152240.771 [CommandExec](INFO): Uninitializing the command executive. 2023-09-19T19:37:20.772Z,1695152240.772 [CommandExec](INFO): Uninitializing the command scheduler. 2023-09-19T19:37:20.772Z,1695152240.772 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:20.803Z,1695152240.803 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-09-19T19:37:20.803Z,1695152240.803 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-09-19T19:37:20.803Z,1695152240.803 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:20.804Z,1695152240.804 [NavChartDb](INFO): Join timeout helper Thread ID is 1178 2023-09-19T19:37:20.900Z,1695152240.900 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:20.900Z,1695152240.900 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:20.915Z,1695152240.915 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-09-19T19:37:20.915Z,1695152240.915 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:20.916Z,1695152240.916 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1179 2023-09-19T19:37:20.987Z,1695152240.987 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:20.988Z,1695152240.988 [WetLabsBB2FL](INFO): Powering down 2023-09-19T19:37:20.988Z,1695152240.988 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:20.995Z,1695152240.995 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-09-19T19:37:20.995Z,1695152240.995 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:20.996Z,1695152240.996 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1180 2023-09-19T19:37:21.279Z,1695152241.279 [CTD_Seabird](INFO): Powering down 2023-09-19T19:37:21.291Z,1695152241.291 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:21.291Z,1695152241.291 [CTD_Seabird](INFO): Powering down 2023-09-19T19:37:21.303Z,1695152241.303 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:21.307Z,1695152241.307 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-09-19T19:37:21.307Z,1695152241.307 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:21.308Z,1695152241.308 [Radio_Surface](INFO): Join timeout helper Thread ID is 1181 2023-09-19T19:37:21.687Z,1695152241.687 [Radio_Surface](INFO): Powering down 2023-09-19T19:37:21.688Z,1695152241.688 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:21.688Z,1695152241.688 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:21.703Z,1695152241.703 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-09-19T19:37:21.703Z,1695152241.703 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:21.704Z,1695152241.704 [Onboard](INFO): Join timeout helper Thread ID is 1182 2023-09-19T19:37:24.203Z,1695152244.203 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:24.203Z,1695152244.203 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.216Z,1695152244.216 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-09-19T19:37:24.216Z,1695152244.216 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.217Z,1695152244.217 [DataOverHttps](INFO): Join timeout helper Thread ID is 1183 2023-09-19T19:37:24.516Z,1695152244.516 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:24.519Z,1695152244.519 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.520Z,1695152244.520 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-09-19T19:37:24.520Z,1695152244.520 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.521Z,1695152244.521 [BackseatComponent](INFO): Join timeout helper Thread ID is 1184 2023-09-19T19:37:24.575Z,1695152244.575 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:24.576Z,1695152244.576 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.580Z,1695152244.580 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-09-19T19:37:24.580Z,1695152244.580 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.580Z,1695152244.580 [logger](INFO): Join timeout helper Thread ID is 1185 2023-09-19T19:37:24.618Z,1695152244.618 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:24.618Z,1695152244.618 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.628Z,1695152244.628 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-09-19T19:37:24.628Z,1695152244.628 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.628Z,1695152244.628 [CommandLine](INFO): Join timeout helper Thread ID is 1186 2023-09-19T19:37:24.679Z,1695152244.679 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:24.679Z,1695152244.679 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.688Z,1695152244.688 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-09-19T19:37:24.688Z,1695152244.688 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.688Z,1695152244.688 [CommandExec](INFO): Join timeout helper Thread ID is 1187 2023-09-19T19:37:24.689Z,1695152244.689 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-09-19T19:37:24.689Z,1695152244.689 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.690Z,1695152244.690 [controlThread](INFO): Join timeout helper Thread ID is 1188 2023-09-19T19:37:24.744Z,1695152244.744 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-19T19:37:24.745Z,1695152244.745 [controlThread](DEBUG): Uninitializing ControlThread 2023-09-19T19:37:24.746Z,1695152244.746 [AHRS_M2](INFO): Powering down 2023-09-19T19:37:24.816Z,1695152244.816 [MultiRay](INFO): Powering down. 2023-09-19T19:37:24.817Z,1695152244.817 [NAL9602](INFO): Powering down 2023-09-19T19:37:24.819Z,1695152244.819 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-09-19T19:37:24.820Z,1695152244.820 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-09-19T19:37:24.820Z,1695152244.820 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-09-19T19:37:24.821Z,1695152244.821 [MissionManager](INFO): Uninitializing Mission Default 2023-09-19T19:37:24.821Z,1695152244.821 [Default] Stopped 2023-09-19T19:37:24.821Z,1695152244.821 [Default](DEBUG): Aggregate::uninitialize Default 2023-09-19T19:37:24.821Z,1695152244.821 [Default:B.GoToSurface] Stopped 2023-09-19T19:37:24.821Z,1695152244.821 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-19T19:37:24.821Z,1695152244.821 [Default:CheckIn] Stopped 2023-09-19T19:37:24.821Z,1695152244.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-19T19:37:24.821Z,1695152244.821 [Default:CheckIn:Read_GPS] Stopped 2023-09-19T19:37:24.824Z,1695152244.824 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-09-19T19:37:24.825Z,1695152244.825 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-09-19T19:37:24.825Z,1695152244.825 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-09-19T19:37:24.825Z,1695152244.825 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-09-19T19:37:24.825Z,1695152244.825 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-09-19T19:37:24.826Z,1695152244.826 [BuoyancyServo](INFO): Powering down 2023-09-19T19:37:24.839Z,1695152244.839 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-09-19T19:37:24.839Z,1695152244.839 [ElevatorServo](INFO): Powering down 2023-09-19T19:37:24.840Z,1695152244.840 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-09-19T19:37:24.840Z,1695152244.840 [MassServo](INFO): Powering down 2023-09-19T19:37:24.841Z,1695152244.841 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-09-19T19:37:24.841Z,1695152244.841 [RudderServo](INFO): Powering down 2023-09-19T19:37:24.842Z,1695152244.842 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-09-19T19:37:24.842Z,1695152244.842 [ThrusterHE](INFO): Powering down 2023-09-19T19:37:24.843Z,1695152244.843 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-09-19T19:37:24.843Z,1695152244.843 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-09-19T19:37:24.844Z,1695152244.844 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-09-19T19:37:24.844Z,1695152244.844 [CBIT](DEBUG): Powering off loads. 2023-09-19T19:37:24.855Z,1695152244.855 [CBIT](DEBUG): Disabling WDT. 2023-09-19T19:37:24.867Z,1695152244.867 [CBIT](DEBUG): Opening all GF detection circuits. 2023-09-19T19:37:24.868Z,1695152244.868 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.908Z,1695152244.908 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.911Z,1695152244.911 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:24.918Z,1695152244.918 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:25.027Z,1695152245.027 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:25.076Z,1695152245.076 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:25.080Z,1695152245.080 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:25.130Z,1695152245.130 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-19T19:37:25.177Z,1695152245.177 [logger ThreadHandler](INFO): Thread cancelled.