2023-05-11T20:13:55.330Z,1683836035.330 [Supervisor](DEBUG): Initializing supervisor. 2023-05-11T20:13:55.335Z,1683836035.335 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-05-11T20:13:55.335Z,1683836035.335 [SyncHandler](INFO): Protected caller Thread ID is 1269 2023-05-11T20:13:55.336Z,1683836035.336 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-05-11T20:13:55.337Z,1683836035.337 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-05-11T20:13:55.338Z,1683836035.338 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1270 2023-05-11T20:13:55.342Z,1683836035.342 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-05-11T20:13:55.359Z,1683836035.359 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-05-11T20:13:55.360Z,1683836035.360 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-05-11T20:13:55.361Z,1683836035.361 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1271 2023-05-11T20:13:55.365Z,1683836035.365 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-05-11T20:13:55.366Z,1683836035.366 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-05-11T20:13:55.366Z,1683836035.366 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1272 2023-05-11T20:13:55.368Z,1683836035.368 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-05-11T20:13:55.369Z,1683836035.369 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-05-11T20:13:55.369Z,1683836035.369 [logger ThreadHandler](INFO): Protected caller Thread ID is 1273 2023-05-11T20:13:55.373Z,1683836035.373 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-05-11T20:13:55.373Z,1683836035.373 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-05-11T20:13:55.375Z,1683836035.375 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-05-11T20:13:55.697Z,1683836035.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-05-11T20:13:55.697Z,1683836035.697 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-05-11T20:13:56.474Z,1683836036.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-05-11T20:13:56.475Z,1683836036.475 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-05-11T20:13:56.580Z,1683836036.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-05-11T20:13:56.581Z,1683836036.581 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-05-11T20:13:57.046Z,1683836037.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-05-11T20:13:57.046Z,1683836037.046 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-05-11T20:13:57.141Z,1683836037.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-05-11T20:13:57.141Z,1683836037.141 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-05-11T20:13:57.221Z,1683836037.221 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-05-11T20:13:57.602Z,1683836037.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-05-11T20:13:57.603Z,1683836037.603 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-05-11T20:13:57.813Z,1683836037.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-05-11T20:13:57.814Z,1683836037.814 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-05-11T20:13:57.946Z,1683836037.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-05-11T20:13:57.946Z,1683836037.946 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-05-11T20:13:58.169Z,1683836038.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-05-11T20:13:58.170Z,1683836038.170 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-05-11T20:13:58.665Z,1683836038.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-05-11T20:13:58.666Z,1683836038.666 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-05-11T20:13:59.023Z,1683836039.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-05-11T20:13:59.024Z,1683836039.024 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-05-11T20:13:59.102Z,1683836039.102 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-05-11T20:13:59.279Z,1683836039.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-05-11T20:13:59.280Z,1683836039.280 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-05-11T20:13:59.490Z,1683836039.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-05-11T20:13:59.491Z,1683836039.491 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-05-11T20:13:59.773Z,1683836039.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-05-11T20:13:59.774Z,1683836039.774 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2023-05-11T20:13:59.775Z,1683836039.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2023-05-11T20:13:59.881Z,1683836039.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2023-05-11T20:14:00.022Z,1683836040.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2023-05-11T20:14:00.105Z,1683836040.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2023-05-11T20:14:00.187Z,1683836040.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2023-05-11T20:14:00.292Z,1683836040.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2023-05-11T20:14:00.458Z,1683836040.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2023-05-11T20:14:00.715Z,1683836040.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-05-11T20:14:00.716Z,1683836040.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2023-05-11T20:14:00.842Z,1683836040.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2023-05-11T20:14:00.933Z,1683836040.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2023-05-11T20:14:01.034Z,1683836041.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2023-05-11T20:14:01.154Z,1683836041.154 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2023-05-11T20:14:01.154Z,1683836041.154 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-05-11T20:14:01.184Z,1683836041.184 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-05-11T20:14:01.393Z,1683836041.393 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-05-11T20:14:01.393Z,1683836041.393 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-05-11T20:14:01.411Z,1683836041.411 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-05-11T20:14:01.418Z,1683836041.418 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-05-11T20:14:01.481Z,1683836041.481 [DepthRateCalculator] Loaded 2023-05-11T20:14:01.481Z,1683836041.481 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-05-11T20:14:01.487Z,1683836041.487 [PitchRateCalculator] Loaded 2023-05-11T20:14:01.487Z,1683836041.487 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-05-11T20:14:01.497Z,1683836041.497 [SpeedCalculator] Loaded 2023-05-11T20:14:01.497Z,1683836041.497 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-05-11T20:14:01.512Z,1683836041.512 [VerticalHomogeneityIndexCalculator] Loaded 2023-05-11T20:14:01.512Z,1683836041.512 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread. 2023-05-11T20:14:01.517Z,1683836041.517 [YawRateCalculator] Loaded 2023-05-11T20:14:01.517Z,1683836041.517 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-05-11T20:14:01.577Z,1683836041.577 [ElevatorOffsetCalculator] Loaded 2023-05-11T20:14:01.577Z,1683836041.577 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-05-11T20:14:01.578Z,1683836041.578 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-05-11T20:14:01.578Z,1683836041.578 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-05-11T20:14:01.669Z,1683836041.669 [VerticalControl](DEBUG): Construct VerticalControl. 2023-05-11T20:14:01.806Z,1683836041.806 [VerticalControl] Loaded 2023-05-11T20:14:01.806Z,1683836041.806 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-05-11T20:14:01.809Z,1683836041.809 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-05-11T20:14:01.881Z,1683836041.881 [HorizontalControl] Loaded 2023-05-11T20:14:01.882Z,1683836041.882 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-05-11T20:14:01.909Z,1683836041.909 [SpeedControl](DEBUG): Construct SpeedControl. 2023-05-11T20:14:01.929Z,1683836041.929 [SpeedControl] Loaded 2023-05-11T20:14:01.929Z,1683836041.929 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-05-11T20:14:01.932Z,1683836041.932 [LoopControl](DEBUG): Construct LoopControl. 2023-05-11T20:14:01.932Z,1683836041.932 [LoopControl] Loaded 2023-05-11T20:14:01.933Z,1683836041.933 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-05-11T20:14:01.933Z,1683836041.933 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-05-11T20:14:01.934Z,1683836041.934 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-05-11T20:14:02.231Z,1683836042.231 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-05-11T20:14:02.231Z,1683836042.231 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-05-11T20:14:02.245Z,1683836042.245 [NavChart] Loaded 2023-05-11T20:14:02.245Z,1683836042.245 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-05-11T20:14:02.250Z,1683836042.250 [UniversalFixResidualReporter] Loaded 2023-05-11T20:14:02.250Z,1683836042.250 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-05-11T20:14:02.251Z,1683836042.251 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-05-11T20:14:02.251Z,1683836042.251 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-05-11T20:14:02.400Z,1683836042.400 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-05-11T20:14:02.401Z,1683836042.401 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-05-11T20:14:02.833Z,1683836042.833 [AHRS_M2] Loaded 2023-05-11T20:14:02.833Z,1683836042.833 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-05-11T20:14:02.867Z,1683836042.867 [BackseatComponent] Loaded 2023-05-11T20:14:02.868Z,1683836042.868 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-05-11T20:14:02.869Z,1683836042.869 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0 2023-05-11T20:14:02.869Z,1683836042.869 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1355 2023-05-11T20:14:02.872Z,1683836042.872 [LcmUniversalReporter] Loaded 2023-05-11T20:14:02.872Z,1683836042.872 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-05-11T20:14:03.684Z,1683836043.684 [BPC1] Loaded 2023-05-11T20:14:03.685Z,1683836043.685 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-05-11T20:14:03.759Z,1683836043.759 [DataOverHttps] Loaded 2023-05-11T20:14:03.759Z,1683836043.759 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-05-11T20:14:03.760Z,1683836043.760 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409264E0 2023-05-11T20:14:03.760Z,1683836043.760 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1356 2023-05-11T20:14:03.781Z,1683836043.781 [Depth_Keller] Loaded 2023-05-11T20:14:03.781Z,1683836043.781 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-05-11T20:14:03.786Z,1683836043.786 [DropWeight] Loaded 2023-05-11T20:14:03.786Z,1683836043.786 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-05-11T20:14:03.823Z,1683836043.823 [DVL_micro] Loaded 2023-05-11T20:14:03.823Z,1683836043.823 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2023-05-11T20:14:03.883Z,1683836043.883 [NAL9602] Loaded 2023-05-11T20:14:03.884Z,1683836043.884 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-05-11T20:14:03.913Z,1683836043.913 [Onboard] Loaded 2023-05-11T20:14:03.914Z,1683836043.914 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-05-11T20:14:03.914Z,1683836043.914 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409564E0 2023-05-11T20:14:03.915Z,1683836043.915 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1357 2023-05-11T20:14:03.927Z,1683836043.927 [Power24vConverter] Loaded 2023-05-11T20:14:03.927Z,1683836043.927 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-05-11T20:14:03.940Z,1683836043.940 [Radio_Surface] Loaded 2023-05-11T20:14:03.940Z,1683836043.940 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-05-11T20:14:03.941Z,1683836043.941 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409864E0 2023-05-11T20:14:03.942Z,1683836043.942 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1358 2023-05-11T20:14:04.032Z,1683836044.032 [DAT] Loaded 2023-05-11T20:14:04.032Z,1683836044.032 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-05-11T20:14:04.033Z,1683836044.033 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409B64E0 2023-05-11T20:14:04.034Z,1683836044.034 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1359 2023-05-11T20:14:04.034Z,1683836044.034 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-05-11T20:14:04.035Z,1683836044.035 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-05-11T20:14:04.164Z,1683836044.164 [BuoyancyServo] Loaded 2023-05-11T20:14:04.164Z,1683836044.164 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-05-11T20:14:04.184Z,1683836044.184 [ElevatorServo] Loaded 2023-05-11T20:14:04.185Z,1683836044.185 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-05-11T20:14:04.204Z,1683836044.204 [MassServo] Loaded 2023-05-11T20:14:04.204Z,1683836044.204 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-05-11T20:14:04.223Z,1683836044.223 [RudderServo] Loaded 2023-05-11T20:14:04.223Z,1683836044.223 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-05-11T20:14:04.237Z,1683836044.237 [ThrusterHE] Loaded 2023-05-11T20:14:04.237Z,1683836044.237 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-05-11T20:14:04.238Z,1683836044.238 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-05-11T20:14:04.238Z,1683836044.238 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-05-11T20:14:04.308Z,1683836044.308 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.100000 degC 2023-05-11T20:14:04.308Z,1683836044.308 [StratificationFrontDetector](INFO): (re)initializing 2023-05-11T20:14:04.308Z,1683836044.308 [StratificationFrontDetector] Loaded 2023-05-11T20:14:04.309Z,1683836044.309 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2023-05-11T20:14:04.309Z,1683836044.309 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-05-11T20:14:04.310Z,1683836044.310 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-05-11T20:14:04.336Z,1683836044.336 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-05-11T20:14:04.336Z,1683836044.336 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-05-11T20:14:04.636Z,1683836044.636 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-05-11T20:14:04.636Z,1683836044.636 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-05-11T20:14:04.803Z,1683836044.803 [CTD_Seabird] Loaded 2023-05-11T20:14:04.803Z,1683836044.803 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-05-11T20:14:04.804Z,1683836044.804 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B494E0 2023-05-11T20:14:04.805Z,1683836044.805 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1360 2023-05-11T20:14:04.838Z,1683836044.838 [ESPComponent] Loaded 2023-05-11T20:14:04.839Z,1683836044.839 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-05-11T20:14:04.858Z,1683836044.858 [PAR_Licor] Loaded 2023-05-11T20:14:04.859Z,1683836044.859 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-05-11T20:14:04.889Z,1683836044.889 [WetLabsBB2FL] Loaded 2023-05-11T20:14:04.889Z,1683836044.889 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-05-11T20:14:04.890Z,1683836044.890 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B794E0 2023-05-11T20:14:04.891Z,1683836044.891 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1361 2023-05-11T20:14:04.891Z,1683836044.891 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-05-11T20:14:04.892Z,1683836044.892 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-05-11T20:14:05.031Z,1683836045.031 [SBIT](DEBUG): Construct Startup Built In Test. 2023-05-11T20:14:05.040Z,1683836045.040 [SBIT] Loaded 2023-05-11T20:14:05.041Z,1683836045.041 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-05-11T20:14:05.044Z,1683836045.044 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-05-11T20:14:05.056Z,1683836045.056 [IBIT] Loaded 2023-05-11T20:14:05.056Z,1683836045.056 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-05-11T20:14:05.062Z,1683836045.062 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-05-11T20:14:05.165Z,1683836045.165 [CBIT] Loaded 2023-05-11T20:14:05.165Z,1683836045.165 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-05-11T20:14:05.166Z,1683836045.166 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-05-11T20:14:05.172Z,1683836045.172 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-05-11T20:14:05.175Z,1683836045.175 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-05-11T20:14:05.186Z,1683836045.186 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-05-11T20:14:05.187Z,1683836045.187 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BE74E0 2023-05-11T20:14:05.187Z,1683836045.187 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1362 2023-05-11T20:14:05.191Z,1683836045.191 [Supervisor](INFO): Main Thread ID is 827 2023-05-11T20:14:05.192Z,1683836045.192 [Supervisor](DEBUG): Running supervisor. 2023-05-11T20:14:05.192Z,1683836045.192 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1363 2023-05-11T20:14:05.193Z,1683836045.193 [CommandExec](INFO): Initializing the command executive. 2023-05-11T20:14:05.194Z,1683836045.194 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1364 2023-05-11T20:14:05.196Z,1683836045.196 [controlThread ThreadHandler](INFO): Handler Thread ID is 1365 2023-05-11T20:14:05.197Z,1683836045.197 [controlThread](DEBUG): Initializing ControlThread 2023-05-11T20:14:05.198Z,1683836045.198 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-05-11T20:14:05.198Z,1683836045.198 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-05-11T20:14:05.198Z,1683836045.198 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-05-11T20:14:05.199Z,1683836045.199 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing 2023-05-11T20:14:05.199Z,1683836045.199 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-05-11T20:14:05.200Z,1683836045.200 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-05-11T20:14:05.201Z,1683836045.201 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-05-11T20:14:05.202Z,1683836045.202 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-05-11T20:14:05.203Z,1683836045.203 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-05-11T20:14:05.203Z,1683836045.203 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-05-11T20:14:05.204Z,1683836045.204 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-05-11T20:14:05.205Z,1683836045.205 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-05-11T20:14:05.216Z,1683836045.216 [SBIT](INFO): Initialize SBIT Component. 2023-05-11T20:14:05.217Z,1683836045.217 [SBIT](IMPORTANT): git: 2023-05-11 2023-05-11T20:14:05.217Z,1683836045.217 [SBIT](INFO): git hash: 819773fe6d6f007a19954d172147646e14066f04 2023-05-11T20:14:05.217Z,1683836045.217 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-05-11T20:14:05.218Z,1683836045.218 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-05-11T20:14:05.219Z,1683836045.219 [SBIT](INFO): Beginning SBIT in 134.000000 seconds. 2023-05-11T20:14:05.220Z,1683836045.220 [IBIT](INFO): Initialize IBIT Component. 2023-05-11T20:14:05.221Z,1683836045.221 [CBIT](DEBUG): Initialize CBIT Component. 2023-05-11T20:14:05.222Z,1683836045.222 [logger ThreadHandler](INFO): Handler Thread ID is 1366 2023-05-11T20:14:05.232Z,1683836045.232 [CBIT](DEBUG): Initialized mux pins. 2023-05-11T20:14:05.232Z,1683836045.232 [CBIT](DEBUG): Initializing the watchdog timer. 2023-05-11T20:14:05.240Z,1683836045.240 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1367 2023-05-11T20:14:05.252Z,1683836045.252 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1368 2023-05-11T20:14:05.253Z,1683836045.253 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-05-11T20:14:05.256Z,1683836045.256 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-05-11T20:14:05.256Z,1683836045.256 [CBIT](DEBUG): Initializing heartbeat. 2023-05-11T20:14:05.264Z,1683836045.264 [Onboard ThreadHandler](INFO): Handler Thread ID is 1369 2023-05-11T20:14:05.282Z,1683836045.282 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1370 2023-05-11T20:14:05.304Z,1683836045.304 [DAT ThreadHandler](INFO): Handler Thread ID is 1371 2023-05-11T20:14:05.305Z,1683836045.305 [DAT](INFO): Powering up 2023-05-11T20:14:05.305Z,1683836045.305 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:14:05.309Z,1683836045.309 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1373 2023-05-11T20:14:05.310Z,1683836045.310 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-11T20:14:05.313Z,1683836045.313 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1375 2023-05-11T20:14:05.316Z,1683836045.316 [WetLabsBB2FL](INFO): Powering up 2023-05-11T20:14:05.318Z,1683836045.318 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1377 2023-05-11T20:14:05.320Z,1683836045.320 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-05-11T20:14:05.321Z,1683836045.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-05-11T20:14:05.321Z,1683836045.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-05-11T20:14:05.321Z,1683836045.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-05-11T20:14:05.321Z,1683836045.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-05-11T20:14:05.321Z,1683836045.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-05-11T20:14:05.322Z,1683836045.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-05-11T20:14:05.322Z,1683836045.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-05-11T20:14:05.328Z,1683836045.328 [CBIT](DEBUG): Deactivating GF circuits. 2023-05-11T20:14:05.328Z,1683836045.328 [CBIT](DEBUG): Deactivating emergency mode. 2023-05-11T20:14:05.368Z,1683836045.368 [CBIT](DEBUG): Backplane powered. 2023-05-11T20:14:05.369Z,1683836045.369 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-05-11T20:14:05.381Z,1683836045.381 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-11T20:14:05.401Z,1683836045.401 [MissionManager](DEBUG): 2023-05-11T20:14:05.402Z,1683836045.402 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-05-11T20:14:05.489Z,1683836045.489 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-05-11T20:14:05.490Z,1683836045.490 [Default:A.Wait](DEBUG): Construct Wait. 2023-05-11T20:14:05.492Z,1683836045.492 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-11T20:14:05.538Z,1683836045.538 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-05-11T20:14:05.541Z,1683836045.541 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-05-11T20:14:05.560Z,1683836045.560 [Default:E.Execute](DEBUG): Construct Execute. 2023-05-11T20:14:05.563Z,1683836045.563 [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-05-11T20:14:05.592Z,1683836045.592 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-05-11T20:14:05.611Z,1683836045.611 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-05-11T20:14:05.688Z,1683836045.688 [Radio_Surface](INFO): Powering up 2023-05-11T20:14:05.690Z,1683836045.690 [Power24vConverter](INFO): Powering up. 2023-05-11T20:14:05.754Z,1683836045.754 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-05-11T20:14:05.760Z,1683836045.760 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-05-11T20:14:05.761Z,1683836045.761 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-05-11T20:14:05.768Z,1683836045.768 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-05-11T20:14:05.769Z,1683836045.769 [MassServo](DEBUG): Initializing EZServoServo. 2023-05-11T20:14:05.776Z,1683836045.776 [MassServo](DEBUG): Initializing MassServo. 2023-05-11T20:14:05.777Z,1683836045.777 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-11T20:14:05.784Z,1683836045.784 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-11T20:14:05.785Z,1683836045.785 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-05-11T20:14:05.792Z,1683836045.792 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-05-11T20:14:06.049Z,1683836046.049 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-05-11T20:14:06.049Z,1683836046.049 [DropWeight] Hardware Fault, FailCount= 1 2023-05-11T20:14:06.049Z,1683836046.049 [DropWeight](ERROR): Hardware Fault 2023-05-11T20:14:06.089Z,1683836046.089 [CommandExec](FAULT): Scheduling is paused 2023-05-11T20:14:06.089Z,1683836046.089 [CBIT](INFO): Critical error at 20230511T201406 2023-05-11T20:14:06.089Z,1683836046.089 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-05-11T20:14:06.092Z,1683836046.092 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-05-11T20:14:06.100Z,1683836046.100 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-05-11T20:14:06.428Z,1683836046.428 [CBIT](INFO): Critical error at 20230511T201406 2023-05-11T20:14:07.928Z,1683836047.928 [WetLabsBB2FL](INFO): Powering down 2023-05-11T20:14:11.341Z,1683836051.341 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-05-11T20:14:12.341Z,1683836052.341 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-05-11T20:14:33.886Z,1683836073.886 [NAL9602](INFO): Powering up NAL9602 2023-05-11T20:14:35.993Z,1683836075.993 [CommandExec](IMPORTANT): got command gfscan 2023-05-11T20:14:36.361Z,1683836076.361 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-11T20:14:44.798Z,1683836084.798 [NAL9602](INFO): NAL9602 initialized 2023-05-11T20:14:47.281Z,1683836087.281 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.008098 CHAN A1 (24V): -0.002335 CHAN A2 (12V): -0.000320 CHAN A3 (5V): 0.000561 CHAN B0 (3.3V): 0.000333 CHAN B1 (3.15aV): 0.000377 CHAN B2 (3.15bV): -0.000412 CHAN B3 (GND): 0.001035 OPEN: 0.005059 Full Scale: +/- 1 mA 2023-05-11T20:15:05.340Z,1683836105.340 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:15:05.340Z,1683836105.340 [DAT] Communications Fault, FailCount= 1 2023-05-11T20:15:05.341Z,1683836105.341 [DAT](ERROR): Communications Fault 2023-05-11T20:15:05.445Z,1683836105.445 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:15:05.744Z,1683836105.744 [DAT](INFO): Powering down 2023-05-11T20:15:06.684Z,1683836106.684 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:15:06.685Z,1683836106.685 [DAT] No Fault, FailCount= 1 2023-05-11T20:15:08.792Z,1683836108.792 [DAT](INFO): Powering up 2023-05-11T20:15:08.792Z,1683836108.792 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:16:09.036Z,1683836169.036 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:16:09.036Z,1683836169.036 [DAT] Communications Fault, FailCount= 2 2023-05-11T20:16:09.036Z,1683836169.036 [DAT](ERROR): Communications Fault 2023-05-11T20:16:09.262Z,1683836169.262 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:16:09.440Z,1683836169.440 [DAT](INFO): Powering down 2023-05-11T20:16:10.487Z,1683836170.487 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:16:10.487Z,1683836170.487 [DAT] No Fault, FailCount= 2 2023-05-11T20:16:12.488Z,1683836172.488 [DAT](INFO): Powering up 2023-05-11T20:16:12.488Z,1683836172.488 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:16:19.751Z,1683836179.751 [SBIT](IMPORTANT): Beginning Startup BIT 2023-05-11T20:16:19.756Z,1683836179.756 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-11T20:16:28.220Z,1683836188.220 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-05-11T20:16:28.221Z,1683836188.221 [DVL_micro](ERROR): Failed to parse: :TS,000000000.0,+20.9,0000.0,1489.0,000 2023-05-11T20:16:30.788Z,1683836190.788 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003822 CHAN A1 (24V): -0.002409 CHAN A2 (12V): -0.000670 CHAN A3 (5V): 0.000738 CHAN B0 (3.3V): 0.001226 CHAN B1 (3.15aV): 0.000712 CHAN B2 (3.15bV): 0.001313 CHAN B3 (GND): 0.000885 OPEN: 0.004890 Full Scale: +/- 1 mA 2023-05-11T20:16:33.587Z,1683836193.587 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004764 2023-05-11T20:17:05.409Z,1683836225.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-05-11T20:17:05.409Z,1683836225.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:17:05.471Z,1683836225.471 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:17:05.961Z,1683836225.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:17:05.961Z,1683836225.961 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-05-11T20:17:12.496Z,1683836232.496 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:17:12.496Z,1683836232.496 [DAT] Communications Fault, FailCount= 3 2023-05-11T20:17:12.496Z,1683836232.496 [DAT](ERROR): Communications Fault 2023-05-11T20:17:12.693Z,1683836232.693 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:17:12.900Z,1683836232.900 [DAT](INFO): Powering down 2023-05-11T20:17:13.479Z,1683836233.479 [SBIT](IMPORTANT): SBIT PASSED 2023-05-11T20:17:13.479Z,1683836233.479 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-05-11T20:17:13.480Z,1683836233.480 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): Express none CBIT.ampHoursUsed; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index 0.050000 practical_salinity_unit; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): Express none height_above_sea_floor; 2023-05-11T20:17:13.481Z,1683836233.481 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.1 celsius; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=208.617815 cubic_centimeter; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalControl.massDefault=28.688721 millimeter; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth1=3 meter; 2023-05-11T20:17:13.482Z,1683836233.482 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth2=5 meter; 2023-05-11T20:17:13.483Z,1683836233.483 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=10 meter; 2023-05-11T20:17:13.483Z,1683836233.483 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=15 meter; 2023-05-11T20:17:13.483Z,1683836233.483 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool; 2023-05-11T20:17:13.898Z,1683836233.898 [MissionManager](IMPORTANT): Started mission Startup 2023-05-11T20:17:13.898Z,1683836233.898 [Startup] Running Loop=1 2023-05-11T20:17:13.899Z,1683836233.899 [Startup](DEBUG): Aggregate::initialize Startup 2023-05-11T20:17:13.899Z,1683836233.899 [Startup:A.GoToSurface] Running Loop=1 2023-05-11T20:17:13.899Z,1683836233.899 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-11T20:17:13.899Z,1683836233.899 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-11T20:17:13.900Z,1683836233.900 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-11T20:17:13.901Z,1683836233.901 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-11T20:17:13.901Z,1683836233.901 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-11T20:17:13.902Z,1683836233.902 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-11T20:17:13.903Z,1683836233.903 [Startup:StartupSatComms] Running Loop=1 2023-05-11T20:17:13.903Z,1683836233.903 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-05-11T20:17:13.903Z,1683836233.903 [Startup:StartupSatComms:A] Running Loop=1 2023-05-11T20:17:13.956Z,1683836233.956 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:17:13.956Z,1683836233.956 [DAT] No Fault, FailCount= 3 2023-05-11T20:17:14.320Z,1683836234.320 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-05-11T20:17:15.948Z,1683836235.948 [DAT](INFO): Powering up 2023-05-11T20:17:15.948Z,1683836235.948 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:18:14.079Z,1683836294.079 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-11T20:17:13.9Z 2023-05-11T20:18:14.079Z,1683836294.079 [Startup:StartupSatComms:A] Stopped 2023-05-11T20:18:14.079Z,1683836294.079 [Startup:StartupSatComms:B] Running Loop=1 2023-05-11T20:18:14.492Z,1683836294.492 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-05-11T20:18:15.984Z,1683836295.984 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:18:15.984Z,1683836295.984 [DAT] Communications Fault, FailCount= 4 2023-05-11T20:18:15.985Z,1683836295.985 [DAT](ERROR): Communications Fault 2023-05-11T20:18:16.126Z,1683836296.126 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:18:16.388Z,1683836296.388 [DAT](INFO): Powering down 2023-05-11T20:18:17.365Z,1683836297.365 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:18:17.365Z,1683836297.365 [DAT] No Fault, FailCount= 4 2023-05-11T20:18:19.436Z,1683836299.436 [DAT](INFO): Powering up 2023-05-11T20:18:19.436Z,1683836299.436 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:18:48.419Z,1683836328.419 [DVL_micro](ERROR): Failed to parse: :BI,+01085,-01508,+01157,+00000,I 2023-05-11T20:19:14.303Z,1683836354.303 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-11T20:18:14.1Z 2023-05-11T20:19:14.303Z,1683836354.303 [Startup:StartupSatComms:B] Stopped 2023-05-11T20:19:14.303Z,1683836354.303 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-05-11T20:19:14.303Z,1683836354.303 [Startup:StartupSatComms] Stopped 2023-05-11T20:19:14.303Z,1683836354.303 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-05-11T20:19:14.304Z,1683836354.304 [Startup](INFO): Completed Startup 2023-05-11T20:19:14.304Z,1683836354.304 [MissionManager](INFO): Startup is completed. 2023-05-11T20:19:14.305Z,1683836354.305 [MissionManager](INFO): Uninitializing Mission Startup 2023-05-11T20:19:14.305Z,1683836354.305 [Startup] Stopped 2023-05-11T20:19:14.305Z,1683836354.305 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-05-11T20:19:14.305Z,1683836354.305 [Startup:A.GoToSurface] Stopped 2023-05-11T20:19:14.305Z,1683836354.305 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-11T20:19:14.695Z,1683836354.695 [MissionManager](IMPORTANT): Started mission Default 2023-05-11T20:19:14.695Z,1683836354.695 [Default] Running Loop=1 2023-05-11T20:19:14.695Z,1683836354.695 [Default](DEBUG): Aggregate::initialize Default 2023-05-11T20:19:14.695Z,1683836354.695 [Default:B.GoToSurface] Running Loop=1 2023-05-11T20:19:14.695Z,1683836354.695 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-11T20:19:14.695Z,1683836354.695 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-11T20:19:14.720Z,1683836354.720 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-11T20:19:14.720Z,1683836354.720 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-11T20:19:14.721Z,1683836354.721 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-11T20:19:14.721Z,1683836354.721 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-11T20:19:14.721Z,1683836354.721 [Default:A.Wait] Running Loop=1 2023-05-11T20:19:14.721Z,1683836354.721 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-11T20:19:19.672Z,1683836359.672 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:19:19.672Z,1683836359.672 [DAT] Communications Fault, FailCount= 5 2023-05-11T20:19:19.673Z,1683836359.673 [DAT](ERROR): Communications Fault 2023-05-11T20:19:19.988Z,1683836359.988 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:19:20.076Z,1683836360.076 [DAT](INFO): Powering down 2023-05-11T20:19:21.202Z,1683836361.202 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:19:21.202Z,1683836361.202 [DAT] No Fault, FailCount= 5 2023-05-11T20:19:23.132Z,1683836363.132 [DAT](INFO): Powering up 2023-05-11T20:19:23.132Z,1683836363.132 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:19:28.026Z,1683836368.026 [Default:A.Wait](INFO): Done Waiting. 2023-05-11T20:19:28.027Z,1683836368.027 [Default:A.Wait] Stopped 2023-05-11T20:19:28.027Z,1683836368.027 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-11T20:19:28.434Z,1683836368.434 [Default:CheckIn] Running Loop=1 2023-05-11T20:19:28.435Z,1683836368.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-11T20:19:28.435Z,1683836368.435 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-11T20:19:28.869Z,1683836368.869 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-05-11T20:19:48.222Z,1683836388.222 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-05-11T20:20:06.430Z,1683836406.430 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-05-11T20:20:06.430Z,1683836406.430 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:20:06.464Z,1683836406.464 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:20:06.827Z,1683836406.827 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:20:06.827Z,1683836406.827 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-05-11T20:20:23.168Z,1683836423.168 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:20:23.168Z,1683836423.168 [DAT] Communications Fault, FailCount= 6 2023-05-11T20:20:23.168Z,1683836423.168 [DAT](ERROR): Communications Fault 2023-05-11T20:20:23.401Z,1683836423.401 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:20:23.572Z,1683836423.572 [DAT](INFO): Powering down 2023-05-11T20:20:24.597Z,1683836424.597 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:20:24.597Z,1683836424.597 [DAT] No Fault, FailCount= 6 2023-05-11T20:20:26.624Z,1683836426.624 [DAT](INFO): Powering up 2023-05-11T20:20:26.624Z,1683836426.624 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:21:26.868Z,1683836486.868 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:21:26.868Z,1683836486.868 [DAT] Communications Fault, FailCount= 7 2023-05-11T20:21:26.868Z,1683836486.868 [DAT](ERROR): Communications Fault 2023-05-11T20:21:26.892Z,1683836486.892 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:21:27.272Z,1683836487.272 [DAT](INFO): Powering down 2023-05-11T20:21:28.467Z,1683836488.467 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:21:28.467Z,1683836488.467 [DAT] No Fault, FailCount= 7 2023-05-11T20:21:30.316Z,1683836490.316 [DAT](INFO): Powering up 2023-05-11T20:21:30.316Z,1683836490.316 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:21:38.909Z,1683836498.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-05-11T20:21:44.565Z,1683836504.565 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-05-11T20:21:44.571Z,1683836504.571 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5. 2023-05-11T20:21:44.575Z,1683836504.575 [BPC1](INFO): Received data from all battery sticks. 2023-05-11T20:22:30.372Z,1683836550.372 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-11T20:22:30.373Z,1683836550.373 [DAT] Communications Fault, FailCount= 8 2023-05-11T20:22:30.373Z,1683836550.373 [DAT](ERROR): Communications Fault 2023-05-11T20:22:30.654Z,1683836550.654 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-11T20:22:30.655Z,1683836550.655 [CBIT](FAULT): Communications Fault in component: DAT 2023-05-11T20:22:30.788Z,1683836550.788 [DAT](INFO): Powering down 2023-05-11T20:23:07.422Z,1683836587.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-05-11T20:23:07.422Z,1683836587.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:23:07.433Z,1683836587.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:23:07.835Z,1683836587.835 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:23:07.835Z,1683836587.835 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-05-11T20:24:28.611Z,1683836668.611 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T20:19:28.4Z 2023-05-11T20:24:28.612Z,1683836668.612 [Default:CheckIn:Read_GPS] Stopped 2023-05-11T20:24:28.612Z,1683836668.612 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-11T20:24:29.011Z,1683836669.011 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-05-11T20:24:45.970Z,1683836685.970 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-11T20:24:45.970Z,1683836685.970 [NAL9602] Data Fault, FailCount= 1 2023-05-11T20:24:45.970Z,1683836685.970 [NAL9602](ERROR): Data Fault 2023-05-11T20:24:46.010Z,1683836686.010 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-11T20:24:46.373Z,1683836686.373 [NAL9602](INFO): Powering down 2023-05-11T20:24:47.203Z,1683836687.203 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-11T20:24:47.203Z,1683836687.203 [NAL9602] No Fault, FailCount= 1 2023-05-11T20:25:16.674Z,1683836716.674 [NAL9602](INFO): Powering up NAL9602 2023-05-11T20:25:27.582Z,1683836727.582 [NAL9602](INFO): NAL9602 initialized 2023-05-11T20:25:46.166Z,1683836746.166 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-05-11T20:26:08.390Z,1683836768.390 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-05-11T20:26:08.390Z,1683836768.390 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:26:08.424Z,1683836768.424 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:26:08.806Z,1683836768.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:26:08.806Z,1683836768.806 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-05-11T20:27:30.447Z,1683836850.447 [CBIT](INFO): Clearing failed state for component DAT 2023-05-11T20:27:30.447Z,1683836850.447 [DAT] No Fault, FailCount= 8 2023-05-11T20:27:30.632Z,1683836850.632 [DAT](INFO): Powering up 2023-05-11T20:27:30.632Z,1683836850.632 [DAT](DEBUG): Initializing DAT. 2023-05-11T20:27:42.476Z,1683836862.476 [DAT](INFO): DAT read: 2023-05-11T20:27:42.478Z,1683836862.478 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-05-11T20:27:43.989Z,1683836863.989 [DAT](INFO): DAT read: MF Frequency Band 2023-05-11T20:27:43.990Z,1683836863.990 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-05-11T20:27:43.991Z,1683836863.991 [DAT](INFO): DAT read: May 11 2023 20:27:49 2023-05-11T20:27:45.249Z,1683836865.249 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-05-11T20:27:45.250Z,1683836865.250 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-05-11T20:27:45.251Z,1683836865.251 [DAT](INFO): commRate: 800 2023-05-11T20:27:47.320Z,1683836867.320 [DAT](INFO): entering command mode 2023-05-11T20:27:47.520Z,1683836867.520 [DAT](INFO): DAT read: 2023-05-11T20:27:47.521Z,1683836867.521 [DAT](INFO): DAT read: user:1> 2023-05-11T20:27:47.521Z,1683836867.521 [DAT](INFO): setting verbose to 3 2023-05-11T20:27:47.773Z,1683836867.773 [DAT](INFO): DAT read: user:1> 2023-05-11T20:27:47.774Z,1683836867.774 [DAT](INFO): DAT read: Verbose | 3 2023-05-11T20:27:47.774Z,1683836867.774 [DAT](INFO): set verbose to 3 2023-05-11T20:27:47.774Z,1683836867.774 [DAT](INFO): setting DatVerbose to 27440 2023-05-11T20:27:48.025Z,1683836868.025 [DAT](INFO): DAT read: user:2> 2023-05-11T20:27:48.026Z,1683836868.026 [DAT](INFO): DAT read: DatVerbose | 27440 2023-05-11T20:27:48.026Z,1683836868.026 [DAT](INFO): set DatVerbose to 27440 2023-05-11T20:27:48.026Z,1683836868.026 [DAT](INFO): setting transmit power to 8 2023-05-11T20:27:48.277Z,1683836868.277 [DAT](INFO): DAT read: user:3> 2023-05-11T20:27:48.278Z,1683836868.278 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-05-11T20:27:48.278Z,1683836868.278 [DAT](INFO): set transmit power to 8 2023-05-11T20:27:48.278Z,1683836868.278 [DAT](INFO): setting local address to 11 2023-05-11T20:27:48.529Z,1683836868.529 [DAT](INFO): DAT read: user:4> 2023-05-11T20:27:48.530Z,1683836868.530 [DAT](INFO): DAT read: LocalAddr | 11 2023-05-11T20:27:48.530Z,1683836868.530 [DAT](INFO): set local address to 11 2023-05-11T20:27:48.531Z,1683836868.531 [DAT](INFO): Setting time to: 20:27:48 And date to:5/11/2023 2023-05-11T20:27:48.781Z,1683836868.781 [DAT](INFO): DAT read: user:5> 2023-05-11T20:27:48.781Z,1683836868.781 [DAT](INFO): DAT read: Thu May 11, 2023 20:27:48 2023-05-11T20:27:48.782Z,1683836868.782 [DAT](INFO): Local DAT time set to Thu May 11, 2023 20:27:48 2023-05-11T20:29:06.176Z,1683836946.176 [CBIT](INFO): Clearing failed state for component DropWeight 2023-05-11T20:29:06.176Z,1683836946.176 [DropWeight] No Fault, FailCount= 1 2023-05-11T20:29:09.385Z,1683836949.385 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-05-11T20:29:09.385Z,1683836949.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:29:09.396Z,1683836949.396 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:29:09.813Z,1683836949.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:29:09.813Z,1683836949.813 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-05-11T20:30:26.156Z,1683837026.156 [DVL_micro](ERROR): Failed to parse: :000000000,35.0,+21.1,0000.0,1489.0,000 2023-05-11T20:30:48.609Z,1683837048.609 [DAT](INFO): DAT read: user:6>Lowpower 2023-05-11T20:30:48.609Z,1683837048.609 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower 2023-05-11T20:31:02.149Z,1683837062.149 [DVL_micro](ERROR): only read 3 of 4 data items 2023-05-11T20:31:02.150Z,1683837062.150 [DVL_micro](ERROR): Failed to parse: :BI,-00061,-01199,+00781,+ 2023-05-11T20:32:10.473Z,1683837130.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-05-11T20:32:10.473Z,1683837130.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:32:10.521Z,1683837130.521 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:32:10.937Z,1683837130.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:32:10.937Z,1683837130.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-05-11T20:32:46.472Z,1683837166.472 [DVL_micro](ERROR): DVL uart error: serial timeout 2023-05-11T20:32:46.472Z,1683837166.472 [DVL_micro] Communications Fault, FailCount= 1 2023-05-11T20:32:46.472Z,1683837166.472 [DVL_micro](ERROR): Communications Fault 2023-05-11T20:32:46.473Z,1683837166.473 [DVL_micro](ERROR): Failed to parse: 2023-05-11T20:32:46.536Z,1683837166.536 [CBIT](ERROR): Communications Fault in component: DVL_micro 2023-05-11T20:32:46.884Z,1683837166.884 [DVL_micro](INFO): Powering down 2023-05-11T20:32:47.664Z,1683837167.664 [CBIT](INFO): Clearing failed state for component DVL_micro 2023-05-11T20:32:47.664Z,1683837167.664 [DVL_micro] No Fault, FailCount= 1 2023-05-11T20:35:11.469Z,1683837311.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-05-11T20:35:11.469Z,1683837311.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:35:11.504Z,1683837311.504 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:35:11.867Z,1683837311.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:35:11.867Z,1683837311.867 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-05-11T20:35:29.626Z,1683837329.626 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-11T20:35:29.627Z,1683837329.627 [NAL9602] Data Fault, FailCount= 2 2023-05-11T20:35:29.627Z,1683837329.627 [NAL9602](ERROR): Data Fault 2023-05-11T20:35:29.695Z,1683837329.695 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-11T20:35:30.022Z,1683837330.022 [NAL9602](INFO): Powering down 2023-05-11T20:35:30.900Z,1683837330.900 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-11T20:35:30.900Z,1683837330.900 [NAL9602] No Fault, FailCount= 2 2023-05-11T20:36:00.327Z,1683837360.327 [NAL9602](INFO): Powering up NAL9602 2023-05-11T20:36:11.230Z,1683837371.230 [NAL9602](INFO): NAL9602 initialized 2023-05-11T20:36:11.639Z,1683837371.639 [DVL_micro](ERROR): only read 3 of 4 data items 2023-05-11T20:36:11.640Z,1683837371.640 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999..99 2023-05-11T20:38:12.441Z,1683837492.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-05-11T20:38:12.441Z,1683837492.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:38:12.452Z,1683837492.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:38:12.879Z,1683837492.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:38:12.879Z,1683837492.879 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-05-11T20:38:39.105Z,1683837519.105 [DVL_micro](ERROR): Failed to parse: :SA,-01.67,+01.08,298.3 2023-05-11T20:41:01.773Z,1683837661.773 [DVL_micro](ERROR): only read 1 of 4 data items 2023-05-11T20:41:01.774Z,1683837661.774 [DVL_micro](ERROR): Failed to parse: :BI,+01246+01202,+00000,I 2023-05-11T20:41:13.511Z,1683837673.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-05-11T20:41:13.511Z,1683837673.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:41:13.554Z,1683837673.554 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:41:13.922Z,1683837673.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:41:13.923Z,1683837673.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-05-11T20:42:35.916Z,1683837755.916 [DVL_micro](ERROR): only read 3 of 4 data items 2023-05-11T20:42:35.916Z,1683837755.916 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.999,+9999.99 2023-05-11T20:44:14.522Z,1683837854.522 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-05-11T20:44:14.523Z,1683837854.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:44:14.546Z,1683837854.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:44:14.934Z,1683837854.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:44:14.935Z,1683837854.935 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-05-11T20:46:12.447Z,1683837972.447 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-11T20:46:12.448Z,1683837972.448 [NAL9602] Data Fault, FailCount= 3 2023-05-11T20:46:12.448Z,1683837972.448 [NAL9602](ERROR): Data Fault 2023-05-11T20:46:12.533Z,1683837972.533 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-11T20:46:12.842Z,1683837972.842 [NAL9602](INFO): Powering down 2023-05-11T20:46:13.683Z,1683837973.683 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-11T20:46:13.683Z,1683837973.683 [NAL9602] No Fault, FailCount= 3 2023-05-11T20:46:43.167Z,1683838003.167 [NAL9602](INFO): Powering up NAL9602 2023-05-11T20:46:54.058Z,1683838014.058 [NAL9602](INFO): NAL9602 initialized 2023-05-11T20:47:15.474Z,1683838035.474 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-05-11T20:47:15.474Z,1683838035.474 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:47:15.484Z,1683838035.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:47:15.903Z,1683838035.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:47:15.903Z,1683838035.903 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-05-11T20:47:41.726Z,1683838061.726 [DVL_micro](ERROR): Failed to parse: 45,-01091,+01808,+00000,I 2023-05-11T20:50:16.466Z,1683838216.466 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-05-11T20:50:16.467Z,1683838216.467 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:50:16.499Z,1683838216.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:50:16.919Z,1683838216.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:50:16.919Z,1683838216.919 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-05-11T20:50:42.312Z,1683838242.312 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-05-11T20:50:42.312Z,1683838242.312 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+21.,1489.0,000 2023-05-11T20:53:17.544Z,1683838397.544 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-05-11T20:53:17.544Z,1683838397.544 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:53:17.563Z,1683838397.563 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:53:17.976Z,1683838397.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:53:17.976Z,1683838397.976 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-05-11T20:55:19.847Z,1683838519.847 [DVL_micro](ERROR): Failed to parse: :SA,-01.71,+01.03,298.4 2023-05-11T20:56:18.469Z,1683838578.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2023-05-11T20:56:18.469Z,1683838578.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:56:18.480Z,1683838578.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:56:18.882Z,1683838578.882 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:56:18.882Z,1683838578.882 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2023-05-11T20:56:55.186Z,1683838615.186 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-05-11T20:56:55.186Z,1683838615.186 [NAL9602] Data Fault, FailCount= 4 2023-05-11T20:56:55.186Z,1683838615.186 [NAL9602](ERROR): Data Fault 2023-05-11T20:56:55.251Z,1683838615.251 [CBIT](ERROR): Data Fault in component: NAL9602 2023-05-11T20:56:55.590Z,1683838615.590 [NAL9602](INFO): Powering down 2023-05-11T20:56:56.425Z,1683838616.425 [CBIT](INFO): Clearing failed state for component NAL9602 2023-05-11T20:56:56.425Z,1683838616.425 [NAL9602] No Fault, FailCount= 4 2023-05-11T20:57:25.894Z,1683838645.894 [NAL9602](INFO): Powering up NAL9602 2023-05-11T20:57:36.798Z,1683838656.798 [NAL9602](INFO): NAL9602 initialized 2023-05-11T20:57:57.813Z,1683838677.813 [DVL_micro](ERROR): only read 0 of 4 data items 2023-05-11T20:57:57.814Z,1683838677.814 [DVL_micro](ERROR): Failed to parse: :BI-01502,+01234,+00000,I 2023-05-11T20:59:07.697Z,1683838747.697 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-05-11T20:59:07.697Z,1683838747.697 [DropWeight] Hardware Fault, FailCount= 1 2023-05-11T20:59:07.697Z,1683838747.697 [DropWeight](ERROR): Hardware Fault 2023-05-11T20:59:07.773Z,1683838747.773 [CBIT](INFO): Critical error at 20230511T205907 2023-05-11T20:59:07.788Z,1683838747.788 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-05-11T20:59:07.789Z,1683838747.789 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-05-11T20:59:08.145Z,1683838748.145 [CBIT](INFO): Critical error at 20230511T205907 2023-05-11T20:59:19.446Z,1683838759.446 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2023-05-11T20:59:19.446Z,1683838759.446 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T20:59:19.473Z,1683838759.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T20:59:19.856Z,1683838759.856 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T20:59:19.856Z,1683838759.856 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2023-05-11T21:00:22.847Z,1683838822.847 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2023-05-11T21:00:22.848Z,1683838822.848 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+21.6,0000.,000 2023-05-11T21:02:20.455Z,1683838940.455 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2023-05-11T21:02:20.455Z,1683838940.455 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T21:02:20.474Z,1683838940.474 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T21:02:20.878Z,1683838940.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T21:02:20.878Z,1683838940.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2023-05-11T21:02:47.905Z,1683838967.905 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2023-05-11T21:05:21.450Z,1683839121.450 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2023-05-11T21:05:21.450Z,1683839121.450 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-11T21:05:21.488Z,1683839121.488 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-11T21:05:21.867Z,1683839121.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-11T21:05:21.867Z,1683839121.867 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2023-05-11T21:07:15.965Z,1683839235.965 [CommandExec](IMPORTANT): got command restart application 2023-05-11T21:07:16.968Z,1683839236.968 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:16.968Z,1683839236.968 [CommandExec](INFO): Uninitializing the command executive. 2023-05-11T21:07:16.968Z,1683839236.968 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-11T21:07:16.969Z,1683839236.969 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:17.052Z,1683839237.052 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-11T21:07:17.052Z,1683839237.052 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-11T21:07:17.052Z,1683839237.052 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:17.053Z,1683839237.053 [NavChartDb](INFO): Join timeout helper Thread ID is 1459 2023-05-11T21:07:17.257Z,1683839237.257 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:17.257Z,1683839237.257 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:17.274Z,1683839237.274 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-11T21:07:17.274Z,1683839237.274 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:17.300Z,1683839237.300 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1460 2023-05-11T21:07:17.348Z,1683839237.348 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:17.349Z,1683839237.349 [WetLabsBB2FL](INFO): Powering down 2023-05-11T21:07:17.349Z,1683839237.349 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:17.360Z,1683839237.360 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-11T21:07:17.360Z,1683839237.360 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:17.361Z,1683839237.361 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1461 2023-05-11T21:07:18.352Z,1683839238.352 [CTD_Seabird](INFO): Powering down 2023-05-11T21:07:18.364Z,1683839238.364 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:18.364Z,1683839238.364 [CTD_Seabird](INFO): Powering down 2023-05-11T21:07:18.387Z,1683839238.387 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:18.404Z,1683839238.404 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-05-11T21:07:18.404Z,1683839238.404 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:18.405Z,1683839238.405 [DAT](INFO): Join timeout helper Thread ID is 1462 2023-05-11T21:07:18.448Z,1683839238.448 [DAT](INFO): Powering down 2023-05-11T21:07:18.521Z,1683839238.521 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:18.521Z,1683839238.521 [DAT](INFO): Powering down 2023-05-11T21:07:18.522Z,1683839238.522 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:18.532Z,1683839238.532 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-11T21:07:18.532Z,1683839238.532 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:18.533Z,1683839238.533 [Radio_Surface](INFO): Join timeout helper Thread ID is 1463 2023-05-11T21:07:18.872Z,1683839238.872 [Radio_Surface](INFO): Powering down 2023-05-11T21:07:18.873Z,1683839238.873 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:18.873Z,1683839238.873 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:18.881Z,1683839238.881 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-11T21:07:18.881Z,1683839238.881 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:18.881Z,1683839238.881 [Onboard](INFO): Join timeout helper Thread ID is 1464 2023-05-11T21:07:23.068Z,1683839243.068 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:23.068Z,1683839243.068 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.089Z,1683839243.089 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-11T21:07:23.089Z,1683839243.089 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.089Z,1683839243.089 [DataOverHttps](INFO): Join timeout helper Thread ID is 1465 2023-05-11T21:07:23.272Z,1683839243.272 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:23.275Z,1683839243.275 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.281Z,1683839243.281 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-05-11T21:07:23.281Z,1683839243.281 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.281Z,1683839243.281 [BackseatComponent](INFO): Join timeout helper Thread ID is 1466 2023-05-11T21:07:23.324Z,1683839243.324 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:23.324Z,1683839243.324 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.331Z,1683839243.331 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-11T21:07:23.331Z,1683839243.331 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.332Z,1683839243.332 [logger](INFO): Join timeout helper Thread ID is 1467 2023-05-11T21:07:23.375Z,1683839243.375 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:23.375Z,1683839243.375 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.389Z,1683839243.389 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-11T21:07:23.389Z,1683839243.389 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.389Z,1683839243.389 [CommandLine](INFO): Join timeout helper Thread ID is 1468 2023-05-11T21:07:23.488Z,1683839243.488 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:23.488Z,1683839243.488 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.509Z,1683839243.509 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-11T21:07:23.509Z,1683839243.509 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.509Z,1683839243.509 [CommandExec](INFO): Join timeout helper Thread ID is 1469 2023-05-11T21:07:23.510Z,1683839243.510 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-11T21:07:23.510Z,1683839243.510 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:23.511Z,1683839243.511 [controlThread](INFO): Join timeout helper Thread ID is 1470 2023-05-11T21:07:23.816Z,1683839243.816 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-11T21:07:23.816Z,1683839243.816 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-11T21:07:23.817Z,1683839243.817 [AHRS_M2](INFO): Powering down 2023-05-11T21:07:23.960Z,1683839243.960 [DVL_micro](INFO): Powering down 2023-05-11T21:07:23.961Z,1683839243.961 [NAL9602](INFO): Powering down 2023-05-11T21:07:23.964Z,1683839243.964 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-11T21:07:23.965Z,1683839243.965 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-11T21:07:23.966Z,1683839243.966 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-11T21:07:23.966Z,1683839243.966 [MissionManager](INFO): Uninitializing Mission Default 2023-05-11T21:07:23.966Z,1683839243.966 [Default] Stopped 2023-05-11T21:07:23.966Z,1683839243.966 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-11T21:07:23.966Z,1683839243.966 [Default:B.GoToSurface] Stopped 2023-05-11T21:07:23.966Z,1683839243.966 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-11T21:07:23.966Z,1683839243.966 [Default:CheckIn] Stopped 2023-05-11T21:07:23.967Z,1683839243.967 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-11T21:07:23.967Z,1683839243.967 [Default:CheckIn:Read_Iridium] Stopped 2023-05-11T21:07:23.970Z,1683839243.970 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-11T21:07:23.970Z,1683839243.970 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-11T21:07:23.970Z,1683839243.970 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-11T21:07:23.970Z,1683839243.970 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-11T21:07:23.971Z,1683839243.971 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-11T21:07:23.971Z,1683839243.971 [BuoyancyServo](INFO): Powering down 2023-05-11T21:07:23.984Z,1683839243.984 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-11T21:07:23.984Z,1683839243.984 [ElevatorServo](INFO): Powering down 2023-05-11T21:07:23.985Z,1683839243.985 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-11T21:07:23.985Z,1683839243.985 [MassServo](INFO): Powering down 2023-05-11T21:07:23.986Z,1683839243.986 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-11T21:07:23.986Z,1683839243.986 [RudderServo](INFO): Powering down 2023-05-11T21:07:23.987Z,1683839243.987 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-05-11T21:07:23.987Z,1683839243.987 [ThrusterHE](INFO): Powering down 2023-05-11T21:07:23.988Z,1683839243.988 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-11T21:07:23.988Z,1683839243.988 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-11T21:07:23.989Z,1683839243.989 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-11T21:07:23.989Z,1683839243.989 [CBIT](DEBUG): Powering off loads. 2023-05-11T21:07:23.000Z,1683839244.000 [CBIT](DEBUG): Disabling WDT. 2023-05-11T21:07:24.012Z,1683839244.012 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-11T21:07:24.013Z,1683839244.013 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.064Z,1683839244.064 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.069Z,1683839244.069 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.071Z,1683839244.071 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.078Z,1683839244.078 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.122Z,1683839244.122 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.173Z,1683839244.173 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.178Z,1683839244.178 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.199Z,1683839244.199 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-11T21:07:24.259Z,1683839244.259 [logger ThreadHandler](INFO): Thread cancelled.