2023-01-26T17:20:23.187Z,1674753623.187 [Supervisor](DEBUG): Initializing supervisor. 2023-01-26T17:20:23.192Z,1674753623.192 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-01-26T17:20:23.193Z,1674753623.193 [SyncHandler](INFO): Protected caller Thread ID is 1130 2023-01-26T17:20:23.193Z,1674753623.193 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-01-26T17:20:23.194Z,1674753623.194 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-01-26T17:20:23.195Z,1674753623.195 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1131 2023-01-26T17:20:23.199Z,1674753623.199 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-01-26T17:20:23.216Z,1674753623.216 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-01-26T17:20:23.217Z,1674753623.217 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-01-26T17:20:23.218Z,1674753623.218 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1132 2023-01-26T17:20:23.222Z,1674753623.222 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-01-26T17:20:23.223Z,1674753623.223 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-01-26T17:20:23.223Z,1674753623.223 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1133 2023-01-26T17:20:23.225Z,1674753623.225 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-01-26T17:20:23.226Z,1674753623.226 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-01-26T17:20:23.227Z,1674753623.227 [logger ThreadHandler](INFO): Protected caller Thread ID is 1134 2023-01-26T17:20:23.230Z,1674753623.230 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-01-26T17:20:23.231Z,1674753623.231 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-01-26T17:20:23.232Z,1674753623.232 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-01-26T17:20:23.439Z,1674753623.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-01-26T17:20:23.439Z,1674753623.439 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-01-26T17:20:23.543Z,1674753623.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-01-26T17:20:23.543Z,1674753623.543 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-01-26T17:20:23.636Z,1674753623.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-01-26T17:20:23.637Z,1674753623.637 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-01-26T17:20:24.074Z,1674753624.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-01-26T17:20:24.074Z,1674753624.074 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-01-26T17:20:24.406Z,1674753624.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-01-26T17:20:24.406Z,1674753624.406 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-01-26T17:20:24.725Z,1674753624.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-01-26T17:20:24.726Z,1674753624.726 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-01-26T17:20:24.902Z,1674753624.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-01-26T17:20:24.903Z,1674753624.903 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-01-26T17:20:24.981Z,1674753624.981 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-01-26T17:20:25.061Z,1674753625.061 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-01-26T17:20:25.438Z,1674753625.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-01-26T17:20:25.438Z,1674753625.438 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-01-26T17:20:25.718Z,1674753625.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-01-26T17:20:25.718Z,1674753625.718 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-01-26T17:20:26.386Z,1674753626.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-01-26T17:20:26.387Z,1674753626.387 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-01-26T17:20:26.593Z,1674753626.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-01-26T17:20:26.593Z,1674753626.593 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-01-26T17:20:26.824Z,1674753626.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-01-26T17:20:26.825Z,1674753626.825 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-01-26T17:20:26.959Z,1674753626.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-01-26T17:20:26.960Z,1674753626.960 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-01-26T17:20:27.696Z,1674753627.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-01-26T17:20:27.698Z,1674753627.698 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-01-26T17:20:27.699Z,1674753627.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-01-26T17:20:27.782Z,1674753627.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-01-26T17:20:27.954Z,1674753627.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-01-26T17:20:28.212Z,1674753628.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-01-26T17:20:28.213Z,1674753628.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-01-26T17:20:28.331Z,1674753628.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-01-26T17:20:28.429Z,1674753628.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-01-26T17:20:28.519Z,1674753628.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-01-26T17:20:28.628Z,1674753628.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-01-26T17:20:28.719Z,1674753628.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-01-26T17:20:28.803Z,1674753628.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-01-26T17:20:28.900Z,1674753628.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-01-26T17:20:29.054Z,1674753629.054 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-01-26T17:20:29.054Z,1674753629.054 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-01-26T17:20:29.064Z,1674753629.064 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-01-26T17:20:29.113Z,1674753629.113 [DepthRateCalculator] Loaded 2023-01-26T17:20:29.113Z,1674753629.113 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-01-26T17:20:29.119Z,1674753629.119 [PitchRateCalculator] Loaded 2023-01-26T17:20:29.119Z,1674753629.119 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-01-26T17:20:29.129Z,1674753629.129 [SpeedCalculator] Loaded 2023-01-26T17:20:29.129Z,1674753629.129 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-01-26T17:20:29.134Z,1674753629.134 [YawRateCalculator] Loaded 2023-01-26T17:20:29.134Z,1674753629.134 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-01-26T17:20:29.156Z,1674753629.156 [ElevatorOffsetCalculator] Loaded 2023-01-26T17:20:29.156Z,1674753629.156 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-01-26T17:20:29.156Z,1674753629.156 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-01-26T17:20:29.157Z,1674753629.157 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-01-26T17:20:29.182Z,1674753629.182 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-01-26T17:20:29.183Z,1674753629.183 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-01-26T17:20:29.193Z,1674753629.193 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-01-26T17:20:29.193Z,1674753629.193 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-01-26T17:20:29.617Z,1674753629.617 [AHRS_M2] Loaded 2023-01-26T17:20:29.617Z,1674753629.617 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-01-26T17:20:29.651Z,1674753629.651 [BackseatComponent] Loaded 2023-01-26T17:20:29.652Z,1674753629.652 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-01-26T17:20:29.653Z,1674753629.653 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4083F4E0 2023-01-26T17:20:29.653Z,1674753629.653 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1216 2023-01-26T17:20:29.656Z,1674753629.656 [LcmUniversalReporter] Loaded 2023-01-26T17:20:29.656Z,1674753629.656 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-01-26T17:20:30.446Z,1674753630.446 [BPC1] Loaded 2023-01-26T17:20:30.446Z,1674753630.446 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-01-26T17:20:30.525Z,1674753630.525 [DataOverHttps] Loaded 2023-01-26T17:20:30.525Z,1674753630.525 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-01-26T17:20:30.526Z,1674753630.526 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0 2023-01-26T17:20:30.526Z,1674753630.526 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1217 2023-01-26T17:20:30.546Z,1674753630.546 [Depth_Keller] Loaded 2023-01-26T17:20:30.546Z,1674753630.546 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-01-26T17:20:30.551Z,1674753630.551 [DropWeight] Loaded 2023-01-26T17:20:30.551Z,1674753630.551 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-01-26T17:20:30.613Z,1674753630.613 [NAL9602] Loaded 2023-01-26T17:20:30.613Z,1674753630.613 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-01-26T17:20:30.641Z,1674753630.641 [Onboard] Loaded 2023-01-26T17:20:30.641Z,1674753630.641 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-01-26T17:20:30.642Z,1674753630.642 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4089F4E0 2023-01-26T17:20:30.643Z,1674753630.643 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1218 2023-01-26T17:20:30.649Z,1674753630.649 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-01-26T17:20:30.659Z,1674753630.659 [PowerOnly] Loaded 2023-01-26T17:20:30.660Z,1674753630.660 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-01-26T17:20:30.671Z,1674753630.671 [Power24vConverter] Loaded 2023-01-26T17:20:30.671Z,1674753630.671 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-01-26T17:20:30.685Z,1674753630.685 [Radio_Surface] Loaded 2023-01-26T17:20:30.685Z,1674753630.685 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-01-26T17:20:30.686Z,1674753630.686 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CF4E0 2023-01-26T17:20:30.687Z,1674753630.687 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1219 2023-01-26T17:20:30.688Z,1674753630.688 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-01-26T17:20:30.688Z,1674753630.688 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-01-26T17:20:30.799Z,1674753630.799 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-01-26T17:20:30.799Z,1674753630.799 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-01-26T17:20:30.840Z,1674753630.840 [DeadReckonUsingSpeedCalculator] Loaded 2023-01-26T17:20:30.840Z,1674753630.840 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-01-26T17:20:30.854Z,1674753630.854 [NavChart] Loaded 2023-01-26T17:20:30.854Z,1674753630.854 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-01-26T17:20:30.860Z,1674753630.860 [UniversalFixResidualReporter] Loaded 2023-01-26T17:20:30.860Z,1674753630.860 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-01-26T17:20:30.860Z,1674753630.860 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-01-26T17:20:30.861Z,1674753630.861 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-01-26T17:20:31.152Z,1674753631.152 [SBIT](DEBUG): Construct Startup Built In Test. 2023-01-26T17:20:31.168Z,1674753631.168 [SBIT] Loaded 2023-01-26T17:20:31.169Z,1674753631.169 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-01-26T17:20:31.171Z,1674753631.171 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-01-26T17:20:31.184Z,1674753631.184 [IBIT] Loaded 2023-01-26T17:20:31.184Z,1674753631.184 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-01-26T17:20:31.190Z,1674753631.190 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-01-26T17:20:31.514Z,1674753631.514 [CBIT] Loaded 2023-01-26T17:20:31.514Z,1674753631.514 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-01-26T17:20:31.514Z,1674753631.514 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-01-26T17:20:31.515Z,1674753631.515 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-01-26T17:20:31.721Z,1674753631.721 [CTD_Seabird] Loaded 2023-01-26T17:20:31.721Z,1674753631.721 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-01-26T17:20:31.722Z,1674753631.722 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409D34E0 2023-01-26T17:20:31.722Z,1674753631.722 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1220 2023-01-26T17:20:31.742Z,1674753631.742 [PAR_Licor] Loaded 2023-01-26T17:20:31.743Z,1674753631.743 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-01-26T17:20:31.773Z,1674753631.773 [WetLabsBB2FL] Loaded 2023-01-26T17:20:31.773Z,1674753631.773 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-01-26T17:20:31.774Z,1674753631.774 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A034E0 2023-01-26T17:20:31.775Z,1674753631.775 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1221 2023-01-26T17:20:31.775Z,1674753631.775 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-01-26T17:20:31.776Z,1674753631.776 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-01-26T17:20:31.904Z,1674753631.904 [BuoyancyServo] Loaded 2023-01-26T17:20:31.904Z,1674753631.904 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-01-26T17:20:31.926Z,1674753631.926 [ElevatorServo] Loaded 2023-01-26T17:20:31.926Z,1674753631.926 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-01-26T17:20:31.946Z,1674753631.946 [MassServo] Loaded 2023-01-26T17:20:31.947Z,1674753631.947 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-01-26T17:20:31.967Z,1674753631.967 [RudderServo] Loaded 2023-01-26T17:20:31.967Z,1674753631.967 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-01-26T17:20:31.982Z,1674753631.982 [ThrusterHE] Loaded 2023-01-26T17:20:31.982Z,1674753631.982 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-01-26T17:20:31.983Z,1674753631.983 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-01-26T17:20:31.983Z,1674753631.983 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-01-26T17:20:32.032Z,1674753632.032 [VerticalControl](DEBUG): Construct VerticalControl. 2023-01-26T17:20:32.084Z,1674753632.084 [VerticalControl] Loaded 2023-01-26T17:20:32.084Z,1674753632.084 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-01-26T17:20:32.087Z,1674753632.087 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-01-26T17:20:32.124Z,1674753632.124 [HorizontalControl] Loaded 2023-01-26T17:20:32.124Z,1674753632.124 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-01-26T17:20:32.127Z,1674753632.127 [SpeedControl](DEBUG): Construct SpeedControl. 2023-01-26T17:20:32.128Z,1674753632.128 [SpeedControl] Loaded 2023-01-26T17:20:32.129Z,1674753632.129 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-01-26T17:20:32.132Z,1674753632.132 [LoopControl](DEBUG): Construct LoopControl. 2023-01-26T17:20:32.132Z,1674753632.132 [LoopControl] Loaded 2023-01-26T17:20:32.132Z,1674753632.132 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-01-26T17:20:32.133Z,1674753632.133 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-01-26T17:20:32.133Z,1674753632.133 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-01-26T17:20:32.229Z,1674753632.229 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-01-26T17:20:32.230Z,1674753632.230 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-01-26T17:20:32.374Z,1674753632.374 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-01-26T17:20:32.375Z,1674753632.375 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-01-26T17:20:32.673Z,1674753632.673 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-01-26T17:20:32.673Z,1674753632.673 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-01-26T17:20:32.727Z,1674753632.727 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-01-26T17:20:32.733Z,1674753632.733 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-01-26T17:20:32.736Z,1674753632.736 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-01-26T17:20:32.747Z,1674753632.747 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-01-26T17:20:32.748Z,1674753632.748 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA64E0 2023-01-26T17:20:32.749Z,1674753632.749 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1222 2023-01-26T17:20:32.753Z,1674753632.753 [Supervisor](INFO): Main Thread ID is 829 2023-01-26T17:20:32.753Z,1674753632.753 [Supervisor](DEBUG): Running supervisor. 2023-01-26T17:20:32.754Z,1674753632.754 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1223 2023-01-26T17:20:32.754Z,1674753632.754 [CommandExec](INFO): Initializing the command executive. 2023-01-26T17:20:32.756Z,1674753632.756 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1224 2023-01-26T17:20:32.758Z,1674753632.758 [controlThread ThreadHandler](INFO): Handler Thread ID is 1225 2023-01-26T17:20:32.758Z,1674753632.758 [controlThread](DEBUG): Initializing ControlThread 2023-01-26T17:20:32.759Z,1674753632.759 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-01-26T17:20:32.759Z,1674753632.759 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-01-26T17:20:32.760Z,1674753632.760 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-01-26T17:20:32.760Z,1674753632.760 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-01-26T17:20:32.760Z,1674753632.760 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-01-26T17:20:32.767Z,1674753632.767 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:20:32.767Z,1674753632.767 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-01-26T17:20:32.767Z,1674753632.767 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-01-26T17:20:32.768Z,1674753632.768 [SBIT](INFO): Initialize SBIT Component. 2023-01-26T17:20:32.768Z,1674753632.768 [SBIT](IMPORTANT): git: 2023-01-26 2023-01-26T17:20:32.768Z,1674753632.768 [SBIT](INFO): git hash: fbcd350f88251f9a867f3fca05699dec8f096e0d 2023-01-26T17:20:32.769Z,1674753632.769 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-01-26T17:20:32.770Z,1674753632.770 [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-01-26T17:20:32.771Z,1674753632.771 [SBIT](INFO): Beginning SBIT in 95.000000 seconds. 2023-01-26T17:20:32.771Z,1674753632.771 [IBIT](INFO): Initialize IBIT Component. 2023-01-26T17:20:32.773Z,1674753632.773 [CBIT](DEBUG): Initialize CBIT Component. 2023-01-26T17:20:32.774Z,1674753632.774 [logger ThreadHandler](INFO): Handler Thread ID is 1226 2023-01-26T17:20:32.784Z,1674753632.784 [CBIT](DEBUG): Initialized mux pins. 2023-01-26T17:20:32.784Z,1674753632.784 [CBIT](DEBUG): Initializing the watchdog timer. 2023-01-26T17:20:32.793Z,1674753632.793 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1227 2023-01-26T17:20:32.804Z,1674753632.805 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1228 2023-01-26T17:20:32.805Z,1674753632.805 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-01-26T17:20:32.808Z,1674753632.808 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-01-26T17:20:32.809Z,1674753632.809 [CBIT](DEBUG): Initializing heartbeat. 2023-01-26T17:20:32.817Z,1674753632.817 [Onboard ThreadHandler](INFO): Handler Thread ID is 1229 2023-01-26T17:20:32.834Z,1674753632.834 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1230 2023-01-26T17:20:32.857Z,1674753632.857 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1231 2023-01-26T17:20:32.858Z,1674753632.858 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-01-26T17:20:32.862Z,1674753632.862 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1233 2023-01-26T17:20:32.865Z,1674753632.865 [WetLabsBB2FL](INFO): Powering up 2023-01-26T17:20:32.866Z,1674753632.866 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1235 2023-01-26T17:20:32.868Z,1674753632.868 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-01-26T17:20:32.868Z,1674753632.868 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-01-26T17:20:32.869Z,1674753632.869 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-01-26T17:20:32.869Z,1674753632.869 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-01-26T17:20:32.869Z,1674753632.869 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-01-26T17:20:32.869Z,1674753632.869 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-01-26T17:20:32.869Z,1674753632.869 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-01-26T17:20:32.869Z,1674753632.869 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-01-26T17:20:32.880Z,1674753632.880 [CBIT](DEBUG): Deactivating GF circuits. 2023-01-26T17:20:32.880Z,1674753632.880 [CBIT](DEBUG): Deactivating emergency mode. 2023-01-26T17:20:32.916Z,1674753632.916 [CBIT](DEBUG): Backplane powered. 2023-01-26T17:20:32.921Z,1674753632.921 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-01-26T17:20:32.923Z,1674753632.923 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-01-26T17:20:32.924Z,1674753632.924 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-01-26T17:20:32.925Z,1674753632.925 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-01-26T17:20:32.925Z,1674753632.925 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-01-26T17:20:32.938Z,1674753632.938 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T17:20:32.962Z,1674753632.962 [MissionManager](DEBUG): 2023-01-26T17:20:32.963Z,1674753632.963 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-01-26T17:20:33.024Z,1674753633.024 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-01-26T17:20:33.026Z,1674753633.026 [Default:A.Wait](DEBUG): Construct Wait. 2023-01-26T17:20:33.040Z,1674753633.040 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T17:20:33.051Z,1674753633.051 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-01-26T17:20:33.066Z,1674753633.066 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-01-26T17:20:33.072Z,1674753633.072 [Default:E.Execute](DEBUG): Construct Execute. 2023-01-26T17:20:33.075Z,1674753633.075 [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-01-26T17:20:33.088Z,1674753633.088 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-01-26T17:20:33.108Z,1674753633.108 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-01-26T17:20:33.175Z,1674753633.175 [Power24vConverter](INFO): Powering up. 2023-01-26T17:20:33.214Z,1674753633.214 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-01-26T17:20:33.220Z,1674753633.220 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-01-26T17:20:33.221Z,1674753633.221 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-01-26T17:20:33.228Z,1674753633.228 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-01-26T17:20:33.229Z,1674753633.229 [MassServo](DEBUG): Initializing EZServoServo. 2023-01-26T17:20:33.236Z,1674753633.236 [MassServo](DEBUG): Initializing MassServo. 2023-01-26T17:20:33.237Z,1674753633.237 [RudderServo](DEBUG): Initializing EZServoServo. 2023-01-26T17:20:33.244Z,1674753633.244 [RudderServo](DEBUG): Initializing RudderServo. 2023-01-26T17:20:33.245Z,1674753633.245 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-01-26T17:20:33.248Z,1674753633.248 [Radio_Surface](INFO): Powering up 2023-01-26T17:20:33.252Z,1674753633.252 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-01-26T17:20:35.508Z,1674753635.508 [WetLabsBB2FL](INFO): Powering down 2023-01-26T17:20:38.997Z,1674753638.997 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-01-26T17:20:39.889Z,1674753639.889 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-01-26T17:21:00.978Z,1674753660.978 [NAL9602](INFO): Powering up NAL9602 2023-01-26T17:21:11.891Z,1674753671.891 [NAL9602](INFO): NAL9602 initialized 2023-01-26T17:22:08.492Z,1674753728.492 [SBIT](IMPORTANT): Beginning Startup BIT 2023-01-26T17:22:08.496Z,1674753728.496 [CBIT](IMPORTANT): Beginning ground fault scan 2023-01-26T17:22:19.409Z,1674753739.409 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003520 CHAN A1 (24V): 0.001090 CHAN A2 (12V): -0.005629 CHAN A3 (5V): -0.002443 CHAN B0 (3.3V): -0.001092 CHAN B1 (3.15aV): -0.001412 CHAN B2 (3.15bV): -0.002162 CHAN B3 (GND): -0.000347 OPEN: 0.004461 Full Scale: +/- 1 mA 2023-01-26T17:22:27.092Z,1674753747.092 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:22:41.227Z,1674753761.227 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:23:00.603Z,1674753780.603 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004788 2023-01-26T17:23:02.599Z,1674753782.599 [SBIT](IMPORTANT): SBIT PASSED 2023-01-26T17:23:02.599Z,1674753782.599 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-01-26T17:23:02.600Z,1674753782.600 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2023-01-26T17:23:02.600Z,1674753782.600 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-01-26T17:23:02.600Z,1674753782.600 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-01-26T17:23:02.601Z,1674753782.601 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-01-26T17:23:02.601Z,1674753782.601 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second; 2023-01-26T17:23:02.601Z,1674753782.601 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter; 2023-01-26T17:23:02.601Z,1674753782.601 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=274.275554 cubic_centimeter; 2023-01-26T17:23:02.601Z,1674753782.601 [SBIT](IMPORTANT): VerticalControl.massDefault=18.860602 millimeter; 2023-01-26T17:23:02.601Z,1674753782.601 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool; 2023-01-26T17:23:02.000Z,1674753783.000 [MissionManager](IMPORTANT): Started mission Startup 2023-01-26T17:23:03.000Z,1674753783.000 [Startup] Running Loop=1 2023-01-26T17:23:03.000Z,1674753783.000 [Startup](DEBUG): Aggregate::initialize Startup 2023-01-26T17:23:03.000Z,1674753783.000 [Startup:A.GoToSurface] Running Loop=1 2023-01-26T17:23:03.000Z,1674753783.000 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T17:23:03.001Z,1674753783.001 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T17:23:03.002Z,1674753783.002 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T17:23:03.002Z,1674753783.002 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T17:23:03.002Z,1674753783.002 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T17:23:03.003Z,1674753783.003 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T17:23:03.004Z,1674753783.004 [Startup:StartupSatComms] Running Loop=1 2023-01-26T17:23:03.004Z,1674753783.004 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-01-26T17:23:03.005Z,1674753783.005 [Startup:StartupSatComms:A] Running Loop=1 2023-01-26T17:23:03.415Z,1674753783.415 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-01-26T17:23:10.581Z,1674753790.581 [CommandExec](IMPORTANT): got command failComponent 2023-01-26T17:23:10.581Z,1674753790.581 [CommandExec](IMPORTANT): Failed components: 2023-01-26T17:23:10.581Z,1674753790.581 [CommandExec](IMPORTANT): No failed Components. 2023-01-26T17:23:32.904Z,1674753812.904 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-01-26T17:23:32.904Z,1674753812.904 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:23:32.905Z,1674753812.905 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-01-26T17:23:32.905Z,1674753812.905 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:23:32.955Z,1674753812.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:23:32.955Z,1674753812.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:23:33.310Z,1674753813.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:23:33.310Z,1674753813.310 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-01-26T17:23:33.310Z,1674753813.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:23:33.310Z,1674753813.310 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-01-26T17:23:33.716Z,1674753813.716 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:23:48.692Z,1674753828.692 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:24:03.194Z,1674753843.194 [Startup:StartupSatComms:A](INFO): Timed out from 2023-01-26T17:23:03.0Z 2023-01-26T17:24:03.194Z,1674753843.194 [Startup:StartupSatComms:A] Stopped 2023-01-26T17:24:03.194Z,1674753843.194 [Startup:StartupSatComms:B] Running Loop=1 2023-01-26T17:24:03.594Z,1674753843.594 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-01-26T17:24:15.008Z,1674753855.008 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:24:17.403Z,1674753857.403 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:25:03.390Z,1674753903.390 [Startup:StartupSatComms:B](INFO): Timed out from 2023-01-26T17:24:03.2Z 2023-01-26T17:25:03.390Z,1674753903.390 [Startup:StartupSatComms:B] Stopped 2023-01-26T17:25:03.391Z,1674753903.391 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-01-26T17:25:03.391Z,1674753903.391 [Startup:StartupSatComms] Stopped 2023-01-26T17:25:03.391Z,1674753903.391 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-01-26T17:25:03.391Z,1674753903.391 [Startup](INFO): Completed Startup 2023-01-26T17:25:03.392Z,1674753903.392 [MissionManager](INFO): Startup is completed. 2023-01-26T17:25:03.392Z,1674753903.392 [MissionManager](INFO): Uninitializing Mission Startup 2023-01-26T17:25:03.392Z,1674753903.392 [Startup] Stopped 2023-01-26T17:25:03.392Z,1674753903.392 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-01-26T17:25:03.392Z,1674753903.392 [Startup:A.GoToSurface] Stopped 2023-01-26T17:25:03.392Z,1674753903.392 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T17:25:03.801Z,1674753903.801 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T17:25:03.801Z,1674753903.801 [Default] Running Loop=1 2023-01-26T17:25:03.802Z,1674753903.802 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T17:25:03.802Z,1674753903.802 [Default:B.GoToSurface] Running Loop=1 2023-01-26T17:25:03.802Z,1674753903.802 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T17:25:03.802Z,1674753903.802 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T17:25:03.802Z,1674753903.802 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T17:25:03.803Z,1674753903.803 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T17:25:03.803Z,1674753903.803 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T17:25:03.803Z,1674753903.803 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T17:25:03.803Z,1674753903.803 [Default:A.Wait] Running Loop=1 2023-01-26T17:25:03.803Z,1674753903.803 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T17:25:09.496Z,1674753909.496 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:25:17.139Z,1674753917.139 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T17:25:17.139Z,1674753917.139 [Default:A.Wait] Stopped 2023-01-26T17:25:17.139Z,1674753917.139 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T17:25:17.530Z,1674753917.530 [Default:CheckIn] Running Loop=1 2023-01-26T17:25:17.530Z,1674753917.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T17:25:17.530Z,1674753917.530 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T17:25:17.935Z,1674753917.935 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-01-26T17:25:43.423Z,1674753943.423 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:26:15.294Z,1674753975.294 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-26T17:26:33.913Z,1674753993.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-01-26T17:26:33.913Z,1674753993.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:26:33.914Z,1674753993.914 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-01-26T17:26:33.914Z,1674753993.914 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:26:33.925Z,1674753993.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:26:33.925Z,1674753993.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:26:34.331Z,1674753994.331 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:26:34.331Z,1674753994.331 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-01-26T17:26:34.340Z,1674753994.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:26:34.340Z,1674753994.340 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-01-26T17:26:34.694Z,1674753994.694 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:27:26.098Z,1674754046.098 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:27:53.115Z,1674754073.115 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:28:20.937Z,1674754100.937 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-01-26T17:28:20.940Z,1674754100.940 [BPC1](INFO): Received data from all battery sticks. 2023-01-26T17:29:34.876Z,1674754174.876 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-01-26T17:29:34.876Z,1674754174.876 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:29:34.877Z,1674754174.877 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-01-26T17:29:34.877Z,1674754174.877 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:29:34.888Z,1674754174.888 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:29:34.888Z,1674754174.888 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:29:35.290Z,1674754175.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:29:35.290Z,1674754175.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-01-26T17:29:35.291Z,1674754175.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:29:35.291Z,1674754175.291 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-01-26T17:29:35.687Z,1674754175.687 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:30:17.710Z,1674754217.710 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-01-26T17:25:17.5Z 2023-01-26T17:30:17.710Z,1674754217.710 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T17:30:17.710Z,1674754217.710 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T17:30:18.103Z,1674754218.103 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-01-26T17:30:33.078Z,1674754233.078 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T17:30:34.262Z,1674754234.262 [Power24vConverter](INFO): Powering down. 2023-01-26T17:31:13.478Z,1674754273.478 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-01-26T17:31:13.478Z,1674754273.478 [NAL9602] Data Fault, FailCount= 1 2023-01-26T17:31:13.478Z,1674754273.478 [NAL9602](ERROR): Data Fault 2023-01-26T17:31:13.516Z,1674754273.516 [CBIT](ERROR): Data Fault in component: NAL9602 2023-01-26T17:31:13.878Z,1674754273.878 [NAL9602](INFO): Powering down 2023-01-26T17:31:14.716Z,1674754274.716 [CBIT](INFO): Clearing failed state for component NAL9602 2023-01-26T17:31:14.716Z,1674754274.716 [NAL9602] No Fault, FailCount= 1 2023-01-26T17:31:44.182Z,1674754304.182 [NAL9602](INFO): Powering up NAL9602 2023-01-26T17:31:55.094Z,1674754315.094 [NAL9602](INFO): NAL9602 initialized 2023-01-26T17:32:35.903Z,1674754355.903 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-01-26T17:32:35.904Z,1674754355.904 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:32:35.905Z,1674754355.905 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-01-26T17:32:35.905Z,1674754355.905 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:32:35.944Z,1674754355.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:32:35.944Z,1674754355.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:32:36.317Z,1674754356.317 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:32:36.317Z,1674754356.317 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-01-26T17:32:36.318Z,1674754356.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:32:36.318Z,1674754356.318 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-01-26T17:32:36.713Z,1674754356.713 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:35:36.944Z,1674754536.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-01-26T17:35:36.945Z,1674754536.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:35:36.946Z,1674754536.946 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-01-26T17:35:36.946Z,1674754536.946 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:35:36.962Z,1674754536.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:35:36.962Z,1674754536.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:35:37.379Z,1674754537.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:35:37.379Z,1674754537.379 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-01-26T17:35:37.379Z,1674754537.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:35:37.379Z,1674754537.379 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-01-26T17:35:37.761Z,1674754537.761 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:36:15.333Z,1674754575.333 [CommandExec](IMPORTANT): got command burn on 2023-01-26T17:36:15.333Z,1674754575.333 [CommandExec](IMPORTANT): Activating dropweight wire 2023-01-26T17:36:43.853Z,1674754603.853 [CommandExec](IMPORTANT): got command burn off 2023-01-26T17:36:43.853Z,1674754603.853 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-01-26T17:37:35.737Z,1674754655.737 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230126T015010/Courier0265.lzma 2023-01-26T17:37:36.738Z,1674754656.738 [DataOverHttps](INFO): Moved sent file to Logs/20230126T015010/Courier0265.lzma.bak 2023-01-26T17:37:36.738Z,1674754656.738 [DataOverHttps](INFO): SBD MOMSN=17405164 2023-01-26T17:37:52.089Z,1674754672.089 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230126T165614/Courier0000.lzma 2023-01-26T17:37:53.090Z,1674754673.090 [DataOverHttps](INFO): Moved sent file to Logs/20230126T165614/Courier0000.lzma.bak 2023-01-26T17:37:53.090Z,1674754673.090 [DataOverHttps](INFO): SBD MOMSN=17405166 2023-01-26T17:38:08.477Z,1674754688.477 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230126T165614/Courier0004.lzma 2023-01-26T17:38:09.478Z,1674754689.478 [DataOverHttps](INFO): Moved sent file to Logs/20230126T165614/Courier0004.lzma.bak 2023-01-26T17:38:09.478Z,1674754689.478 [DataOverHttps](INFO): SBD MOMSN=17405169 2023-01-26T17:38:24.895Z,1674754704.895 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230126T165614/Courier0007.lzma 2023-01-26T17:38:25.894Z,1674754705.894 [DataOverHttps](INFO): Moved sent file to Logs/20230126T165614/Courier0007.lzma.bak 2023-01-26T17:38:25.894Z,1674754705.894 [DataOverHttps](INFO): SBD MOMSN=17405171 2023-01-26T17:38:37.964Z,1674754717.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-01-26T17:38:37.964Z,1674754717.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:38:37.965Z,1674754717.965 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2023-01-26T17:38:37.965Z,1674754717.965 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:38:38.028Z,1674754718.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:38:38.028Z,1674754718.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:38:38.418Z,1674754718.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:38:38.418Z,1674754718.418 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-01-26T17:38:38.418Z,1674754718.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:38:38.418Z,1674754718.418 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2023-01-26T17:38:38.776Z,1674754718.776 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:38:41.336Z,1674754721.336 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230126T171457/Courier0000.lzma 2023-01-26T17:38:42.338Z,1674754722.338 [DataOverHttps](INFO): Moved sent file to Logs/20230126T171457/Courier0000.lzma.bak 2023-01-26T17:38:42.338Z,1674754722.338 [DataOverHttps](INFO): SBD MOMSN=17405173 2023-01-26T17:38:57.644Z,1674754737.644 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230126T171841/Courier0000.lzma 2023-01-26T17:38:58.646Z,1674754738.646 [DataOverHttps](INFO): Moved sent file to Logs/20230126T171841/Courier0000.lzma.bak 2023-01-26T17:38:58.646Z,1674754738.646 [DataOverHttps](INFO): SBD MOMSN=17405176 2023-01-26T17:39:13.506Z,1674754753.506 [CommandExec](IMPORTANT): got command burn off 2023-01-26T17:39:13.506Z,1674754753.506 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-01-26T17:39:14.324Z,1674754754.324 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230126T172023/Courier0000.lzma 2023-01-26T17:39:15.326Z,1674754755.326 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0000.lzma.bak 2023-01-26T17:39:15.326Z,1674754755.326 [DataOverHttps](INFO): SBD MOMSN=17405179 2023-01-26T17:39:30.648Z,1674754770.648 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230126T172023/Courier0004.lzma 2023-01-26T17:39:31.650Z,1674754771.650 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0004.lzma.bak 2023-01-26T17:39:31.650Z,1674754771.650 [DataOverHttps](INFO): SBD MOMSN=17405182 2023-01-26T17:39:47.481Z,1674754787.481 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20230126T015010/Express0266.lzma 2023-01-26T17:39:48.482Z,1674754788.482 [DataOverHttps](INFO): Moved sent file to Logs/20230126T015010/Express0266.lzma.bak 2023-01-26T17:39:48.482Z,1674754788.482 [DataOverHttps](INFO): SBD MOMSN=17405184 2023-01-26T17:40:04.015Z,1674754804.015 [DataOverHttps](INFO): Sending 1199 bytes from file Logs/20230126T165614/Express0001.lzma 2023-01-26T17:40:05.014Z,1674754805.014 [DataOverHttps](INFO): Moved sent file to Logs/20230126T165614/Express0001.lzma.bak 2023-01-26T17:40:05.014Z,1674754805.014 [DataOverHttps](INFO): SBD MOMSN=17405188 2023-01-26T17:40:20.717Z,1674754820.717 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20230126T165614/Express0005.lzma 2023-01-26T17:40:21.718Z,1674754821.718 [DataOverHttps](INFO): Moved sent file to Logs/20230126T165614/Express0005.lzma.bak 2023-01-26T17:40:21.718Z,1674754821.718 [DataOverHttps](INFO): SBD MOMSN=17405232 2023-01-26T17:40:33.158Z,1674754833.158 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T17:40:37.011Z,1674754837.011 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230126T165614/Express0008.lzma 2023-01-26T17:40:38.014Z,1674754838.014 [DataOverHttps](INFO): Moved sent file to Logs/20230126T165614/Express0008.lzma.bak 2023-01-26T17:40:38.014Z,1674754838.014 [DataOverHttps](INFO): SBD MOMSN=17405245 2023-01-26T17:40:53.366Z,1674754853.366 [DataOverHttps](INFO): Sending 1132 bytes from file Logs/20230126T171457/Express0001.lzma 2023-01-26T17:40:54.366Z,1674754854.366 [DataOverHttps](INFO): Moved sent file to Logs/20230126T171457/Express0001.lzma.bak 2023-01-26T17:40:54.366Z,1674754854.366 [DataOverHttps](INFO): SBD MOMSN=17405247 2023-01-26T17:41:10.111Z,1674754870.111 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230126T171457/Express0005.lzma 2023-01-26T17:41:11.114Z,1674754871.114 [DataOverHttps](INFO): Moved sent file to Logs/20230126T171457/Express0005.lzma.bak 2023-01-26T17:41:11.114Z,1674754871.114 [DataOverHttps](INFO): SBD MOMSN=17405287 2023-01-26T17:41:26.488Z,1674754886.488 [DataOverHttps](INFO): Sending 456 bytes from file Logs/20230126T171841/Express0001.lzma 2023-01-26T17:41:27.492Z,1674754887.492 [DataOverHttps](INFO): Moved sent file to Logs/20230126T171841/Express0001.lzma.bak 2023-01-26T17:41:27.492Z,1674754887.492 [DataOverHttps](INFO): SBD MOMSN=17405289 2023-01-26T17:41:38.996Z,1674754898.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-01-26T17:41:38.996Z,1674754898.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:41:38.998Z,1674754898.998 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2023-01-26T17:41:38.998Z,1674754898.998 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:41:39.087Z,1674754899.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:41:39.087Z,1674754899.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:41:39.435Z,1674754899.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:41:39.435Z,1674754899.435 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-01-26T17:41:39.435Z,1674754899.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:41:39.435Z,1674754899.435 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2023-01-26T17:41:39.800Z,1674754899.800 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:41:43.205Z,1674754903.205 [DataOverHttps](INFO): Sending 878 bytes from file Logs/20230126T172023/Express0001.lzma 2023-01-26T17:41:44.206Z,1674754904.206 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0001.lzma.bak 2023-01-26T17:41:44.206Z,1674754904.206 [DataOverHttps](INFO): SBD MOMSN=17405300 2023-01-26T17:41:56.342Z,1674754916.342 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-01-26T17:41:56.342Z,1674754916.342 [NAL9602] Data Fault, FailCount= 2 2023-01-26T17:41:56.342Z,1674754916.342 [NAL9602](ERROR): Data Fault 2023-01-26T17:41:56.428Z,1674754916.428 [CBIT](ERROR): Data Fault in component: NAL9602 2023-01-26T17:41:56.745Z,1674754916.745 [NAL9602](INFO): Powering down 2023-01-26T17:41:57.599Z,1674754917.599 [CBIT](INFO): Clearing failed state for component NAL9602 2023-01-26T17:41:57.599Z,1674754917.599 [NAL9602] No Fault, FailCount= 2 2023-01-26T17:41:59.604Z,1674754919.604 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230126T172023/Express0005.lzma 2023-01-26T17:42:00.606Z,1674754920.606 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0005.lzma.bak 2023-01-26T17:42:00.606Z,1674754920.606 [DataOverHttps](INFO): SBD MOMSN=17405323 2023-01-26T17:42:02.007Z,1674754922.007 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T17:42:02.007Z,1674754922.007 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T17:42:02.007Z,1674754922.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T17:42:27.066Z,1674754947.066 [NAL9602](INFO): Powering up NAL9602 2023-01-26T17:42:37.970Z,1674754957.970 [NAL9602](INFO): NAL9602 initialized 2023-01-26T17:44:39.991Z,1674755079.991 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-01-26T17:44:39.992Z,1674755079.992 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:44:39.993Z,1674755079.993 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2023-01-26T17:44:39.993Z,1674755079.993 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:44:40.003Z,1674755080.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:44:40.003Z,1674755080.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:44:40.427Z,1674755080.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:44:40.427Z,1674755080.427 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-01-26T17:44:40.427Z,1674755080.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:44:40.427Z,1674755080.427 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2023-01-26T17:44:40.817Z,1674755080.817 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:47:02.606Z,1674755222.606 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T17:47:02.606Z,1674755222.606 [Default:CheckIn:C.Wait] Stopped 2023-01-26T17:47:02.606Z,1674755222.606 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T17:47:02.606Z,1674755222.606 [Default:CheckIn:D] Running Loop=1 2023-01-26T17:47:03.011Z,1674755223.011 [Default:CheckIn:D] Stopped 2023-01-26T17:47:03.011Z,1674755223.011 [Default:CheckIn:E] Running Loop=1 2023-01-26T17:47:03.415Z,1674755223.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.986820 min 2023-01-26T17:47:03.415Z,1674755223.415 [Default:CheckIn:E] Stopped 2023-01-26T17:47:03.415Z,1674755223.415 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T17:47:03.415Z,1674755223.415 [Default:CheckIn] Stopped 2023-01-26T17:47:03.415Z,1674755223.415 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T17:47:03.415Z,1674755223.415 [Default:CheckIn](INFO): Running loop #2 2023-01-26T17:47:03.416Z,1674755223.416 [Default:CheckIn] Running Loop=2 2023-01-26T17:47:03.416Z,1674755223.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T17:47:03.416Z,1674755223.416 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T17:47:40.988Z,1674755260.988 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-01-26T17:47:40.988Z,1674755260.988 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:47:40.989Z,1674755260.989 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2023-01-26T17:47:40.989Z,1674755260.989 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:47:41.016Z,1674755261.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:47:41.016Z,1674755261.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:47:41.415Z,1674755261.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:47:41.415Z,1674755261.415 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-01-26T17:47:41.415Z,1674755261.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:47:41.415Z,1674755261.415 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2023-01-26T17:47:41.813Z,1674755261.813 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:50:33.538Z,1674755433.538 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T17:50:42.012Z,1674755442.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-01-26T17:50:42.012Z,1674755442.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:50:42.013Z,1674755442.013 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2023-01-26T17:50:42.013Z,1674755442.013 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:50:42.024Z,1674755442.024 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:50:42.024Z,1674755442.024 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:50:42.433Z,1674755442.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:50:42.433Z,1674755442.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-01-26T17:50:42.434Z,1674755442.434 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:50:42.434Z,1674755442.434 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2023-01-26T17:50:42.820Z,1674755442.820 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:52:03.622Z,1674755523.622 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-01-26T17:47:03.4Z 2023-01-26T17:52:03.622Z,1674755523.622 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T17:52:03.622Z,1674755523.622 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T17:52:09.815Z,1674755529.815 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230126T172023/Courier0007.lzma 2023-01-26T17:52:10.818Z,1674755530.818 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0007.lzma.bak 2023-01-26T17:52:10.818Z,1674755530.818 [DataOverHttps](INFO): SBD MOMSN=17405327 2023-01-26T17:52:26.272Z,1674755546.272 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20230126T172023/Express0008.lzma 2023-01-26T17:52:27.274Z,1674755547.274 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0008.lzma.bak 2023-01-26T17:52:27.274Z,1674755547.274 [DataOverHttps](INFO): SBD MOMSN=17405329 2023-01-26T17:52:28.695Z,1674755548.695 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T17:52:28.695Z,1674755548.695 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T17:52:28.695Z,1674755548.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T17:52:39.571Z,1674755559.571 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-01-26T17:52:39.571Z,1674755559.571 [NAL9602] Data Fault, FailCount= 3 2023-01-26T17:52:39.571Z,1674755559.571 [NAL9602](ERROR): Data Fault 2023-01-26T17:52:39.588Z,1674755559.588 [CBIT](ERROR): Data Fault in component: NAL9602 2023-01-26T17:52:39.966Z,1674755559.966 [NAL9602](INFO): Powering down 2023-01-26T17:52:40.798Z,1674755560.798 [CBIT](INFO): Clearing failed state for component NAL9602 2023-01-26T17:52:40.798Z,1674755560.798 [NAL9602] No Fault, FailCount= 3 2023-01-26T17:53:10.267Z,1674755590.267 [NAL9602](INFO): Powering up NAL9602 2023-01-26T17:53:21.182Z,1674755601.182 [NAL9602](INFO): NAL9602 initialized 2023-01-26T17:53:43.032Z,1674755623.032 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-01-26T17:53:43.032Z,1674755623.032 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:53:43.034Z,1674755623.034 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2023-01-26T17:53:43.034Z,1674755623.034 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:53:43.089Z,1674755623.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:53:43.089Z,1674755623.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:53:43.455Z,1674755623.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:53:43.455Z,1674755623.455 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-01-26T17:53:43.455Z,1674755623.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:53:43.455Z,1674755623.455 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2023-01-26T17:53:43.808Z,1674755623.808 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:56:44.021Z,1674755804.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-01-26T17:56:44.021Z,1674755804.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:56:44.022Z,1674755804.022 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2023-01-26T17:56:44.022Z,1674755804.022 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:56:44.034Z,1674755804.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:56:44.034Z,1674755804.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:56:44.430Z,1674755804.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:56:44.430Z,1674755804.430 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-01-26T17:56:44.431Z,1674755804.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:56:44.431Z,1674755804.431 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2023-01-26T17:56:44.797Z,1674755804.797 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:57:29.260Z,1674755849.260 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T17:57:29.260Z,1674755849.260 [Default:CheckIn:C.Wait] Stopped 2023-01-26T17:57:29.260Z,1674755849.260 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T17:57:29.260Z,1674755849.260 [Default:CheckIn:D] Running Loop=1 2023-01-26T17:57:29.672Z,1674755849.672 [Default:CheckIn:D] Stopped 2023-01-26T17:57:29.672Z,1674755849.672 [Default:CheckIn:E] Running Loop=1 2023-01-26T17:57:30.052Z,1674755850.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.431167 min 2023-01-26T17:57:30.052Z,1674755850.052 [Default:CheckIn:E] Stopped 2023-01-26T17:57:30.052Z,1674755850.052 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T17:57:30.052Z,1674755850.052 [Default:CheckIn] Stopped 2023-01-26T17:57:30.052Z,1674755850.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T17:57:30.052Z,1674755850.052 [Default:CheckIn](INFO): Running loop #3 2023-01-26T17:57:30.052Z,1674755850.053 [Default:CheckIn] Running Loop=3 2023-01-26T17:57:30.053Z,1674755850.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T17:57:30.053Z,1674755850.053 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T17:59:45.021Z,1674755985.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-01-26T17:59:45.021Z,1674755985.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:59:45.022Z,1674755985.022 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2023-01-26T17:59:45.022Z,1674755985.022 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:59:45.077Z,1674755985.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:59:45.077Z,1674755985.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:59:45.440Z,1674755985.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:59:45.444Z,1674755985.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-01-26T17:59:45.444Z,1674755985.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:59:45.445Z,1674755985.445 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2023-01-26T17:59:45.816Z,1674755985.816 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T18:00:33.924Z,1674756033.924 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T18:00:58.939Z,1674756058.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180057.00,A,3648.17601,N,12147.28142,W,0.117,0.00,260123,,,A*70 2023-01-26T18:00:58.943Z,1674756058.943 [NAL9602](INFO): GPS fix at 20230126T180057: (36.802934, -121.788024) 2023-01-26T18:00:58.957Z,1674756058.957 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:00:58.957Z,1674756058.957 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:01:06.308Z,1674756066.308 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230126T172023/Courier0010.lzma 2023-01-26T18:01:07.310Z,1674756067.310 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0010.lzma.bak 2023-01-26T18:01:07.310Z,1674756067.310 [DataOverHttps](INFO): SBD MOMSN=17405343 2023-01-26T18:01:22.920Z,1674756082.920 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20230126T172023/Express0011.lzma 2023-01-26T18:01:23.922Z,1674756083.922 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0011.lzma.bak 2023-01-26T18:01:23.922Z,1674756083.922 [DataOverHttps](INFO): SBD MOMSN=17405346 2023-01-26T18:01:25.613Z,1674756085.613 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:01:25.613Z,1674756085.613 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:01:25.613Z,1674756085.613 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:01:31.255Z,1674756091.255 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:01:51.365Z,1674756111.365 [CommandExec](IMPORTANT): got command ibit 2023-01-26T18:01:51.483Z,1674756111.483 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-01-26T18:01:51.483Z,1674756111.483 [IBIT](IMPORTANT): Beginning control surface checks. 2023-01-26T18:01:51.487Z,1674756111.487 [CBIT](IMPORTANT): Beginning ground fault scan 2023-01-26T18:01:53.084Z,1674756113.084 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180151.00,A,3648.17554,N,12147.27949,W,0.097,0.00,260123,,,A*71 2023-01-26T18:01:53.086Z,1674756113.086 [NAL9602](INFO): GPS fix at 20230126T180151: (36.802926, -121.787992) 2023-01-26T18:01:54.421Z,1674756114.421 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-01-26T18:02:02.390Z,1674756122.390 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006668 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005123 CHAN A3 (5V): -0.003452 CHAN B0 (3.3V): -0.001302 CHAN B1 (3.15aV): -0.002061 CHAN B2 (3.15bV): -0.000881 CHAN B3 (GND): -0.000454 OPEN: 0.004158 Full Scale: +/- 1 mA 2023-01-26T18:02:38.025Z,1674756158.025 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802925 Longitude: -121.787994 2023-01-26T18:02:38.323Z,1674756158.323 [NAL9602](INFO): SBD MO Status=2, MOMSN=11067, MT Status=2, MTMSN=0 2023-01-26T18:02:38.323Z,1674756158.323 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-26T18:02:38.402Z,1674756158.402 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 15.660000 2023-01-26T18:02:38.402Z,1674756158.402 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2023-01-26T18:02:38.402Z,1674756158.402 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2023-01-26T18:02:38.791Z,1674756158.791 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-01-26T18:02:38.791Z,1674756158.791 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-01-26T18:02:38.791Z,1674756158.791 [IBIT](IMPORTANT): Pressure:9.190597 PSI 2023-01-26T18:02:38.792Z,1674756158.792 [IBIT](IMPORTANT): Humidity:15.608595 % 2023-01-26T18:02:39.167Z,1674756159.167 [IBIT](IMPORTANT): Vehicle Pitch:-0.540703 degrees 2023-01-26T18:02:39.167Z,1674756159.167 [IBIT](IMPORTANT): Vehicle Roll:-2.896957 degrees 2023-01-26T18:02:39.168Z,1674756159.168 [IBIT](IMPORTANT): Vehicle Heading:72.973724 degrees 2023-01-26T18:02:39.589Z,1674756159.589 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-01-26T18:02:39.589Z,1674756159.589 [IBIT](IMPORTANT): buoyancyNeutral: 274.275543 cc 2023-01-26T18:02:39.589Z,1674756159.589 [IBIT](IMPORTANT): massDefault: 1.886060 cm 2023-01-26T18:02:39.590Z,1674756159.590 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2023-01-26T18:02:39.590Z,1674756159.590 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2023-01-26T18:02:39.590Z,1674756159.590 [IBIT](IMPORTANT): IBIT PASSED 2023-01-26T18:03:01.351Z,1674756181.351 [NAL9602](INFO): SBD MO Status=0, MOMSN=11067, MT Status=0, MTMSN=0 2023-01-26T18:03:01.351Z,1674756181.351 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:03:32.053Z,1674756212.053 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:06:26.246Z,1674756386.246 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T18:06:26.246Z,1674756386.246 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:06:26.246Z,1674756386.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:06:26.246Z,1674756386.246 [Default:CheckIn:D] Running Loop=1 2023-01-26T18:06:26.636Z,1674756386.636 [Default:CheckIn:D] Stopped 2023-01-26T18:06:26.636Z,1674756386.636 [Default:CheckIn:E] Running Loop=1 2023-01-26T18:06:27.039Z,1674756387.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.380570 min 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn:E] Stopped 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn] Stopped 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn](INFO): Running loop #4 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn] Running Loop=4 2023-01-26T18:06:27.040Z,1674756387.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:06:27.041Z,1674756387.041 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:06:29.063Z,1674756389.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180628.00,A,3648.17186,N,12147.27815,W,0.117,0.00,260123,,,A*72 2023-01-26T18:06:29.065Z,1674756389.065 [NAL9602](INFO): GPS fix at 20230126T180628: (36.802864, -121.787969) 2023-01-26T18:06:29.077Z,1674756389.077 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:06:29.077Z,1674756389.077 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:06:36.199Z,1674756396.199 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230126T172023/Courier0013.lzma 2023-01-26T18:06:37.202Z,1674756397.202 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0013.lzma.bak 2023-01-26T18:06:37.202Z,1674756397.202 [DataOverHttps](INFO): SBD MOMSN=17405352 2023-01-26T18:06:46.422Z,1674756406.422 [NAL9602](INFO): SBD MO Status=0, MOMSN=11068, MT Status=0, MTMSN=0 2023-01-26T18:06:46.422Z,1674756406.422 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:06:52.825Z,1674756412.825 [DataOverHttps](INFO): Sending 776 bytes from file Logs/20230126T172023/Express0014.lzma 2023-01-26T18:06:53.826Z,1674756413.826 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0014.lzma.bak 2023-01-26T18:06:53.826Z,1674756413.826 [DataOverHttps](INFO): SBD MOMSN=17405355 2023-01-26T18:06:55.320Z,1674756415.320 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:06:55.320Z,1674756415.320 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:06:55.320Z,1674756415.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:07:17.129Z,1674756437.129 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:10:34.298Z,1674756634.298 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T18:11:10.182Z,1674756670.182 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T181109 2023-01-26T18:11:17.476Z,1674756677.476 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b3uf 1 2 2023-01-26T18:11:17.497Z,1674756677.497 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b3uf 1 2.000000 2023-01-26T18:11:17.498Z,1674756677.498 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4b3uf 2023-01-26T18:11:17.499Z,1674756677.499 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='4b3uf'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m", ASAP 2023-01-26T18:11:18.478Z,1674756678.478 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T181117 2023-01-26T18:11:25.756Z,1674756685.756 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b3uf 2 2 2023-01-26T18:11:25.815Z,1674756685.815 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b3uf 2 2.000000 2023-01-26T18:11:25.815Z,1674756685.815 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4b3uf 2023-01-26T18:11:25.816Z,1674756685.816 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='4b3uf'): "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run", ASAP 2023-01-26T18:11:25.930Z,1674756685.930 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2023-01-26T18:11:25.930Z,1674756685.930 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2023-01-26T18:11:25.930Z,1674756685.930 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx 2023-01-26T18:11:26.034Z,1674756686.034 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2023-01-26T18:11:26.049Z,1674756686.049 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2023-01-26T18:11:26.051Z,1674756686.051 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2023-01-26T18:11:26.058Z,1674756686.058 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2023-01-26T18:11:26.061Z,1674756686.061 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2023-01-26T18:11:26.064Z,1674756686.064 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2023-01-26T18:11:26.066Z,1674756686.066 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2023-01-26T18:11:26.093Z,1674756686.093 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2023-01-26T18:11:26.096Z,1674756686.096 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2023-01-26T18:11:26.099Z,1674756686.099 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2023-01-26T18:11:26.101Z,1674756686.101 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2023-01-26T18:11:26.104Z,1674756686.104 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2023-01-26T18:11:26.118Z,1674756686.118 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2023-01-26T18:11:26.119Z,1674756686.119 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2023-01-26T18:11:26.202Z,1674756686.202 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2023-01-26T18:11:26.213Z,1674756686.213 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2023-01-26T18:11:26.220Z,1674756686.220 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2023-01-26T18:11:26.223Z,1674756686.223 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2023-01-26T18:11:26.234Z,1674756686.234 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2023-01-26T18:11:26.238Z,1674756686.238 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2023-01-26T18:11:26.249Z,1674756686.249 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2023-01-26T18:11:26.256Z,1674756686.256 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2023-01-26T18:11:26.267Z,1674756686.267 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T18:11:26.306Z,1674756686.306 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2023-01-26T18:11:26.347Z,1674756686.347 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2023-01-26T18:11:26.364Z,1674756686.364 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2023-01-26T18:11:26.367Z,1674756686.367 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2023-01-26T18:11:26.368Z,1674756686.368 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2023-01-26T18:11:26.435Z,1674756686.435 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2023-01-26T18:11:26.457Z,1674756686.457 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2023-01-26T18:11:26.480Z,1674756686.480 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2023-01-26T18:11:26.483Z,1674756686.483 [transit:MassHold.Pitch](DEBUG): Construct. 2023-01-26T18:11:26.503Z,1674756686.503 [transit:Transit:A.Pitch](DEBUG): Construct. 2023-01-26T18:11:26.513Z,1674756686.513 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2023-01-26T18:11:26.520Z,1674756686.520 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2023-01-26T18:11:26.577Z,1674756686.577 [MissionManager](DEBUG): mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.xml assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.xml assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2023-01-26T18:11:26.577Z,1674756686.577 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl 2023-01-26T18:11:34.538Z,1674756694.538 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute 2023-01-26T18:11:34.539Z,1674756694.539 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute 2023-01-26T18:11:34.540Z,1674756694.540 [CommandExec](IMPORTANT): got command set transit.Latitude 36.80125 degree 2023-01-26T18:11:34.541Z,1674756694.541 [CommandExec](IMPORTANT): got command set transit.Longitude -121.79578 degree 2023-01-26T18:11:34.541Z,1674756694.541 [CommandExec](IMPORTANT): got command set transit.Depth 0.5 meter 2023-01-26T18:11:34.542Z,1674756694.542 [CommandExec](IMPORTANT): got command set transit.MaxDepth 1 meter 2023-01-26T18:11:37.383Z,1674756697.383 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer 2023-01-26T18:11:37.384Z,1674756697.384 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter 2023-01-26T18:11:37.384Z,1674756697.384 [CommandExec](IMPORTANT): got command run 2023-01-26T18:11:37.386Z,1674756697.386 [CommandExec](IMPORTANT): Running 2023-01-26T18:11:37.745Z,1674756697.745 [Default] Stopped 2023-01-26T18:11:37.745Z,1674756697.745 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T18:11:37.745Z,1674756697.745 [Default:B.GoToSurface] Stopped 2023-01-26T18:11:37.745Z,1674756697.745 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T18:11:37.745Z,1674756697.745 [Default:CheckIn] Stopped 2023-01-26T18:11:37.745Z,1674756697.745 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:11:37.745Z,1674756697.745 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:11:37.745Z,1674756697.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:11:37.746Z,1674756697.746 [MissionManager](IMPORTANT): Started mission transit 2023-01-26T18:11:37.746Z,1674756697.746 [transit] Running Loop=1 2023-01-26T18:11:37.746Z,1674756697.746 [transit](DEBUG): Aggregate::initialize transit 2023-01-26T18:11:37.746Z,1674756697.746 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:11:37.746Z,1674756697.746 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2023-01-26T18:11:37.746Z,1674756697.746 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:11:37.746Z,1674756697.746 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2023-01-26T18:11:37.747Z,1674756697.747 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:11:37.747Z,1674756697.747 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2023-01-26T18:11:37.747Z,1674756697.747 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:11:37.747Z,1674756697.747 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2023-01-26T18:11:37.747Z,1674756697.747 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:11:37.747Z,1674756697.747 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-01-26T18:11:37.747Z,1674756697.747 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:11:37.747Z,1674756697.747 [transit:MassHold.Pitch](DEBUG): Initialize. 2023-01-26T18:11:37.748Z,1674756697.748 [transit:B] Running Loop=1 2023-01-26T18:11:37.748Z,1674756697.748 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:11:37.749Z,1674756697.749 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:11:37.749Z,1674756697.749 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:11:37.749Z,1674756697.749 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:11:37.749Z,1674756697.749 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:11:37.750Z,1674756697.750 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:11:37.751Z,1674756697.751 [transit:B] Stopped 2023-01-26T18:11:37.751Z,1674756697.751 [transit:C] Running Loop=1 2023-01-26T18:11:38.132Z,1674756698.132 [transit:C] Stopped 2023-01-26T18:11:38.132Z,1674756698.132 [transit:E] Running Loop=1 2023-01-26T18:11:38.529Z,1674756698.529 [transit:E] Stopped 2023-01-26T18:11:38.529Z,1674756698.529 [transit:F] Running Loop=1 2023-01-26T18:11:38.945Z,1674756698.945 [transit:F] Stopped 2023-01-26T18:11:38.945Z,1674756698.945 [transit:G] Running Loop=1 2023-01-26T18:11:39.336Z,1674756699.336 [transit:G] Stopped 2023-01-26T18:11:39.336Z,1674756699.336 [transit:Transit] Running Loop=1 2023-01-26T18:11:39.336Z,1674756699.336 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2023-01-26T18:11:39.336Z,1674756699.336 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:11:39.336Z,1674756699.336 [transit:Transit:A.Pitch](DEBUG): Initialize. 2023-01-26T18:11:39.336Z,1674756699.336 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:11:39.336Z,1674756699.336 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2023-01-26T18:11:39.337Z,1674756699.337 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2023-01-26T18:11:39.337Z,1674756699.337 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2023-01-26T18:11:39.338Z,1674756699.338 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.801250,-121.795776 2023-01-26T18:11:39.339Z,1674756699.339 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2023-01-26T18:11:39.747Z,1674756699.747 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:11:39.747Z,1674756699.747 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:13:13.986Z,1674756793.986 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T181313 2023-01-26T18:13:21.688Z,1674756801.688 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 5 m;set transit.MaxDepth 10 m;set transit.MinOffshore 0.1 km;run 2023-01-26T18:13:21.763Z,1674756801.763 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2023-01-26T18:13:21.764Z,1674756801.764 [MissionManager](INFO): Uninitializing Mission transit 2023-01-26T18:13:21.764Z,1674756801.764 [transit] Stopped 2023-01-26T18:13:21.764Z,1674756801.764 [transit](DEBUG): Aggregate::uninitialize transit 2023-01-26T18:13:21.764Z,1674756801.764 [transit:StandardEnvelopes] Stopped 2023-01-26T18:13:21.764Z,1674756801.764 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2023-01-26T18:13:21.764Z,1674756801.764 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2023-01-26T18:13:21.764Z,1674756801.764 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2023-01-26T18:13:21.765Z,1674756801.765 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2023-01-26T18:13:21.765Z,1674756801.765 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2023-01-26T18:13:21.765Z,1674756801.765 [transit:BuoyancyHold.Buoyancy] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-01-26T18:13:21.765Z,1674756801.765 [transit:MassHold.Pitch] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit:A.Pitch] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit:B.SetSpeed] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit:Wpt1.Waypoint] Stopped 2023-01-26T18:13:21.765Z,1674756801.765 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2023-01-26T18:13:21.776Z,1674756801.776 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2023-01-26T18:13:21.777Z,1674756801.777 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx 2023-01-26T18:13:21.892Z,1674756801.892 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2023-01-26T18:13:21.894Z,1674756801.894 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2023-01-26T18:13:21.896Z,1674756801.896 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2023-01-26T18:13:21.898Z,1674756801.898 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2023-01-26T18:13:21.899Z,1674756801.899 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2023-01-26T18:13:21.902Z,1674756801.902 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2023-01-26T18:13:21.927Z,1674756801.927 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2023-01-26T18:13:21.929Z,1674756801.929 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2023-01-26T18:13:21.931Z,1674756801.931 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2023-01-26T18:13:21.934Z,1674756801.934 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2023-01-26T18:13:21.936Z,1674756801.936 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2023-01-26T18:13:21.982Z,1674756801.982 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2023-01-26T18:13:21.984Z,1674756801.984 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2023-01-26T18:13:21.984Z,1674756801.984 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2023-01-26T18:13:22.114Z,1674756802.114 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2023-01-26T18:13:22.120Z,1674756802.120 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2023-01-26T18:13:22.122Z,1674756802.122 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2023-01-26T18:13:22.132Z,1674756802.132 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2023-01-26T18:13:22.138Z,1674756802.138 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2023-01-26T18:13:22.145Z,1674756802.145 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2023-01-26T18:13:22.147Z,1674756802.147 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2023-01-26T18:13:22.149Z,1674756802.149 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2023-01-26T18:13:22.167Z,1674756802.167 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T18:13:22.205Z,1674756802.205 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2023-01-26T18:13:22.243Z,1674756802.243 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2023-01-26T18:13:22.250Z,1674756802.250 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2023-01-26T18:13:22.257Z,1674756802.257 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2023-01-26T18:13:22.257Z,1674756802.257 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2023-01-26T18:13:22.267Z,1674756802.267 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2023-01-26T18:13:22.281Z,1674756802.281 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2023-01-26T18:13:22.290Z,1674756802.290 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2023-01-26T18:13:22.293Z,1674756802.293 [transit:MassHold.Pitch](DEBUG): Construct. 2023-01-26T18:13:22.298Z,1674756802.298 [transit:Transit:A.Pitch](DEBUG): Construct. 2023-01-26T18:13:22.302Z,1674756802.302 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2023-01-26T18:13:22.304Z,1674756802.304 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2023-01-26T18:13:22.317Z,1674756802.317 [MissionManager](DEBUG): mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.xml assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.xml assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2023-01-26T18:13:22.317Z,1674756802.317 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl 2023-01-26T18:13:22.403Z,1674756802.403 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T18:13:22.403Z,1674756802.403 [Default] Running Loop=1 2023-01-26T18:13:22.403Z,1674756802.403 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T18:13:22.403Z,1674756802.403 [Default:B.GoToSurface] Running Loop=1 2023-01-26T18:13:22.403Z,1674756802.403 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T18:13:22.404Z,1674756802.404 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T18:13:22.404Z,1674756802.404 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T18:13:22.404Z,1674756802.404 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T18:13:22.405Z,1674756802.405 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T18:13:22.405Z,1674756802.405 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T18:13:22.405Z,1674756802.405 [Default:A.Wait] Running Loop=1 2023-01-26T18:13:22.405Z,1674756802.405 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:13:28.788Z,1674756808.788 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree 2023-01-26T18:13:28.789Z,1674756808.789 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree 2023-01-26T18:13:28.789Z,1674756808.789 [CommandExec](IMPORTANT): got command set transit.Depth 5 meter 2023-01-26T18:13:28.790Z,1674756808.790 [CommandExec](IMPORTANT): got command set transit.MaxDepth 10 meter 2023-01-26T18:13:28.791Z,1674756808.791 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.1 kilometer 2023-01-26T18:13:28.791Z,1674756808.791 [CommandExec](IMPORTANT): got command run 2023-01-26T18:13:28.861Z,1674756808.861 [CommandExec](IMPORTANT): Running 2023-01-26T18:13:28.867Z,1674756808.867 [Default] Stopped 2023-01-26T18:13:28.867Z,1674756808.867 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T18:13:28.867Z,1674756808.867 [Default:A.Wait] Stopped 2023-01-26T18:13:28.867Z,1674756808.867 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:13:28.867Z,1674756808.867 [Default:B.GoToSurface] Stopped 2023-01-26T18:13:28.867Z,1674756808.867 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T18:13:28.867Z,1674756808.867 [MissionManager](IMPORTANT): Started mission transit 2023-01-26T18:13:28.868Z,1674756808.868 [transit] Running Loop=1 2023-01-26T18:13:28.876Z,1674756808.876 [transit](DEBUG): Aggregate::initialize transit 2023-01-26T18:13:28.876Z,1674756808.876 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:13:28.876Z,1674756808.876 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2023-01-26T18:13:28.876Z,1674756808.876 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:13:28.877Z,1674756808.877 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2023-01-26T18:13:28.877Z,1674756808.877 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:13:28.877Z,1674756808.877 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2023-01-26T18:13:28.877Z,1674756808.877 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:13:28.877Z,1674756808.877 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2023-01-26T18:13:28.877Z,1674756808.877 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:13:28.877Z,1674756808.877 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-01-26T18:13:28.877Z,1674756808.877 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:13:28.877Z,1674756808.877 [transit:MassHold.Pitch](DEBUG): Initialize. 2023-01-26T18:13:28.878Z,1674756808.878 [transit:B] Running Loop=1 2023-01-26T18:13:28.878Z,1674756808.878 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:13:28.878Z,1674756808.878 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:13:28.879Z,1674756808.879 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:13:28.879Z,1674756808.879 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:13:28.879Z,1674756808.879 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:13:28.879Z,1674756808.879 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:13:28.888Z,1674756808.888 [transit:B] Stopped 2023-01-26T18:13:28.888Z,1674756808.888 [transit:C] Running Loop=1 2023-01-26T18:13:29.300Z,1674756809.300 [transit:C] Stopped 2023-01-26T18:13:29.300Z,1674756809.300 [transit:E] Running Loop=1 2023-01-26T18:13:29.694Z,1674756809.694 [transit:E] Stopped 2023-01-26T18:13:29.694Z,1674756809.694 [transit:F] Running Loop=1 2023-01-26T18:13:30.053Z,1674756810.053 [transit:F] Stopped 2023-01-26T18:13:30.053Z,1674756810.053 [transit:G] Running Loop=1 2023-01-26T18:13:30.484Z,1674756810.484 [transit:G] Stopped 2023-01-26T18:13:30.484Z,1674756810.484 [transit:Transit] Running Loop=1 2023-01-26T18:13:30.484Z,1674756810.484 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2023-01-26T18:13:30.484Z,1674756810.484 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:13:30.484Z,1674756810.484 [transit:Transit:A.Pitch](DEBUG): Initialize. 2023-01-26T18:13:30.484Z,1674756810.484 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:13:30.484Z,1674756810.484 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2023-01-26T18:13:30.485Z,1674756810.485 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2023-01-26T18:13:30.485Z,1674756810.485 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2023-01-26T18:13:30.486Z,1674756810.486 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2023-01-26T18:13:30.487Z,1674756810.487 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2023-01-26T18:13:30.863Z,1674756810.863 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:13:30.863Z,1674756810.863 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:13:35.970Z,1674756815.970 [CommandExec](IMPORTANT): got command stop 2023-01-26T18:13:35.971Z,1674756815.971 [CommandExec](IMPORTANT): Scheduling is paused 2023-01-26T18:13:35.971Z,1674756815.971 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-01-26T18:13:36.127Z,1674756816.127 [MissionManager](INFO): MissionManager is completed. 2023-01-26T18:13:36.128Z,1674756816.128 [MissionManager](INFO): Uninitializing Mission transit 2023-01-26T18:13:36.128Z,1674756816.128 [transit] Stopped 2023-01-26T18:13:36.128Z,1674756816.128 [transit](DEBUG): Aggregate::uninitialize transit 2023-01-26T18:13:36.128Z,1674756816.128 [transit:StandardEnvelopes] Stopped 2023-01-26T18:13:36.128Z,1674756816.128 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2023-01-26T18:13:36.128Z,1674756816.128 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2023-01-26T18:13:36.128Z,1674756816.128 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2023-01-26T18:13:36.128Z,1674756816.128 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2023-01-26T18:13:36.128Z,1674756816.128 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2023-01-26T18:13:36.129Z,1674756816.129 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2023-01-26T18:13:36.129Z,1674756816.129 [transit:BuoyancyHold.Buoyancy] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-01-26T18:13:36.129Z,1674756816.129 [transit:MassHold.Pitch] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit:A.Pitch] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit:B.SetSpeed] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit:Wpt1.Waypoint] Stopped 2023-01-26T18:13:36.129Z,1674756816.129 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2023-01-26T18:13:36.519Z,1674756816.519 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T18:13:36.519Z,1674756816.519 [Default] Running Loop=1 2023-01-26T18:13:36.519Z,1674756816.519 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T18:13:36.519Z,1674756816.519 [Default:B.GoToSurface] Running Loop=1 2023-01-26T18:13:36.519Z,1674756816.519 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T18:13:36.520Z,1674756816.520 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T18:13:36.544Z,1674756816.544 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T18:13:36.544Z,1674756816.544 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T18:13:36.545Z,1674756816.545 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T18:13:36.545Z,1674756816.545 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T18:13:36.545Z,1674756816.545 [Default:A.Wait] Running Loop=1 2023-01-26T18:13:36.545Z,1674756816.545 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:13:49.877Z,1674756829.877 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T18:13:49.877Z,1674756829.877 [Default:A.Wait] Stopped 2023-01-26T18:13:49.877Z,1674756829.877 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:13:50.288Z,1674756830.288 [Default:CheckIn] Running Loop=1 2023-01-26T18:13:50.288Z,1674756830.288 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:13:50.288Z,1674756830.288 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:13:52.283Z,1674756832.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181351.00,A,3648.17540,N,12147.27644,W,0.117,0.00,260123,,,A*7C 2023-01-26T18:13:52.285Z,1674756832.285 [NAL9602](INFO): GPS fix at 20230126T181351: (36.802923, -121.787941) 2023-01-26T18:13:52.341Z,1674756832.341 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:13:52.341Z,1674756832.341 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:13:57.920Z,1674756837.920 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230126T172023/Courier0016.lzma 2023-01-26T18:13:58.922Z,1674756838.922 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0016.lzma.bak 2023-01-26T18:13:58.922Z,1674756838.922 [DataOverHttps](INFO): SBD MOMSN=17405386 2023-01-26T18:14:14.901Z,1674756854.901 [DataOverHttps](INFO): Sending 846 bytes from file Logs/20230126T172023/Express0017.lzma 2023-01-26T18:14:15.902Z,1674756855.902 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0017.lzma.bak 2023-01-26T18:14:15.902Z,1674756855.902 [DataOverHttps](INFO): SBD MOMSN=17405389 2023-01-26T18:14:17.341Z,1674756857.341 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:14:17.341Z,1674756857.341 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:14:17.342Z,1674756857.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:14:18.145Z,1674756858.145 [NAL9602](INFO): SBD MO Status=0, MOMSN=11069, MT Status=0, MTMSN=0 2023-01-26T18:14:18.145Z,1674756858.145 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:14:48.845Z,1674756888.845 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:19:17.945Z,1674757157.945 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T18:19:17.945Z,1674757157.945 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:19:17.945Z,1674757157.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:19:17.945Z,1674757157.945 [Default:CheckIn:D] Running Loop=1 2023-01-26T18:19:18.361Z,1674757158.361 [Default:CheckIn:D] Stopped 2023-01-26T18:19:18.361Z,1674757158.361 [Default:CheckIn:E] Running Loop=1 2023-01-26T18:19:18.750Z,1674757158.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.697358 min 2023-01-26T18:19:18.750Z,1674757158.750 [Default:CheckIn:E] Stopped 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn] Stopped 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn](INFO): Running loop #2 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn] Running Loop=2 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:19:18.751Z,1674757158.751 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:19:20.767Z,1674757160.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181920.00,A,3648.33402,N,12147.22816,W,1.030,343.89,260123,,,D*78 2023-01-26T18:19:20.769Z,1674757160.769 [NAL9602](INFO): GPS fix at 20230126T181920: (36.805567, -121.787136) 2023-01-26T18:19:20.781Z,1674757160.781 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:19:20.781Z,1674757160.781 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:19:28.027Z,1674757168.027 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230126T172023/Courier0019.lzma 2023-01-26T18:19:29.183Z,1674757169.183 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0019.lzma.bak 2023-01-26T18:19:29.183Z,1674757169.183 [DataOverHttps](INFO): SBD MOMSN=17405433 2023-01-26T18:19:45.800Z,1674757185.800 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230126T172023/Express0021.lzma 2023-01-26T18:19:46.227Z,1674757186.227 [NAL9602](INFO): SBD MO Status=0, MOMSN=11070, MT Status=0, MTMSN=0 2023-01-26T18:19:46.227Z,1674757186.227 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:19:46.802Z,1674757186.802 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0021.lzma.bak 2023-01-26T18:19:46.802Z,1674757186.802 [DataOverHttps](INFO): SBD MOMSN=17405437 2023-01-26T18:19:48.673Z,1674757188.673 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:19:48.673Z,1674757188.673 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:19:48.673Z,1674757188.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:20:16.951Z,1674757216.951 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:20:34.357Z,1674757234.357 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T18:24:49.264Z,1674757489.264 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T18:24:49.264Z,1674757489.264 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:24:49.264Z,1674757489.264 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:24:49.264Z,1674757489.264 [Default:CheckIn:D] Running Loop=1 2023-01-26T18:24:49.663Z,1674757489.663 [Default:CheckIn:D] Stopped 2023-01-26T18:24:49.663Z,1674757489.663 [Default:CheckIn:E] Running Loop=1 2023-01-26T18:24:50.073Z,1674757490.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.219059 min 2023-01-26T18:24:50.073Z,1674757490.073 [Default:CheckIn:E] Stopped 2023-01-26T18:24:50.073Z,1674757490.073 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T18:24:50.073Z,1674757490.073 [Default:CheckIn] Stopped 2023-01-26T18:24:50.073Z,1674757490.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:24:50.074Z,1674757490.074 [Default:CheckIn](INFO): Running loop #3 2023-01-26T18:24:50.074Z,1674757490.074 [Default:CheckIn] Running Loop=3 2023-01-26T18:24:50.074Z,1674757490.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:24:50.074Z,1674757490.074 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:24:52.083Z,1674757492.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182451.00,A,3648.35797,N,12147.31783,W,0.019,199.49,260123,,,D*7B 2023-01-26T18:24:52.098Z,1674757492.098 [NAL9602](INFO): GPS fix at 20230126T182451: (36.805966, -121.788630) 2023-01-26T18:24:52.109Z,1674757492.109 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:24:52.109Z,1674757492.109 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:24:59.059Z,1674757499.059 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230126T172023/Courier0025.lzma 2023-01-26T18:25:00.062Z,1674757500.062 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0025.lzma.bak 2023-01-26T18:25:00.062Z,1674757500.062 [DataOverHttps](INFO): SBD MOMSN=17405441 2023-01-26T18:25:16.004Z,1674757516.004 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230126T172023/Express0026.lzma 2023-01-26T18:25:16.323Z,1674757516.323 [NAL9602](INFO): SBD MO Status=2, MOMSN=11071, MT Status=2, MTMSN=0 2023-01-26T18:25:16.323Z,1674757516.323 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-26T18:25:17.006Z,1674757517.006 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0026.lzma.bak 2023-01-26T18:25:17.006Z,1674757517.006 [DataOverHttps](INFO): SBD MOMSN=17405444 2023-01-26T18:25:18.350Z,1674757518.350 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:25:18.351Z,1674757518.351 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:25:18.351Z,1674757518.351 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:25:28.847Z,1674757528.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=11071, MT Status=0, MTMSN=0 2023-01-26T18:25:28.847Z,1674757528.847 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:25:59.549Z,1674757559.549 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:30:18.923Z,1674757818.923 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T18:30:18.923Z,1674757818.923 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:30:18.923Z,1674757818.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:30:18.924Z,1674757818.924 [Default:CheckIn:D] Running Loop=1 2023-01-26T18:30:19.332Z,1674757819.332 [Default:CheckIn:D] Stopped 2023-01-26T18:30:19.333Z,1674757819.333 [Default:CheckIn:E] Running Loop=1 2023-01-26T18:30:19.733Z,1674757819.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.713554 min 2023-01-26T18:30:19.733Z,1674757819.733 [Default:CheckIn:E] Stopped 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn] Stopped 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn](INFO): Running loop #4 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn] Running Loop=4 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:30:19.734Z,1674757819.734 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:30:21.753Z,1674757821.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183021.00,A,3648.35782,N,12147.31785,W,0.039,199.49,260123,,,D*79 2023-01-26T18:30:21.756Z,1674757821.756 [NAL9602](INFO): GPS fix at 20230126T183021: (36.805964, -121.788631) 2023-01-26T18:30:21.767Z,1674757821.767 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:30:21.767Z,1674757821.767 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:30:29.503Z,1674757829.503 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230126T172023/Courier0028.lzma 2023-01-26T18:30:30.506Z,1674757830.506 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0028.lzma.bak 2023-01-26T18:30:30.506Z,1674757830.506 [DataOverHttps](INFO): SBD MOMSN=17405449 2023-01-26T18:30:34.713Z,1674757834.713 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T18:30:46.391Z,1674757846.391 [NAL9602](INFO): SBD MO Status=0, MOMSN=11072, MT Status=0, MTMSN=0 2023-01-26T18:30:46.391Z,1674757846.391 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:30:46.404Z,1674757846.404 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230126T172023/Express0029.lzma 2023-01-26T18:30:47.406Z,1674757847.406 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0029.lzma.bak 2023-01-26T18:30:47.406Z,1674757847.406 [DataOverHttps](INFO): SBD MOMSN=17405452 2023-01-26T18:30:48.824Z,1674757848.824 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:30:48.824Z,1674757848.824 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:30:48.824Z,1674757848.824 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:31:17.213Z,1674757877.213 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:35:46.997Z,1674758146.997 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-01-26T18:35:47.016Z,1674758147.016 [BPC1](INFO): Received data from all battery sticks. 2023-01-26T18:35:49.433Z,1674758149.433 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-01-26T18:35:49.433Z,1674758149.433 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:35:49.433Z,1674758149.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:35:49.433Z,1674758149.433 [Default:CheckIn:D] Running Loop=1 2023-01-26T18:35:49.855Z,1674758149.855 [Default:CheckIn:D] Stopped 2023-01-26T18:35:49.855Z,1674758149.855 [Default:CheckIn:E] Running Loop=1 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.222264 min 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn:E] Stopped 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn] Stopped 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn](INFO): Running loop #5 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn] Running Loop=5 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:35:50.235Z,1674758150.235 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:35:52.247Z,1674758152.247 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183551.00,A,3648.35747,N,12147.31836,W,0.078,199.49,260123,,,D*70 2023-01-26T18:35:52.249Z,1674758152.249 [NAL9602](INFO): GPS fix at 20230126T183551: (36.805958, -121.788639) 2023-01-26T18:35:52.269Z,1674758152.269 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:35:52.269Z,1674758152.269 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:36:05.147Z,1674758165.147 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20230126T172023/Courier0031.lzma 2023-01-26T18:36:06.174Z,1674758166.174 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0031.lzma.bak 2023-01-26T18:36:06.174Z,1674758166.174 [DataOverHttps](INFO): SBD MOMSN=17405457 2023-01-26T18:36:07.331Z,1674758167.331 [NAL9602](INFO): SBD MO Status=0, MOMSN=11073, MT Status=0, MTMSN=0 2023-01-26T18:36:07.332Z,1674758167.332 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:36:26.860Z,1674758186.860 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230126T172023/Express0032.lzma 2023-01-26T18:36:27.862Z,1674758187.862 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0032.lzma.bak 2023-01-26T18:36:27.862Z,1674758187.862 [DataOverHttps](INFO): SBD MOMSN=17405460 2023-01-26T18:36:29.201Z,1674758189.201 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:36:29.201Z,1674758189.201 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:36:29.201Z,1674758189.201 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:36:38.032Z,1674758198.032 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:40:09.138Z,1674758409.138 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184008 2023-01-26T18:40:16.512Z,1674758416.512 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b560 1 2 2023-01-26T18:40:16.565Z,1674758416.565 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b560 1 2.000000 2023-01-26T18:40:16.565Z,1674758416.565 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4b560 2023-01-26T18:40:16.566Z,1674758416.566 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='4b560'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.80125 degree;set transit.Longitude -121.79578 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m", ASAP 2023-01-26T18:40:17.514Z,1674758417.514 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184016 2023-01-26T18:40:24.836Z,1674758424.836 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b560 2 2 2023-01-26T18:40:24.891Z,1674758424.891 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b560 2 2.000000 2023-01-26T18:40:24.891Z,1674758424.891 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4b560 2023-01-26T18:40:24.892Z,1674758424.892 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='4b560'): "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run", ASAP 2023-01-26T18:40:25.003Z,1674758425.003 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2023-01-26T18:40:25.004Z,1674758425.004 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2023-01-26T18:40:25.004Z,1674758425.004 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx 2023-01-26T18:40:25.072Z,1674758425.072 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2023-01-26T18:40:25.074Z,1674758425.074 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2023-01-26T18:40:25.076Z,1674758425.076 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2023-01-26T18:40:25.078Z,1674758425.078 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2023-01-26T18:40:25.080Z,1674758425.080 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2023-01-26T18:40:25.082Z,1674758425.082 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2023-01-26T18:40:25.084Z,1674758425.084 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2023-01-26T18:40:25.085Z,1674758425.085 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2023-01-26T18:40:25.087Z,1674758425.087 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2023-01-26T18:40:25.090Z,1674758425.090 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2023-01-26T18:40:25.091Z,1674758425.091 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2023-01-26T18:40:25.093Z,1674758425.093 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2023-01-26T18:40:25.095Z,1674758425.095 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2023-01-26T18:40:25.095Z,1674758425.095 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2023-01-26T18:40:25.267Z,1674758425.267 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2023-01-26T18:40:25.270Z,1674758425.270 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2023-01-26T18:40:25.272Z,1674758425.272 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2023-01-26T18:40:25.280Z,1674758425.280 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2023-01-26T18:40:25.282Z,1674758425.282 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2023-01-26T18:40:25.284Z,1674758425.284 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2023-01-26T18:40:25.286Z,1674758425.286 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2023-01-26T18:40:25.307Z,1674758425.307 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2023-01-26T18:40:25.323Z,1674758425.323 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T18:40:25.345Z,1674758425.345 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2023-01-26T18:40:25.373Z,1674758425.373 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2023-01-26T18:40:25.375Z,1674758425.375 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2023-01-26T18:40:25.378Z,1674758425.378 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2023-01-26T18:40:25.380Z,1674758425.380 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2023-01-26T18:40:25.387Z,1674758425.387 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2023-01-26T18:40:25.393Z,1674758425.393 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2023-01-26T18:40:25.422Z,1674758425.422 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2023-01-26T18:40:25.425Z,1674758425.425 [transit:MassHold.Pitch](DEBUG): Construct. 2023-01-26T18:40:25.431Z,1674758425.431 [transit:Transit:A.Pitch](DEBUG): Construct. 2023-01-26T18:40:25.434Z,1674758425.434 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2023-01-26T18:40:25.437Z,1674758425.437 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2023-01-26T18:40:25.449Z,1674758425.449 [MissionManager](DEBUG): mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.xml assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.xml assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2023-01-26T18:40:25.449Z,1674758425.449 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl 2023-01-26T18:40:33.133Z,1674758433.133 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute 2023-01-26T18:40:33.134Z,1674758433.134 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute 2023-01-26T18:40:33.135Z,1674758433.135 [CommandExec](IMPORTANT): got command set transit.Latitude 36.80125 degree 2023-01-26T18:40:33.135Z,1674758433.135 [CommandExec](IMPORTANT): got command set transit.Longitude -121.79578 degree 2023-01-26T18:40:33.136Z,1674758433.136 [CommandExec](IMPORTANT): got command set transit.Depth 0.5 meter 2023-01-26T18:40:33.137Z,1674758433.137 [CommandExec](IMPORTANT): got command set transit.MaxDepth 1 meter 2023-01-26T18:40:34.851Z,1674758434.851 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T18:40:35.927Z,1674758435.927 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer 2023-01-26T18:40:35.927Z,1674758435.927 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter 2023-01-26T18:40:35.928Z,1674758435.928 [CommandExec](IMPORTANT): got command run 2023-01-26T18:40:35.930Z,1674758435.930 [CommandExec](IMPORTANT): Running 2023-01-26T18:40:36.034Z,1674758436.034 [Default] Stopped 2023-01-26T18:40:36.034Z,1674758436.034 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T18:40:36.034Z,1674758436.034 [Default:B.GoToSurface] Stopped 2023-01-26T18:40:36.034Z,1674758436.034 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T18:40:36.034Z,1674758436.034 [Default:CheckIn] Stopped 2023-01-26T18:40:36.034Z,1674758436.034 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:40:36.035Z,1674758436.035 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:40:36.035Z,1674758436.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:40:36.035Z,1674758436.035 [MissionManager](IMPORTANT): Started mission transit 2023-01-26T18:40:36.035Z,1674758436.035 [transit] Running Loop=1 2023-01-26T18:40:36.035Z,1674758436.035 [transit](DEBUG): Aggregate::initialize transit 2023-01-26T18:40:36.035Z,1674758436.035 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:40:36.035Z,1674758436.035 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2023-01-26T18:40:36.035Z,1674758436.035 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:40:36.035Z,1674758436.035 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2023-01-26T18:40:36.036Z,1674758436.036 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:40:36.036Z,1674758436.036 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2023-01-26T18:40:36.036Z,1674758436.036 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:40:36.036Z,1674758436.036 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2023-01-26T18:40:36.036Z,1674758436.036 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:40:36.036Z,1674758436.036 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-01-26T18:40:36.036Z,1674758436.036 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:40:36.037Z,1674758436.037 [transit:MassHold.Pitch](DEBUG): Initialize. 2023-01-26T18:40:36.037Z,1674758436.037 [transit:B] Running Loop=1 2023-01-26T18:40:36.037Z,1674758436.037 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:40:36.038Z,1674758436.038 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:40:36.038Z,1674758436.038 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:40:36.038Z,1674758436.038 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:40:36.038Z,1674758436.038 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:40:36.039Z,1674758436.039 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:40:36.039Z,1674758436.039 [transit:B] Stopped 2023-01-26T18:40:36.039Z,1674758436.039 [transit:C] Running Loop=1 2023-01-26T18:40:36.446Z,1674758436.446 [transit:C] Stopped 2023-01-26T18:40:36.447Z,1674758436.447 [transit:E] Running Loop=1 2023-01-26T18:40:36.840Z,1674758436.840 [transit:E] Stopped 2023-01-26T18:40:36.840Z,1674758436.840 [transit:F] Running Loop=1 2023-01-26T18:40:37.247Z,1674758437.247 [transit:F] Stopped 2023-01-26T18:40:37.247Z,1674758437.247 [transit:G] Running Loop=1 2023-01-26T18:40:37.650Z,1674758437.650 [transit:G] Stopped 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit] Running Loop=1 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit:A.Pitch](DEBUG): Initialize. 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2023-01-26T18:40:37.650Z,1674758437.650 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2023-01-26T18:40:37.652Z,1674758437.652 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.801250,-121.795776 2023-01-26T18:40:37.653Z,1674758437.653 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2023-01-26T18:40:38.065Z,1674758438.065 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:40:38.065Z,1674758438.065 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:42:12.749Z,1674758532.749 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184211 2023-01-26T18:42:20.616Z,1674758540.616 [DataOverHttps](INFO): Received command: stop 2023-01-26T18:42:20.645Z,1674758540.645 [CommandExec](IMPORTANT): got command stop 2023-01-26T18:42:20.646Z,1674758540.646 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-01-26T18:42:20.691Z,1674758540.691 [MissionManager](INFO): MissionManager is completed. 2023-01-26T18:42:20.692Z,1674758540.692 [MissionManager](INFO): Uninitializing Mission transit 2023-01-26T18:42:20.692Z,1674758540.692 [transit] Stopped 2023-01-26T18:42:20.692Z,1674758540.692 [transit](DEBUG): Aggregate::uninitialize transit 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes] Stopped 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2023-01-26T18:42:20.692Z,1674758540.692 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2023-01-26T18:42:20.693Z,1674758540.693 [transit:BuoyancyHold.Buoyancy] Stopped 2023-01-26T18:42:20.693Z,1674758540.693 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-01-26T18:42:20.693Z,1674758540.693 [transit:MassHold.Pitch] Stopped 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit] Stopped 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit:A.Pitch] Stopped 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit:B.SetSpeed] Stopped 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit:Wpt1.Waypoint] Stopped 2023-01-26T18:42:20.693Z,1674758540.693 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2023-01-26T18:42:21.094Z,1674758541.094 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T18:42:21.094Z,1674758541.094 [Default] Running Loop=1 2023-01-26T18:42:21.095Z,1674758541.095 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T18:42:21.095Z,1674758541.095 [Default:B.GoToSurface] Running Loop=1 2023-01-26T18:42:21.095Z,1674758541.095 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T18:42:21.095Z,1674758541.095 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T18:42:21.095Z,1674758541.095 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T18:42:21.096Z,1674758541.096 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T18:42:21.096Z,1674758541.096 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T18:42:21.097Z,1674758541.097 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T18:42:21.097Z,1674758541.097 [Default:A.Wait] Running Loop=1 2023-01-26T18:42:21.097Z,1674758541.097 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:42:34.404Z,1674758554.404 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T18:42:34.404Z,1674758554.404 [Default:A.Wait] Stopped 2023-01-26T18:42:34.404Z,1674758554.404 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:42:34.809Z,1674758554.809 [Default:CheckIn] Running Loop=1 2023-01-26T18:42:34.809Z,1674758554.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:42:34.809Z,1674758554.809 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:42:36.823Z,1674758556.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184236.00,A,3648.34933,N,12147.34187,W,0.019,236.57,260123,,,D*75 2023-01-26T18:42:36.825Z,1674758556.825 [NAL9602](INFO): GPS fix at 20230126T184236: (36.805822, -121.789031) 2023-01-26T18:42:36.837Z,1674758556.837 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:42:36.837Z,1674758556.837 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:42:44.207Z,1674758564.207 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230126T172023/Courier0034.lzma 2023-01-26T18:42:45.210Z,1674758565.210 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0034.lzma.bak 2023-01-26T18:42:45.210Z,1674758565.210 [DataOverHttps](INFO): SBD MOMSN=17405471 2023-01-26T18:42:52.187Z,1674758572.187 [NAL9602](INFO): SBD MO Status=0, MOMSN=11074, MT Status=0, MTMSN=0 2023-01-26T18:42:52.187Z,1674758572.187 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:43:01.129Z,1674758581.129 [DataOverHttps](INFO): Sending 696 bytes from file Logs/20230126T172023/Express0035.lzma 2023-01-26T18:43:02.130Z,1674758582.130 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0035.lzma.bak 2023-01-26T18:43:02.130Z,1674758582.130 [DataOverHttps](INFO): SBD MOMSN=17405474 2023-01-26T18:43:17.951Z,1674758597.951 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230126T172023/Express0038.lzma 2023-01-26T18:43:18.954Z,1674758598.954 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0038.lzma.bak 2023-01-26T18:43:18.954Z,1674758598.954 [DataOverHttps](INFO): SBD MOMSN=17405503 2023-01-26T18:43:20.473Z,1674758600.473 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:43:20.473Z,1674758600.473 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:43:20.473Z,1674758600.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:43:22.873Z,1674758602.873 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:44:03.194Z,1674758643.194 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184402 2023-01-26T18:44:10.508Z,1674758650.508 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.79715 degree;set transit.Longitude -121.80109 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b5ce 1 2 2023-01-26T18:44:10.556Z,1674758650.556 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.79715 degree;set transit.Longitude -121.80109 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b5ce 1 2.000000 2023-01-26T18:44:10.557Z,1674758650.557 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4b5ce 2023-01-26T18:44:10.558Z,1674758650.558 [CommandExec](IMPORTANT): Scheduled #5 (#1 of 2 with id='4b5ce'): "load Transport/transit.tl;set transit.MissionTimeout 20 min;set transit.NeedCommsTime 10 min;set transit.Latitude 36.79715 degree;set transit.Longitude -121.80109 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m", ASAP 2023-01-26T18:44:11.510Z,1674758651.510 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184410 2023-01-26T18:44:18.872Z,1674758658.872 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b5ce 2 2 2023-01-26T18:44:18.907Z,1674758658.907 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b5ce 2 2.000000 2023-01-26T18:44:18.907Z,1674758658.907 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4b5ce 2023-01-26T18:44:18.908Z,1674758658.908 [CommandExec](IMPORTANT): Scheduled #6 (#2 of 2 with id='4b5ce'): "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run", ASAP 2023-01-26T18:44:19.037Z,1674758659.037 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2023-01-26T18:44:19.037Z,1674758659.037 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2023-01-26T18:44:19.037Z,1674758659.037 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx 2023-01-26T18:44:19.159Z,1674758659.159 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2023-01-26T18:44:19.161Z,1674758659.161 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2023-01-26T18:44:19.164Z,1674758659.164 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2023-01-26T18:44:19.167Z,1674758659.167 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2023-01-26T18:44:19.169Z,1674758659.169 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2023-01-26T18:44:19.171Z,1674758659.171 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2023-01-26T18:44:19.174Z,1674758659.174 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2023-01-26T18:44:19.176Z,1674758659.176 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2023-01-26T18:44:19.178Z,1674758659.178 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2023-01-26T18:44:19.180Z,1674758659.180 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2023-01-26T18:44:19.182Z,1674758659.182 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2023-01-26T18:44:19.184Z,1674758659.184 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2023-01-26T18:44:19.186Z,1674758659.186 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2023-01-26T18:44:19.187Z,1674758659.187 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2023-01-26T18:44:19.298Z,1674758659.298 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2023-01-26T18:44:19.309Z,1674758659.309 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2023-01-26T18:44:19.314Z,1674758659.314 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2023-01-26T18:44:19.316Z,1674758659.316 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2023-01-26T18:44:19.318Z,1674758659.318 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2023-01-26T18:44:19.320Z,1674758659.320 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2023-01-26T18:44:19.322Z,1674758659.322 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2023-01-26T18:44:19.324Z,1674758659.324 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2023-01-26T18:44:19.330Z,1674758659.330 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T18:44:19.343Z,1674758659.343 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2023-01-26T18:44:19.390Z,1674758659.390 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2023-01-26T18:44:19.410Z,1674758659.410 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2023-01-26T18:44:19.412Z,1674758659.412 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2023-01-26T18:44:19.413Z,1674758659.413 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2023-01-26T18:44:19.438Z,1674758659.438 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2023-01-26T18:44:19.444Z,1674758659.444 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2023-01-26T18:44:19.488Z,1674758659.488 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2023-01-26T18:44:19.491Z,1674758659.491 [transit:MassHold.Pitch](DEBUG): Construct. 2023-01-26T18:44:19.509Z,1674758659.509 [transit:Transit:A.Pitch](DEBUG): Construct. 2023-01-26T18:44:19.512Z,1674758659.512 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2023-01-26T18:44:19.515Z,1674758659.515 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2023-01-26T18:44:19.539Z,1674758659.539 [MissionManager](DEBUG): mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.xml assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.xml assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2023-01-26T18:44:19.551Z,1674758659.551 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl 2023-01-26T18:44:27.229Z,1674758667.229 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 20 minute 2023-01-26T18:44:27.229Z,1674758667.229 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 10 minute 2023-01-26T18:44:27.230Z,1674758667.230 [CommandExec](IMPORTANT): got command set transit.Latitude 36.79715 degree 2023-01-26T18:44:27.231Z,1674758667.231 [CommandExec](IMPORTANT): got command set transit.Longitude -121.80109 degree 2023-01-26T18:44:27.231Z,1674758667.231 [CommandExec](IMPORTANT): got command set transit.Depth 0.5 meter 2023-01-26T18:44:27.233Z,1674758667.233 [CommandExec](IMPORTANT): got command set transit.MaxDepth 1 meter 2023-01-26T18:44:29.988Z,1674758669.988 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer 2023-01-26T18:44:29.988Z,1674758669.988 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter 2023-01-26T18:44:29.989Z,1674758669.989 [CommandExec](IMPORTANT): got command run 2023-01-26T18:44:29.990Z,1674758669.990 [CommandExec](IMPORTANT): Running 2023-01-26T18:44:30.352Z,1674758670.352 [Default] Stopped 2023-01-26T18:44:30.352Z,1674758670.352 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T18:44:30.352Z,1674758670.352 [Default:B.GoToSurface] Stopped 2023-01-26T18:44:30.352Z,1674758670.352 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T18:44:30.352Z,1674758670.352 [Default:CheckIn] Stopped 2023-01-26T18:44:30.353Z,1674758670.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:44:30.353Z,1674758670.353 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:44:30.353Z,1674758670.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:44:30.353Z,1674758670.353 [MissionManager](IMPORTANT): Started mission transit 2023-01-26T18:44:30.353Z,1674758670.353 [transit] Running Loop=1 2023-01-26T18:44:30.353Z,1674758670.353 [transit](DEBUG): Aggregate::initialize transit 2023-01-26T18:44:30.353Z,1674758670.353 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:44:30.353Z,1674758670.353 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2023-01-26T18:44:30.353Z,1674758670.353 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:44:30.354Z,1674758670.354 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2023-01-26T18:44:30.354Z,1674758670.354 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:44:30.354Z,1674758670.354 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2023-01-26T18:44:30.354Z,1674758670.354 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:44:30.354Z,1674758670.354 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2023-01-26T18:44:30.354Z,1674758670.354 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:44:30.354Z,1674758670.354 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-01-26T18:44:30.354Z,1674758670.354 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:44:30.354Z,1674758670.354 [transit:MassHold.Pitch](DEBUG): Initialize. 2023-01-26T18:44:30.355Z,1674758670.355 [transit:B] Running Loop=1 2023-01-26T18:44:30.355Z,1674758670.355 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:44:30.355Z,1674758670.355 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:44:30.356Z,1674758670.356 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:44:30.356Z,1674758670.356 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:44:30.368Z,1674758670.368 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:44:30.368Z,1674758670.368 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:44:30.369Z,1674758670.369 [transit:B] Stopped 2023-01-26T18:44:30.369Z,1674758670.369 [transit:C] Running Loop=1 2023-01-26T18:44:30.763Z,1674758670.763 [transit:C] Stopped 2023-01-26T18:44:30.763Z,1674758670.763 [transit:E] Running Loop=1 2023-01-26T18:44:31.165Z,1674758671.165 [transit:E] Stopped 2023-01-26T18:44:31.165Z,1674758671.165 [transit:F] Running Loop=1 2023-01-26T18:44:31.578Z,1674758671.578 [transit:F] Stopped 2023-01-26T18:44:31.578Z,1674758671.578 [transit:G] Running Loop=1 2023-01-26T18:44:31.967Z,1674758671.967 [transit:G] Stopped 2023-01-26T18:44:31.967Z,1674758671.967 [transit:Transit] Running Loop=1 2023-01-26T18:44:31.968Z,1674758671.968 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2023-01-26T18:44:31.968Z,1674758671.968 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:44:31.968Z,1674758671.968 [transit:Transit:A.Pitch](DEBUG): Initialize. 2023-01-26T18:44:31.968Z,1674758671.968 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:44:31.968Z,1674758671.968 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2023-01-26T18:44:31.968Z,1674758671.968 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2023-01-26T18:44:31.969Z,1674758671.969 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2023-01-26T18:44:31.970Z,1674758671.970 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797150,-121.801086 2023-01-26T18:44:31.970Z,1674758671.970 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2023-01-26T18:44:32.380Z,1674758672.380 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:44:32.380Z,1674758672.380 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:44:55.945Z,1674758695.945 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184455 2023-01-26T18:45:03.896Z,1674758703.896 [DataOverHttps](INFO): Received command: stop 2023-01-26T18:45:03.941Z,1674758703.941 [CommandExec](IMPORTANT): got command stop 2023-01-26T18:45:03.942Z,1674758703.942 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-01-26T18:45:04.291Z,1674758704.291 [MissionManager](INFO): MissionManager is completed. 2023-01-26T18:45:04.292Z,1674758704.292 [MissionManager](INFO): Uninitializing Mission transit 2023-01-26T18:45:04.292Z,1674758704.292 [transit] Stopped 2023-01-26T18:45:04.292Z,1674758704.292 [transit](DEBUG): Aggregate::uninitialize transit 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes] Stopped 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2023-01-26T18:45:04.292Z,1674758704.292 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2023-01-26T18:45:04.292Z,1674758704.292 [transit:BuoyancyHold.Buoyancy] Stopped 2023-01-26T18:45:04.293Z,1674758704.293 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-01-26T18:45:04.293Z,1674758704.293 [transit:MassHold.Pitch] Stopped 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit] Stopped 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit:A.Pitch] Stopped 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit:B.SetSpeed] Stopped 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit:Wpt1.Waypoint] Stopped 2023-01-26T18:45:04.293Z,1674758704.293 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2023-01-26T18:45:04.700Z,1674758704.700 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T18:45:04.701Z,1674758704.701 [Default] Running Loop=1 2023-01-26T18:45:04.701Z,1674758704.701 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T18:45:04.701Z,1674758704.701 [Default:B.GoToSurface] Running Loop=1 2023-01-26T18:45:04.701Z,1674758704.701 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T18:45:04.701Z,1674758704.701 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T18:45:04.701Z,1674758704.701 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T18:45:04.702Z,1674758704.702 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T18:45:04.702Z,1674758704.702 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T18:45:04.702Z,1674758704.702 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T18:45:04.703Z,1674758704.703 [Default:A.Wait] Running Loop=1 2023-01-26T18:45:04.703Z,1674758704.703 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:45:18.029Z,1674758718.029 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T18:45:18.029Z,1674758718.029 [Default:A.Wait] Stopped 2023-01-26T18:45:18.029Z,1674758718.029 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:45:18.460Z,1674758718.460 [Default:CheckIn] Running Loop=1 2023-01-26T18:45:18.460Z,1674758718.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T18:45:18.460Z,1674758718.460 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T18:45:20.438Z,1674758720.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184519.00,A,3648.34963,N,12147.34217,W,0.019,236.57,260123,,,D*70 2023-01-26T18:45:20.440Z,1674758720.440 [NAL9602](INFO): GPS fix at 20230126T184519: (36.805827, -121.789036) 2023-01-26T18:45:20.452Z,1674758720.452 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T18:45:20.452Z,1674758720.452 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T18:45:27.395Z,1674758727.395 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230126T172023/Courier0040.lzma 2023-01-26T18:45:28.398Z,1674758728.398 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0040.lzma.bak 2023-01-26T18:45:28.398Z,1674758728.398 [DataOverHttps](INFO): SBD MOMSN=17405511 2023-01-26T18:45:36.193Z,1674758736.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=11075, MT Status=0, MTMSN=0 2023-01-26T18:45:36.194Z,1674758736.194 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:45:44.196Z,1674758744.196 [DataOverHttps](INFO): Sending 657 bytes from file Logs/20230126T172023/Express0041.lzma 2023-01-26T18:45:45.198Z,1674758745.198 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0041.lzma.bak 2023-01-26T18:45:45.198Z,1674758745.198 [DataOverHttps](INFO): SBD MOMSN=17405514 2023-01-26T18:45:46.807Z,1674758746.807 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T18:45:46.807Z,1674758746.807 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T18:45:46.807Z,1674758746.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T18:46:06.977Z,1674758766.977 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T18:48:15.878Z,1674758895.878 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184814 2023-01-26T18:48:23.160Z,1674758903.160 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 25 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.79715 degree;set transit.Longitude -121.80109 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b5jr 1 2 2023-01-26T18:48:23.260Z,1674758903.260 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 25 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.79715 degree;set transit.Longitude -121.80109 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m" 4b5jr 1 2.000000 2023-01-26T18:48:23.261Z,1674758903.261 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4b5jr 2023-01-26T18:48:23.262Z,1674758903.262 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 2 with id='4b5jr'): "load Transport/transit.tl;set transit.MissionTimeout 25 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.79715 degree;set transit.Longitude -121.80109 degree;set transit.Depth 0.5 m;set transit.MaxDepth 1 m", ASAP 2023-01-26T18:48:24.161Z,1674758904.161 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T184823 2023-01-26T18:48:41.608Z,1674758921.608 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b5jr 2 2 2023-01-26T18:48:41.707Z,1674758921.707 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run" 4b5jr 2 2.000000 2023-01-26T18:48:41.708Z,1674758921.708 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4b5jr 2023-01-26T18:48:41.709Z,1674758921.709 [CommandExec](IMPORTANT): Scheduled #8 (#2 of 2 with id='4b5jr'): "set transit.MinOffshore 0.0001 km;set transit.MinAltitude 1 m;run", ASAP 2023-01-26T18:48:41.820Z,1674758921.820 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2023-01-26T18:48:41.820Z,1674758921.820 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2023-01-26T18:48:41.821Z,1674758921.821 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx 2023-01-26T18:48:41.892Z,1674758921.892 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2023-01-26T18:48:41.894Z,1674758921.894 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2023-01-26T18:48:41.905Z,1674758921.905 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2023-01-26T18:48:41.907Z,1674758921.907 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2023-01-26T18:48:41.909Z,1674758921.909 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2023-01-26T18:48:41.911Z,1674758921.911 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2023-01-26T18:48:41.913Z,1674758921.913 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2023-01-26T18:48:41.915Z,1674758921.915 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2023-01-26T18:48:41.917Z,1674758921.917 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2023-01-26T18:48:41.919Z,1674758921.919 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2023-01-26T18:48:41.922Z,1674758921.922 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2023-01-26T18:48:41.923Z,1674758921.923 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2023-01-26T18:48:41.925Z,1674758921.925 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2023-01-26T18:48:41.926Z,1674758921.926 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2023-01-26T18:48:42.019Z,1674758922.019 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2023-01-26T18:48:42.032Z,1674758922.032 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2023-01-26T18:48:42.034Z,1674758922.034 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2023-01-26T18:48:42.036Z,1674758922.036 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2023-01-26T18:48:42.038Z,1674758922.038 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2023-01-26T18:48:42.040Z,1674758922.040 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2023-01-26T18:48:42.042Z,1674758922.042 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2023-01-26T18:48:42.044Z,1674758922.044 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2023-01-26T18:48:42.050Z,1674758922.050 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T18:48:42.138Z,1674758922.138 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2023-01-26T18:48:42.190Z,1674758922.190 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2023-01-26T18:48:42.193Z,1674758922.193 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2023-01-26T18:48:42.195Z,1674758922.195 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2023-01-26T18:48:42.195Z,1674758922.195 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2023-01-26T18:48:42.213Z,1674758922.213 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2023-01-26T18:48:42.218Z,1674758922.218 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2023-01-26T18:48:42.241Z,1674758922.241 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2023-01-26T18:48:42.244Z,1674758922.244 [transit:MassHold.Pitch](DEBUG): Construct. 2023-01-26T18:48:42.261Z,1674758922.261 [transit:Transit:A.Pitch](DEBUG): Construct. 2023-01-26T18:48:42.264Z,1674758922.264 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2023-01-26T18:48:42.267Z,1674758922.267 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2023-01-26T18:48:42.280Z,1674758922.280 [MissionManager](DEBUG): mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.xml assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.xml assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2023-01-26T18:48:42.280Z,1674758922.280 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl 2023-01-26T18:48:49.958Z,1674758929.958 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 25 minute 2023-01-26T18:48:49.959Z,1674758929.959 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute 2023-01-26T18:48:49.960Z,1674758929.960 [CommandExec](IMPORTANT): got command set transit.Latitude 36.79715 degree 2023-01-26T18:48:49.960Z,1674758929.960 [CommandExec](IMPORTANT): got command set transit.Longitude -121.80109 degree 2023-01-26T18:48:49.961Z,1674758929.961 [CommandExec](IMPORTANT): got command set transit.Depth 0.5 meter 2023-01-26T18:48:49.962Z,1674758929.962 [CommandExec](IMPORTANT): got command set transit.MaxDepth 1 meter 2023-01-26T18:48:52.790Z,1674758932.790 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer 2023-01-26T18:48:52.791Z,1674758932.791 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter 2023-01-26T18:48:52.792Z,1674758932.792 [CommandExec](IMPORTANT): got command run 2023-01-26T18:48:52.793Z,1674758932.793 [CommandExec](IMPORTANT): Running 2023-01-26T18:48:53.028Z,1674758933.028 [Default] Stopped 2023-01-26T18:48:53.028Z,1674758933.028 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T18:48:53.028Z,1674758933.028 [Default:B.GoToSurface] Stopped 2023-01-26T18:48:53.028Z,1674758933.028 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T18:48:53.028Z,1674758933.028 [Default:CheckIn] Stopped 2023-01-26T18:48:53.028Z,1674758933.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T18:48:53.028Z,1674758933.028 [Default:CheckIn:C.Wait] Stopped 2023-01-26T18:48:53.028Z,1674758933.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T18:48:53.028Z,1674758933.028 [MissionManager](IMPORTANT): Started mission transit 2023-01-26T18:48:53.029Z,1674758933.029 [transit] Running Loop=1 2023-01-26T18:48:53.029Z,1674758933.029 [transit](DEBUG): Aggregate::initialize transit 2023-01-26T18:48:53.029Z,1674758933.029 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:48:53.029Z,1674758933.029 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2023-01-26T18:48:53.029Z,1674758933.029 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:48:53.029Z,1674758933.029 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2023-01-26T18:48:53.029Z,1674758933.029 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:48:53.029Z,1674758933.029 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2023-01-26T18:48:53.030Z,1674758933.030 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:48:53.030Z,1674758933.030 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2023-01-26T18:48:53.030Z,1674758933.030 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:48:53.030Z,1674758933.030 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-01-26T18:48:53.030Z,1674758933.030 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:48:53.030Z,1674758933.030 [transit:MassHold.Pitch](DEBUG): Initialize. 2023-01-26T18:48:53.030Z,1674758933.030 [transit:B] Running Loop=1 2023-01-26T18:48:53.031Z,1674758933.031 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T18:48:53.031Z,1674758933.031 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T18:48:53.031Z,1674758933.031 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T18:48:53.031Z,1674758933.031 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T18:48:53.031Z,1674758933.031 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T18:48:53.032Z,1674758933.032 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T18:48:53.032Z,1674758933.032 [transit:B] Stopped 2023-01-26T18:48:53.032Z,1674758933.032 [transit:C] Running Loop=1 2023-01-26T18:48:53.440Z,1674758933.440 [transit:C] Stopped 2023-01-26T18:48:53.440Z,1674758933.440 [transit:E] Running Loop=1 2023-01-26T18:48:53.835Z,1674758933.835 [transit:E] Stopped 2023-01-26T18:48:53.836Z,1674758933.836 [transit:F] Running Loop=1 2023-01-26T18:48:54.245Z,1674758934.245 [transit:F] Stopped 2023-01-26T18:48:54.245Z,1674758934.245 [transit:G] Running Loop=1 2023-01-26T18:48:54.649Z,1674758934.649 [transit:G] Stopped 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit] Running Loop=1 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit:A.Pitch](DEBUG): Initialize. 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2023-01-26T18:48:54.649Z,1674758934.649 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2023-01-26T18:48:54.650Z,1674758934.650 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2023-01-26T18:48:54.651Z,1674758934.651 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797150,-121.801086 2023-01-26T18:48:54.651Z,1674758934.651 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2023-01-26T18:48:55.057Z,1674758935.057 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T18:48:55.058Z,1674758935.058 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T18:50:34.938Z,1674759034.938 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T18:52:55.488Z,1674759175.488 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.910297 2023-01-26T18:53:29.458Z,1674759209.458 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003933 2023-01-26T18:55:15.324Z,1674759315.324 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.918507 2023-01-26T18:56:33.877Z,1674759393.877 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003934 2023-01-26T18:57:21.452Z,1674759441.452 [transit:NeedComms] Running Loop=1 2023-01-26T18:57:21.452Z,1674759441.452 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2023-01-26T18:57:21.452Z,1674759441.452 [transit:NeedComms:B.GoToSurface] Running Loop=1 2023-01-26T18:57:21.452Z,1674759441.452 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T18:57:21.452Z,1674759441.452 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2023-01-26T18:57:21.453Z,1674759441.453 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2023-01-26T18:57:21.453Z,1674759441.453 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2023-01-26T18:57:21.454Z,1674759441.454 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2023-01-26T18:57:21.454Z,1674759441.454 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T18:57:21.454Z,1674759441.454 [transit:NeedComms:A] Running Loop=1 2023-01-26T18:57:21.455Z,1674759441.455 [transit:NeedComms:A](INFO): last time_fix was: 1674758719.000000 second since 1970/01/01T00:00:00Z 2023-01-26T18:57:21.456Z,1674759441.456 [transit:NeedComms:A] Stopped 2023-01-26T18:57:21.828Z,1674759441.828 [transit:NeedComms:C] Running Loop=1 2023-01-26T18:57:22.242Z,1674759442.242 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-01-26T18:57:23.807Z,1674759443.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185723.00,A,3648.19316,N,12147.57638,W,2.119,231.09,260123,,,A*7B 2023-01-26T18:57:23.809Z,1674759443.809 [NAL9602](INFO): GPS fix at 20230126T185723: (36.803219, -121.792940) 2023-01-26T18:57:23.869Z,1674759443.869 [transit:NeedComms:C] Stopped 2023-01-26T18:57:23.869Z,1674759443.869 [transit:NeedComms:D] Running Loop=1 2023-01-26T18:57:24.290Z,1674759444.290 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-01-26T18:57:33.956Z,1674759453.956 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.082558 2023-01-26T18:57:57.761Z,1674759477.761 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003324 2023-01-26T18:58:05.471Z,1674759485.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230126T172023/Courier0043.lzma 2023-01-26T18:58:06.474Z,1674759486.474 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0043.lzma.bak 2023-01-26T18:58:06.474Z,1674759486.474 [DataOverHttps](INFO): SBD MOMSN=17405547 2023-01-26T18:58:18.115Z,1674759498.115 [NAL9602](INFO): SBD MO Status=0, MOMSN=11076, MT Status=0, MTMSN=0 2023-01-26T18:58:18.115Z,1674759498.115 [NAL9602](INFO): No messages in MT queue 2023-01-26T18:58:22.933Z,1674759502.933 [DataOverHttps](INFO): Sending 798 bytes from file Logs/20230126T172023/Express0045.lzma 2023-01-26T18:58:23.934Z,1674759503.934 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0045.lzma.bak 2023-01-26T18:58:23.934Z,1674759503.934 [DataOverHttps](INFO): SBD MOMSN=17405550 2023-01-26T18:58:40.099Z,1674759520.099 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230126T172023/Express0050.lzma 2023-01-26T18:58:41.102Z,1674759521.102 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0050.lzma.bak 2023-01-26T18:58:41.102Z,1674759521.102 [DataOverHttps](INFO): SBD MOMSN=17405576 2023-01-26T18:58:42.765Z,1674759522.765 [transit:NeedComms:D] Stopped 2023-01-26T18:58:42.766Z,1674759522.766 [transit:NeedComms:E] Running Loop=1 2023-01-26T18:58:43.174Z,1674759523.174 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-01-26T18:58:44.777Z,1674759524.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185844.00,A,3648.17381,N,12147.58679,W,0.350,209.54,260123,,,D*74 2023-01-26T18:58:44.779Z,1674759524.779 [NAL9602](INFO): GPS fix at 20230126T185844: (36.802897, -121.793113) 2023-01-26T18:58:44.808Z,1674759524.808 [transit:NeedComms:E] Stopped 2023-01-26T18:58:44.809Z,1674759524.809 [transit:NeedComms](INFO): Completed transit:NeedComms 2023-01-26T18:58:44.809Z,1674759524.809 [transit:NeedComms] Stopped 2023-01-26T18:58:44.809Z,1674759524.809 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2023-01-26T18:58:44.809Z,1674759524.809 [transit:NeedComms:B.GoToSurface] Stopped 2023-01-26T18:58:44.810Z,1674759524.810 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T18:59:17.499Z,1674759557.499 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T19:00:11.643Z,1674759611.643 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 18.86 mm (1 active estimators). 2023-01-26T19:00:35.343Z,1674759635.343 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T19:01:21.548Z,1674759681.548 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.806561 2023-01-26T19:02:47.745Z,1674759767.745 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:03:58.561Z,1674759838.561 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003331 2023-01-26T19:08:30.037Z,1674760110.037 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.835335 2023-01-26T19:09:55.249Z,1674760195.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:10:35.658Z,1674760235.658 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T19:10:46.083Z,1674760246.083 [transit:NeedComms] Running Loop=1 2023-01-26T19:10:46.083Z,1674760246.083 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2023-01-26T19:10:46.083Z,1674760246.083 [transit:NeedComms:B.GoToSurface] Running Loop=1 2023-01-26T19:10:46.083Z,1674760246.083 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T19:10:46.083Z,1674760246.083 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2023-01-26T19:10:46.084Z,1674760246.084 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2023-01-26T19:10:46.084Z,1674760246.084 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2023-01-26T19:10:46.084Z,1674760246.084 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2023-01-26T19:10:46.085Z,1674760246.085 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T19:10:46.085Z,1674760246.085 [transit:NeedComms:A] Running Loop=1 2023-01-26T19:10:46.086Z,1674760246.086 [transit:NeedComms:A](INFO): last time_fix was: 1674759524.000000 second since 1970/01/01T00:00:00Z 2023-01-26T19:10:46.086Z,1674760246.086 [transit:NeedComms:A] Stopped 2023-01-26T19:10:46.497Z,1674760246.497 [transit:NeedComms:C] Running Loop=1 2023-01-26T19:10:48.506Z,1674760248.506 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191047.00,A,3647.91323,N,12147.93186,W,2.119,219.37,260123,,,A*7F 2023-01-26T19:10:48.508Z,1674760248.508 [NAL9602](INFO): GPS fix at 20230126T191047: (36.798554, -121.798864) 2023-01-26T19:10:48.523Z,1674760248.523 [UniversalFixResidualReporter](INFO): Fix residual: 7.4 %DT, over the last 685.6 m. Residual distance 50.8 m at bearing 29.7 degrees. Fix at (36.7986, -121.7989) with 703.9 m made good. 2023-01-26T19:10:48.527Z,1674760248.527 [transit:NeedComms:C] Stopped 2023-01-26T19:10:48.527Z,1674760248.527 [transit:NeedComms:D] Running Loop=1 2023-01-26T19:10:49.105Z,1674760249.105 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003400 2023-01-26T19:11:29.708Z,1674760289.708 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230126T172023/Courier0052.lzma 2023-01-26T19:11:30.710Z,1674760290.710 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0052.lzma.bak 2023-01-26T19:11:30.710Z,1674760290.710 [DataOverHttps](IMPORTANT): SBD MOMSN=17405580, MTMSN=20230126T191129 2023-01-26T19:11:38.368Z,1674760298.368 [DataOverHttps](INFO): Received command: stop 2023-01-26T19:11:38.461Z,1674760298.461 [CommandExec](IMPORTANT): got command stop 2023-01-26T19:11:38.461Z,1674760298.461 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-01-26T19:11:38.632Z,1674760298.632 [MissionManager](INFO): MissionManager is completed. 2023-01-26T19:11:38.633Z,1674760298.633 [MissionManager](INFO): Uninitializing Mission transit 2023-01-26T19:11:38.633Z,1674760298.633 [transit] Stopped 2023-01-26T19:11:38.633Z,1674760298.633 [transit](DEBUG): Aggregate::uninitialize transit 2023-01-26T19:11:38.633Z,1674760298.633 [transit:NeedComms] Stopped 2023-01-26T19:11:38.633Z,1674760298.633 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2023-01-26T19:11:38.633Z,1674760298.633 [transit:NeedComms:B.GoToSurface] Stopped 2023-01-26T19:11:38.633Z,1674760298.633 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T19:11:38.633Z,1674760298.633 [transit:NeedComms:D] Stopped 2023-01-26T19:11:38.633Z,1674760298.633 [transit:StandardEnvelopes] Stopped 2023-01-26T19:11:38.633Z,1674760298.633 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2023-01-26T19:11:38.633Z,1674760298.633 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2023-01-26T19:11:38.634Z,1674760298.634 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2023-01-26T19:11:38.634Z,1674760298.634 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2023-01-26T19:11:38.634Z,1674760298.634 [transit:BuoyancyHold.Buoyancy] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-01-26T19:11:38.634Z,1674760298.634 [transit:MassHold.Pitch] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit:A.Pitch] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit:B.SetSpeed] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit:Wpt1.Waypoint] Stopped 2023-01-26T19:11:38.634Z,1674760298.634 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2023-01-26T19:11:39.070Z,1674760299.070 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T19:11:39.071Z,1674760299.071 [Default] Running Loop=1 2023-01-26T19:11:39.071Z,1674760299.071 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T19:11:39.071Z,1674760299.071 [Default:B.GoToSurface] Running Loop=1 2023-01-26T19:11:39.071Z,1674760299.071 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T19:11:39.071Z,1674760299.071 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T19:11:39.071Z,1674760299.071 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T19:11:39.072Z,1674760299.072 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T19:11:39.117Z,1674760299.117 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T19:11:39.118Z,1674760299.118 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T19:11:39.128Z,1674760299.128 [Default:A.Wait] Running Loop=1 2023-01-26T19:11:39.128Z,1674760299.128 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T19:11:47.548Z,1674760307.548 [DataOverHttps](INFO): Sending 357 bytes from file Logs/20230126T172023/Express0053.lzma 2023-01-26T19:11:48.550Z,1674760308.550 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Express0053.lzma.bak 2023-01-26T19:11:48.550Z,1674760308.550 [DataOverHttps](INFO): SBD MOMSN=17405584 2023-01-26T19:11:52.349Z,1674760312.349 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T19:11:52.349Z,1674760312.349 [Default:A.Wait] Stopped 2023-01-26T19:11:52.350Z,1674760312.350 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T19:11:52.757Z,1674760312.757 [Default:CheckIn] Running Loop=1 2023-01-26T19:11:52.758Z,1674760312.758 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T19:11:52.758Z,1674760312.758 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T19:12:16.975Z,1674760336.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=11077, MT Status=2, MTMSN=0 2023-01-26T19:12:16.975Z,1674760336.975 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-26T19:12:36.770Z,1674760356.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=11077, MT Status=0, MTMSN=0 2023-01-26T19:12:36.770Z,1674760356.770 [NAL9602](INFO): No messages in MT queue 2023-01-26T19:12:37.987Z,1674760357.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191237.00,A,3647.89646,N,12147.96135,W,0.758,238.07,260123,,,A*79 2023-01-26T19:12:37.997Z,1674760357.997 [NAL9602](INFO): GPS fix at 20230126T191237: (36.798274, -121.799356) 2023-01-26T19:12:38.010Z,1674760358.010 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T19:12:38.010Z,1674760358.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T19:12:45.599Z,1674760365.599 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230126T172023/Courier0055.lzma 2023-01-26T19:12:46.602Z,1674760366.602 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0055.lzma.bak 2023-01-26T19:12:46.602Z,1674760366.602 [DataOverHttps](INFO): SBD MOMSN=17405588 2023-01-26T19:12:54.906Z,1674760374.906 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T191254 2023-01-26T19:13:02.219Z,1674760382.219 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230126T172023/Courier0058.lzma 2023-01-26T19:13:02.222Z,1674760382.222 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 5 m;set transit.MaxDepth 10 m;set transit.MinOffshore 0.1 km;run 2023-01-26T19:13:02.248Z,1674760382.248 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2023-01-26T19:13:02.249Z,1674760382.249 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2023-01-26T19:13:02.249Z,1674760382.249 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/transit.tx 2023-01-26T19:13:02.374Z,1674760382.374 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2023-01-26T19:13:02.376Z,1674760382.376 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2023-01-26T19:13:02.378Z,1674760382.378 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2023-01-26T19:13:02.381Z,1674760382.381 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2023-01-26T19:13:02.383Z,1674760382.383 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2023-01-26T19:13:02.409Z,1674760382.409 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2023-01-26T19:13:02.411Z,1674760382.411 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2023-01-26T19:13:02.417Z,1674760382.417 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2023-01-26T19:13:02.420Z,1674760382.420 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2023-01-26T19:13:02.426Z,1674760382.426 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2023-01-26T19:13:02.440Z,1674760382.440 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2023-01-26T19:13:02.442Z,1674760382.442 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2023-01-26T19:13:02.448Z,1674760382.448 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2023-01-26T19:13:02.449Z,1674760382.449 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml 2023-01-26T19:13:02.517Z,1674760382.517 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 3.000000 h 2023-01-26T19:13:02.519Z,1674760382.519 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2023-01-26T19:13:02.521Z,1674760382.521 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2023-01-26T19:13:02.523Z,1674760382.523 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2023-01-26T19:13:02.525Z,1674760382.525 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2023-01-26T19:13:02.527Z,1674760382.527 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2023-01-26T19:13:02.529Z,1674760382.529 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2023-01-26T19:13:02.531Z,1674760382.531 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2023-01-26T19:13:02.536Z,1674760382.536 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T19:13:02.594Z,1674760382.594 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml 2023-01-26T19:13:02.635Z,1674760382.635 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2023-01-26T19:13:02.637Z,1674760382.637 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2023-01-26T19:13:02.639Z,1674760382.639 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2023-01-26T19:13:02.640Z,1674760382.640 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2023-01-26T19:13:02.677Z,1674760382.677 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2023-01-26T19:13:02.683Z,1674760382.683 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2023-01-26T19:13:02.707Z,1674760382.707 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2023-01-26T19:13:02.726Z,1674760382.726 [transit:MassHold.Pitch](DEBUG): Construct. 2023-01-26T19:13:02.732Z,1674760382.732 [transit:Transit:A.Pitch](DEBUG): Construct. 2023-01-26T19:13:02.735Z,1674760382.735 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2023-01-26T19:13:02.751Z,1674760382.751 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2023-01-26T19:13:02.773Z,1674760382.773 [MissionManager](DEBUG): mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.xml assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.xml assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2023-01-26T19:13:02.773Z,1674760382.773 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl 2023-01-26T19:13:03.648Z,1674760383.648 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0058.lzma.bak 2023-01-26T19:13:03.648Z,1674760383.648 [DataOverHttps](INFO): SBD MOMSN=17405592 2023-01-26T19:13:10.301Z,1674760390.301 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T19:13:12.807Z,1674760392.807 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree 2023-01-26T19:13:12.808Z,1674760392.808 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree 2023-01-26T19:13:12.809Z,1674760392.809 [CommandExec](IMPORTANT): got command set transit.Depth 5 meter 2023-01-26T19:13:12.809Z,1674760392.809 [CommandExec](IMPORTANT): got command set transit.MaxDepth 10 meter 2023-01-26T19:13:12.810Z,1674760392.810 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.1 kilometer 2023-01-26T19:13:12.810Z,1674760392.810 [CommandExec](IMPORTANT): got command run 2023-01-26T19:13:12.862Z,1674760392.862 [CommandExec](IMPORTANT): Running 2023-01-26T19:13:13.152Z,1674760393.152 [Default] Stopped 2023-01-26T19:13:13.152Z,1674760393.152 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T19:13:13.152Z,1674760393.152 [Default:B.GoToSurface] Stopped 2023-01-26T19:13:13.152Z,1674760393.152 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T19:13:13.152Z,1674760393.152 [Default:CheckIn] Stopped 2023-01-26T19:13:13.153Z,1674760393.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T19:13:13.153Z,1674760393.153 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T19:13:13.153Z,1674760393.153 [MissionManager](IMPORTANT): Started mission transit 2023-01-26T19:13:13.153Z,1674760393.153 [transit] Running Loop=1 2023-01-26T19:13:13.153Z,1674760393.153 [transit](DEBUG): Aggregate::initialize transit 2023-01-26T19:13:13.153Z,1674760393.153 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T19:13:13.153Z,1674760393.153 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2023-01-26T19:13:13.153Z,1674760393.153 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T19:13:13.154Z,1674760393.154 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2023-01-26T19:13:13.154Z,1674760393.154 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T19:13:13.154Z,1674760393.154 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2023-01-26T19:13:13.154Z,1674760393.154 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T19:13:13.154Z,1674760393.154 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2023-01-26T19:13:13.154Z,1674760393.154 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T19:13:13.154Z,1674760393.154 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-01-26T19:13:13.154Z,1674760393.154 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T19:13:13.154Z,1674760393.154 [transit:MassHold.Pitch](DEBUG): Initialize. 2023-01-26T19:13:13.155Z,1674760393.155 [transit:B] Running Loop=1 2023-01-26T19:13:13.155Z,1674760393.155 [transit:MassHold.Pitch] Running Loop=1 2023-01-26T19:13:13.155Z,1674760393.155 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2023-01-26T19:13:13.156Z,1674760393.156 [transit:StandardEnvelopes] Running Loop=1 2023-01-26T19:13:13.156Z,1674760393.156 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2023-01-26T19:13:13.156Z,1674760393.156 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2023-01-26T19:13:13.156Z,1674760393.156 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2023-01-26T19:13:13.157Z,1674760393.157 [transit:B] Stopped 2023-01-26T19:13:13.157Z,1674760393.157 [transit:C] Running Loop=1 2023-01-26T19:13:13.571Z,1674760393.571 [transit:C] Stopped 2023-01-26T19:13:13.571Z,1674760393.571 [transit:E] Running Loop=1 2023-01-26T19:13:13.953Z,1674760393.953 [transit:E] Stopped 2023-01-26T19:13:13.953Z,1674760393.953 [transit:F] Running Loop=1 2023-01-26T19:13:14.362Z,1674760394.362 [transit:F] Stopped 2023-01-26T19:13:14.362Z,1674760394.362 [transit:G] Running Loop=1 2023-01-26T19:13:14.766Z,1674760394.766 [transit:G] Stopped 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit] Running Loop=1 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit:A.Pitch](DEBUG): Initialize. 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2023-01-26T19:13:14.766Z,1674760394.766 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2023-01-26T19:13:14.767Z,1674760394.767 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2023-01-26T19:13:14.768Z,1674760394.768 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2023-01-26T19:13:14.768Z,1674760394.768 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2023-01-26T19:13:15.187Z,1674760395.187 [transit:Transit:B.SetSpeed] Running Loop=1 2023-01-26T19:13:15.187Z,1674760395.187 [transit:Transit:A.Pitch] Running Loop=1 2023-01-26T19:13:22.944Z,1674760402.944 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230126T172023/Courier0061.lzma 2023-01-26T19:13:23.946Z,1674760403.946 [DataOverHttps](INFO): Moved sent file to Logs/20230126T172023/Courier0061.lzma.bak 2023-01-26T19:13:23.946Z,1674760403.946 [DataOverHttps](INFO): SBD MOMSN=17405595 2023-01-26T19:14:58.036Z,1674760498.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:15:00.045Z,1674760500.045 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247284 2023-01-26T19:15:32.385Z,1674760532.385 [Radio_Surface](INFO): Powering down 2023-01-26T19:15:33.177Z,1674760533.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:15:34.180Z,1674760534.180 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-01-26T19:20:36.026Z,1674760836.026 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T19:30:36.418Z,1674761436.418 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T19:40:36.718Z,1674762036.718 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T19:42:38.227Z,1674762158.227 [transit:NeedComms] Running Loop=1 2023-01-26T19:42:38.227Z,1674762158.227 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2023-01-26T19:42:38.227Z,1674762158.227 [transit:NeedComms:B.GoToSurface] Running Loop=1 2023-01-26T19:42:38.227Z,1674762158.227 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T19:42:38.227Z,1674762158.227 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2023-01-26T19:42:38.228Z,1674762158.228 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2023-01-26T19:42:38.228Z,1674762158.228 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2023-01-26T19:42:38.229Z,1674762158.229 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2023-01-26T19:42:38.229Z,1674762158.229 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T19:42:38.229Z,1674762158.229 [transit:NeedComms:A] Running Loop=1 2023-01-26T19:42:38.231Z,1674762158.231 [transit:NeedComms:A](INFO): last time_fix was: 1674760357.000000 second since 1970/01/01T00:00:00Z 2023-01-26T19:42:38.231Z,1674762158.231 [transit:NeedComms:A] Stopped 2023-01-26T19:43:00.462Z,1674762180.462 [transit:NeedComms:C] Running Loop=1 2023-01-26T19:43:00.874Z,1674762180.874 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-01-26T19:43:01.108Z,1674762181.108 [Radio_Surface](INFO): Powering up 2023-01-26T19:43:07.072Z,1674762187.072 [DataOverHttps](INFO): Radio surface powered ON. 2023-01-26T19:43:13.364Z,1674762193.364 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-01-26T19:43:13.366Z,1674762193.366 [BPC1](INFO): Received data from all battery sticks. 2023-01-26T19:43:14.569Z,1674762194.569 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11078, MT Status=1, MTMSN=375 2023-01-26T19:43:14.569Z,1674762194.569 [NAL9602](INFO): Data available in MT queue 2023-01-26T19:43:15.056Z,1674762195.056 [NAL9602](INFO): Received command: stop 2023-01-26T19:43:15.130Z,1674762195.130 [CommandExec](IMPORTANT): got command stop 2023-01-26T19:43:15.130Z,1674762195.130 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-01-26T19:43:15.400Z,1674762195.400 [MissionManager](INFO): MissionManager is completed. 2023-01-26T19:43:15.401Z,1674762195.401 [MissionManager](INFO): Uninitializing Mission transit 2023-01-26T19:43:15.401Z,1674762195.401 [transit] Stopped 2023-01-26T19:43:15.401Z,1674762195.401 [transit](DEBUG): Aggregate::uninitialize transit 2023-01-26T19:43:15.401Z,1674762195.401 [transit:NeedComms] Stopped 2023-01-26T19:43:15.401Z,1674762195.401 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2023-01-26T19:43:15.401Z,1674762195.401 [transit:NeedComms:B.GoToSurface] Stopped 2023-01-26T19:43:15.401Z,1674762195.401 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T19:43:15.401Z,1674762195.401 [transit:NeedComms:C] Stopped 2023-01-26T19:43:15.401Z,1674762195.401 [transit:StandardEnvelopes] Stopped 2023-01-26T19:43:15.401Z,1674762195.401 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2023-01-26T19:43:15.401Z,1674762195.401 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2023-01-26T19:43:15.401Z,1674762195.401 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2023-01-26T19:43:15.401Z,1674762195.401 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2023-01-26T19:43:15.402Z,1674762195.402 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2023-01-26T19:43:15.402Z,1674762195.402 [transit:BuoyancyHold.Buoyancy] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-01-26T19:43:15.402Z,1674762195.402 [transit:MassHold.Pitch] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit:A.Pitch] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit:B.SetSpeed] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit:Wpt1.Waypoint] Stopped 2023-01-26T19:43:15.402Z,1674762195.402 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2023-01-26T19:43:15.817Z,1674762195.817 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T19:43:15.817Z,1674762195.817 [Default] Running Loop=1 2023-01-26T19:43:15.818Z,1674762195.818 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T19:43:15.818Z,1674762195.818 [Default:B.GoToSurface] Running Loop=1 2023-01-26T19:43:15.818Z,1674762195.818 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T19:43:15.818Z,1674762195.818 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T19:43:15.818Z,1674762195.818 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T19:43:15.819Z,1674762195.819 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T19:43:15.819Z,1674762195.819 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T19:43:15.819Z,1674762195.819 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T19:43:15.820Z,1674762195.820 [Default:A.Wait] Running Loop=1 2023-01-26T19:43:15.820Z,1674762195.820 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T19:43:16.187Z,1674762196.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194315.00,A,3647.73003,N,12149.19809,W,1.244,256.67,260123,,,A*77 2023-01-26T19:43:16.189Z,1674762196.189 [NAL9602](INFO): GPS fix at 20230126T194315: (36.795501, -121.819968) 2023-01-26T19:43:16.204Z,1674762196.204 [UniversalFixResidualReporter](INFO): Fix residual: 15.7 %DT, over the last 1753.4 m. Residual distance 274.9 m at bearing 24.6 degrees. Fix at (36.7955, -121.8200) with 1861.1 m made good. 2023-01-26T19:43:29.131Z,1674762209.131 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T19:43:29.131Z,1674762209.131 [Default:A.Wait] Stopped 2023-01-26T19:43:29.131Z,1674762209.131 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T19:43:29.532Z,1674762209.532 [Default:CheckIn] Running Loop=1 2023-01-26T19:43:29.532Z,1674762209.532 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T19:43:29.532Z,1674762209.532 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T19:43:31.545Z,1674762211.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194330.00,A,3647.72855,N,12149.20175,W,0.680,247.47,260123,,,A*7D 2023-01-26T19:43:31.547Z,1674762211.547 [NAL9602](INFO): GPS fix at 20230126T194330: (36.795476, -121.820029) 2023-01-26T19:43:31.559Z,1674762211.559 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T19:43:31.559Z,1674762211.559 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T19:43:37.196Z,1674762217.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:44:06.059Z,1674762246.059 [NAL9602](INFO): SBD MO Status=1, MOMSN=11079, MT Status=0, MTMSN=0 2023-01-26T19:44:06.108Z,1674762246.108 [NAL9602](INFO): Sent 94 bytes from file Logs/20230126T172023/Courier0064.lzma 2023-01-26T19:44:06.109Z,1674762246.109 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:44:12.337Z,1674762252.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:44:15.417Z,1674762255.417 [NAL9602](INFO): SBD MO Status=1, MOMSN=11080, MT Status=0, MTMSN=0 2023-01-26T19:44:15.466Z,1674762255.466 [NAL9602](INFO): Sent 190 bytes from file Logs/20230126T172023/Express0056.lzma 2023-01-26T19:44:15.466Z,1674762255.466 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:44:23.206Z,1674762263.206 [NAL9602](INFO): SBD MO Status=1, MOMSN=11081, MT Status=0, MTMSN=0 2023-01-26T19:44:23.260Z,1674762263.260 [NAL9602](INFO): Sent 61 bytes from file Logs/20230126T172023/Express0059.lzma 2023-01-26T19:44:23.261Z,1674762263.261 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:44:32.974Z,1674762272.974 [NAL9602](INFO): SBD MO Status=1, MOMSN=11082, MT Status=0, MTMSN=0 2023-01-26T19:44:33.030Z,1674762273.030 [NAL9602](INFO): Sent 203 bytes from file Logs/20230126T172023/Express0062.lzma 2023-01-26T19:44:33.030Z,1674762273.030 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:44:41.543Z,1674762281.543 [NAL9602](INFO): SBD MO Status=1, MOMSN=11083, MT Status=0, MTMSN=0 2023-01-26T19:44:41.596Z,1674762281.596 [NAL9602](INFO): Sent 332 bytes from file Logs/20230126T172023/Express0065.lzma 2023-01-26T19:44:41.596Z,1674762281.596 [NAL9602](INFO): Packets left to send: 2 2023-01-26T19:44:47.492Z,1674762287.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:44:49.686Z,1674762289.686 [NAL9602](INFO): SBD MO Status=1, MOMSN=11084, MT Status=0, MTMSN=0 2023-01-26T19:44:49.740Z,1674762289.740 [NAL9602](INFO): Sent 332 bytes from file Logs/20230126T172023/Express0065.lzma 2023-01-26T19:44:49.740Z,1674762289.740 [NAL9602](INFO): Packets left to send: 1 2023-01-26T19:45:07.605Z,1674762307.605 [NAL9602](INFO): SBD MO Status=2, MOMSN=11085, MT Status=2, MTMSN=0 2023-01-26T19:45:07.605Z,1674762307.605 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-01-26T19:45:22.640Z,1674762322.640 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:45:22.679Z,1674762322.679 [NAL9602](INFO): SBD MO Status=1, MOMSN=11085, MT Status=0, MTMSN=0 2023-01-26T19:45:22.728Z,1674762322.728 [NAL9602](INFO): Sent 29 bytes from file Logs/20230126T172023/Express0065.lzma 2023-01-26T19:45:22.729Z,1674762322.729 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:45:31.159Z,1674762331.159 [NAL9602](INFO): SBD MO Status=0, MOMSN=11086, MT Status=0, MTMSN=0 2023-01-26T19:45:48.579Z,1674762348.579 [NAL9602](INFO): SBD MO Status=1, MOMSN=11087, MT Status=0, MTMSN=0 2023-01-26T19:45:48.632Z,1674762348.632 [NAL9602](INFO): Sent 39 bytes from file Logs/20230126T172023/Courier0067.lzma 2023-01-26T19:45:48.632Z,1674762348.632 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:45:57.784Z,1674762357.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-01-26T19:45:59.475Z,1674762359.475 [NAL9602](INFO): SBD MO Status=1, MOMSN=11088, MT Status=0, MTMSN=0 2023-01-26T19:45:59.528Z,1674762359.528 [NAL9602](INFO): Sent 71 bytes from file Logs/20230126T172023/Express0068.lzma 2023-01-26T19:45:59.528Z,1674762359.528 [NAL9602](INFO): Packets left to send: 0 2023-01-26T19:46:14.427Z,1674762374.427 [NAL9602](INFO): SBD MO Status=0, MOMSN=11089, MT Status=0, MTMSN=0 2023-01-26T19:46:14.517Z,1674762374.517 [Default:CheckIn:Read_Iridium] Stopped 2023-01-26T19:46:14.518Z,1674762374.518 [Default:CheckIn:C.Wait] Running Loop=1 2023-01-26T19:46:14.518Z,1674762374.518 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-01-26T19:46:30.285Z,1674762390.285 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003404 2023-01-26T19:46:45.129Z,1674762405.129 [NAL9602](INFO): Not Powering down - fast GPS 2023-01-26T19:49:42.657Z,1674762582.657 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T194941 2023-01-26T19:49:52.588Z,1674762592.588 [DataOverHttps](INFO): Received command: configSet Express linearApproximation height_above_sea_floor 5 meter persist 2023-01-26T19:49:53.597Z,1674762593.597 [DataOverHttps](IMPORTANT): SBD MTMSN=20230126T194952 2023-01-26T19:49:54.059Z,1674762594.059 [CommandExec](IMPORTANT): got command configSet Express linearApproximation height_above_sea_floor 5 meter persist 2023-01-26T19:50:01.028Z,1674762601.028 [DataOverHttps](INFO): Received command: restart app 2023-01-26T19:50:01.061Z,1674762601.061 [CommandExec](IMPORTANT): got command restart application 2023-01-26T19:50:02.064Z,1674762602.064 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:02.065Z,1674762602.065 [CommandExec](INFO): Uninitializing the command executive. 2023-01-26T19:50:02.065Z,1674762602.065 [CommandExec](INFO): Uninitializing the command scheduler. 2023-01-26T19:50:02.065Z,1674762602.065 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:02.092Z,1674762602.092 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-01-26T19:50:02.092Z,1674762602.092 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-01-26T19:50:02.092Z,1674762602.092 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:02.093Z,1674762602.093 [NavChartDb](INFO): Join timeout helper Thread ID is 2956 2023-01-26T19:50:02.464Z,1674762602.464 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:02.465Z,1674762602.464 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:02.468Z,1674762602.468 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-01-26T19:50:02.468Z,1674762602.468 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:02.469Z,1674762602.469 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2957 2023-01-26T19:50:02.728Z,1674762602.728 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:02.729Z,1674762602.729 [WetLabsBB2FL](INFO): Powering down 2023-01-26T19:50:02.729Z,1674762602.729 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:02.732Z,1674762602.732 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-01-26T19:50:02.732Z,1674762602.732 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:02.732Z,1674762602.732 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2958 2023-01-26T19:50:03.649Z,1674762603.649 [CTD_Seabird](INFO): Powering down 2023-01-26T19:50:03.660Z,1674762603.660 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:03.660Z,1674762603.660 [CTD_Seabird](INFO): Powering down 2023-01-26T19:50:03.672Z,1674762603.672 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:03.684Z,1674762603.684 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-01-26T19:50:03.684Z,1674762603.684 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:03.684Z,1674762603.684 [Radio_Surface](INFO): Join timeout helper Thread ID is 2959 2023-01-26T19:50:03.772Z,1674762603.772 [Radio_Surface](INFO): Powering down 2023-01-26T19:50:03.773Z,1674762603.773 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:03.773Z,1674762603.773 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:03.792Z,1674762603.792 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-01-26T19:50:03.792Z,1674762603.792 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:03.792Z,1674762603.792 [Onboard](INFO): Join timeout helper Thread ID is 2960 2023-01-26T19:50:04.036Z,1674762604.036 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-01-26T19:50:05.452Z,1674762605.452 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:05.452Z,1674762605.452 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:05.476Z,1674762605.476 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-01-26T19:50:05.476Z,1674762605.476 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:05.476Z,1674762605.476 [DataOverHttps](INFO): Join timeout helper Thread ID is 2961 2023-01-26T19:50:06.045Z,1674762606.045 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:06.045Z,1674762606.045 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.049Z,1674762606.049 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-01-26T19:50:06.049Z,1674762606.049 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.049Z,1674762606.049 [BackseatComponent](INFO): Join timeout helper Thread ID is 2962 2023-01-26T19:50:06.236Z,1674762606.236 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:06.236Z,1674762606.236 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.249Z,1674762606.249 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-01-26T19:50:06.249Z,1674762606.249 [logger ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.249Z,1674762606.249 [logger](INFO): Join timeout helper Thread ID is 2963 2023-01-26T19:50:06.284Z,1674762606.284 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:06.284Z,1674762606.284 [logger ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.289Z,1674762606.289 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-01-26T19:50:06.289Z,1674762606.289 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.289Z,1674762606.289 [CommandLine](INFO): Join timeout helper Thread ID is 2964 2023-01-26T19:50:06.312Z,1674762606.312 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:06.312Z,1674762606.312 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.329Z,1674762606.329 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-01-26T19:50:06.329Z,1674762606.329 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.330Z,1674762606.330 [CommandExec](INFO): Join timeout helper Thread ID is 2965 2023-01-26T19:50:06.331Z,1674762606.331 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-01-26T19:50:06.331Z,1674762606.331 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.331Z,1674762606.331 [controlThread](INFO): Join timeout helper Thread ID is 2966 2023-01-26T19:50:06.372Z,1674762606.372 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T19:50:06.372Z,1674762606.372 [controlThread](DEBUG): Uninitializing ControlThread 2023-01-26T19:50:06.373Z,1674762606.373 [AHRS_M2](INFO): Powering down 2023-01-26T19:50:06.445Z,1674762606.445 [NAL9602](INFO): Powering down 2023-01-26T19:50:06.447Z,1674762606.447 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-01-26T19:50:06.448Z,1674762606.448 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-01-26T19:50:06.448Z,1674762606.448 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-01-26T19:50:06.449Z,1674762606.449 [MissionManager](INFO): Uninitializing Mission Default 2023-01-26T19:50:06.449Z,1674762606.449 [Default] Stopped 2023-01-26T19:50:06.449Z,1674762606.449 [Default](DEBUG): Aggregate::uninitialize Default 2023-01-26T19:50:06.449Z,1674762606.449 [Default:B.GoToSurface] Stopped 2023-01-26T19:50:06.449Z,1674762606.449 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T19:50:06.449Z,1674762606.449 [Default:CheckIn] Stopped 2023-01-26T19:50:06.450Z,1674762606.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-01-26T19:50:06.450Z,1674762606.450 [Default:CheckIn:C.Wait] Stopped 2023-01-26T19:50:06.450Z,1674762606.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T19:50:06.452Z,1674762606.452 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-01-26T19:50:06.453Z,1674762606.453 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-01-26T19:50:06.453Z,1674762606.453 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-01-26T19:50:06.453Z,1674762606.453 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-01-26T19:50:06.454Z,1674762606.454 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-01-26T19:50:06.454Z,1674762606.454 [BuoyancyServo](INFO): Powering down 2023-01-26T19:50:06.468Z,1674762606.468 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-01-26T19:50:06.468Z,1674762606.468 [ElevatorServo](INFO): Powering down 2023-01-26T19:50:06.469Z,1674762606.469 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-01-26T19:50:06.469Z,1674762606.469 [MassServo](INFO): Powering down 2023-01-26T19:50:06.470Z,1674762606.470 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-01-26T19:50:06.470Z,1674762606.470 [RudderServo](INFO): Powering down 2023-01-26T19:50:06.471Z,1674762606.471 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-01-26T19:50:06.471Z,1674762606.471 [ThrusterHE](INFO): Powering down 2023-01-26T19:50:06.472Z,1674762606.472 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-01-26T19:50:06.472Z,1674762606.472 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-01-26T19:50:06.473Z,1674762606.473 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-01-26T19:50:06.473Z,1674762606.473 [CBIT](DEBUG): Powering off loads. 2023-01-26T19:50:06.484Z,1674762606.484 [CBIT](DEBUG): Disabling WDT. 2023-01-26T19:50:06.496Z,1674762606.496 [CBIT](DEBUG): Opening all GF detection circuits. 2023-01-26T19:50:06.497Z,1674762606.497 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.521Z,1674762606.521 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.525Z,1674762606.525 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.531Z,1674762606.531 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.575Z,1674762606.575 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.625Z,1674762606.625 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.629Z,1674762606.629 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.683Z,1674762606.683 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-26T19:50:06.727Z,1674762606.727 [logger ThreadHandler](INFO): Thread cancelled.