2023-05-02T18:55:19.689Z,1683053719.689 [Supervisor](DEBUG): Initializing supervisor. 2023-05-02T18:55:19.693Z,1683053719.693 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-05-02T18:55:19.694Z,1683053719.694 [SyncHandler](INFO): Protected caller Thread ID is 822 2023-05-02T18:55:19.695Z,1683053719.695 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-05-02T18:55:19.695Z,1683053719.695 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-05-02T18:55:19.696Z,1683053719.696 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 823 2023-05-02T18:55:19.700Z,1683053719.700 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-05-02T18:55:19.717Z,1683053719.717 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-05-02T18:55:19.718Z,1683053719.718 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-05-02T18:55:19.718Z,1683053719.718 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 824 2023-05-02T18:55:19.722Z,1683053719.722 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-05-02T18:55:19.723Z,1683053719.723 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-05-02T18:55:19.724Z,1683053719.724 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 825 2023-05-02T18:55:19.726Z,1683053719.726 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-05-02T18:55:19.727Z,1683053719.727 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-05-02T18:55:19.727Z,1683053719.727 [logger ThreadHandler](INFO): Protected caller Thread ID is 826 2023-05-02T18:55:19.731Z,1683053719.731 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-05-02T18:55:19.731Z,1683053719.731 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-05-02T18:55:19.735Z,1683053719.735 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-05-02T18:55:19.953Z,1683053719.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-05-02T18:55:19.955Z,1683053719.955 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-05-02T18:55:20.164Z,1683053720.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-05-02T18:55:20.165Z,1683053720.165 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-05-02T18:55:20.258Z,1683053720.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-05-02T18:55:20.259Z,1683053720.259 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-05-02T18:55:20.436Z,1683053720.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-05-02T18:55:20.437Z,1683053720.437 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-05-02T18:55:20.875Z,1683053720.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-05-02T18:55:20.876Z,1683053720.876 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-05-02T18:55:20.981Z,1683053720.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-05-02T18:55:20.981Z,1683053720.981 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-05-02T18:55:21.244Z,1683053721.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-05-02T18:55:21.245Z,1683053721.245 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-05-02T18:55:21.439Z,1683053721.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-05-02T18:55:21.440Z,1683053721.440 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-05-02T18:55:21.909Z,1683053721.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-05-02T18:55:21.910Z,1683053721.910 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-05-02T18:55:22.305Z,1683053722.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-05-02T18:55:22.305Z,1683053722.305 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-05-02T18:55:22.384Z,1683053722.384 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-05-02T18:55:22.728Z,1683053722.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-05-02T18:55:22.729Z,1683053722.729 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-05-02T18:55:22.863Z,1683053722.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-05-02T18:55:22.865Z,1683053722.865 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-05-02T18:55:23.834Z,1683053723.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-05-02T18:55:23.835Z,1683053723.835 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-05-02T18:55:29.979Z,1683053729.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-05-02T18:55:29.980Z,1683053729.980 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-05-02T18:55:30.717Z,1683053730.717 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2023-05-02T18:55:30.721Z,1683053730.721 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2023-05-02T18:55:30.806Z,1683053730.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2023-05-02T18:55:30.907Z,1683053730.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2023-05-02T18:55:31.065Z,1683053731.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg 2023-05-02T18:55:31.167Z,1683053731.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2023-05-02T18:55:31.264Z,1683053731.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2023-05-02T18:55:31.348Z,1683053731.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2023-05-02T18:55:31.457Z,1683053731.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2023-05-02T18:55:31.582Z,1683053731.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2023-05-02T18:55:31.737Z,1683053731.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2023-05-02T18:55:31.832Z,1683053731.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2023-05-02T18:55:32.093Z,1683053732.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-05-02T18:55:32.095Z,1683053732.095 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/ 2023-05-02T18:55:32.095Z,1683053732.095 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-05-02T18:55:32.104Z,1683053732.104 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-05-02T18:55:32.471Z,1683053732.471 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-05-02T18:55:32.471Z,1683053732.471 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-05-02T18:55:35.332Z,1683053735.332 [AHRS_M2] Loaded 2023-05-02T18:55:35.332Z,1683053735.332 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-05-02T18:55:36.366Z,1683053736.366 [BPC1] Loaded 2023-05-02T18:55:36.366Z,1683053736.366 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-05-02T18:55:36.554Z,1683053736.554 [DataOverHttps] Loaded 2023-05-02T18:55:36.554Z,1683053736.554 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-05-02T18:55:36.555Z,1683053736.555 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408684E0 2023-05-02T18:55:36.556Z,1683053736.556 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 911 2023-05-02T18:55:36.601Z,1683053736.601 [Depth_Keller] Loaded 2023-05-02T18:55:36.601Z,1683053736.601 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-05-02T18:55:36.607Z,1683053736.607 [DropWeight] Loaded 2023-05-02T18:55:36.607Z,1683053736.607 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-05-02T18:55:36.625Z,1683053736.625 [MultiRay] Loaded 2023-05-02T18:55:36.625Z,1683053736.625 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-05-02T18:55:36.686Z,1683053736.686 [NAL9602] Loaded 2023-05-02T18:55:36.686Z,1683053736.686 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-05-02T18:55:36.715Z,1683053736.715 [Onboard] Loaded 2023-05-02T18:55:36.716Z,1683053736.716 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-05-02T18:55:36.717Z,1683053736.717 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408984E0 2023-05-02T18:55:36.717Z,1683053736.717 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 912 2023-05-02T18:55:36.730Z,1683053736.730 [Power24vConverter] Loaded 2023-05-02T18:55:36.731Z,1683053736.731 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-05-02T18:55:36.763Z,1683053736.763 [PNI_TCM] Loaded 2023-05-02T18:55:36.764Z,1683053736.764 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2023-05-02T18:55:36.778Z,1683053736.778 [Radio_Surface] Loaded 2023-05-02T18:55:36.778Z,1683053736.778 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-05-02T18:55:36.779Z,1683053736.779 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0 2023-05-02T18:55:36.780Z,1683053736.780 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 913 2023-05-02T18:55:36.869Z,1683053736.869 [DAT] Loaded 2023-05-02T18:55:36.869Z,1683053736.869 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-05-02T18:55:36.870Z,1683053736.870 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408F84E0 2023-05-02T18:55:36.871Z,1683053736.871 [DAT ThreadHandler](INFO): Protected caller Thread ID is 914 2023-05-02T18:55:36.871Z,1683053736.871 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-05-02T18:55:36.872Z,1683053736.872 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-05-02T18:55:37.000Z,1683053737.000 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-05-02T18:55:37.001Z,1683053737.001 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-05-02T18:55:37.071Z,1683053737.071 [VerticalControl](DEBUG): Construct VerticalControl. 2023-05-02T18:55:37.130Z,1683053737.130 [VerticalControl] Loaded 2023-05-02T18:55:37.131Z,1683053737.131 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-05-02T18:55:37.133Z,1683053737.133 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-05-02T18:55:37.175Z,1683053737.175 [HorizontalControl] Loaded 2023-05-02T18:55:37.175Z,1683053737.175 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-05-02T18:55:37.178Z,1683053737.178 [SpeedControl](DEBUG): Construct SpeedControl. 2023-05-02T18:55:37.181Z,1683053737.181 [SpeedControl] Loaded 2023-05-02T18:55:37.181Z,1683053737.181 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-05-02T18:55:37.184Z,1683053737.184 [LoopControl](DEBUG): Construct LoopControl. 2023-05-02T18:55:37.184Z,1683053737.184 [LoopControl] Loaded 2023-05-02T18:55:37.185Z,1683053737.185 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-05-02T18:55:37.185Z,1683053737.185 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-05-02T18:55:37.186Z,1683053737.186 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-05-02T18:55:37.201Z,1683053737.201 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-05-02T18:55:37.202Z,1683053737.202 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-05-02T18:55:37.246Z,1683053737.246 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-05-02T18:55:37.247Z,1683053737.247 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-05-02T18:55:37.389Z,1683053737.389 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-05-02T18:55:37.389Z,1683053737.389 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-05-02T18:55:37.431Z,1683053737.431 [DeadReckonUsingSpeedCalculator] Loaded 2023-05-02T18:55:37.432Z,1683053737.432 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-05-02T18:55:37.473Z,1683053737.473 [DeadReckonWithRespectToSeafloor] Loaded 2023-05-02T18:55:37.474Z,1683053737.474 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2023-05-02T18:55:37.487Z,1683053737.487 [NavChart] Loaded 2023-05-02T18:55:37.488Z,1683053737.488 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-05-02T18:55:37.493Z,1683053737.493 [UniversalFixResidualReporter] Loaded 2023-05-02T18:55:37.494Z,1683053737.494 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-05-02T18:55:37.494Z,1683053737.494 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-05-02T18:55:37.496Z,1683053737.496 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-05-02T18:55:37.669Z,1683053737.669 [SBIT](DEBUG): Construct Startup Built In Test. 2023-05-02T18:55:37.678Z,1683053737.678 [SBIT] Loaded 2023-05-02T18:55:37.678Z,1683053737.678 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-05-02T18:55:37.681Z,1683053737.681 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-05-02T18:55:37.694Z,1683053737.694 [IBIT] Loaded 2023-05-02T18:55:37.694Z,1683053737.694 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-05-02T18:55:37.700Z,1683053737.700 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-05-02T18:55:37.805Z,1683053737.805 [CBIT] Loaded 2023-05-02T18:55:37.806Z,1683053737.806 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-05-02T18:55:37.806Z,1683053737.806 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-05-02T18:55:37.807Z,1683053737.807 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-05-02T18:55:37.995Z,1683053737.995 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-05-02T18:55:37.996Z,1683053737.996 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-05-02T18:55:38.158Z,1683053738.158 [BuoyancyServo] Loaded 2023-05-02T18:55:38.159Z,1683053738.159 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-05-02T18:55:38.179Z,1683053738.179 [ElevatorServo] Loaded 2023-05-02T18:55:38.179Z,1683053738.179 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-05-02T18:55:38.199Z,1683053738.199 [MassServo] Loaded 2023-05-02T18:55:38.199Z,1683053738.199 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-05-02T18:55:38.217Z,1683053738.217 [RudderServo] Loaded 2023-05-02T18:55:38.218Z,1683053738.218 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-05-02T18:55:38.233Z,1683053738.233 [ThrusterHE] Loaded 2023-05-02T18:55:38.233Z,1683053738.233 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-05-02T18:55:38.233Z,1683053738.233 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-05-02T18:55:38.234Z,1683053738.234 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-05-02T18:55:38.421Z,1683053738.421 [Aanderaa_O2] Loaded 2023-05-02T18:55:38.421Z,1683053738.421 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2023-05-02T18:55:38.482Z,1683053738.482 [CTD_Seabird] Loaded 2023-05-02T18:55:38.483Z,1683053738.483 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-05-02T18:55:38.484Z,1683053738.484 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0 2023-05-02T18:55:38.484Z,1683053738.484 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 915 2023-05-02T18:55:38.517Z,1683053738.517 [ESPComponent] Loaded 2023-05-02T18:55:38.517Z,1683053738.517 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-05-02T18:55:38.538Z,1683053738.538 [PAR_Licor] Loaded 2023-05-02T18:55:38.538Z,1683053738.538 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-05-02T18:55:38.569Z,1683053738.569 [WetLabsBB2FL] Loaded 2023-05-02T18:55:38.569Z,1683053738.569 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-05-02T18:55:38.571Z,1683053738.571 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B2C4E0 2023-05-02T18:55:38.571Z,1683053738.571 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 916 2023-05-02T18:55:38.572Z,1683053738.572 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-05-02T18:55:38.572Z,1683053738.572 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-05-02T18:55:38.643Z,1683053738.643 [DepthRateCalculator] Loaded 2023-05-02T18:55:38.643Z,1683053738.643 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-05-02T18:55:38.648Z,1683053738.648 [PitchRateCalculator] Loaded 2023-05-02T18:55:38.648Z,1683053738.648 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-05-02T18:55:38.657Z,1683053738.657 [SpeedCalculator] Loaded 2023-05-02T18:55:38.658Z,1683053738.658 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-05-02T18:55:38.662Z,1683053738.662 [YawRateCalculator] Loaded 2023-05-02T18:55:38.662Z,1683053738.662 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-05-02T18:55:38.681Z,1683053738.681 [ElevatorOffsetCalculator] Loaded 2023-05-02T18:55:38.682Z,1683053738.682 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-05-02T18:55:38.682Z,1683053738.682 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-05-02T18:55:38.683Z,1683053738.683 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-05-02T18:55:38.766Z,1683053738.766 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-05-02T18:55:38.772Z,1683053738.772 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-05-02T18:55:38.775Z,1683053738.775 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-05-02T18:55:38.786Z,1683053738.786 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-05-02T18:55:38.787Z,1683053738.787 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BAE4E0 2023-05-02T18:55:38.787Z,1683053738.787 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 917 2023-05-02T18:55:38.792Z,1683053738.792 [Supervisor](INFO): Main Thread ID is 818 2023-05-02T18:55:38.792Z,1683053738.792 [Supervisor](DEBUG): Running supervisor. 2023-05-02T18:55:38.793Z,1683053738.793 [CommandExec ThreadHandler](INFO): Handler Thread ID is 918 2023-05-02T18:55:38.793Z,1683053738.793 [CommandExec](INFO): Initializing the command executive. 2023-05-02T18:55:38.795Z,1683053738.795 [CommandLine ThreadHandler](INFO): Handler Thread ID is 919 2023-05-02T18:55:38.797Z,1683053738.797 [controlThread ThreadHandler](INFO): Handler Thread ID is 920 2023-05-02T18:55:38.797Z,1683053738.797 [controlThread](DEBUG): Initializing ControlThread 2023-05-02T18:55:38.805Z,1683053738.805 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-05-02T18:55:38.806Z,1683053738.806 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-05-02T18:55:38.807Z,1683053738.807 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-05-02T18:55:38.807Z,1683053738.807 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-05-02T18:55:38.808Z,1683053738.808 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-05-02T18:55:38.809Z,1683053738.809 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-05-02T18:55:38.809Z,1683053738.809 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-05-02T18:55:38.810Z,1683053738.810 [SBIT](INFO): Initialize SBIT Component. 2023-05-02T18:55:38.810Z,1683053738.810 [SBIT](IMPORTANT): git: 2023-02-15A-21-g3d3976af4 2023-05-02T18:55:38.810Z,1683053738.810 [SBIT](INFO): git hash: 3d3976af40c3333a6154de93af21df77ac8c6f9b 2023-05-02T18:55:38.811Z,1683053738.811 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-05-02T18:55:38.812Z,1683053738.812 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-05-02T18:55:38.813Z,1683053738.813 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2023-05-02T18:55:38.814Z,1683053738.814 [IBIT](INFO): Initialize IBIT Component. 2023-05-02T18:55:38.815Z,1683053738.815 [CBIT](DEBUG): Initialize CBIT Component. 2023-05-02T18:55:38.815Z,1683053738.815 [logger ThreadHandler](INFO): Handler Thread ID is 921 2023-05-02T18:55:38.826Z,1683053738.826 [CBIT](DEBUG): Initialized mux pins. 2023-05-02T18:55:38.827Z,1683053738.827 [CBIT](DEBUG): Initializing the watchdog timer. 2023-05-02T18:55:38.835Z,1683053738.835 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 922 2023-05-02T18:55:38.836Z,1683053738.836 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-05-02T18:55:38.847Z,1683053738.847 [Onboard ThreadHandler](INFO): Handler Thread ID is 923 2023-05-02T18:55:38.851Z,1683053738.851 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-05-02T18:55:38.852Z,1683053738.852 [CBIT](DEBUG): Initializing heartbeat. 2023-05-02T18:55:38.867Z,1683053738.867 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 924 2023-05-02T18:55:38.883Z,1683053738.883 [DAT ThreadHandler](INFO): Handler Thread ID is 925 2023-05-02T18:55:38.883Z,1683053738.883 [DAT](INFO): Powering up 2023-05-02T18:55:38.884Z,1683053738.884 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:55:38.888Z,1683053738.888 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 927 2023-05-02T18:55:38.888Z,1683053738.888 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:55:38.892Z,1683053738.892 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 929 2023-05-02T18:55:38.895Z,1683053738.895 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:55:38.897Z,1683053738.897 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 931 2023-05-02T18:55:38.906Z,1683053738.906 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2023-05-02T18:55:38.906Z,1683053738.906 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2023-05-02T18:55:38.906Z,1683053738.906 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2023-05-02T18:55:38.907Z,1683053738.907 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2023-05-02T18:55:38.908Z,1683053738.908 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2023-05-02T18:55:38.908Z,1683053738.908 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2023-05-02T18:55:38.922Z,1683053738.922 [CBIT](DEBUG): Deactivating GF circuits. 2023-05-02T18:55:38.923Z,1683053738.923 [CBIT](DEBUG): Deactivating emergency mode. 2023-05-02T18:55:38.958Z,1683053738.958 [CBIT](DEBUG): Backplane powered. 2023-05-02T18:55:38.963Z,1683053738.963 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-05-02T18:55:38.963Z,1683053738.963 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-05-02T18:55:38.964Z,1683053738.964 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-05-02T18:55:38.964Z,1683053738.964 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-05-02T18:55:38.964Z,1683053738.964 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-05-02T18:55:38.965Z,1683053738.965 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-05-02T18:55:39.009Z,1683053739.009 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-02T18:55:39.021Z,1683053739.021 [MissionManager](DEBUG): 2023-05-02T18:55:39.022Z,1683053739.022 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-05-02T18:55:39.106Z,1683053739.106 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-05-02T18:55:39.123Z,1683053739.123 [Default:A.Wait](DEBUG): Construct Wait. 2023-05-02T18:55:39.126Z,1683053739.126 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-02T18:55:39.160Z,1683053739.160 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-05-02T18:55:39.166Z,1683053739.166 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-05-02T18:55:39.173Z,1683053739.173 [Default:E.Execute](DEBUG): Construct Execute. 2023-05-02T18:55:39.176Z,1683053739.176 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-05-02T18:55:39.191Z,1683053739.191 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2023-05-02T18:55:39.208Z,1683053739.208 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-05-02T18:55:39.271Z,1683053739.271 [Radio_Surface](INFO): Powering up 2023-05-02T18:55:39.357Z,1683053739.357 [MultiRay](INFO): Powering up MultiRay Lights 2023-05-02T18:55:39.395Z,1683053739.395 [Power24vConverter](INFO): Powering up. 2023-05-02T18:55:39.438Z,1683053739.438 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:55:39.447Z,1683053739.447 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-05-02T18:55:39.448Z,1683053739.448 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:55:39.455Z,1683053739.455 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-05-02T18:55:39.456Z,1683053739.456 [MassServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:55:39.463Z,1683053739.463 [MassServo](DEBUG): Initializing MassServo. 2023-05-02T18:55:39.464Z,1683053739.464 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:55:39.471Z,1683053739.471 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-02T18:55:39.472Z,1683053739.472 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-05-02T18:55:39.479Z,1683053739.479 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-05-02T18:55:39.659Z,1683053739.659 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-05-02T18:55:39.659Z,1683053739.659 [DropWeight] Hardware Fault, FailCount= 1 2023-05-02T18:55:39.659Z,1683053739.659 [DropWeight](ERROR): Hardware Fault 2023-05-02T18:55:39.731Z,1683053739.731 [CommandExec](FAULT): Scheduling is paused 2023-05-02T18:55:39.732Z,1683053739.732 [CBIT](INFO): Critical error at 20230502T185539 2023-05-02T18:55:39.732Z,1683053739.732 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-05-02T18:55:39.735Z,1683053739.735 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-05-02T18:55:39.735Z,1683053739.735 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-05-02T18:55:40.350Z,1683053740.350 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2023-05-02T18:55:40.351Z,1683053740.351 [RudderServo](FAULT): Rudder failed to initialize 2023-05-02T18:55:40.351Z,1683053740.351 [RudderServo] Communications Fault, FailCount= 1 2023-05-02T18:55:40.351Z,1683053740.351 [RudderServo](ERROR): Communications Fault 2023-05-02T18:55:40.353Z,1683053740.353 [CBIT](INFO): Critical error at 20230502T185539 2023-05-02T18:55:40.355Z,1683053740.355 [CBIT](ERROR): Communications Fault in component: RudderServo 2023-05-02T18:55:40.675Z,1683053740.675 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-02T18:55:40.675Z,1683053740.675 [RudderServo](INFO): Powering down 2023-05-02T18:55:41.273Z,1683053741.273 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:55:41.391Z,1683053741.391 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-02T18:55:41.396Z,1683053741.396 [CBIT](INFO): Clearing failed state for component RudderServo 2023-05-02T18:55:41.396Z,1683053741.396 [RudderServo] No Fault, FailCount= 1 2023-05-02T18:55:42.429Z,1683053742.429 [AHRS_M2](DEBUG): boresightMatrix m[ decimal 0 0 2 2 f0.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f0.0 ]m set drop 2023-05-02T18:55:42.832Z,1683053742.832 [AHRS_M2](DEBUG): chan0Format 2 set drop 2023-05-02T18:55:43.235Z,1683053743.235 [AHRS_M2](DEBUG): chan0Trigger 5 set drop 2023-05-02T18:55:43.639Z,1683053743.639 [AHRS_M2](DEBUG): chan0Enables array[ 0 15 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 ]array set drop 2023-05-02T18:55:43.639Z,1683053743.639 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop 2023-05-02T18:55:44.043Z,1683053744.043 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop 2023-05-02T18:55:44.190Z,1683053744.190 [WetLabsBB2FL] Communications Fault, FailCount= 1 2023-05-02T18:55:44.190Z,1683053744.190 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:55:44.447Z,1683053744.447 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop 2023-05-02T18:55:44.542Z,1683053744.542 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:55:44.691Z,1683053744.691 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:55:44.852Z,1683053744.852 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop 2023-05-02T18:55:45.019Z,1683053745.019 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-05-02T18:55:45.256Z,1683053745.256 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop 2023-05-02T18:55:45.659Z,1683053745.659 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop 2023-05-02T18:55:45.727Z,1683053745.727 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:55:45.727Z,1683053745.727 [WetLabsBB2FL] No Fault, FailCount= 1 2023-05-02T18:55:45.911Z,1683053745.911 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:55:46.063Z,1683053746.063 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop 2023-05-02T18:55:46.468Z,1683053746.468 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop 2023-05-02T18:55:46.472Z,1683053746.472 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:55:46.472Z,1683053746.472 [PNI_TCM] Data Fault, FailCount= 1 2023-05-02T18:55:46.472Z,1683053746.472 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:55:46.548Z,1683053746.548 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:55:46.872Z,1683053746.872 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop 2023-05-02T18:55:46.876Z,1683053746.876 [PNI_TCM](INFO): Powering down 2023-05-02T18:55:47.275Z,1683053747.275 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop 2023-05-02T18:55:48.140Z,1683053748.140 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:55:48.140Z,1683053748.140 [PNI_TCM] No Fault, FailCount= 1 2023-05-02T18:55:48.952Z,1683053748.952 [CBIT](CRITICAL): Environmental Failure. Press:14.580303 PSI. Humidity:38%. Temp:26 C. ABORTING MISSION 2023-05-02T18:55:49.389Z,1683053749.389 [CBIT](INFO): Critical error at 20230502T185548 2023-05-02T18:55:49.710Z,1683053749.710 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:55:49.710Z,1683053749.710 [Aanderaa_O2] Communications Fault, FailCount= 1 2023-05-02T18:55:49.710Z,1683053749.710 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:55:49.730Z,1683053749.730 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:55:50.230Z,1683053750.230 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:55:50.936Z,1683053750.936 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:55:50.936Z,1683053750.936 [Aanderaa_O2] No Fault, FailCount= 1 2023-05-02T18:55:51.198Z,1683053751.198 [WetLabsBB2FL] Communications Fault, FailCount= 2 2023-05-02T18:55:51.198Z,1683053751.198 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:55:51.471Z,1683053751.471 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:55:51.699Z,1683053751.699 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:55:51.938Z,1683053751.938 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:55:51.939Z,1683053751.939 [CTD_Seabird] Communications Fault, FailCount= 1 2023-05-02T18:55:51.939Z,1683053751.939 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:55:52.151Z,1683053752.151 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:55:52.343Z,1683053752.343 [CTD_Seabird](INFO): Powering down 2023-05-02T18:55:52.561Z,1683053752.561 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:55:52.561Z,1683053752.561 [WetLabsBB2FL] No Fault, FailCount= 2 2023-05-02T18:55:52.912Z,1683053752.912 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:55:53.360Z,1683053753.360 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:55:53.360Z,1683053753.360 [CTD_Seabird] No Fault, FailCount= 1 2023-05-02T18:55:53.682Z,1683053753.682 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:55:55.768Z,1683053755.768 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:55:55.768Z,1683053755.768 [PNI_TCM] Data Fault, FailCount= 2 2023-05-02T18:55:55.768Z,1683053755.768 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:55:55.807Z,1683053755.807 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:55:56.172Z,1683053756.172 [PNI_TCM](INFO): Powering down 2023-05-02T18:55:57.375Z,1683053757.375 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2023-05-02T18:55:57.375Z,1683053757.375 [AHRS_M2] Data Fault, FailCount= 1 2023-05-02T18:55:57.375Z,1683053757.375 [AHRS_M2](ERROR): Data Fault 2023-05-02T18:55:57.423Z,1683053757.423 [CBIT](ERROR): Data Fault in component: AHRS_M2 2023-05-02T18:55:57.424Z,1683053757.424 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2023-05-02T18:55:57.424Z,1683053757.424 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:55:57.424Z,1683053757.424 [PNI_TCM] No Fault, FailCount= 2 2023-05-02T18:55:57.800Z,1683053757.800 [CBIT](INFO): Critical error at 20230502T185557 2023-05-02T18:55:58.226Z,1683053758.226 [WetLabsBB2FL] Communications Fault, FailCount= 3 2023-05-02T18:55:58.226Z,1683053758.226 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:55:58.628Z,1683053758.628 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:55:58.742Z,1683053758.742 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:55:59.418Z,1683053759.418 [SBIT](IMPORTANT): Beginning Startup BIT 2023-05-02T18:55:59.422Z,1683053759.422 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-02T18:55:59.892Z,1683053759.892 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:55:59.892Z,1683053759.892 [WetLabsBB2FL] No Fault, FailCount= 3 2023-05-02T18:55:59.956Z,1683053759.956 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:56:01.425Z,1683053761.425 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:56:01.425Z,1683053761.425 [Aanderaa_O2] Communications Fault, FailCount= 2 2023-05-02T18:56:01.425Z,1683053761.425 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:56:01.557Z,1683053761.557 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:56:01.947Z,1683053761.947 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:56:02.762Z,1683053762.762 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:56:02.762Z,1683053762.762 [Aanderaa_O2] No Fault, FailCount= 2 2023-05-02T18:56:05.054Z,1683053765.054 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:56:05.054Z,1683053765.054 [PNI_TCM] Data Fault, FailCount= 3 2023-05-02T18:56:05.054Z,1683053765.054 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:56:05.127Z,1683053765.127 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:56:05.262Z,1683053765.262 [WetLabsBB2FL] Communications Fault, FailCount= 4 2023-05-02T18:56:05.262Z,1683053765.262 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:56:05.461Z,1683053765.461 [PNI_TCM](INFO): Powering down 2023-05-02T18:56:05.600Z,1683053765.600 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:56:05.763Z,1683053765.763 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:56:06.721Z,1683053766.721 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:56:06.721Z,1683053766.721 [PNI_TCM] No Fault, FailCount= 3 2023-05-02T18:56:06.721Z,1683053766.721 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:56:06.721Z,1683053766.721 [WetLabsBB2FL] No Fault, FailCount= 4 2023-05-02T18:56:06.735Z,1683053766.735 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:56:06.735Z,1683053766.735 [CTD_Seabird] Communications Fault, FailCount= 2 2023-05-02T18:56:06.735Z,1683053766.735 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:56:06.976Z,1683053766.976 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:56:07.081Z,1683053767.081 [NAL9602](INFO): Powering up NAL9602 2023-05-02T18:56:07.128Z,1683053767.128 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:56:07.139Z,1683053767.139 [CTD_Seabird](INFO): Powering down 2023-05-02T18:56:08.375Z,1683053768.375 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:56:08.375Z,1683053768.375 [CTD_Seabird] No Fault, FailCount= 2 2023-05-02T18:56:08.483Z,1683053768.483 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:56:10.327Z,1683053770.327 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.009326 CHAN A1 (24V): -0.006922 CHAN A2 (12V): 0.000454 CHAN A3 (5V): -0.002027 CHAN B0 (3.3V): -0.000605 CHAN B1 (3.15aV): -0.000888 CHAN B2 (3.15bV): -0.001446 CHAN B3 (GND): -0.001160 OPEN: 0.004084 Full Scale: +/- 1 mA 2023-05-02T18:56:12.266Z,1683053772.266 [WetLabsBB2FL] Communications Fault, FailCount= 5 2023-05-02T18:56:12.266Z,1683053772.266 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:56:12.348Z,1683053772.348 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:56:12.348Z,1683053772.348 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:56:12.767Z,1683053772.767 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:56:13.140Z,1683053773.140 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:56:13.141Z,1683053773.141 [Aanderaa_O2] Communications Fault, FailCount= 3 2023-05-02T18:56:13.141Z,1683053773.141 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:56:13.157Z,1683053773.157 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:56:13.158Z,1683053773.158 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2023-05-02T18:56:13.659Z,1683053773.659 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:56:14.348Z,1683053774.348 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:56:14.348Z,1683053774.348 [PNI_TCM] Data Fault, FailCount= 4 2023-05-02T18:56:14.348Z,1683053774.348 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:56:14.391Z,1683053774.391 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:56:14.749Z,1683053774.749 [PNI_TCM](INFO): Powering down 2023-05-02T18:56:16.075Z,1683053776.075 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:56:16.075Z,1683053776.075 [PNI_TCM] No Fault, FailCount= 4 2023-05-02T18:56:17.988Z,1683053777.988 [NAL9602](INFO): NAL9602 initialized 2023-05-02T18:56:21.535Z,1683053781.535 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:56:21.535Z,1683053781.535 [CTD_Seabird] Communications Fault, FailCount= 3 2023-05-02T18:56:21.535Z,1683053781.535 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:56:21.692Z,1683053781.692 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:56:21.692Z,1683053781.692 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2023-05-02T18:56:21.939Z,1683053781.939 [CTD_Seabird](INFO): Powering down 2023-05-02T18:56:23.641Z,1683053783.641 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:56:23.641Z,1683053783.641 [PNI_TCM] Data Fault, FailCount= 5 2023-05-02T18:56:23.641Z,1683053783.641 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:56:23.712Z,1683053783.712 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:56:23.712Z,1683053783.712 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2023-05-02T18:56:24.046Z,1683053784.046 [PNI_TCM](INFO): Powering down 2023-05-02T18:56:24.182Z,1683053784.182 [CBIT](INFO): Critical error at 20230502T185623 2023-05-02T18:56:38.896Z,1683053798.896 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-02T18:56:38.896Z,1683053798.896 [DAT] Communications Fault, FailCount= 1 2023-05-02T18:56:38.896Z,1683053798.896 [DAT](ERROR): Communications Fault 2023-05-02T18:56:39.011Z,1683053799.011 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-02T18:56:39.298Z,1683053799.298 [DAT](INFO): Powering down 2023-05-02T18:56:40.259Z,1683053800.259 [CBIT](INFO): Clearing failed state for component DAT 2023-05-02T18:56:40.259Z,1683053800.259 [DAT] No Fault, FailCount= 1 2023-05-02T18:56:42.347Z,1683053802.347 [DAT](INFO): Powering up 2023-05-02T18:56:42.347Z,1683053802.347 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:56:46.669Z,1683053806.669 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-05-02T18:56:53.553Z,1683053813.553 [SBIT](IMPORTANT): SBIT PASSED 2023-05-02T18:56:53.553Z,1683053813.553 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-05-02T18:56:53.554Z,1683053813.554 [SBIT](IMPORTANT): VerticalControl.buoyancyDefault=915 cubic_centimeter; 2023-05-02T18:56:53.554Z,1683053813.554 [SBIT](IMPORTANT): VerticalControl.buoyancyLimitLoCC=0 cubic_centimeter; 2023-05-02T18:56:53.950Z,1683053813.950 [MissionManager](IMPORTANT): Started mission Startup 2023-05-02T18:56:53.950Z,1683053813.950 [Startup] Running Loop=1 2023-05-02T18:56:53.950Z,1683053813.950 [Startup](DEBUG): Aggregate::initialize Startup 2023-05-02T18:56:53.951Z,1683053813.951 [Startup:A.GoToSurface] Running Loop=1 2023-05-02T18:56:53.951Z,1683053813.951 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-02T18:56:53.951Z,1683053813.951 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-02T18:56:53.952Z,1683053813.952 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-02T18:56:53.952Z,1683053813.952 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-02T18:56:53.952Z,1683053813.952 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-02T18:56:53.953Z,1683053813.953 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-02T18:56:53.954Z,1683053813.954 [Startup:StartupSatComms] Running Loop=1 2023-05-02T18:56:53.954Z,1683053813.954 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-05-02T18:56:53.954Z,1683053813.954 [Startup:StartupSatComms:A] Running Loop=1 2023-05-02T18:56:54.357Z,1683053814.357 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-05-02T18:57:14.339Z,1683053834.339 [CommandExec](IMPORTANT): got command quit 2023-05-02T18:57:15.342Z,1683053835.342 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:15.343Z,1683053835.343 [CommandExec](INFO): Uninitializing the command executive. 2023-05-02T18:57:15.343Z,1683053835.343 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-02T18:57:15.343Z,1683053835.343 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.490Z,1683053835.490 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-02T18:57:15.490Z,1683053835.490 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-02T18:57:15.491Z,1683053835.491 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.491Z,1683053835.491 [NavChartDb](INFO): Join timeout helper Thread ID is 966 2023-05-02T18:57:15.571Z,1683053835.571 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:15.571Z,1683053835.571 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.574Z,1683053835.574 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-02T18:57:15.574Z,1683053835.574 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.575Z,1683053835.575 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 967 2023-05-02T18:57:15.842Z,1683053835.842 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:15.843Z,1683053835.843 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:57:15.843Z,1683053835.843 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.863Z,1683053835.863 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-02T18:57:15.863Z,1683053835.863 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.863Z,1683053835.863 [CTD_Seabird](INFO): Join timeout helper Thread ID is 968 2023-05-02T18:57:15.886Z,1683053835.886 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:15.887Z,1683053835.887 [CTD_Seabird](INFO): Powering down 2023-05-02T18:57:15.899Z,1683053835.899 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.914Z,1683053835.914 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-05-02T18:57:15.914Z,1683053835.914 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:15.915Z,1683053835.915 [DAT](INFO): Join timeout helper Thread ID is 969 2023-05-02T18:57:16.155Z,1683053836.155 [DAT](INFO): Powering down 2023-05-02T18:57:16.231Z,1683053836.231 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:16.231Z,1683053836.231 [DAT](INFO): Powering down 2023-05-02T18:57:16.232Z,1683053836.232 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:16.250Z,1683053836.250 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-02T18:57:16.251Z,1683053836.251 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:16.251Z,1683053836.251 [Radio_Surface](INFO): Join timeout helper Thread ID is 970 2023-05-02T18:57:16.255Z,1683053836.255 [Radio_Surface](INFO): Powering down 2023-05-02T18:57:16.256Z,1683053836.256 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:16.256Z,1683053836.256 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:16.278Z,1683053836.278 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-02T18:57:16.278Z,1683053836.278 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:16.279Z,1683053836.279 [Onboard](INFO): Join timeout helper Thread ID is 971 2023-05-02T18:57:18.926Z,1683053838.926 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:18.927Z,1683053838.927 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:18.935Z,1683053838.935 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-02T18:57:18.935Z,1683053838.935 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:18.936Z,1683053838.936 [DataOverHttps](INFO): Join timeout helper Thread ID is 972 2023-05-02T18:57:19.259Z,1683053839.259 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:19.259Z,1683053839.259 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.275Z,1683053839.275 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-02T18:57:19.275Z,1683053839.275 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.276Z,1683053839.276 [logger](INFO): Join timeout helper Thread ID is 973 2023-05-02T18:57:19.287Z,1683053839.287 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:19.287Z,1683053839.287 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.295Z,1683053839.295 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-02T18:57:19.295Z,1683053839.295 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.296Z,1683053839.296 [CommandLine](INFO): Join timeout helper Thread ID is 974 2023-05-02T18:57:19.371Z,1683053839.371 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:19.371Z,1683053839.371 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.375Z,1683053839.375 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-02T18:57:19.375Z,1683053839.375 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.376Z,1683053839.376 [CommandExec](INFO): Join timeout helper Thread ID is 975 2023-05-02T18:57:19.377Z,1683053839.377 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-02T18:57:19.377Z,1683053839.377 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.377Z,1683053839.377 [controlThread](INFO): Join timeout helper Thread ID is 976 2023-05-02T18:57:19.383Z,1683053839.383 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:57:19.383Z,1683053839.383 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-02T18:57:19.385Z,1683053839.385 [MultiRay](INFO): Powering down. 2023-05-02T18:57:19.385Z,1683053839.385 [NAL9602](INFO): Powering down 2023-05-02T18:57:19.387Z,1683053839.387 [PNI_TCM](INFO): Powering down 2023-05-02T18:57:19.387Z,1683053839.387 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:57:19.390Z,1683053839.390 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-02T18:57:19.390Z,1683053839.390 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-02T18:57:19.391Z,1683053839.391 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-02T18:57:19.391Z,1683053839.391 [MissionManager](INFO): Uninitializing Mission Default 2023-05-02T18:57:19.394Z,1683053839.394 [MissionManager](INFO): Uninitializing Mission Startup 2023-05-02T18:57:19.394Z,1683053839.394 [Startup] Stopped 2023-05-02T18:57:19.394Z,1683053839.394 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-05-02T18:57:19.394Z,1683053839.394 [Startup:A.GoToSurface] Stopped 2023-05-02T18:57:19.394Z,1683053839.394 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-02T18:57:19.394Z,1683053839.394 [Startup:StartupSatComms] Stopped 2023-05-02T18:57:19.394Z,1683053839.394 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-05-02T18:57:19.394Z,1683053839.394 [Startup:StartupSatComms:A] Stopped 2023-05-02T18:57:19.396Z,1683053839.396 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-02T18:57:19.396Z,1683053839.396 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-02T18:57:19.396Z,1683053839.396 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-02T18:57:19.396Z,1683053839.396 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-02T18:57:19.397Z,1683053839.397 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-02T18:57:19.397Z,1683053839.397 [BuoyancyServo](INFO): Powering down 2023-05-02T18:57:19.526Z,1683053839.526 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-02T18:57:19.527Z,1683053839.527 [ElevatorServo](INFO): Powering down 2023-05-02T18:57:19.528Z,1683053839.528 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-02T18:57:19.528Z,1683053839.528 [MassServo](INFO): Powering down 2023-05-02T18:57:19.528Z,1683053839.528 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-02T18:57:19.529Z,1683053839.529 [RudderServo](INFO): Powering down 2023-05-02T18:57:19.529Z,1683053839.529 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-05-02T18:57:19.529Z,1683053839.529 [ThrusterHE](INFO): Powering down 2023-05-02T18:57:19.530Z,1683053839.530 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-02T18:57:19.531Z,1683053839.531 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-02T18:57:19.531Z,1683053839.531 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-02T18:57:19.531Z,1683053839.531 [CBIT](DEBUG): Powering off loads. 2023-05-02T18:57:19.542Z,1683053839.542 [CBIT](DEBUG): Disabling WDT. 2023-05-02T18:57:19.554Z,1683053839.554 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-02T18:57:19.555Z,1683053839.555 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.567Z,1683053839.567 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.572Z,1683053839.572 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.575Z,1683053839.575 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.581Z,1683053839.581 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.703Z,1683053839.703 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.708Z,1683053839.708 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.726Z,1683053839.726 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:57:19.770Z,1683053839.770 [logger ThreadHandler](INFO): Thread cancelled.