2023-06-05T18:54:28.901Z,1685991268.901 [Supervisor](DEBUG): Initializing supervisor. 2023-06-05T18:54:28.906Z,1685991268.906 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-06-05T18:54:28.906Z,1685991268.906 [SyncHandler](INFO): Protected caller Thread ID is 837 2023-06-05T18:54:28.907Z,1685991268.907 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-06-05T18:54:28.908Z,1685991268.908 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-06-05T18:54:28.908Z,1685991268.908 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2023-06-05T18:54:28.912Z,1685991268.912 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-06-05T18:54:28.930Z,1685991268.930 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-06-05T18:54:28.931Z,1685991268.931 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-06-05T18:54:28.931Z,1685991268.931 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2023-06-05T18:54:28.935Z,1685991268.935 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-06-05T18:54:28.936Z,1685991268.936 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-06-05T18:54:28.936Z,1685991268.936 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2023-06-05T18:54:28.938Z,1685991268.938 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-06-05T18:54:28.939Z,1685991268.939 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-06-05T18:54:28.940Z,1685991268.940 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2023-06-05T18:54:28.943Z,1685991268.943 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-06-05T18:54:28.944Z,1685991268.944 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-06-05T18:54:28.948Z,1685991268.948 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-06-05T18:54:29.546Z,1685991269.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-06-05T18:54:29.548Z,1685991269.548 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-06-05T18:54:29.769Z,1685991269.769 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-06-05T18:54:29.771Z,1685991269.771 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-06-05T18:54:29.854Z,1685991269.854 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-06-05T18:54:30.084Z,1685991270.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-06-05T18:54:30.086Z,1685991270.086 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-06-05T18:54:30.168Z,1685991270.168 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-06-05T18:54:30.267Z,1685991270.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-06-05T18:54:30.268Z,1685991270.268 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-06-05T18:54:30.650Z,1685991270.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-06-05T18:54:30.651Z,1685991270.651 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-06-05T18:54:30.789Z,1685991270.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-06-05T18:54:30.791Z,1685991270.791 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-06-05T18:54:31.309Z,1685991271.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-06-05T18:54:31.311Z,1685991271.311 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-06-05T18:54:31.505Z,1685991271.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-06-05T18:54:31.507Z,1685991271.507 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-06-05T18:54:31.927Z,1685991271.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-06-05T18:54:31.929Z,1685991271.929 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-06-05T18:54:32.314Z,1685991272.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-06-05T18:54:32.780Z,1685991272.780 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-06-05T18:54:33.449Z,1685991273.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-06-05T18:54:33.451Z,1685991273.451 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-06-05T18:54:34.278Z,1685991274.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-06-05T18:54:34.279Z,1685991274.279 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-06-05T18:54:34.630Z,1685991274.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-06-05T18:54:34.630Z,1685991274.630 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-06-05T18:54:34.871Z,1685991274.871 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-06-05T18:54:34.873Z,1685991274.873 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2023-06-05T18:54:34.875Z,1685991274.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2023-06-05T18:54:35.000Z,1685991275.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2023-06-05T18:54:35.085Z,1685991275.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2023-06-05T18:54:35.228Z,1685991275.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2023-06-05T18:54:35.314Z,1685991275.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2023-06-05T18:54:35.587Z,1685991275.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-06-05T18:54:35.588Z,1685991275.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2023-06-05T18:54:35.693Z,1685991275.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2023-06-05T18:54:35.802Z,1685991275.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2023-06-05T18:54:35.918Z,1685991275.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2023-06-05T18:54:36.068Z,1685991276.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2023-06-05T18:54:36.169Z,1685991276.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2023-06-05T18:54:36.266Z,1685991276.266 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2023-06-05T18:54:36.266Z,1685991276.266 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-06-05T18:54:36.283Z,1685991276.283 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-06-05T18:54:36.299Z,1685991276.299 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-06-05T18:54:36.301Z,1685991276.301 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-06-05T18:54:36.433Z,1685991276.433 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-06-05T18:54:36.434Z,1685991276.434 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-06-05T18:54:36.504Z,1685991276.504 [VerticalControl](DEBUG): Construct VerticalControl. 2023-06-05T18:54:36.566Z,1685991276.566 [VerticalControl] Loaded 2023-06-05T18:54:36.566Z,1685991276.566 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-06-05T18:54:36.569Z,1685991276.569 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-06-05T18:54:36.611Z,1685991276.611 [HorizontalControl] Loaded 2023-06-05T18:54:36.611Z,1685991276.611 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-06-05T18:54:36.613Z,1685991276.613 [SpeedControl](DEBUG): Construct SpeedControl. 2023-06-05T18:54:36.617Z,1685991276.617 [SpeedControl] Loaded 2023-06-05T18:54:36.617Z,1685991276.617 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-06-05T18:54:36.619Z,1685991276.619 [LoopControl](DEBUG): Construct LoopControl. 2023-06-05T18:54:36.620Z,1685991276.620 [LoopControl] Loaded 2023-06-05T18:54:36.620Z,1685991276.620 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-06-05T18:54:36.621Z,1685991276.621 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-06-05T18:54:36.621Z,1685991276.621 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-06-05T18:54:36.808Z,1685991276.808 [BuoyancyServo] Loaded 2023-06-05T18:54:36.808Z,1685991276.808 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-06-05T18:54:36.829Z,1685991276.829 [ElevatorServo] Loaded 2023-06-05T18:54:36.829Z,1685991276.829 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-06-05T18:54:36.849Z,1685991276.849 [MassServo] Loaded 2023-06-05T18:54:36.849Z,1685991276.849 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-06-05T18:54:36.868Z,1685991276.868 [RudderServo] Loaded 2023-06-05T18:54:36.868Z,1685991276.868 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-06-05T18:54:36.883Z,1685991276.883 [ThrusterHE] Loaded 2023-06-05T18:54:36.884Z,1685991276.884 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-06-05T18:54:36.884Z,1685991276.884 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-06-05T18:54:36.885Z,1685991276.885 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-06-05T18:54:36.956Z,1685991276.956 [DepthRateCalculator] Loaded 2023-06-05T18:54:36.957Z,1685991276.957 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-06-05T18:54:36.962Z,1685991276.962 [PitchRateCalculator] Loaded 2023-06-05T18:54:36.962Z,1685991276.962 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-06-05T18:54:36.972Z,1685991276.972 [SpeedCalculator] Loaded 2023-06-05T18:54:36.972Z,1685991276.972 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-06-05T18:54:36.977Z,1685991276.977 [YawRateCalculator] Loaded 2023-06-05T18:54:36.977Z,1685991276.977 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-06-05T18:54:36.996Z,1685991276.996 [ElevatorOffsetCalculator] Loaded 2023-06-05T18:54:36.996Z,1685991276.996 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-06-05T18:54:36.997Z,1685991276.997 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-06-05T18:54:36.997Z,1685991276.997 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-06-05T18:54:37.141Z,1685991277.141 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-06-05T18:54:37.141Z,1685991277.141 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-06-05T18:54:37.155Z,1685991277.155 [NavChart] Loaded 2023-06-05T18:54:37.155Z,1685991277.155 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-06-05T18:54:37.161Z,1685991277.161 [UniversalFixResidualReporter] Loaded 2023-06-05T18:54:37.161Z,1685991277.161 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-06-05T18:54:37.162Z,1685991277.162 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-06-05T18:54:37.163Z,1685991277.163 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-06-05T18:54:37.925Z,1685991277.925 [AHRS_M2] Loaded 2023-06-05T18:54:37.926Z,1685991277.926 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-06-05T18:54:38.176Z,1685991278.176 [BackseatComponent] Loaded 2023-06-05T18:54:38.176Z,1685991278.176 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-06-05T18:54:38.177Z,1685991278.177 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0 2023-06-05T18:54:38.178Z,1685991278.178 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 2023-06-05T18:54:38.181Z,1685991278.181 [LcmUniversalReporter] Loaded 2023-06-05T18:54:38.181Z,1685991278.181 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-06-05T18:54:38.181Z,1685991278.181 [Module Loader](CRITICAL): Could not find element BioacousticsDataBridge.loadAtStartup 2023-06-05T18:54:39.033Z,1685991279.033 [BPC1] Loaded 2023-06-05T18:54:39.033Z,1685991279.033 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-06-05T18:54:39.175Z,1685991279.175 [DataOverHttps] Loaded 2023-06-05T18:54:39.175Z,1685991279.175 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-06-05T18:54:39.176Z,1685991279.176 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409154E0 2023-06-05T18:54:39.177Z,1685991279.177 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925 2023-06-05T18:54:39.199Z,1685991279.199 [Depth_Keller] Loaded 2023-06-05T18:54:39.199Z,1685991279.199 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-06-05T18:54:39.204Z,1685991279.204 [DropWeight] Loaded 2023-06-05T18:54:39.205Z,1685991279.205 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-06-05T18:54:39.269Z,1685991279.269 [NAL9602] Loaded 2023-06-05T18:54:39.269Z,1685991279.269 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-06-05T18:54:39.301Z,1685991279.301 [Onboard] Loaded 2023-06-05T18:54:39.301Z,1685991279.301 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-06-05T18:54:39.302Z,1685991279.302 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409454E0 2023-06-05T18:54:39.302Z,1685991279.302 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926 2023-06-05T18:54:39.315Z,1685991279.315 [Power24vConverter] Loaded 2023-06-05T18:54:39.315Z,1685991279.315 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-06-05T18:54:39.329Z,1685991279.329 [Radio_Surface] Loaded 2023-06-05T18:54:39.329Z,1685991279.329 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-06-05T18:54:39.330Z,1685991279.330 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409754E0 2023-06-05T18:54:39.330Z,1685991279.330 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927 2023-06-05T18:54:39.345Z,1685991279.345 [Sonardyne_Nano] Loaded 2023-06-05T18:54:39.345Z,1685991279.345 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-06-05T18:54:39.346Z,1685991279.346 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-06-05T18:54:39.346Z,1685991279.346 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-06-05T18:54:39.740Z,1685991279.740 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-06-05T18:54:39.918Z,1685991279.918 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-06-05T18:54:40.109Z,1685991280.109 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-06-05T18:54:40.110Z,1685991280.110 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-06-05T18:54:40.154Z,1685991280.154 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-06-05T18:54:40.155Z,1685991280.155 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-06-05T18:54:40.379Z,1685991280.379 [CTD_Seabird] Loaded 2023-06-05T18:54:40.379Z,1685991280.379 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-06-05T18:54:40.380Z,1685991280.380 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2023-06-05T18:54:40.381Z,1685991280.381 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2023-06-05T18:54:40.401Z,1685991280.401 [PAR_Licor] Loaded 2023-06-05T18:54:40.402Z,1685991280.402 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-06-05T18:54:40.434Z,1685991280.434 [WetLabsBB2FL] Loaded 2023-06-05T18:54:40.434Z,1685991280.434 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-06-05T18:54:40.435Z,1685991280.435 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0 2023-06-05T18:54:40.436Z,1685991280.436 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2023-06-05T18:54:40.437Z,1685991280.437 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-06-05T18:54:40.437Z,1685991280.437 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-06-05T18:54:40.811Z,1685991280.811 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-06-05T18:54:40.811Z,1685991280.811 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-06-05T18:54:40.995Z,1685991280.995 [SBIT](DEBUG): Construct Startup Built In Test. 2023-06-05T18:54:41.005Z,1685991281.005 [SBIT] Loaded 2023-06-05T18:54:41.005Z,1685991281.005 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-06-05T18:54:41.008Z,1685991281.008 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-06-05T18:54:41.021Z,1685991281.021 [IBIT] Loaded 2023-06-05T18:54:41.021Z,1685991281.021 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-06-05T18:54:41.027Z,1685991281.027 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-06-05T18:54:41.137Z,1685991281.137 [CBIT] Loaded 2023-06-05T18:54:41.137Z,1685991281.137 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-06-05T18:54:41.138Z,1685991281.138 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-06-05T18:54:41.144Z,1685991281.144 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-06-05T18:54:41.147Z,1685991281.147 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-06-05T18:54:41.158Z,1685991281.158 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-06-05T18:54:41.159Z,1685991281.159 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0 2023-06-05T18:54:41.159Z,1685991281.159 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2023-06-05T18:54:41.164Z,1685991281.164 [Supervisor](INFO): Main Thread ID is 830 2023-06-05T18:54:41.164Z,1685991281.164 [Supervisor](DEBUG): Running supervisor. 2023-06-05T18:54:41.165Z,1685991281.165 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2023-06-05T18:54:41.165Z,1685991281.165 [CommandExec](INFO): Initializing the command executive. 2023-06-05T18:54:41.166Z,1685991281.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2023-06-05T18:54:41.169Z,1685991281.169 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2023-06-05T18:54:41.169Z,1685991281.169 [controlThread](DEBUG): Initializing ControlThread 2023-06-05T18:54:41.170Z,1685991281.170 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-06-05T18:54:41.172Z,1685991281.172 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-06-05T18:54:41.173Z,1685991281.173 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-06-05T18:54:41.173Z,1685991281.173 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-06-05T18:54:41.174Z,1685991281.174 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-06-05T18:54:41.175Z,1685991281.175 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-06-05T18:54:41.175Z,1685991281.175 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-06-05T18:54:41.176Z,1685991281.176 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-06-05T18:54:41.176Z,1685991281.176 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-06-05T18:54:41.177Z,1685991281.177 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-06-05T18:54:41.178Z,1685991281.178 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-06-05T18:54:41.184Z,1685991281.184 [SBIT](INFO): Initialize SBIT Component. 2023-06-05T18:54:41.185Z,1685991281.185 [SBIT](IMPORTANT): git: 2023-06-05-2-g9e32b0e98 2023-06-05T18:54:41.185Z,1685991281.185 [SBIT](INFO): git hash: 9e32b0e981350fee9bf4704d824d093f5851367a 2023-06-05T18:54:41.185Z,1685991281.185 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-06-05T18:54:41.186Z,1685991281.186 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-06-05T18:54:41.187Z,1685991281.187 [SBIT](INFO): Beginning SBIT in 120.000000 seconds. 2023-06-05T18:54:41.188Z,1685991281.188 [IBIT](INFO): Initialize IBIT Component. 2023-06-05T18:54:41.189Z,1685991281.189 [CBIT](DEBUG): Initialize CBIT Component. 2023-06-05T18:54:41.190Z,1685991281.190 [logger ThreadHandler](INFO): Handler Thread ID is 934 2023-06-05T18:54:41.200Z,1685991281.200 [CBIT](DEBUG): Initialized mux pins. 2023-06-05T18:54:41.200Z,1685991281.200 [CBIT](DEBUG): Initializing the watchdog timer. 2023-06-05T18:54:41.208Z,1685991281.208 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2023-06-05T18:54:41.220Z,1685991281.220 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2023-06-05T18:54:41.221Z,1685991281.221 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-06-05T18:54:41.224Z,1685991281.224 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-06-05T18:54:41.224Z,1685991281.224 [CBIT](DEBUG): Initializing heartbeat. 2023-06-05T18:54:41.233Z,1685991281.233 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2023-06-05T18:54:41.250Z,1685991281.250 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2023-06-05T18:54:41.269Z,1685991281.269 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2023-06-05T18:54:41.269Z,1685991281.269 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-06-05T18:54:41.273Z,1685991281.273 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2023-06-05T18:54:41.276Z,1685991281.276 [WetLabsBB2FL](INFO): Powering up 2023-06-05T18:54:41.278Z,1685991281.278 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2023-06-05T18:54:41.285Z,1685991281.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-06-05T18:54:41.285Z,1685991281.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-06-05T18:54:41.285Z,1685991281.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-06-05T18:54:41.285Z,1685991281.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-06-05T18:54:41.286Z,1685991281.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-06-05T18:54:41.286Z,1685991281.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-06-05T18:54:41.286Z,1685991281.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-06-05T18:54:41.286Z,1685991281.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-06-05T18:54:41.296Z,1685991281.296 [CBIT](DEBUG): Deactivating GF circuits. 2023-06-05T18:54:41.296Z,1685991281.296 [CBIT](DEBUG): Deactivating emergency mode. 2023-06-05T18:54:41.336Z,1685991281.336 [CBIT](DEBUG): Backplane powered. 2023-06-05T18:54:41.337Z,1685991281.337 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-06-05T18:54:41.364Z,1685991281.364 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-05T18:54:41.404Z,1685991281.404 [MissionManager](DEBUG): 2023-06-05T18:54:41.405Z,1685991281.405 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-06-05T18:54:41.465Z,1685991281.465 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-06-05T18:54:41.466Z,1685991281.466 [Default:A.Wait](DEBUG): Construct Wait. 2023-06-05T18:54:41.482Z,1685991281.482 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-05T18:54:41.536Z,1685991281.536 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-06-05T18:54:41.539Z,1685991281.539 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-06-05T18:54:41.561Z,1685991281.561 [Default:E.Execute](DEBUG): Construct Execute. 2023-06-05T18:54:41.564Z,1685991281.564 [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-06-05T18:54:41.580Z,1685991281.580 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-06-05T18:54:41.592Z,1685991281.592 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-05T18:54:41.656Z,1685991281.656 [Radio_Surface](INFO): Powering up 2023-06-05T18:54:41.797Z,1685991281.797 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1926.023804 decibar, 0.000000 m 2023-06-05T18:54:41.798Z,1685991281.798 [Power24vConverter](INFO): Powering up. 2023-06-05T18:54:41.799Z,1685991281.799 [Sonardyne_Nano](INFO): Initializing. 2023-06-05T18:54:41.856Z,1685991281.856 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-06-05T18:54:41.888Z,1685991281.888 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-06-05T18:54:41.897Z,1685991281.897 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-06-05T18:54:41.898Z,1685991281.898 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-06-05T18:54:41.904Z,1685991281.904 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-06-05T18:54:41.905Z,1685991281.905 [MassServo](DEBUG): Initializing EZServoServo. 2023-06-05T18:54:41.912Z,1685991281.912 [MassServo](DEBUG): Initializing MassServo. 2023-06-05T18:54:41.913Z,1685991281.913 [RudderServo](DEBUG): Initializing EZServoServo. 2023-06-05T18:54:41.920Z,1685991281.920 [RudderServo](DEBUG): Initializing RudderServo. 2023-06-05T18:54:41.921Z,1685991281.921 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-06-05T18:54:41.928Z,1685991281.928 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-06-05T18:54:41.930Z,1685991281.930 [CommandExec](FAULT): Scheduling is paused 2023-06-05T18:54:41.930Z,1685991281.930 [CBIT](INFO): Critical error at 20230605T185438 2023-06-05T18:54:41.931Z,1685991281.931 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-06-05T18:54:43.384Z,1685991283.384 [WetLabsBB2FL](INFO): Powering down 2023-06-05T18:54:47.513Z,1685991287.513 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-06-05T18:54:48.300Z,1685991288.300 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-06-05T18:55:09.470Z,1685991309.470 [NAL9602](INFO): Powering up NAL9602 2023-06-05T18:55:20.378Z,1685991320.378 [NAL9602](INFO): NAL9602 initialized 2023-06-05T18:55:21.184Z,1685991321.184 [CommandExec](IMPORTANT): got command quit 2023-06-05T18:55:22.188Z,1685991322.188 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:22.188Z,1685991322.188 [CommandExec](INFO): Uninitializing the command executive. 2023-06-05T18:55:22.188Z,1685991322.188 [CommandExec](INFO): Uninitializing the command scheduler. 2023-06-05T18:55:22.188Z,1685991322.188 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:22.336Z,1685991322.336 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-06-05T18:55:22.336Z,1685991322.336 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-06-05T18:55:22.336Z,1685991322.336 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:22.337Z,1685991322.337 [NavChartDb](INFO): Join timeout helper Thread ID is 953 2023-06-05T18:55:22.576Z,1685991322.576 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:22.576Z,1685991322.576 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:22.580Z,1685991322.580 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-06-05T18:55:22.580Z,1685991322.580 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:22.580Z,1685991322.580 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 954 2023-06-05T18:55:22.600Z,1685991322.600 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:22.600Z,1685991322.600 [WetLabsBB2FL](INFO): Powering down 2023-06-05T18:55:22.601Z,1685991322.601 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:22.602Z,1685991322.602 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-06-05T18:55:22.602Z,1685991322.602 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:22.602Z,1685991322.602 [CTD_Seabird](INFO): Join timeout helper Thread ID is 955 2023-06-05T18:55:23.444Z,1685991323.444 [CTD_Seabird](INFO): Powering down 2023-06-05T18:55:23.456Z,1685991323.456 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:23.456Z,1685991323.456 [CTD_Seabird](INFO): Powering down 2023-06-05T18:55:23.468Z,1685991323.468 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:23.493Z,1685991323.493 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-06-05T18:55:23.493Z,1685991323.493 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:23.493Z,1685991323.493 [Radio_Surface](INFO): Join timeout helper Thread ID is 956 2023-06-05T18:55:23.701Z,1685991323.701 [Radio_Surface](INFO): Powering down 2023-06-05T18:55:23.702Z,1685991323.702 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:23.702Z,1685991323.702 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:23.708Z,1685991323.708 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-06-05T18:55:23.708Z,1685991323.708 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:23.708Z,1685991323.708 [Onboard](INFO): Join timeout helper Thread ID is 957 2023-06-05T18:55:26.268Z,1685991326.268 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:26.268Z,1685991326.268 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.290Z,1685991326.290 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-06-05T18:55:26.291Z,1685991326.291 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.291Z,1685991326.291 [DataOverHttps](INFO): Join timeout helper Thread ID is 958 2023-06-05T18:55:26.436Z,1685991326.436 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:26.436Z,1685991326.436 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.453Z,1685991326.453 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-06-05T18:55:26.453Z,1685991326.453 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.453Z,1685991326.453 [BackseatComponent](INFO): Join timeout helper Thread ID is 959 2023-06-05T18:55:26.536Z,1685991326.536 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:26.536Z,1685991326.536 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.541Z,1685991326.541 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-06-05T18:55:26.541Z,1685991326.541 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.541Z,1685991326.541 [logger](INFO): Join timeout helper Thread ID is 960 2023-06-05T18:55:26.552Z,1685991326.552 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:26.552Z,1685991326.552 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.560Z,1685991326.560 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-06-05T18:55:26.561Z,1685991326.561 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.561Z,1685991326.561 [CommandLine](INFO): Join timeout helper Thread ID is 961 2023-06-05T18:55:26.624Z,1685991326.624 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:26.624Z,1685991326.624 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.640Z,1685991326.640 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-06-05T18:55:26.641Z,1685991326.641 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.641Z,1685991326.641 [CommandExec](INFO): Join timeout helper Thread ID is 962 2023-06-05T18:55:26.642Z,1685991326.642 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-06-05T18:55:26.642Z,1685991326.642 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:26.643Z,1685991326.643 [controlThread](INFO): Join timeout helper Thread ID is 963 2023-06-05T18:55:26.828Z,1685991326.828 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-05T18:55:26.828Z,1685991326.828 [controlThread](DEBUG): Uninitializing ControlThread 2023-06-05T18:55:26.829Z,1685991326.829 [AHRS_M2](INFO): Powering down 2023-06-05T18:55:26.901Z,1685991326.901 [NAL9602](INFO): Powering down 2023-06-05T18:55:26.903Z,1685991326.903 [Sonardyne_Nano](INFO): Powering down 2023-06-05T18:55:27.021Z,1685991327.021 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-06-05T18:55:27.021Z,1685991327.021 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-06-05T18:55:27.022Z,1685991327.022 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-06-05T18:55:27.022Z,1685991327.022 [MissionManager](INFO): Uninitializing Mission Default 2023-06-05T18:55:27.025Z,1685991327.025 [MissionManager](INFO): Uninitializing Mission Startup 2023-06-05T18:55:27.026Z,1685991327.026 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-06-05T18:55:27.027Z,1685991327.027 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-06-05T18:55:27.027Z,1685991327.027 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-06-05T18:55:27.027Z,1685991327.027 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-06-05T18:55:27.028Z,1685991327.028 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-06-05T18:55:27.028Z,1685991327.028 [BuoyancyServo](INFO): Powering down 2023-06-05T18:55:27.040Z,1685991327.040 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-06-05T18:55:27.040Z,1685991327.040 [ElevatorServo](INFO): Powering down 2023-06-05T18:55:27.041Z,1685991327.041 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-06-05T18:55:27.041Z,1685991327.041 [MassServo](INFO): Powering down 2023-06-05T18:55:27.042Z,1685991327.042 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-06-05T18:55:27.042Z,1685991327.042 [RudderServo](INFO): Powering down 2023-06-05T18:55:27.043Z,1685991327.043 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-06-05T18:55:27.043Z,1685991327.043 [ThrusterHE](INFO): Powering down 2023-06-05T18:55:27.044Z,1685991327.044 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-06-05T18:55:27.044Z,1685991327.044 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-06-05T18:55:27.045Z,1685991327.045 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-06-05T18:55:27.045Z,1685991327.045 [CBIT](DEBUG): Powering off loads. 2023-06-05T18:55:27.056Z,1685991327.056 [CBIT](DEBUG): Disabling WDT. 2023-06-05T18:55:27.068Z,1685991327.068 [CBIT](DEBUG): Opening all GF detection circuits. 2023-06-05T18:55:27.069Z,1685991327.069 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.357Z,1685991327.357 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.359Z,1685991327.359 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.365Z,1685991327.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.409Z,1685991327.409 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.445Z,1685991327.445 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.448Z,1685991327.448 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.481Z,1685991327.481 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-05T18:55:27.543Z,1685991327.543 [logger ThreadHandler](INFO): Thread cancelled.