2023-09-27T16:25:35.987Z,1695831935.987 [Supervisor](DEBUG): Initializing supervisor. 2023-09-27T16:25:35.991Z,1695831935.991 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-09-27T16:25:35.992Z,1695831935.992 [SyncHandler](INFO): Protected caller Thread ID is 4545 2023-09-27T16:25:35.992Z,1695831935.992 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-09-27T16:25:35.993Z,1695831935.993 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-09-27T16:25:35.993Z,1695831935.993 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4546 2023-09-27T16:25:35.997Z,1695831935.997 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-09-27T16:25:36.014Z,1695831936.014 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-09-27T16:25:36.015Z,1695831936.015 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-09-27T16:25:36.016Z,1695831936.016 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4547 2023-09-27T16:25:36.020Z,1695831936.020 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-09-27T16:25:36.021Z,1695831936.021 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-09-27T16:25:36.021Z,1695831936.021 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4548 2023-09-27T16:25:36.023Z,1695831936.023 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-09-27T16:25:36.024Z,1695831936.024 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-09-27T16:25:36.024Z,1695831936.024 [logger ThreadHandler](INFO): Protected caller Thread ID is 4549 2023-09-27T16:25:36.028Z,1695831936.028 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-09-27T16:25:36.028Z,1695831936.028 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-09-27T16:25:36.030Z,1695831936.030 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-09-27T16:25:36.128Z,1695831936.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-09-27T16:25:36.128Z,1695831936.128 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-09-27T16:25:36.842Z,1695831936.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-09-27T16:25:36.843Z,1695831936.843 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-09-27T16:25:37.067Z,1695831937.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-09-27T16:25:37.068Z,1695831937.068 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-09-27T16:25:37.148Z,1695831937.148 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-09-27T16:25:37.279Z,1695831937.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-09-27T16:25:37.280Z,1695831937.280 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-09-27T16:25:37.361Z,1695831937.361 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-09-27T16:25:37.470Z,1695831937.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-09-27T16:25:37.471Z,1695831937.471 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-09-27T16:25:37.860Z,1695831937.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-09-27T16:25:37.861Z,1695831937.861 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-09-27T16:25:38.355Z,1695831938.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-09-27T16:25:38.356Z,1695831938.356 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-09-27T16:25:38.575Z,1695831938.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-09-27T16:25:38.575Z,1695831938.575 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-09-27T16:25:38.767Z,1695831938.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-09-27T16:25:38.767Z,1695831938.767 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-09-27T16:25:39.289Z,1695831939.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-09-27T16:25:39.289Z,1695831939.289 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-09-27T16:25:39.579Z,1695831939.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-09-27T16:25:39.579Z,1695831939.579 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-09-27T16:25:39.805Z,1695831939.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-09-27T16:25:39.805Z,1695831939.805 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-09-27T16:25:40.197Z,1695831940.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-09-27T16:25:40.198Z,1695831940.198 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-09-27T16:25:40.538Z,1695831940.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-09-27T16:25:40.540Z,1695831940.540 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2023-09-27T16:25:40.541Z,1695831940.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2023-09-27T16:25:40.649Z,1695831940.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2023-09-27T16:25:41.205Z,1695831941.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2023-09-27T16:25:41.318Z,1695831941.318 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2023-09-27T16:25:41.455Z,1695831941.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2023-09-27T16:25:41.632Z,1695831941.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2023-09-27T16:25:41.821Z,1695831941.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2023-09-27T16:25:42.094Z,1695831942.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-09-27T16:25:42.095Z,1695831942.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2023-09-27T16:25:42.191Z,1695831942.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2023-09-27T16:25:42.291Z,1695831942.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2023-09-27T16:25:42.434Z,1695831942.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2023-09-27T16:25:42.536Z,1695831942.536 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-09-27T16:25:42.547Z,1695831942.547 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-09-27T16:25:42.648Z,1695831942.648 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-09-27T16:25:42.648Z,1695831942.648 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-09-27T16:25:42.700Z,1695831942.700 [VerticalControl](DEBUG): Construct VerticalControl. 2023-09-27T16:25:42.759Z,1695831942.759 [VerticalControl] Loaded 2023-09-27T16:25:42.759Z,1695831942.759 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-09-27T16:25:42.762Z,1695831942.762 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-09-27T16:25:42.802Z,1695831942.802 [HorizontalControl] Loaded 2023-09-27T16:25:42.802Z,1695831942.802 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-09-27T16:25:42.805Z,1695831942.805 [SpeedControl](DEBUG): Construct SpeedControl. 2023-09-27T16:25:42.808Z,1695831942.808 [SpeedControl] Loaded 2023-09-27T16:25:42.808Z,1695831942.808 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-09-27T16:25:42.811Z,1695831942.811 [LoopControl](DEBUG): Construct LoopControl. 2023-09-27T16:25:42.811Z,1695831942.811 [LoopControl] Loaded 2023-09-27T16:25:42.811Z,1695831942.811 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-09-27T16:25:42.812Z,1695831942.812 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-09-27T16:25:42.812Z,1695831942.812 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-09-27T16:25:42.862Z,1695831942.862 [DepthRateCalculator] Loaded 2023-09-27T16:25:42.862Z,1695831942.862 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-09-27T16:25:42.867Z,1695831942.867 [PitchRateCalculator] Loaded 2023-09-27T16:25:42.867Z,1695831942.867 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-09-27T16:25:42.877Z,1695831942.877 [SpeedCalculator] Loaded 2023-09-27T16:25:42.877Z,1695831942.877 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-09-27T16:25:42.882Z,1695831942.882 [YawRateCalculator] Loaded 2023-09-27T16:25:42.882Z,1695831942.882 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-09-27T16:25:42.900Z,1695831942.900 [ElevatorOffsetCalculator] Loaded 2023-09-27T16:25:42.900Z,1695831942.900 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-09-27T16:25:42.901Z,1695831942.901 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-09-27T16:25:42.901Z,1695831942.901 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-09-27T16:25:42.928Z,1695831942.928 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-09-27T16:25:42.929Z,1695831942.929 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-09-27T16:25:42.984Z,1695831942.984 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-09-27T16:25:42.985Z,1695831942.985 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-09-27T16:25:43.290Z,1695831943.290 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-09-27T16:25:43.291Z,1695831943.291 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-09-27T16:25:43.439Z,1695831943.439 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-09-27T16:25:43.440Z,1695831943.440 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-09-27T16:25:43.864Z,1695831943.864 [AHRS_M2] Loaded 2023-09-27T16:25:43.864Z,1695831943.864 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-09-27T16:25:43.900Z,1695831943.900 [BackseatComponent] Loaded 2023-09-27T16:25:43.900Z,1695831943.900 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-09-27T16:25:43.901Z,1695831943.901 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4098F4E0 2023-09-27T16:25:43.902Z,1695831943.902 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4631 2023-09-27T16:25:43.904Z,1695831943.904 [LcmUniversalReporter] Loaded 2023-09-27T16:25:43.905Z,1695831943.905 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-09-27T16:25:44.728Z,1695831944.728 [BPC1] Loaded 2023-09-27T16:25:44.728Z,1695831944.728 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-09-27T16:25:44.801Z,1695831944.801 [DataOverHttps] Loaded 2023-09-27T16:25:44.801Z,1695831944.801 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-09-27T16:25:44.802Z,1695831944.802 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0 2023-09-27T16:25:44.803Z,1695831944.803 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4632 2023-09-27T16:25:44.824Z,1695831944.824 [Depth_Keller] Loaded 2023-09-27T16:25:44.824Z,1695831944.824 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-09-27T16:25:44.888Z,1695831944.888 [NAL9602] Loaded 2023-09-27T16:25:44.888Z,1695831944.888 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-09-27T16:25:44.918Z,1695831944.918 [Onboard] Loaded 2023-09-27T16:25:44.918Z,1695831944.918 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-09-27T16:25:44.919Z,1695831944.919 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0 2023-09-27T16:25:44.920Z,1695831944.920 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4633 2023-09-27T16:25:44.925Z,1695831944.925 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2023-09-27T16:25:44.937Z,1695831944.937 [PowerOnly] Loaded 2023-09-27T16:25:44.937Z,1695831944.937 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-09-27T16:25:44.949Z,1695831944.949 [Power24vConverter] Loaded 2023-09-27T16:25:44.949Z,1695831944.949 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-09-27T16:25:44.963Z,1695831944.963 [Radio_Surface] Loaded 2023-09-27T16:25:44.963Z,1695831944.963 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-09-27T16:25:44.964Z,1695831944.964 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0 2023-09-27T16:25:44.964Z,1695831944.964 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4634 2023-09-27T16:25:45.009Z,1695831945.009 [RDI_Pathfinder] Loaded 2023-09-27T16:25:45.009Z,1695831945.009 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2023-09-27T16:25:45.102Z,1695831945.102 [DAT] Loaded 2023-09-27T16:25:45.102Z,1695831945.102 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-09-27T16:25:45.103Z,1695831945.103 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0 2023-09-27T16:25:45.103Z,1695831945.103 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4635 2023-09-27T16:25:45.104Z,1695831945.104 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-09-27T16:25:45.104Z,1695831945.104 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-09-27T16:25:45.215Z,1695831945.215 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-09-27T16:25:45.215Z,1695831945.215 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-09-27T16:25:45.229Z,1695831945.229 [NavChart] Loaded 2023-09-27T16:25:45.229Z,1695831945.229 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-09-27T16:25:45.235Z,1695831945.235 [UniversalFixResidualReporter] Loaded 2023-09-27T16:25:45.235Z,1695831945.235 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-09-27T16:25:45.235Z,1695831945.235 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-09-27T16:25:45.236Z,1695831945.236 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-09-27T16:25:45.379Z,1695831945.379 [SBIT](DEBUG): Construct Startup Built In Test. 2023-09-27T16:25:45.388Z,1695831945.388 [SBIT] Loaded 2023-09-27T16:25:45.388Z,1695831945.388 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-09-27T16:25:45.391Z,1695831945.391 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-09-27T16:25:45.404Z,1695831945.404 [IBIT] Loaded 2023-09-27T16:25:45.404Z,1695831945.404 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-09-27T16:25:45.410Z,1695831945.410 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-09-27T16:25:45.513Z,1695831945.513 [CBIT] Loaded 2023-09-27T16:25:45.513Z,1695831945.513 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-09-27T16:25:45.514Z,1695831945.514 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-09-27T16:25:45.514Z,1695831945.514 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-09-27T16:25:45.644Z,1695831945.644 [BuoyancyServo] Loaded 2023-09-27T16:25:45.644Z,1695831945.644 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-09-27T16:25:45.665Z,1695831945.665 [ElevatorServo] Loaded 2023-09-27T16:25:45.665Z,1695831945.665 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-09-27T16:25:45.685Z,1695831945.685 [MassServo] Loaded 2023-09-27T16:25:45.685Z,1695831945.685 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-09-27T16:25:45.704Z,1695831945.704 [RudderServo] Loaded 2023-09-27T16:25:45.704Z,1695831945.704 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-09-27T16:25:45.721Z,1695831945.721 [ThrusterServo] Loaded 2023-09-27T16:25:45.721Z,1695831945.721 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2023-09-27T16:25:45.722Z,1695831945.722 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-09-27T16:25:45.722Z,1695831945.722 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-09-27T16:25:45.804Z,1695831945.804 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-09-27T16:25:45.804Z,1695831945.804 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-09-27T16:25:46.357Z,1695831946.357 [CTD_Seabird] Loaded 2023-09-27T16:25:46.358Z,1695831946.358 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-09-27T16:25:46.362Z,1695831946.362 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9A4E0 2023-09-27T16:25:46.364Z,1695831946.364 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4636 2023-09-27T16:25:46.384Z,1695831946.384 [PAR_Licor] Loaded 2023-09-27T16:25:46.384Z,1695831946.384 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-09-27T16:25:46.425Z,1695831946.425 [WetLabsBB2FL] Loaded 2023-09-27T16:25:46.425Z,1695831946.425 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-09-27T16:25:46.426Z,1695831946.426 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0 2023-09-27T16:25:46.426Z,1695831946.426 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4637 2023-09-27T16:25:46.446Z,1695831946.446 [WetLabsUBAT] Loaded 2023-09-27T16:25:46.446Z,1695831946.446 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2023-09-27T16:25:46.447Z,1695831946.447 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BFA4E0 2023-09-27T16:25:46.448Z,1695831946.448 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 4638 2023-09-27T16:25:46.448Z,1695831946.448 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-09-27T16:25:46.454Z,1695831946.454 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-09-27T16:25:46.457Z,1695831946.457 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-09-27T16:25:46.468Z,1695831946.468 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-09-27T16:25:46.469Z,1695831946.469 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C2A4E0 2023-09-27T16:25:46.469Z,1695831946.469 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4639 2023-09-27T16:25:46.474Z,1695831946.474 [Supervisor](INFO): Main Thread ID is 4544 2023-09-27T16:25:46.474Z,1695831946.474 [Supervisor](DEBUG): Running supervisor. 2023-09-27T16:25:46.475Z,1695831946.475 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4640 2023-09-27T16:25:46.475Z,1695831946.475 [CommandExec](INFO): Initializing the command executive. 2023-09-27T16:25:46.477Z,1695831946.477 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4641 2023-09-27T16:25:46.479Z,1695831946.479 [controlThread ThreadHandler](INFO): Handler Thread ID is 4642 2023-09-27T16:25:46.479Z,1695831946.479 [controlThread](DEBUG): Initializing ControlThread 2023-09-27T16:25:46.480Z,1695831946.480 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-09-27T16:25:46.482Z,1695831946.482 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-09-27T16:25:46.483Z,1695831946.483 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-09-27T16:25:46.483Z,1695831946.483 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-09-27T16:25:46.484Z,1695831946.484 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-09-27T16:25:46.484Z,1695831946.484 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-09-27T16:25:46.484Z,1695831946.484 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-09-27T16:25:46.485Z,1695831946.485 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-09-27T16:25:46.485Z,1695831946.485 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-09-27T16:25:46.492Z,1695831946.492 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-09-27T16:25:46.492Z,1695831946.492 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-09-27T16:25:46.492Z,1695831946.492 [SBIT](INFO): Initialize SBIT Component. 2023-09-27T16:25:46.493Z,1695831946.493 [SBIT](IMPORTANT): git: 2023-08-02-8-ga2b2183f7 2023-09-27T16:25:46.493Z,1695831946.493 [SBIT](INFO): git hash: a2b2183f71e07b707bfeaaf571c74c3561721119 2023-09-27T16:25:46.493Z,1695831946.493 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-09-27T16:25:46.495Z,1695831946.495 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-09-27T16:25:46.496Z,1695831946.496 [SBIT](INFO): Beginning SBIT in 43.000000 seconds. 2023-09-27T16:25:46.496Z,1695831946.496 [IBIT](INFO): Initialize IBIT Component. 2023-09-27T16:25:46.497Z,1695831946.497 [CBIT](DEBUG): Initialize CBIT Component. 2023-09-27T16:25:46.498Z,1695831946.498 [logger ThreadHandler](INFO): Handler Thread ID is 4643 2023-09-27T16:25:46.510Z,1695831946.510 [CBIT](DEBUG): Initialized mux pins. 2023-09-27T16:25:46.510Z,1695831946.510 [CBIT](DEBUG): Initializing the watchdog timer. 2023-09-27T16:25:46.519Z,1695831946.519 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4644 2023-09-27T16:25:46.531Z,1695831946.531 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4645 2023-09-27T16:25:46.532Z,1695831946.532 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-09-27T16:25:46.535Z,1695831946.535 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-09-27T16:25:46.535Z,1695831946.535 [CBIT](DEBUG): Initializing heartbeat. 2023-09-27T16:25:46.543Z,1695831946.543 [Onboard ThreadHandler](INFO): Handler Thread ID is 4646 2023-09-27T16:25:46.560Z,1695831946.560 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4647 2023-09-27T16:25:46.579Z,1695831946.579 [DAT ThreadHandler](INFO): Handler Thread ID is 4648 2023-09-27T16:25:46.580Z,1695831946.580 [DAT](INFO): Powering up 2023-09-27T16:25:46.580Z,1695831946.580 [DAT](DEBUG): Initializing DAT. 2023-09-27T16:25:46.584Z,1695831946.584 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4650 2023-09-27T16:25:46.584Z,1695831946.584 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-09-27T16:25:46.588Z,1695831946.588 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4652 2023-09-27T16:25:46.591Z,1695831946.591 [WetLabsBB2FL](INFO): Powering up 2023-09-27T16:25:46.592Z,1695831946.592 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 4654 2023-09-27T16:25:46.595Z,1695831946.595 [WetLabsUBAT](INFO): Powering up 2023-09-27T16:25:46.596Z,1695831946.596 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4656 2023-09-27T16:25:46.600Z,1695831946.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-09-27T16:25:46.600Z,1695831946.600 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-09-27T16:25:46.600Z,1695831946.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-09-27T16:25:46.600Z,1695831946.600 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-09-27T16:25:46.600Z,1695831946.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-09-27T16:25:46.601Z,1695831946.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-09-27T16:25:46.601Z,1695831946.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-09-27T16:25:46.601Z,1695831946.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-09-27T16:25:46.606Z,1695831946.606 [CBIT](DEBUG): Deactivating GF circuits. 2023-09-27T16:25:46.606Z,1695831946.606 [CBIT](DEBUG): Deactivating emergency mode. 2023-09-27T16:25:46.646Z,1695831946.646 [CBIT](DEBUG): Backplane powered. 2023-09-27T16:25:46.651Z,1695831946.651 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-09-27T16:25:46.672Z,1695831946.672 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-27T16:25:46.704Z,1695831946.704 [MissionManager](DEBUG): 2023-09-27T16:25:46.705Z,1695831946.705 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-09-27T16:25:46.799Z,1695831946.799 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-09-27T16:25:46.801Z,1695831946.801 [Default:A.Wait](DEBUG): Construct Wait. 2023-09-27T16:25:46.819Z,1695831946.819 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-27T16:25:46.850Z,1695831946.850 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-09-27T16:25:46.853Z,1695831946.853 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-09-27T16:25:46.875Z,1695831946.875 [Default:E.Execute](DEBUG): Construct Execute. 2023-09-27T16:25:46.891Z,1695831946.891 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-09-27T16:25:46.896Z,1695831946.896 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-09-27T16:25:46.906Z,1695831946.906 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-09-27T16:25:46.972Z,1695831946.972 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m 2023-09-27T16:25:46.973Z,1695831946.973 [PowerOnly](INFO): Powering up loadControl 2023-09-27T16:25:46.974Z,1695831946.974 [Radio_Surface](INFO): Powering up 2023-09-27T16:25:46.991Z,1695831946.991 [Power24vConverter](INFO): Powering up. 2023-09-27T16:25:46.992Z,1695831946.992 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2023-09-27T16:25:47.050Z,1695831947.050 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-09-27T16:25:47.068Z,1695831947.068 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-09-27T16:25:47.075Z,1695831947.075 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-09-27T16:25:47.076Z,1695831947.076 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-09-27T16:25:47.083Z,1695831947.083 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-09-27T16:25:47.084Z,1695831947.084 [MassServo](DEBUG): Initializing EZServoServo. 2023-09-27T16:25:47.091Z,1695831947.091 [MassServo](DEBUG): Initializing MassServo. 2023-09-27T16:25:47.092Z,1695831947.092 [RudderServo](DEBUG): Initializing EZServoServo. 2023-09-27T16:25:47.099Z,1695831947.099 [RudderServo](DEBUG): Initializing RudderServo. 2023-09-27T16:25:47.100Z,1695831947.100 [ThrusterServo](DEBUG): Initializing EZServoServo. 2023-09-27T16:25:47.107Z,1695831947.107 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2023-09-27T16:25:49.202Z,1695831949.202 [WetLabsBB2FL](INFO): Powering down 2023-09-27T16:25:55.866Z,1695831955.866 [WetLabsUBAT](INFO): Powering down 2023-09-27T16:25:59.513Z,1695831959.513 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004713 2023-09-27T16:26:00.447Z,1695831960.447 [DAT](INFO): DAT read: 2023-09-27T16:26:00.448Z,1695831960.448 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-09-27T16:26:02.211Z,1695831962.211 [DAT](INFO): DAT read: MF Frequency Band 2023-09-27T16:26:02.212Z,1695831962.212 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-09-27T16:26:02.213Z,1695831962.213 [DAT](INFO): DAT read: Sep 26 2023 18:33:36 2023-09-27T16:26:03.219Z,1695831963.219 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-09-27T16:26:03.221Z,1695831963.221 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-09-27T16:26:03.221Z,1695831963.221 [DAT](INFO): commRate: 800 2023-09-27T16:26:05.286Z,1695831965.286 [DAT](INFO): entering command mode 2023-09-27T16:26:05.487Z,1695831965.487 [DAT](INFO): DAT read: 2023-09-27T16:26:05.487Z,1695831965.487 [DAT](INFO): DAT read: user:1> 2023-09-27T16:26:05.488Z,1695831965.488 [DAT](INFO): setting verbose to 3 2023-09-27T16:26:05.739Z,1695831965.739 [DAT](INFO): DAT read: user:1> 2023-09-27T16:26:05.740Z,1695831965.740 [DAT](INFO): DAT read: Verbose | 3 2023-09-27T16:26:05.740Z,1695831965.740 [DAT](INFO): set verbose to 3 2023-09-27T16:26:05.741Z,1695831965.741 [DAT](INFO): setting DatVerbose to 27440 2023-09-27T16:26:05.991Z,1695831965.991 [DAT](INFO): DAT read: user:2> 2023-09-27T16:26:05.992Z,1695831965.992 [DAT](INFO): DAT read: DatVerbose | 27440 2023-09-27T16:26:05.992Z,1695831965.992 [DAT](INFO): set DatVerbose to 27440 2023-09-27T16:26:05.993Z,1695831965.993 [DAT](INFO): setting transmit power to 8 2023-09-27T16:26:06.243Z,1695831966.243 [DAT](INFO): DAT read: user:3> 2023-09-27T16:26:06.244Z,1695831966.244 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-09-27T16:26:06.244Z,1695831966.244 [DAT](INFO): set transmit power to 8 2023-09-27T16:26:06.245Z,1695831966.245 [DAT](INFO): setting local address to 10 2023-09-27T16:26:06.499Z,1695831966.499 [DAT](INFO): DAT read: user:4> 2023-09-27T16:26:06.503Z,1695831966.503 [DAT](INFO): DAT read: LocalAddr | 10 2023-09-27T16:26:06.505Z,1695831966.505 [DAT](INFO): set local address to 10 2023-09-27T16:26:06.508Z,1695831966.508 [DAT](INFO): Setting time to: 16:26:6 And date to:9/27/2023 2023-09-27T16:26:06.747Z,1695831966.747 [DAT](INFO): DAT read: user:5> 2023-09-27T16:26:06.748Z,1695831966.748 [DAT](INFO): DAT read: Wed Sep 27, 2023 16:26:06 2023-09-27T16:26:06.748Z,1695831966.748 [DAT](INFO): Local DAT time set to Wed Sep 27, 2023 16:26:06 2023-09-27T16:26:15.240Z,1695831975.240 [NAL9602](INFO): Powering up NAL9602 2023-09-27T16:26:26.144Z,1695831986.144 [NAL9602](INFO): NAL9602 initialized 2023-09-27T16:26:30.285Z,1695831990.285 [SBIT](IMPORTANT): Beginning Startup BIT 2023-09-27T16:26:30.290Z,1695831990.290 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-27T16:26:41.159Z,1695832001.159 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006795 CHAN A1 (24V): 0.002926 CHAN A2 (12V): -0.005637 CHAN A3 (5V): -0.002037 CHAN B0 (3.3V): 0.000108 CHAN B1 (3.15aV): 0.000030 CHAN B2 (3.15bV): 0.000252 CHAN B3 (GND): 0.001878 OPEN: 0.005505 Full Scale: +/- 1 mA 2023-09-27T16:27:24.348Z,1695832044.348 [SBIT](IMPORTANT): SBIT PASSED 2023-09-27T16:27:24.348Z,1695832044.348 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-09-27T16:27:24.349Z,1695832044.349 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2023-09-27T16:27:24.349Z,1695832044.349 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2023-09-27T16:27:24.349Z,1695832044.349 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2023-09-27T16:27:24.349Z,1695832044.349 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-09-27T16:27:24.349Z,1695832044.349 [SBIT](IMPORTANT): Express none PowerOnly.component_avgCurrent_loadControl; 2023-09-27T16:27:24.350Z,1695832044.350 [SBIT](IMPORTANT): Express none WetLabsUBAT.flow_rate; 2023-09-27T16:27:24.350Z,1695832044.350 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2023-09-27T16:27:24.350Z,1695832044.350 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2023-09-27T16:27:24.350Z,1695832044.350 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool; 2023-09-27T16:27:24.350Z,1695832044.350 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=307.497256 cubic_centimeter; 2023-09-27T16:27:24.350Z,1695832044.350 [SBIT](IMPORTANT): VerticalControl.massDefault=5.929260 millimeter; 2023-09-27T16:27:24.351Z,1695832044.351 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2023-09-27T16:27:24.789Z,1695832044.789 [MissionManager](IMPORTANT): Started mission Startup 2023-09-27T16:27:24.789Z,1695832044.789 [Startup] Running Loop=1 2023-09-27T16:27:24.789Z,1695832044.789 [Startup](DEBUG): Aggregate::initialize Startup 2023-09-27T16:27:24.790Z,1695832044.790 [Startup:A.GoToSurface] Running Loop=1 2023-09-27T16:27:24.790Z,1695832044.790 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-27T16:27:24.790Z,1695832044.790 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-27T16:27:24.791Z,1695832044.791 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-27T16:27:24.791Z,1695832044.791 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-27T16:27:24.792Z,1695832044.792 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-27T16:27:24.792Z,1695832044.792 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-27T16:27:24.794Z,1695832044.794 [Startup:StartupSatComms] Running Loop=1 2023-09-27T16:27:24.794Z,1695832044.794 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-09-27T16:27:24.794Z,1695832044.794 [Startup:StartupSatComms:A] Running Loop=1 2023-09-27T16:27:25.137Z,1695832045.137 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-09-27T16:28:24.576Z,1695832104.576 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2023-09-27T16:28:24.576Z,1695832104.576 [RDI_Pathfinder] Communications Fault, FailCount= 1 2023-09-27T16:28:24.576Z,1695832104.576 [RDI_Pathfinder](ERROR): Communications Fault 2023-09-27T16:28:24.599Z,1695832104.599 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2023-09-27T16:28:24.987Z,1695832104.987 [RDI_Pathfinder](INFO): Powering down 2023-09-27T16:28:25.001Z,1695832105.001 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-27T16:27:24.8Z 2023-09-27T16:28:25.001Z,1695832105.001 [Startup:StartupSatComms:A] Stopped 2023-09-27T16:28:25.001Z,1695832105.001 [Startup:StartupSatComms:B] Running Loop=1 2023-09-27T16:28:25.333Z,1695832105.333 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-27T16:28:25.746Z,1695832105.746 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2023-09-27T16:28:25.746Z,1695832105.746 [RDI_Pathfinder] No Fault, FailCount= 1 2023-09-27T16:28:26.139Z,1695832106.139 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2023-09-27T16:28:31.902Z,1695832111.902 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230927T162535/Courier0000.lzma 2023-09-27T16:28:32.905Z,1695832112.905 [DataOverHttps](INFO): Moved sent file to Logs/20230927T162535/Courier0000.lzma.bak 2023-09-27T16:28:32.905Z,1695832112.905 [DataOverHttps](INFO): SBD MOMSN=18909717 2023-09-27T16:28:46.754Z,1695832126.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-09-27T16:28:46.754Z,1695832126.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-27T16:28:46.801Z,1695832126.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-27T16:28:47.208Z,1695832127.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-27T16:28:47.208Z,1695832127.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-09-27T16:28:48.831Z,1695832128.831 [DataOverHttps](INFO): Sending 939 bytes from file Logs/20230927T162535/Express0001.lzma 2023-09-27T16:28:49.832Z,1695832129.832 [DataOverHttps](INFO): Moved sent file to Logs/20230927T162535/Express0001.lzma.bak 2023-09-27T16:28:49.832Z,1695832129.832 [DataOverHttps](INFO): SBD MOMSN=18909724 2023-09-27T16:28:51.185Z,1695832131.185 [Startup:StartupSatComms:B] Stopped 2023-09-27T16:28:51.185Z,1695832131.185 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-09-27T16:28:51.185Z,1695832131.185 [Startup:StartupSatComms] Stopped 2023-09-27T16:28:51.185Z,1695832131.185 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-09-27T16:28:51.186Z,1695832131.186 [Startup](INFO): Completed Startup 2023-09-27T16:28:51.186Z,1695832131.186 [MissionManager](INFO): Startup is completed. 2023-09-27T16:28:51.186Z,1695832131.186 [MissionManager](INFO): Uninitializing Mission Startup 2023-09-27T16:28:51.186Z,1695832131.186 [Startup] Stopped 2023-09-27T16:28:51.187Z,1695832131.187 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-09-27T16:28:51.187Z,1695832131.187 [Startup:A.GoToSurface] Stopped 2023-09-27T16:28:51.187Z,1695832131.187 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-27T16:28:51.625Z,1695832131.625 [MissionManager](IMPORTANT): Started mission Default 2023-09-27T16:28:51.625Z,1695832131.625 [Default] Running Loop=1 2023-09-27T16:28:51.625Z,1695832131.625 [Default](DEBUG): Aggregate::initialize Default 2023-09-27T16:28:51.625Z,1695832131.625 [Default:B.GoToSurface] Running Loop=1 2023-09-27T16:28:51.625Z,1695832131.625 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-27T16:28:51.626Z,1695832131.626 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-27T16:28:51.626Z,1695832131.626 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-27T16:28:51.630Z,1695832131.630 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-27T16:28:51.631Z,1695832131.631 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-27T16:28:51.631Z,1695832131.631 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-27T16:28:51.631Z,1695832131.631 [Default:A.Wait] Running Loop=1 2023-09-27T16:28:51.631Z,1695832131.631 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-27T16:29:04.963Z,1695832144.963 [Default:A.Wait](INFO): Done Waiting. 2023-09-27T16:29:04.963Z,1695832144.963 [Default:A.Wait] Stopped 2023-09-27T16:29:04.963Z,1695832144.963 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-27T16:29:05.345Z,1695832145.345 [Default:CheckIn] Running Loop=1 2023-09-27T16:29:05.345Z,1695832145.345 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-27T16:29:05.345Z,1695832145.345 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-27T16:29:05.767Z,1695832145.767 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-09-27T16:31:29.556Z,1695832289.556 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-27T16:31:47.768Z,1695832307.768 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-09-27T16:31:47.768Z,1695832307.768 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-27T16:31:47.779Z,1695832307.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-27T16:31:48.208Z,1695832308.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-27T16:31:48.209Z,1695832308.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-09-27T16:33:15.070Z,1695832395.070 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-27T16:33:15.074Z,1695832395.074 [BPC1](INFO): Received data from all battery sticks. 2023-09-27T16:33:15.871Z,1695832395.871 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS8,V 2023-09-27T16:34:05.599Z,1695832445.599 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-27T16:29:05.3Z 2023-09-27T16:34:05.599Z,1695832445.599 [Default:CheckIn:Read_GPS] Stopped 2023-09-27T16:34:05.599Z,1695832445.599 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-27T16:34:05.973Z,1695832445.973 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-27T16:34:12.602Z,1695832452.602 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230927T162535/Courier0004.lzma 2023-09-27T16:34:13.604Z,1695832453.604 [DataOverHttps](INFO): Moved sent file to Logs/20230927T162535/Courier0004.lzma.bak 2023-09-27T16:34:13.604Z,1695832453.604 [DataOverHttps](INFO): SBD MOMSN=18909821 2023-09-27T16:34:29.493Z,1695832469.493 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20230927T162535/Express0005.lzma 2023-09-27T16:34:30.488Z,1695832470.488 [DataOverHttps](INFO): Moved sent file to Logs/20230927T162535/Express0005.lzma.bak 2023-09-27T16:34:30.488Z,1695832470.488 [DataOverHttps](INFO): SBD MOMSN=18909823 2023-09-27T16:34:31.864Z,1695832471.864 [Default:CheckIn:Read_Iridium] Stopped 2023-09-27T16:34:31.864Z,1695832471.864 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-27T16:34:31.864Z,1695832471.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-27T16:34:48.796Z,1695832488.796 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-09-27T16:34:48.796Z,1695832488.796 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-27T16:34:48.807Z,1695832488.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-27T16:34:49.218Z,1695832489.218 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-27T16:34:49.218Z,1695832489.218 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-09-27T16:36:29.392Z,1695832589.392 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-27T16:36:29.392Z,1695832589.392 [NAL9602] Data Fault, FailCount= 1 2023-09-27T16:36:29.392Z,1695832589.392 [NAL9602](ERROR): Data Fault 2023-09-27T16:36:29.427Z,1695832589.427 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-27T16:36:29.792Z,1695832589.792 [NAL9602](INFO): Powering down 2023-09-27T16:36:30.199Z,1695832590.199 [NAL9602](FAULT): LCB fault: Current Limiter Activated. 2023-09-27T16:36:30.200Z,1695832590.200 [NAL9602] Hardware Fault, FailCount= 1 2023-09-27T16:36:30.200Z,1695832590.200 [NAL9602](ERROR): Hardware Fault 2023-09-27T16:36:30.623Z,1695832590.623 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-27T16:36:30.623Z,1695832590.623 [NAL9602] No Fault, FailCount= 1 2023-09-27T16:37:00.096Z,1695832620.096 [NAL9602](INFO): Powering up NAL9602 2023-09-27T16:37:11.002Z,1695832631.002 [NAL9602](INFO): NAL9602 initialized 2023-09-27T16:37:49.822Z,1695832669.822 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-09-27T16:37:49.822Z,1695832669.822 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-27T16:37:49.833Z,1695832669.833 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-27T16:37:50.207Z,1695832670.207 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-27T16:37:50.207Z,1695832670.207 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-09-27T16:37:55.900Z,1695832675.900 [CommandExec](IMPORTANT): got command restart application 2023-09-27T16:37:56.906Z,1695832676.906 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-09-27T16:37:56.907Z,1695832676.907 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:56.907Z,1695832676.907 [CommandExec](INFO): Uninitializing the command executive. 2023-09-27T16:37:56.907Z,1695832676.907 [CommandExec](INFO): Uninitializing the command scheduler. 2023-09-27T16:37:56.907Z,1695832676.907 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.002Z,1695832677.002 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-09-27T16:37:57.002Z,1695832677.002 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-09-27T16:37:57.002Z,1695832677.002 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.003Z,1695832677.003 [NavChartDb](INFO): Join timeout helper Thread ID is 4686 2023-09-27T16:37:57.199Z,1695832677.199 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:57.199Z,1695832677.199 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.218Z,1695832677.218 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-09-27T16:37:57.218Z,1695832677.218 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.219Z,1695832677.219 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 4687 2023-09-27T16:37:57.366Z,1695832677.366 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:57.367Z,1695832677.367 [WetLabsUBAT](INFO): Powering down 2023-09-27T16:37:57.367Z,1695832677.367 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.378Z,1695832677.378 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-09-27T16:37:57.378Z,1695832677.378 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.379Z,1695832677.379 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4688 2023-09-27T16:37:57.767Z,1695832677.767 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:57.767Z,1695832677.767 [WetLabsBB2FL](INFO): Powering down 2023-09-27T16:37:57.768Z,1695832677.768 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.774Z,1695832677.774 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-09-27T16:37:57.774Z,1695832677.774 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:57.775Z,1695832677.775 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4689 2023-09-27T16:37:58.603Z,1695832678.603 [CTD_Seabird](INFO): Powering down 2023-09-27T16:37:58.614Z,1695832678.614 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:58.615Z,1695832678.615 [CTD_Seabird](INFO): Powering down 2023-09-27T16:37:58.626Z,1695832678.626 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:58.634Z,1695832678.634 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-09-27T16:37:58.634Z,1695832678.634 [DAT ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:58.635Z,1695832678.635 [DAT](INFO): Join timeout helper Thread ID is 4690 2023-09-27T16:37:58.771Z,1695832678.771 [DAT](INFO): Powering down 2023-09-27T16:37:58.843Z,1695832678.843 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:58.843Z,1695832678.843 [DAT](INFO): Powering down 2023-09-27T16:37:58.844Z,1695832678.844 [DAT ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:58.851Z,1695832678.851 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-09-27T16:37:58.851Z,1695832678.851 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:58.852Z,1695832678.852 [Radio_Surface](INFO): Join timeout helper Thread ID is 4691 2023-09-27T16:37:59.206Z,1695832679.206 [Radio_Surface](INFO): Powering down 2023-09-27T16:37:59.207Z,1695832679.207 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:37:59.208Z,1695832679.208 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:59.223Z,1695832679.223 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-09-27T16:37:59.223Z,1695832679.223 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:37:59.223Z,1695832679.223 [Onboard](INFO): Join timeout helper Thread ID is 4692 2023-09-27T16:37:59.779Z,1695832679.779 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-09-27T16:38:02.150Z,1695832682.150 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:38:02.151Z,1695832682.151 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.159Z,1695832682.159 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-09-27T16:38:02.159Z,1695832682.159 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.159Z,1695832682.159 [DataOverHttps](INFO): Join timeout helper Thread ID is 4693 2023-09-27T16:38:02.790Z,1695832682.790 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:38:02.791Z,1695832682.791 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.808Z,1695832682.808 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-09-27T16:38:02.808Z,1695832682.808 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.808Z,1695832682.808 [BackseatComponent](INFO): Join timeout helper Thread ID is 4694 2023-09-27T16:38:02.890Z,1695832682.890 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:38:02.891Z,1695832682.891 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.907Z,1695832682.907 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-09-27T16:38:02.907Z,1695832682.907 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.907Z,1695832682.907 [logger](INFO): Join timeout helper Thread ID is 4695 2023-09-27T16:38:02.934Z,1695832682.934 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:38:02.935Z,1695832682.935 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.947Z,1695832682.947 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-09-27T16:38:02.947Z,1695832682.947 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:02.947Z,1695832682.947 [CommandLine](INFO): Join timeout helper Thread ID is 4696 2023-09-27T16:38:03.011Z,1695832683.011 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:38:03.011Z,1695832683.011 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.027Z,1695832683.027 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-09-27T16:38:03.027Z,1695832683.027 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.027Z,1695832683.027 [CommandExec](INFO): Join timeout helper Thread ID is 4697 2023-09-27T16:38:03.029Z,1695832683.029 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-09-27T16:38:03.029Z,1695832683.029 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.029Z,1695832683.029 [controlThread](INFO): Join timeout helper Thread ID is 4698 2023-09-27T16:38:03.102Z,1695832683.102 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-27T16:38:03.103Z,1695832683.103 [controlThread](DEBUG): Uninitializing ControlThread 2023-09-27T16:38:03.103Z,1695832683.103 [AHRS_M2](INFO): Powering down 2023-09-27T16:38:03.175Z,1695832683.175 [NAL9602](INFO): Powering down 2023-09-27T16:38:03.247Z,1695832683.247 [RDI_Pathfinder](INFO): Powering down 2023-09-27T16:38:03.248Z,1695832683.248 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-09-27T16:38:03.249Z,1695832683.249 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-09-27T16:38:03.249Z,1695832683.249 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-09-27T16:38:03.250Z,1695832683.250 [MissionManager](INFO): Uninitializing Mission Default 2023-09-27T16:38:03.250Z,1695832683.250 [Default] Stopped 2023-09-27T16:38:03.250Z,1695832683.250 [Default](DEBUG): Aggregate::uninitialize Default 2023-09-27T16:38:03.250Z,1695832683.250 [Default:B.GoToSurface] Stopped 2023-09-27T16:38:03.250Z,1695832683.250 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-27T16:38:03.250Z,1695832683.250 [Default:CheckIn] Stopped 2023-09-27T16:38:03.250Z,1695832683.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-27T16:38:03.250Z,1695832683.250 [Default:CheckIn:C.Wait] Stopped 2023-09-27T16:38:03.250Z,1695832683.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-27T16:38:03.253Z,1695832683.253 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-09-27T16:38:03.254Z,1695832683.254 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-09-27T16:38:03.254Z,1695832683.254 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-09-27T16:38:03.254Z,1695832683.254 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-09-27T16:38:03.255Z,1695832683.255 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-09-27T16:38:03.255Z,1695832683.255 [BuoyancyServo](INFO): Powering down 2023-09-27T16:38:03.267Z,1695832683.267 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-09-27T16:38:03.267Z,1695832683.267 [ElevatorServo](INFO): Powering down 2023-09-27T16:38:03.268Z,1695832683.268 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-09-27T16:38:03.268Z,1695832683.268 [MassServo](INFO): Powering down 2023-09-27T16:38:03.269Z,1695832683.269 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-09-27T16:38:03.269Z,1695832683.269 [RudderServo](INFO): Powering down 2023-09-27T16:38:03.269Z,1695832683.269 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-09-27T16:38:03.270Z,1695832683.270 [ThrusterServo](INFO): Powering down 2023-09-27T16:38:03.271Z,1695832683.271 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-09-27T16:38:03.271Z,1695832683.271 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-09-27T16:38:03.271Z,1695832683.271 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-09-27T16:38:03.271Z,1695832683.271 [CBIT](DEBUG): Powering off loads. 2023-09-27T16:38:03.282Z,1695832683.282 [CBIT](DEBUG): Disabling WDT. 2023-09-27T16:38:03.294Z,1695832683.294 [CBIT](DEBUG): Opening all GF detection circuits. 2023-09-27T16:38:03.295Z,1695832683.295 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.350Z,1695832683.350 [DAT ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.357Z,1695832683.357 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.360Z,1695832683.360 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.366Z,1695832683.366 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.409Z,1695832683.409 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.467Z,1695832683.467 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.468Z,1695832683.468 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.472Z,1695832683.472 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.480Z,1695832683.480 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-27T16:38:03.543Z,1695832683.543 [logger ThreadHandler](INFO): Thread cancelled.