2023-05-16T20:01:32.130Z,1684267292.130 [Supervisor](DEBUG): Initializing supervisor. 2023-05-16T20:01:32.135Z,1684267292.135 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-05-16T20:01:32.135Z,1684267292.135 [SyncHandler](INFO): Protected caller Thread ID is 838 2023-05-16T20:01:32.136Z,1684267292.136 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-05-16T20:01:32.137Z,1684267292.137 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-05-16T20:01:32.137Z,1684267292.137 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839 2023-05-16T20:01:32.141Z,1684267292.141 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-05-16T20:01:32.158Z,1684267292.158 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-05-16T20:01:32.159Z,1684267292.159 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-05-16T20:01:32.160Z,1684267292.160 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840 2023-05-16T20:01:32.164Z,1684267292.164 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-05-16T20:01:32.165Z,1684267292.165 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-05-16T20:01:32.165Z,1684267292.165 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841 2023-05-16T20:01:32.167Z,1684267292.167 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-05-16T20:01:32.168Z,1684267292.168 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-05-16T20:01:32.168Z,1684267292.168 [logger ThreadHandler](INFO): Protected caller Thread ID is 842 2023-05-16T20:01:32.172Z,1684267292.172 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-05-16T20:01:32.172Z,1684267292.172 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-05-16T20:01:32.176Z,1684267292.176 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-05-16T20:01:32.391Z,1684267292.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-05-16T20:01:32.393Z,1684267292.393 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-05-16T20:01:32.499Z,1684267292.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-05-16T20:01:32.501Z,1684267292.501 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-05-16T20:01:32.595Z,1684267292.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-05-16T20:01:32.597Z,1684267292.597 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-05-16T20:01:33.068Z,1684267293.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-05-16T20:01:33.070Z,1684267293.070 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-05-16T20:01:33.419Z,1684267293.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-05-16T20:01:33.420Z,1684267293.420 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-05-16T20:01:33.756Z,1684267293.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-05-16T20:01:33.757Z,1684267293.757 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-05-16T20:01:33.942Z,1684267293.942 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-05-16T20:01:33.944Z,1684267293.944 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-05-16T20:01:34.023Z,1684267294.023 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-05-16T20:01:34.103Z,1684267294.103 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-05-16T20:01:34.496Z,1684267294.496 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-05-16T20:01:34.498Z,1684267294.498 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-05-16T20:01:34.773Z,1684267294.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-05-16T20:01:34.774Z,1684267294.774 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-05-16T20:01:35.408Z,1684267295.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-05-16T20:01:35.410Z,1684267295.410 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-05-16T20:01:36.154Z,1684267296.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-05-16T20:01:36.155Z,1684267296.155 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-05-16T20:01:36.396Z,1684267296.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-05-16T20:01:36.397Z,1684267296.397 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-05-16T20:01:36.536Z,1684267296.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-05-16T20:01:36.537Z,1684267296.537 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-05-16T20:01:37.326Z,1684267297.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-05-16T20:01:37.328Z,1684267297.328 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-05-16T20:01:37.331Z,1684267297.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-05-16T20:01:37.415Z,1684267297.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-05-16T20:01:37.594Z,1684267297.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-05-16T20:01:37.858Z,1684267297.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-05-16T20:01:37.858Z,1684267297.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-05-16T20:01:37.980Z,1684267297.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-05-16T20:01:38.079Z,1684267298.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-05-16T20:01:38.173Z,1684267298.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-05-16T20:01:38.280Z,1684267298.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-05-16T20:01:38.374Z,1684267298.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-05-16T20:01:38.460Z,1684267298.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-05-16T20:01:38.558Z,1684267298.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-05-16T20:01:38.719Z,1684267298.719 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-05-16T20:01:38.719Z,1684267298.719 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2023-05-16T20:01:38.723Z,1684267298.723 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-05-16T20:01:38.797Z,1684267298.797 [DepthRateCalculator] Loaded 2023-05-16T20:01:38.797Z,1684267298.797 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-05-16T20:01:38.803Z,1684267298.803 [PitchRateCalculator] Loaded 2023-05-16T20:01:38.803Z,1684267298.803 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-05-16T20:01:38.813Z,1684267298.813 [SpeedCalculator] Loaded 2023-05-16T20:01:38.814Z,1684267298.814 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-05-16T20:01:38.818Z,1684267298.818 [YawRateCalculator] Loaded 2023-05-16T20:01:38.819Z,1684267298.819 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-05-16T20:01:38.840Z,1684267298.840 [ElevatorOffsetCalculator] Loaded 2023-05-16T20:01:38.840Z,1684267298.840 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-05-16T20:01:38.841Z,1684267298.841 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-05-16T20:01:38.843Z,1684267298.843 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-05-16T20:01:38.886Z,1684267298.886 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-05-16T20:01:38.886Z,1684267298.886 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-05-16T20:01:38.901Z,1684267298.901 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-05-16T20:01:38.902Z,1684267298.902 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-05-16T20:01:39.655Z,1684267299.655 [AHRS_M2] Loaded 2023-05-16T20:01:39.655Z,1684267299.655 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-05-16T20:01:39.904Z,1684267299.904 [BackseatComponent] Loaded 2023-05-16T20:01:39.904Z,1684267299.904 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-05-16T20:01:39.905Z,1684267299.905 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408474E0 2023-05-16T20:01:39.905Z,1684267299.905 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 926 2023-05-16T20:01:39.908Z,1684267299.908 [LcmUniversalReporter] Loaded 2023-05-16T20:01:39.908Z,1684267299.908 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-05-16T20:01:40.701Z,1684267300.701 [BPC1] Loaded 2023-05-16T20:01:40.701Z,1684267300.701 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-05-16T20:01:40.846Z,1684267300.846 [DataOverHttps] Loaded 2023-05-16T20:01:40.846Z,1684267300.846 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-05-16T20:01:40.847Z,1684267300.847 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408774E0 2023-05-16T20:01:40.847Z,1684267300.847 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 927 2023-05-16T20:01:40.868Z,1684267300.868 [Depth_Keller] Loaded 2023-05-16T20:01:40.868Z,1684267300.868 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-05-16T20:01:40.873Z,1684267300.873 [DropWeight] Loaded 2023-05-16T20:01:40.873Z,1684267300.873 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-05-16T20:01:40.934Z,1684267300.934 [NAL9602] Loaded 2023-05-16T20:01:40.935Z,1684267300.935 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-05-16T20:01:40.964Z,1684267300.964 [Onboard] Loaded 2023-05-16T20:01:40.964Z,1684267300.964 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-05-16T20:01:40.965Z,1684267300.965 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408A74E0 2023-05-16T20:01:40.966Z,1684267300.966 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 928 2023-05-16T20:01:40.972Z,1684267300.972 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-05-16T20:01:40.982Z,1684267300.982 [PowerOnly] Loaded 2023-05-16T20:01:40.983Z,1684267300.983 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-05-16T20:01:40.994Z,1684267300.994 [Power24vConverter] Loaded 2023-05-16T20:01:40.994Z,1684267300.994 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-05-16T20:01:41.009Z,1684267301.009 [Radio_Surface] Loaded 2023-05-16T20:01:41.009Z,1684267301.009 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-05-16T20:01:41.010Z,1684267301.010 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408D74E0 2023-05-16T20:01:41.010Z,1684267301.010 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 929 2023-05-16T20:01:41.033Z,1684267301.033 [Waterlinked] Loaded 2023-05-16T20:01:41.033Z,1684267301.033 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-05-16T20:01:41.034Z,1684267301.034 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-05-16T20:01:41.034Z,1684267301.034 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-05-16T20:01:41.385Z,1684267301.385 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-05-16T20:01:41.385Z,1684267301.385 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-05-16T20:01:41.522Z,1684267301.522 [DeadReckonUsingSpeedCalculator] Loaded 2023-05-16T20:01:41.522Z,1684267301.522 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-05-16T20:01:41.538Z,1684267301.538 [NavChart] Loaded 2023-05-16T20:01:41.538Z,1684267301.538 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-05-16T20:01:41.544Z,1684267301.544 [UniversalFixResidualReporter] Loaded 2023-05-16T20:01:41.544Z,1684267301.544 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-05-16T20:01:41.545Z,1684267301.545 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-05-16T20:01:41.719Z,1684267301.719 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-05-16T20:01:41.890Z,1684267301.890 [SBIT](DEBUG): Construct Startup Built In Test. 2023-05-16T20:01:41.904Z,1684267301.904 [SBIT] Loaded 2023-05-16T20:01:41.905Z,1684267301.905 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-05-16T20:01:41.907Z,1684267301.907 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-05-16T20:01:41.920Z,1684267301.920 [IBIT] Loaded 2023-05-16T20:01:41.920Z,1684267301.920 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-05-16T20:01:41.926Z,1684267301.926 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-05-16T20:01:42.191Z,1684267302.191 [CBIT] Loaded 2023-05-16T20:01:42.191Z,1684267302.191 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-05-16T20:01:42.191Z,1684267302.191 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-05-16T20:01:42.200Z,1684267302.200 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-05-16T20:01:42.495Z,1684267302.495 [CTD_Seabird] Loaded 2023-05-16T20:01:42.496Z,1684267302.496 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-05-16T20:01:42.497Z,1684267302.497 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0 2023-05-16T20:01:42.497Z,1684267302.497 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930 2023-05-16T20:01:42.517Z,1684267302.517 [PAR_Licor] Loaded 2023-05-16T20:01:42.517Z,1684267302.517 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-05-16T20:01:42.548Z,1684267302.548 [WetLabsBB2FL] Loaded 2023-05-16T20:01:42.548Z,1684267302.548 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-05-16T20:01:42.549Z,1684267302.549 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0 2023-05-16T20:01:42.550Z,1684267302.550 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931 2023-05-16T20:01:42.550Z,1684267302.550 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-05-16T20:01:42.551Z,1684267302.551 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-05-16T20:01:42.715Z,1684267302.715 [BuoyancyServo] Loaded 2023-05-16T20:01:42.715Z,1684267302.715 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-05-16T20:01:42.737Z,1684267302.737 [ElevatorServo] Loaded 2023-05-16T20:01:42.737Z,1684267302.737 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-05-16T20:01:42.758Z,1684267302.758 [MassServo] Loaded 2023-05-16T20:01:42.758Z,1684267302.758 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-05-16T20:01:42.779Z,1684267302.779 [RudderServo] Loaded 2023-05-16T20:01:42.779Z,1684267302.779 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-05-16T20:01:42.796Z,1684267302.796 [ThrusterHE] Loaded 2023-05-16T20:01:42.796Z,1684267302.796 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-05-16T20:01:42.797Z,1684267302.797 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-05-16T20:01:42.797Z,1684267302.797 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-05-16T20:01:42.866Z,1684267302.866 [VerticalControl](DEBUG): Construct VerticalControl. 2023-05-16T20:01:42.917Z,1684267302.917 [VerticalControl] Loaded 2023-05-16T20:01:42.917Z,1684267302.917 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-05-16T20:01:42.920Z,1684267302.920 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-05-16T20:01:42.957Z,1684267302.957 [HorizontalControl] Loaded 2023-05-16T20:01:42.957Z,1684267302.957 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-05-16T20:01:42.960Z,1684267302.960 [SpeedControl](DEBUG): Construct SpeedControl. 2023-05-16T20:01:42.961Z,1684267302.961 [SpeedControl] Loaded 2023-05-16T20:01:42.962Z,1684267302.962 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-05-16T20:01:42.964Z,1684267302.964 [LoopControl](DEBUG): Construct LoopControl. 2023-05-16T20:01:42.965Z,1684267302.965 [LoopControl] Loaded 2023-05-16T20:01:42.965Z,1684267302.965 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-05-16T20:01:42.966Z,1684267302.966 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-05-16T20:01:42.966Z,1684267302.966 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-05-16T20:01:43.089Z,1684267303.089 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-05-16T20:01:43.090Z,1684267303.090 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-05-16T20:01:43.274Z,1684267303.274 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-05-16T20:01:43.275Z,1684267303.275 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-05-16T20:01:43.633Z,1684267303.633 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-05-16T20:01:43.634Z,1684267303.634 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-05-16T20:01:43.717Z,1684267303.717 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-05-16T20:01:43.723Z,1684267303.723 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-05-16T20:01:43.726Z,1684267303.726 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-05-16T20:01:43.737Z,1684267303.737 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-05-16T20:01:43.738Z,1684267303.738 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB74E0 2023-05-16T20:01:43.739Z,1684267303.739 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 932 2023-05-16T20:01:43.743Z,1684267303.743 [Supervisor](INFO): Main Thread ID is 831 2023-05-16T20:01:43.743Z,1684267303.743 [Supervisor](DEBUG): Running supervisor. 2023-05-16T20:01:43.744Z,1684267303.744 [CommandExec ThreadHandler](INFO): Handler Thread ID is 933 2023-05-16T20:01:43.744Z,1684267303.744 [CommandExec](INFO): Initializing the command executive. 2023-05-16T20:01:43.746Z,1684267303.746 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934 2023-05-16T20:01:43.748Z,1684267303.748 [controlThread ThreadHandler](INFO): Handler Thread ID is 935 2023-05-16T20:01:43.748Z,1684267303.748 [controlThread](DEBUG): Initializing ControlThread 2023-05-16T20:01:43.749Z,1684267303.749 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-05-16T20:01:43.749Z,1684267303.749 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-05-16T20:01:43.750Z,1684267303.750 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-05-16T20:01:43.750Z,1684267303.750 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-05-16T20:01:43.750Z,1684267303.750 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-05-16T20:01:43.758Z,1684267303.758 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-05-16T20:01:43.759Z,1684267303.759 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-05-16T20:01:43.759Z,1684267303.759 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-05-16T20:01:43.760Z,1684267303.760 [SBIT](INFO): Initialize SBIT Component. 2023-05-16T20:01:43.760Z,1684267303.760 [SBIT](IMPORTANT): git: 2023-05-11-1-g0756d5a45 2023-05-16T20:01:43.760Z,1684267303.760 [SBIT](INFO): git hash: 0756d5a45b55fc759b5de63a78c860713c4b2bf5 2023-05-16T20:01:43.761Z,1684267303.761 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-05-16T20:01:43.762Z,1684267303.762 [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-16T20:01:43.763Z,1684267303.763 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2023-05-16T20:01:43.764Z,1684267303.764 [IBIT](INFO): Initialize IBIT Component. 2023-05-16T20:01:43.764Z,1684267303.764 [CBIT](DEBUG): Initialize CBIT Component. 2023-05-16T20:01:43.765Z,1684267303.765 [logger ThreadHandler](INFO): Handler Thread ID is 936 2023-05-16T20:01:43.776Z,1684267303.776 [CBIT](DEBUG): Initialized mux pins. 2023-05-16T20:01:43.776Z,1684267303.776 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2023-05-16T20:01:43.776Z,1684267303.776 [CBIT](DEBUG): Initializing the watchdog timer. 2023-05-16T20:01:43.784Z,1684267303.784 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937 2023-05-16T20:01:43.796Z,1684267303.796 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938 2023-05-16T20:01:43.797Z,1684267303.797 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-05-16T20:01:43.800Z,1684267303.800 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2023-05-16T20:01:43.800Z,1684267303.800 [CBIT](DEBUG): Initializing heartbeat. 2023-05-16T20:01:43.808Z,1684267303.808 [Onboard ThreadHandler](INFO): Handler Thread ID is 939 2023-05-16T20:01:43.825Z,1684267303.825 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940 2023-05-16T20:01:43.844Z,1684267303.844 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941 2023-05-16T20:01:43.845Z,1684267303.845 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-16T20:01:43.850Z,1684267303.850 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943 2023-05-16T20:01:43.852Z,1684267303.852 [WetLabsBB2FL](INFO): Powering up 2023-05-16T20:01:43.853Z,1684267303.853 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945 2023-05-16T20:01:43.861Z,1684267303.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-05-16T20:01:43.861Z,1684267303.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-05-16T20:01:43.861Z,1684267303.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-05-16T20:01:43.861Z,1684267303.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-05-16T20:01:43.861Z,1684267303.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-05-16T20:01:43.861Z,1684267303.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-05-16T20:01:43.862Z,1684267303.862 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-05-16T20:01:43.862Z,1684267303.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-05-16T20:01:43.872Z,1684267303.872 [CBIT](DEBUG): Deactivating GF circuits. 2023-05-16T20:01:43.872Z,1684267303.872 [CBIT](DEBUG): Deactivating emergency mode. 2023-05-16T20:01:43.912Z,1684267303.912 [CBIT](DEBUG): Backplane powered. 2023-05-16T20:01:43.917Z,1684267303.917 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-05-16T20:01:43.919Z,1684267303.919 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-05-16T20:01:43.920Z,1684267303.920 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-05-16T20:01:43.920Z,1684267303.920 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-05-16T20:01:43.921Z,1684267303.921 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-05-16T20:01:43.957Z,1684267303.957 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-16T20:01:43.982Z,1684267303.982 [MissionManager](DEBUG): 2023-05-16T20:01:43.983Z,1684267303.983 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-05-16T20:01:44.041Z,1684267304.041 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-05-16T20:01:44.042Z,1684267304.042 [Default:A.Wait](DEBUG): Construct Wait. 2023-05-16T20:01:44.057Z,1684267304.057 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-16T20:01:44.068Z,1684267304.068 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-05-16T20:01:44.071Z,1684267304.071 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-05-16T20:01:44.106Z,1684267304.106 [Default:E.Execute](DEBUG): Construct Execute. 2023-05-16T20:01:44.122Z,1684267304.122 [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-16T20:01:44.127Z,1684267304.127 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-05-16T20:01:44.145Z,1684267304.145 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-05-16T20:01:44.232Z,1684267304.232 [Radio_Surface](INFO): Powering up 2023-05-16T20:01:44.283Z,1684267304.283 [Power24vConverter](INFO): Powering up. 2023-05-16T20:01:44.330Z,1684267304.330 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-05-16T20:01:44.336Z,1684267304.336 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-05-16T20:01:44.337Z,1684267304.337 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-05-16T20:01:44.344Z,1684267304.344 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-05-16T20:01:44.345Z,1684267304.345 [MassServo](DEBUG): Initializing EZServoServo. 2023-05-16T20:01:44.353Z,1684267304.353 [MassServo](DEBUG): Initializing MassServo. 2023-05-16T20:01:44.353Z,1684267304.353 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-16T20:01:44.360Z,1684267304.360 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-16T20:01:44.361Z,1684267304.361 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-05-16T20:01:44.369Z,1684267304.369 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-05-16T20:01:44.371Z,1684267304.371 [CommandExec](FAULT): Scheduling is paused 2023-05-16T20:01:44.371Z,1684267304.371 [CBIT](INFO): Critical error at 20230516T200143 2023-05-16T20:01:44.371Z,1684267304.371 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-05-16T20:01:46.464Z,1684267306.464 [WetLabsBB2FL](INFO): Powering down 2023-05-16T20:01:50.049Z,1684267310.049 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-05-16T20:01:50.876Z,1684267310.876 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-05-16T20:01:57.801Z,1684267317.801 [CommandExec](IMPORTANT): got command restart application 2023-05-16T20:01:58.804Z,1684267318.804 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:01:58.804Z,1684267318.804 [CommandExec](INFO): Uninitializing the command executive. 2023-05-16T20:01:58.804Z,1684267318.804 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-16T20:01:58.805Z,1684267318.805 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:58.976Z,1684267318.976 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-16T20:01:58.976Z,1684267318.976 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-16T20:01:58.976Z,1684267318.976 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:58.977Z,1684267318.977 [NavChartDb](INFO): Join timeout helper Thread ID is 953 2023-05-16T20:01:59.320Z,1684267319.320 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:01:59.320Z,1684267319.320 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.339Z,1684267319.339 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-16T20:01:59.339Z,1684267319.339 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.340Z,1684267319.340 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 954 2023-05-16T20:01:59.498Z,1684267319.498 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:01:59.499Z,1684267319.499 [WetLabsBB2FL](INFO): Powering down 2023-05-16T20:01:59.499Z,1684267319.499 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.516Z,1684267319.516 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-16T20:01:59.516Z,1684267319.516 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.516Z,1684267319.516 [CTD_Seabird](INFO): Join timeout helper Thread ID is 955 2023-05-16T20:01:59.909Z,1684267319.909 [CTD_Seabird](INFO): Powering down 2023-05-16T20:01:59.924Z,1684267319.924 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:01:59.924Z,1684267319.924 [CTD_Seabird](INFO): Powering down 2023-05-16T20:01:59.936Z,1684267319.936 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.956Z,1684267319.956 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-16T20:01:59.956Z,1684267319.956 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.956Z,1684267319.956 [Radio_Surface](INFO): Join timeout helper Thread ID is 956 2023-05-16T20:01:59.992Z,1684267319.992 [Radio_Surface](INFO): Powering down 2023-05-16T20:01:59.993Z,1684267319.993 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:01:59.993Z,1684267319.993 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:01:59.000Z,1684267320.000 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-16T20:01:59.000Z,1684267320.000 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:00.000Z,1684267320.000 [Onboard](INFO): Join timeout helper Thread ID is 957 2023-05-16T20:02:03.824Z,1684267323.824 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:02:03.824Z,1684267323.824 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:03.837Z,1684267323.837 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-16T20:02:03.837Z,1684267323.837 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:03.837Z,1684267323.837 [DataOverHttps](INFO): Join timeout helper Thread ID is 958 2023-05-16T20:02:03.880Z,1684267323.880 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:02:03.880Z,1684267323.880 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:03.899Z,1684267323.899 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-05-16T20:02:03.899Z,1684267323.899 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:03.899Z,1684267323.899 [BackseatComponent](INFO): Join timeout helper Thread ID is 959 2023-05-16T20:02:04.012Z,1684267324.012 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:02:04.012Z,1684267324.012 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.024Z,1684267324.024 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-16T20:02:04.025Z,1684267324.025 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.025Z,1684267324.025 [logger](INFO): Join timeout helper Thread ID is 960 2023-05-16T20:02:04.028Z,1684267324.028 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:02:04.028Z,1684267324.028 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.044Z,1684267324.044 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-16T20:02:04.045Z,1684267324.045 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.045Z,1684267324.045 [CommandLine](INFO): Join timeout helper Thread ID is 961 2023-05-16T20:02:04.068Z,1684267324.068 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:02:04.068Z,1684267324.068 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.085Z,1684267324.085 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-16T20:02:04.085Z,1684267324.085 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.085Z,1684267324.085 [CommandExec](INFO): Join timeout helper Thread ID is 962 2023-05-16T20:02:04.091Z,1684267324.091 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-16T20:02:04.091Z,1684267324.091 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.092Z,1684267324.092 [controlThread](INFO): Join timeout helper Thread ID is 963 2023-05-16T20:02:04.324Z,1684267324.324 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-16T20:02:04.324Z,1684267324.324 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-16T20:02:04.325Z,1684267324.325 [AHRS_M2](INFO): Powering down 2023-05-16T20:02:04.405Z,1684267324.405 [NAL9602](INFO): Powering down 2023-05-16T20:02:04.476Z,1684267324.476 [Waterlinked](INFO): Powering down 2023-05-16T20:02:04.477Z,1684267324.477 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-16T20:02:04.478Z,1684267324.478 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-16T20:02:04.479Z,1684267324.479 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-16T20:02:04.479Z,1684267324.479 [MissionManager](INFO): Uninitializing Mission Default 2023-05-16T20:02:04.482Z,1684267324.482 [MissionManager](INFO): Uninitializing Mission Startup 2023-05-16T20:02:04.483Z,1684267324.483 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-16T20:02:04.484Z,1684267324.484 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-16T20:02:04.484Z,1684267324.484 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-16T20:02:04.484Z,1684267324.484 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-16T20:02:04.485Z,1684267324.485 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-16T20:02:04.485Z,1684267324.485 [BuoyancyServo](INFO): Powering down 2023-05-16T20:02:04.500Z,1684267324.500 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-16T20:02:04.500Z,1684267324.500 [ElevatorServo](INFO): Powering down 2023-05-16T20:02:04.501Z,1684267324.501 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-16T20:02:04.501Z,1684267324.501 [MassServo](INFO): Powering down 2023-05-16T20:02:04.502Z,1684267324.502 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-16T20:02:04.502Z,1684267324.502 [RudderServo](INFO): Powering down 2023-05-16T20:02:04.503Z,1684267324.503 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-05-16T20:02:04.503Z,1684267324.503 [ThrusterHE](INFO): Powering down 2023-05-16T20:02:04.504Z,1684267324.504 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-16T20:02:04.504Z,1684267324.504 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-16T20:02:04.504Z,1684267324.504 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-16T20:02:04.504Z,1684267324.504 [CBIT](DEBUG): Powering off loads. 2023-05-16T20:02:04.516Z,1684267324.516 [CBIT](DEBUG): Disabling WDT. 2023-05-16T20:02:04.528Z,1684267324.528 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-16T20:02:04.528Z,1684267324.528 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.546Z,1684267324.546 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.550Z,1684267324.550 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.555Z,1684267324.555 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.599Z,1684267324.599 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.645Z,1684267324.645 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.648Z,1684267324.648 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.934Z,1684267324.934 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-16T20:02:04.979Z,1684267324.979 [logger ThreadHandler](INFO): Thread cancelled.