2022-07-20T15:33:37.716Z,1658331217.716 [Supervisor](DEBUG): Initializing supervisor. 2022-07-20T15:33:37.720Z,1658331217.720 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-07-20T15:33:37.721Z,1658331217.721 [SyncHandler](INFO): Protected caller Thread ID is 835 2022-07-20T15:33:37.721Z,1658331217.721 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-07-20T15:33:37.722Z,1658331217.722 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-07-20T15:33:37.723Z,1658331217.723 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836 2022-07-20T15:33:37.727Z,1658331217.727 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-07-20T15:33:37.745Z,1658331217.745 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-07-20T15:33:37.745Z,1658331217.745 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-07-20T15:33:37.746Z,1658331217.746 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837 2022-07-20T15:33:37.750Z,1658331217.750 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-07-20T15:33:37.751Z,1658331217.751 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-07-20T15:33:37.751Z,1658331217.751 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838 2022-07-20T15:33:37.753Z,1658331217.753 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-07-20T15:33:37.754Z,1658331217.754 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-07-20T15:33:37.755Z,1658331217.755 [logger ThreadHandler](INFO): Protected caller Thread ID is 839 2022-07-20T15:33:37.758Z,1658331217.758 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-07-20T15:33:37.759Z,1658331217.759 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-07-20T15:33:37.763Z,1658331217.763 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-07-20T15:33:38.031Z,1658331218.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-07-20T15:33:38.033Z,1658331218.033 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-07-20T15:33:38.114Z,1658331218.114 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-07-20T15:33:38.635Z,1658331218.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-07-20T15:33:38.637Z,1658331218.637 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-07-20T15:33:38.960Z,1658331218.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-07-20T15:33:38.961Z,1658331218.961 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-07-20T15:33:39.058Z,1658331219.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-07-20T15:33:39.060Z,1658331219.060 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-07-20T15:33:39.422Z,1658331219.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-07-20T15:33:39.423Z,1658331219.423 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-07-20T15:33:39.634Z,1658331219.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-07-20T15:33:39.635Z,1658331219.635 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-07-20T15:33:40.090Z,1658331220.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-07-20T15:33:40.090Z,1658331220.090 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-07-20T15:33:40.218Z,1658331220.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-07-20T15:33:40.219Z,1658331220.219 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-07-20T15:33:40.300Z,1658331220.300 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-07-20T15:33:41.001Z,1658331221.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-07-20T15:33:41.003Z,1658331221.003 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-07-20T15:33:41.439Z,1658331221.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-07-20T15:33:41.440Z,1658331221.440 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-07-20T15:33:42.183Z,1658331222.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-07-20T15:33:42.184Z,1658331222.184 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-07-20T15:33:42.294Z,1658331222.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-07-20T15:33:42.295Z,1658331222.295 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-07-20T15:33:42.515Z,1658331222.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-07-20T15:33:42.516Z,1658331222.516 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-07-20T15:33:42.743Z,1658331222.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-07-20T15:33:42.745Z,1658331222.745 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2022-07-20T15:33:42.748Z,1658331222.748 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2022-07-20T15:33:42.840Z,1658331222.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2022-07-20T15:33:42.927Z,1658331222.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2022-07-20T15:33:43.030Z,1658331223.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2022-07-20T15:33:43.114Z,1658331223.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2022-07-20T15:33:43.212Z,1658331223.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2022-07-20T15:33:43.314Z,1658331223.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2022-07-20T15:33:43.501Z,1658331223.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2022-07-20T15:33:43.578Z,1658331223.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2022-07-20T15:33:43.791Z,1658331223.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2022-07-20T15:33:43.946Z,1658331223.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2022-07-20T15:33:44.060Z,1658331224.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2022-07-20T15:33:44.321Z,1658331224.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-07-20T15:33:44.321Z,1658331224.321 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-07-20T15:33:44.337Z,1658331224.337 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-07-20T15:33:44.407Z,1658331224.407 [VerticalControl](DEBUG): Construct VerticalControl. 2022-07-20T15:33:44.467Z,1658331224.467 [VerticalControl] Loaded 2022-07-20T15:33:44.467Z,1658331224.467 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-07-20T15:33:44.470Z,1658331224.470 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-07-20T15:33:44.509Z,1658331224.509 [HorizontalControl] Loaded 2022-07-20T15:33:44.509Z,1658331224.509 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-07-20T15:33:44.511Z,1658331224.511 [SpeedControl](DEBUG): Construct SpeedControl. 2022-07-20T15:33:44.514Z,1658331224.514 [SpeedControl] Loaded 2022-07-20T15:33:44.515Z,1658331224.515 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-07-20T15:33:44.517Z,1658331224.517 [LoopControl](DEBUG): Construct LoopControl. 2022-07-20T15:33:44.518Z,1658331224.518 [LoopControl] Loaded 2022-07-20T15:33:44.518Z,1658331224.518 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-07-20T15:33:44.518Z,1658331224.518 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-07-20T15:33:44.520Z,1658331224.520 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-07-20T15:33:44.535Z,1658331224.535 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-07-20T15:33:44.536Z,1658331224.536 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-07-20T15:33:44.678Z,1658331224.678 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-07-20T15:33:44.679Z,1658331224.679 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-07-20T15:33:44.853Z,1658331224.853 [BuoyancyServo] Loaded 2022-07-20T15:33:44.853Z,1658331224.853 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-07-20T15:33:44.872Z,1658331224.872 [ElevatorServo] Loaded 2022-07-20T15:33:44.872Z,1658331224.872 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-07-20T15:33:44.890Z,1658331224.890 [MassServo] Loaded 2022-07-20T15:33:44.891Z,1658331224.891 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-07-20T15:33:44.908Z,1658331224.908 [RudderServo] Loaded 2022-07-20T15:33:44.908Z,1658331224.908 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-07-20T15:33:44.926Z,1658331224.926 [ThrusterServo] Loaded 2022-07-20T15:33:44.926Z,1658331224.926 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2022-07-20T15:33:44.927Z,1658331224.927 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-07-20T15:33:44.927Z,1658331224.927 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-07-20T15:33:45.064Z,1658331225.064 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-07-20T15:33:45.064Z,1658331225.064 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-07-20T15:33:45.078Z,1658331225.078 [NavChart] Loaded 2022-07-20T15:33:45.078Z,1658331225.078 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-07-20T15:33:45.083Z,1658331225.083 [UniversalFixResidualReporter] Loaded 2022-07-20T15:33:45.084Z,1658331225.084 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-07-20T15:33:45.084Z,1658331225.084 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-07-20T15:33:45.085Z,1658331225.085 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-07-20T15:33:45.168Z,1658331225.168 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-07-20T15:33:45.169Z,1658331225.169 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-07-20T15:33:45.868Z,1658331225.868 [AHRS_M2] Loaded 2022-07-20T15:33:45.868Z,1658331225.868 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-07-20T15:33:46.669Z,1658331226.669 [BPC1] Loaded 2022-07-20T15:33:46.670Z,1658331226.670 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-07-20T15:33:46.810Z,1658331226.810 [DataOverHttps] Loaded 2022-07-20T15:33:46.810Z,1658331226.810 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-07-20T15:33:46.811Z,1658331226.811 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0 2022-07-20T15:33:46.812Z,1658331226.812 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926 2022-07-20T15:33:46.831Z,1658331226.831 [Depth_Keller] Loaded 2022-07-20T15:33:46.832Z,1658331226.832 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-07-20T15:33:46.890Z,1658331226.890 [NAL9602] Loaded 2022-07-20T15:33:46.890Z,1658331226.890 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-07-20T15:33:46.919Z,1658331226.919 [Onboard] Loaded 2022-07-20T15:33:46.919Z,1658331226.919 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-07-20T15:33:46.920Z,1658331226.920 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409214E0 2022-07-20T15:33:46.920Z,1658331226.920 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927 2022-07-20T15:33:46.933Z,1658331226.933 [Power24vConverter] Loaded 2022-07-20T15:33:46.933Z,1658331226.933 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-07-20T15:33:46.946Z,1658331226.946 [Radio_Surface] Loaded 2022-07-20T15:33:46.946Z,1658331226.946 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-07-20T15:33:46.947Z,1658331226.947 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409514E0 2022-07-20T15:33:46.947Z,1658331226.947 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928 2022-07-20T15:33:46.948Z,1658331226.948 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-07-20T15:33:46.949Z,1658331226.949 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-07-20T15:33:47.400Z,1658331227.400 [DepthRateCalculator] Loaded 2022-07-20T15:33:47.400Z,1658331227.400 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-07-20T15:33:47.405Z,1658331227.405 [PitchRateCalculator] Loaded 2022-07-20T15:33:47.421Z,1658331227.421 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-07-20T15:33:47.441Z,1658331227.441 [SpeedCalculator] Loaded 2022-07-20T15:33:47.442Z,1658331227.442 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-07-20T15:33:47.446Z,1658331227.446 [YawRateCalculator] Loaded 2022-07-20T15:33:47.446Z,1658331227.446 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-07-20T15:33:47.528Z,1658331227.528 [ElevatorOffsetCalculator] Loaded 2022-07-20T15:33:47.528Z,1658331227.528 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-07-20T15:33:47.529Z,1658331227.529 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-07-20T15:33:47.565Z,1658331227.565 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-07-20T15:33:48.089Z,1658331228.089 [CANONSampler] Loaded 2022-07-20T15:33:48.089Z,1658331228.089 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2022-07-20T15:33:48.148Z,1658331228.148 [CTD_Seabird] Loaded 2022-07-20T15:33:48.149Z,1658331228.149 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-07-20T15:33:48.150Z,1658331228.150 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0 2022-07-20T15:33:48.150Z,1658331228.150 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929 2022-07-20T15:33:48.170Z,1658331228.170 [PAR_Licor] Loaded 2022-07-20T15:33:48.170Z,1658331228.170 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-07-20T15:33:48.201Z,1658331228.201 [WetLabsBB2FL] Loaded 2022-07-20T15:33:48.201Z,1658331228.201 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-07-20T15:33:48.202Z,1658331228.202 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A394E0 2022-07-20T15:33:48.202Z,1658331228.202 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930 2022-07-20T15:33:48.203Z,1658331228.203 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-07-20T15:33:48.204Z,1658331228.204 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-07-20T15:33:48.246Z,1658331228.246 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-07-20T15:33:48.246Z,1658331228.246 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-07-20T15:33:48.607Z,1658331228.607 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-07-20T15:33:48.609Z,1658331228.609 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-07-20T15:33:48.777Z,1658331228.777 [SBIT](DEBUG): Construct Startup Built In Test. 2022-07-20T15:33:48.786Z,1658331228.786 [SBIT] Loaded 2022-07-20T15:33:48.786Z,1658331228.786 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-07-20T15:33:48.789Z,1658331228.789 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-07-20T15:33:48.802Z,1658331228.802 [IBIT] Loaded 2022-07-20T15:33:48.803Z,1658331228.803 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-07-20T15:33:48.809Z,1658331228.809 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-07-20T15:33:48.912Z,1658331228.912 [CBIT] Loaded 2022-07-20T15:33:48.912Z,1658331228.912 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-07-20T15:33:48.913Z,1658331228.913 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-07-20T15:33:48.914Z,1658331228.914 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-07-20T15:33:49.034Z,1658331229.034 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-07-20T15:33:49.040Z,1658331229.040 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-07-20T15:33:49.042Z,1658331229.042 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-07-20T15:33:49.053Z,1658331229.053 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-07-20T15:33:49.054Z,1658331229.054 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B754E0 2022-07-20T15:33:49.055Z,1658331229.055 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 2022-07-20T15:33:49.059Z,1658331229.059 [Supervisor](INFO): Main Thread ID is 830 2022-07-20T15:33:49.059Z,1658331229.059 [Supervisor](DEBUG): Running supervisor. 2022-07-20T15:33:49.060Z,1658331229.060 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 2022-07-20T15:33:49.060Z,1658331229.060 [CommandExec](INFO): Initializing the command executive. 2022-07-20T15:33:49.062Z,1658331229.062 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 2022-07-20T15:33:49.064Z,1658331229.064 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 2022-07-20T15:33:49.064Z,1658331229.064 [controlThread](DEBUG): Initializing ControlThread 2022-07-20T15:33:49.065Z,1658331229.065 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-07-20T15:33:49.067Z,1658331229.067 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-07-20T15:33:49.068Z,1658331229.068 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-07-20T15:33:49.068Z,1658331229.068 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-07-20T15:33:49.070Z,1658331229.070 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-07-20T15:33:49.070Z,1658331229.070 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-07-20T15:33:49.074Z,1658331229.074 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-07-20T15:33:49.074Z,1658331229.074 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-07-20T15:33:49.074Z,1658331229.074 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-07-20T15:33:49.075Z,1658331229.075 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-07-20T15:33:49.075Z,1658331229.075 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-07-20T15:33:49.077Z,1658331229.077 [SBIT](INFO): Initialize SBIT Component. 2022-07-20T15:33:49.078Z,1658331229.078 [SBIT](IMPORTANT): git: 2022-04-11 2022-07-20T15:33:49.078Z,1658331229.078 [SBIT](INFO): git hash: 059cb0c4bb1d2c42f72e645cf8ce57fde1aeb3d9 2022-07-20T15:33:49.078Z,1658331229.078 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-07-20T15:33:49.079Z,1658331229.079 [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 2022-07-20T15:33:49.080Z,1658331229.080 [SBIT](INFO): Beginning SBIT in 93.000000 seconds. 2022-07-20T15:33:49.081Z,1658331229.081 [IBIT](INFO): Initialize IBIT Component. 2022-07-20T15:33:49.082Z,1658331229.082 [CBIT](DEBUG): Initialize CBIT Component. 2022-07-20T15:33:49.083Z,1658331229.083 [logger ThreadHandler](INFO): Handler Thread ID is 935 2022-07-20T15:33:49.093Z,1658331229.093 [CBIT](DEBUG): Initialized mux pins. 2022-07-20T15:33:49.093Z,1658331229.093 [CBIT](DEBUG): Initializing the watchdog timer. 2022-07-20T15:33:49.101Z,1658331229.101 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2022-07-20T15:33:49.102Z,1658331229.102 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-07-20T15:33:49.113Z,1658331229.113 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2022-07-20T15:33:49.118Z,1658331229.118 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-07-20T15:33:49.118Z,1658331229.118 [CBIT](DEBUG): Initializing heartbeat. 2022-07-20T15:33:49.134Z,1658331229.134 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2022-07-20T15:33:49.146Z,1658331229.146 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2022-07-20T15:33:49.147Z,1658331229.147 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-07-20T15:33:49.149Z,1658331229.149 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2022-07-20T15:33:49.154Z,1658331229.154 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2022-07-20T15:33:49.154Z,1658331229.154 [WetLabsBB2FL](INFO): Powering up 2022-07-20T15:33:49.163Z,1658331229.163 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-07-20T15:33:49.163Z,1658331229.163 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-07-20T15:33:49.164Z,1658331229.164 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-07-20T15:33:49.164Z,1658331229.164 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-07-20T15:33:49.164Z,1658331229.164 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-07-20T15:33:49.164Z,1658331229.164 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-07-20T15:33:49.164Z,1658331229.164 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2022-07-20T15:33:49.164Z,1658331229.164 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2022-07-20T15:33:49.165Z,1658331229.165 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-07-20T15:33:49.165Z,1658331229.165 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-07-20T15:33:49.165Z,1658331229.165 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2022-07-20T15:33:49.165Z,1658331229.165 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2022-07-20T15:33:49.166Z,1658331229.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2022-07-20T15:33:49.166Z,1658331229.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2022-07-20T15:33:49.166Z,1658331229.166 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2022-07-20T15:33:49.166Z,1658331229.166 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2022-07-20T15:33:49.189Z,1658331229.189 [CBIT](DEBUG): Deactivating GF circuits. 2022-07-20T15:33:49.189Z,1658331229.189 [CBIT](DEBUG): Deactivating emergency mode. 2022-07-20T15:33:49.225Z,1658331229.225 [CBIT](DEBUG): Backplane powered. 2022-07-20T15:33:49.226Z,1658331229.226 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-07-20T15:33:49.256Z,1658331229.256 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-07-20T15:33:49.276Z,1658331229.276 [MissionManager](DEBUG): 2022-07-20T15:33:49.276Z,1658331229.276 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-07-20T15:33:49.340Z,1658331229.340 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-07-20T15:33:49.362Z,1658331229.362 [Default:A.Wait](DEBUG): Construct Wait. 2022-07-20T15:33:49.364Z,1658331229.364 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-07-20T15:33:49.407Z,1658331229.407 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-07-20T15:33:49.426Z,1658331229.426 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-07-20T15:33:49.432Z,1658331229.432 [Default:E.Execute](DEBUG): Construct Execute. 2022-07-20T15:33:49.451Z,1658331229.451 [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 2022-07-20T15:33:49.456Z,1658331229.456 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-07-20T15:33:49.466Z,1658331229.466 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-20T15:33:49.537Z,1658331229.537 [Radio_Surface](INFO): Powering up 2022-07-20T15:33:49.654Z,1658331229.654 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2022-07-20T15:33:49.655Z,1658331229.655 [Power24vConverter](INFO): Powering up. 2022-07-20T15:33:49.656Z,1658331229.656 [CANONSampler](INFO): Powering down 2022-07-20T15:33:49.667Z,1658331229.667 [DepthRateCalculator](ERROR): Depth measurement is not active 2022-07-20T15:33:49.702Z,1658331229.702 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-07-20T15:33:49.709Z,1658331229.709 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-07-20T15:33:49.711Z,1658331229.711 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-07-20T15:33:49.717Z,1658331229.717 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-07-20T15:33:49.718Z,1658331229.718 [MassServo](DEBUG): Initializing EZServoServo. 2022-07-20T15:33:49.726Z,1658331229.726 [MassServo](DEBUG): Initializing MassServo. 2022-07-20T15:33:49.726Z,1658331229.726 [RudderServo](DEBUG): Initializing EZServoServo. 2022-07-20T15:33:49.733Z,1658331229.733 [RudderServo](DEBUG): Initializing RudderServo. 2022-07-20T15:33:49.734Z,1658331229.734 [ThrusterServo](DEBUG): Initializing EZServoServo. 2022-07-20T15:33:49.741Z,1658331229.741 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2022-07-20T15:33:50.605Z,1658331230.605 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-07-20T15:33:50.605Z,1658331230.605 [RudderServo](FAULT): Rudder failed to initialize 2022-07-20T15:33:50.605Z,1658331230.605 [RudderServo] Communications Fault, FailCount= 1 2022-07-20T15:33:50.605Z,1658331230.605 [RudderServo](ERROR): Communications Fault 2022-07-20T15:33:50.705Z,1658331230.705 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-07-20T15:33:50.914Z,1658331230.914 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-07-20T15:33:50.914Z,1658331230.914 [RudderServo](INFO): Powering down 2022-07-20T15:33:51.261Z,1658331231.261 [WetLabsBB2FL](INFO): Powering down 2022-07-20T15:33:51.541Z,1658331231.541 [RudderServo](DEBUG): Initializing EZServoServo. 2022-07-20T15:33:51.666Z,1658331231.666 [RudderServo](DEBUG): Initializing RudderServo. 2022-07-20T15:33:51.670Z,1658331231.670 [CBIT](INFO): Clearing failed state for component RudderServo 2022-07-20T15:33:51.670Z,1658331231.670 [RudderServo] No Fault, FailCount= 1 2022-07-20T15:33:56.178Z,1658331236.178 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2022-07-20T15:34:16.978Z,1658331256.978 [NAL9602](INFO): Powering up NAL9602 2022-07-20T15:34:18.366Z,1658331258.366 [CommandExec](IMPORTANT): got command failComponent 2022-07-20T15:34:18.366Z,1658331258.366 [CommandExec](IMPORTANT): Failed components: 2022-07-20T15:34:18.366Z,1658331258.366 [CommandExec](IMPORTANT): No failed Components. 2022-07-20T15:34:23.491Z,1658331263.491 [CommandExec](IMPORTANT): got command restart application 2022-07-20T15:34:24.493Z,1658331264.493 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2022-07-20T15:34:24.493Z,1658331264.493 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:24.494Z,1658331264.494 [CommandExec](INFO): Uninitializing the command executive. 2022-07-20T15:34:24.494Z,1658331264.494 [CommandExec](INFO): Uninitializing the command scheduler. 2022-07-20T15:34:24.494Z,1658331264.494 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:24.537Z,1658331264.537 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-07-20T15:34:24.537Z,1658331264.537 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-07-20T15:34:24.537Z,1658331264.537 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:24.538Z,1658331264.538 [NavChartDb](INFO): Join timeout helper Thread ID is 952 2022-07-20T15:34:24.958Z,1658331264.958 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:24.959Z,1658331264.959 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:24.965Z,1658331264.965 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-07-20T15:34:24.965Z,1658331264.965 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:24.966Z,1658331264.966 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 953 2022-07-20T15:34:25.437Z,1658331265.437 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:25.437Z,1658331265.437 [WetLabsBB2FL](INFO): Powering down 2022-07-20T15:34:25.438Z,1658331265.438 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:25.449Z,1658331265.449 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-07-20T15:34:25.449Z,1658331265.449 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:25.450Z,1658331265.450 [CTD_Seabird](INFO): Join timeout helper Thread ID is 954 2022-07-20T15:34:26.293Z,1658331266.293 [CTD_Seabird](INFO): Powering down 2022-07-20T15:34:26.305Z,1658331266.305 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:26.305Z,1658331266.305 [CTD_Seabird](INFO): Powering down 2022-07-20T15:34:26.317Z,1658331266.317 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:26.318Z,1658331266.318 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-07-20T15:34:26.318Z,1658331266.318 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:26.318Z,1658331266.318 [Radio_Surface](INFO): Join timeout helper Thread ID is 955 2022-07-20T15:34:26.337Z,1658331266.337 [Radio_Surface](INFO): Powering down 2022-07-20T15:34:26.338Z,1658331266.338 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:26.338Z,1658331266.338 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:26.349Z,1658331266.349 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-07-20T15:34:26.349Z,1658331266.349 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:26.350Z,1658331266.350 [Onboard](INFO): Join timeout helper Thread ID is 956 2022-07-20T15:34:27.891Z,1658331267.891 [NAL9602](INFO): NAL9602 initialized 2022-07-20T15:34:29.145Z,1658331269.145 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:29.145Z,1658331269.145 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.150Z,1658331269.150 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-07-20T15:34:29.150Z,1658331269.150 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.150Z,1658331269.150 [DataOverHttps](INFO): Join timeout helper Thread ID is 958 2022-07-20T15:34:29.282Z,1658331269.282 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:29.284Z,1658331269.284 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.290Z,1658331269.290 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-07-20T15:34:29.290Z,1658331269.290 [logger ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.290Z,1658331269.290 [logger](INFO): Join timeout helper Thread ID is 959 2022-07-20T15:34:29.305Z,1658331269.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:29.305Z,1658331269.305 [logger ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.310Z,1658331269.310 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-07-20T15:34:29.310Z,1658331269.310 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.310Z,1658331269.310 [CommandLine](INFO): Join timeout helper Thread ID is 960 2022-07-20T15:34:29.321Z,1658331269.321 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:29.321Z,1658331269.321 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.330Z,1658331269.330 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-07-20T15:34:29.330Z,1658331269.330 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.330Z,1658331269.330 [CommandExec](INFO): Join timeout helper Thread ID is 961 2022-07-20T15:34:29.331Z,1658331269.331 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-07-20T15:34:29.331Z,1658331269.331 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.332Z,1658331269.332 [controlThread](INFO): Join timeout helper Thread ID is 962 2022-07-20T15:34:29.493Z,1658331269.493 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-07-20T15:34:29.493Z,1658331269.493 [controlThread](DEBUG): Uninitializing ControlThread 2022-07-20T15:34:29.494Z,1658331269.494 [AHRS_M2](INFO): Powering down 2022-07-20T15:34:29.566Z,1658331269.566 [NAL9602](INFO): Powering down 2022-07-20T15:34:29.569Z,1658331269.569 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-07-20T15:34:29.570Z,1658331269.570 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-07-20T15:34:29.570Z,1658331269.570 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-07-20T15:34:29.571Z,1658331269.571 [MissionManager](INFO): Uninitializing Mission Default 2022-07-20T15:34:29.573Z,1658331269.573 [MissionManager](INFO): Uninitializing Mission Startup 2022-07-20T15:34:29.575Z,1658331269.575 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-07-20T15:34:29.575Z,1658331269.575 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-07-20T15:34:29.575Z,1658331269.575 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-07-20T15:34:29.575Z,1658331269.575 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-07-20T15:34:29.576Z,1658331269.576 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-07-20T15:34:29.576Z,1658331269.576 [BuoyancyServo](INFO): Powering down 2022-07-20T15:34:29.589Z,1658331269.589 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-07-20T15:34:29.589Z,1658331269.589 [ElevatorServo](INFO): Powering down 2022-07-20T15:34:29.590Z,1658331269.590 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-07-20T15:34:29.590Z,1658331269.590 [MassServo](INFO): Powering down 2022-07-20T15:34:29.591Z,1658331269.591 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-07-20T15:34:29.591Z,1658331269.591 [RudderServo](INFO): Powering down 2022-07-20T15:34:29.592Z,1658331269.592 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2022-07-20T15:34:29.592Z,1658331269.592 [ThrusterServo](INFO): Powering down 2022-07-20T15:34:29.593Z,1658331269.593 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-07-20T15:34:29.593Z,1658331269.593 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-07-20T15:34:29.594Z,1658331269.594 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-07-20T15:34:29.594Z,1658331269.594 [CBIT](DEBUG): Powering off loads. 2022-07-20T15:34:29.605Z,1658331269.605 [CBIT](DEBUG): Disabling WDT. 2022-07-20T15:34:29.617Z,1658331269.617 [CBIT](DEBUG): Opening all GF detection circuits. 2022-07-20T15:34:29.618Z,1658331269.618 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.782Z,1658331269.782 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.785Z,1658331269.785 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.790Z,1658331269.790 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.858Z,1658331269.858 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.861Z,1658331269.861 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.900Z,1658331269.900 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-07-20T15:34:29.962Z,1658331269.962 [logger ThreadHandler](INFO): Thread cancelled.