2024-08-14T23:09:54.448Z,1723676994.448 [Supervisor](DEBUG): Initializing supervisor. 2024-08-14T23:09:54.452Z,1723676994.452 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-08-14T23:09:54.452Z,1723676994.452 [SyncHandler](INFO): Protected caller Thread ID is 304 2024-08-14T23:09:54.453Z,1723676994.453 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-08-14T23:09:54.453Z,1723676994.453 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-08-14T23:09:54.454Z,1723676994.454 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 305 2024-08-14T23:09:54.458Z,1723676994.458 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-08-14T23:09:54.475Z,1723676994.475 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-08-14T23:09:54.476Z,1723676994.476 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-08-14T23:09:54.476Z,1723676994.476 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 306 2024-08-14T23:09:54.480Z,1723676994.480 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-08-14T23:09:54.481Z,1723676994.481 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-08-14T23:09:54.481Z,1723676994.481 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 307 2024-08-14T23:09:54.483Z,1723676994.483 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-08-14T23:09:54.484Z,1723676994.484 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-08-14T23:09:54.485Z,1723676994.485 [logger ThreadHandler](INFO): Protected caller Thread ID is 308 2024-08-14T23:09:54.488Z,1723676994.488 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-08-14T23:09:54.488Z,1723676994.488 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-08-14T23:09:54.490Z,1723676994.490 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-08-14T23:09:54.850Z,1723676994.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-08-14T23:09:54.850Z,1723676994.850 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-08-14T23:09:55.612Z,1723676995.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-08-14T23:09:55.613Z,1723676995.613 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-08-14T23:09:55.733Z,1723676995.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-08-14T23:09:55.733Z,1723676995.733 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-08-14T23:09:56.293Z,1723676996.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-08-14T23:09:56.294Z,1723676996.294 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-08-14T23:09:56.401Z,1723676996.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-08-14T23:09:56.402Z,1723676996.402 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-08-14T23:09:56.494Z,1723676996.494 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-08-14T23:09:56.918Z,1723676996.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-08-14T23:09:56.919Z,1723676996.919 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-08-14T23:09:57.155Z,1723676997.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-08-14T23:09:57.155Z,1723676997.155 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-08-14T23:09:57.305Z,1723676997.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-08-14T23:09:57.305Z,1723676997.305 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-08-14T23:09:57.560Z,1723676997.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-08-14T23:09:57.560Z,1723676997.560 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-08-14T23:09:58.120Z,1723676998.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-08-14T23:09:58.120Z,1723676998.120 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-08-14T23:09:58.520Z,1723676998.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-08-14T23:09:58.520Z,1723676998.520 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-08-14T23:09:58.612Z,1723676998.612 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-08-14T23:09:58.818Z,1723676998.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-08-14T23:09:58.819Z,1723676998.819 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-08-14T23:09:59.062Z,1723676999.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-08-14T23:09:59.062Z,1723676999.062 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-08-14T23:09:59.480Z,1723676999.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-08-14T23:09:59.482Z,1723676999.482 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2024-08-14T23:09:59.483Z,1723676999.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2024-08-14T23:09:59.766Z,1723676999.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2024-08-14T23:09:59.977Z,1723676999.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2024-08-14T23:10:00.089Z,1723677000.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2024-08-14T23:10:00.280Z,1723677000.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2024-08-14T23:10:00.401Z,1723677000.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2024-08-14T23:10:00.497Z,1723677000.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2024-08-14T23:10:00.614Z,1723677000.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2024-08-14T23:10:00.888Z,1723677000.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-08-14T23:10:00.889Z,1723677000.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2024-08-14T23:10:01.034Z,1723677001.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2024-08-14T23:10:01.139Z,1723677001.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2024-08-14T23:10:01.244Z,1723677001.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2024-08-14T23:10:01.360Z,1723677001.360 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-08-14T23:10:01.376Z,1723677001.376 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-08-14T23:10:01.484Z,1723677001.484 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-08-14T23:10:01.485Z,1723677001.485 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-08-14T23:10:01.494Z,1723677001.494 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-08-14T23:10:01.495Z,1723677001.495 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-08-14T23:10:01.543Z,1723677001.543 [DepthRateCalculator] Loaded 2024-08-14T23:10:01.544Z,1723677001.544 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-08-14T23:10:01.549Z,1723677001.549 [PitchRateCalculator] Loaded 2024-08-14T23:10:01.549Z,1723677001.549 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-08-14T23:10:01.559Z,1723677001.559 [SpeedCalculator] Loaded 2024-08-14T23:10:01.559Z,1723677001.559 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-08-14T23:10:01.564Z,1723677001.564 [YawRateCalculator] Loaded 2024-08-14T23:10:01.564Z,1723677001.564 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-08-14T23:10:01.585Z,1723677001.585 [ElevatorOffsetCalculator] Loaded 2024-08-14T23:10:01.585Z,1723677001.585 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-08-14T23:10:01.585Z,1723677001.585 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-08-14T23:10:01.586Z,1723677001.586 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-08-14T23:10:01.636Z,1723677001.636 [VerticalControl](DEBUG): Construct VerticalControl. 2024-08-14T23:10:01.700Z,1723677001.700 [VerticalControl] Loaded 2024-08-14T23:10:01.700Z,1723677001.700 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-08-14T23:10:01.703Z,1723677001.703 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-08-14T23:10:01.750Z,1723677001.750 [HorizontalControl] Loaded 2024-08-14T23:10:01.750Z,1723677001.750 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-08-14T23:10:01.752Z,1723677001.752 [SpeedControl](DEBUG): Construct SpeedControl. 2024-08-14T23:10:01.755Z,1723677001.755 [SpeedControl] Loaded 2024-08-14T23:10:01.755Z,1723677001.755 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-08-14T23:10:01.758Z,1723677001.758 [LoopControl](DEBUG): Construct LoopControl. 2024-08-14T23:10:01.758Z,1723677001.758 [LoopControl] Loaded 2024-08-14T23:10:01.758Z,1723677001.758 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-08-14T23:10:01.759Z,1723677001.759 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-08-14T23:10:01.759Z,1723677001.759 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-08-14T23:10:01.866Z,1723677001.866 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-08-14T23:10:01.867Z,1723677001.867 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-08-14T23:10:01.880Z,1723677001.880 [NavChart] Loaded 2024-08-14T23:10:01.881Z,1723677001.881 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-08-14T23:10:01.886Z,1723677001.886 [UniversalFixResidualReporter] Loaded 2024-08-14T23:10:01.886Z,1723677001.886 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-08-14T23:10:01.887Z,1723677001.887 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-08-14T23:10:01.887Z,1723677001.887 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-08-14T23:10:02.054Z,1723677002.054 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-08-14T23:10:02.054Z,1723677002.054 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-08-14T23:10:02.559Z,1723677002.559 [AHRS_M2] Loaded 2024-08-14T23:10:02.559Z,1723677002.559 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-08-14T23:10:02.599Z,1723677002.599 [BackseatComponent] Loaded 2024-08-14T23:10:02.599Z,1723677002.599 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-08-14T23:10:02.600Z,1723677002.600 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0 2024-08-14T23:10:02.600Z,1723677002.600 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 394 2024-08-14T23:10:02.603Z,1723677002.603 [LcmUniversalReporter] Loaded 2024-08-14T23:10:02.603Z,1723677002.603 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-08-14T23:10:03.421Z,1723677003.421 [BPC1] Loaded 2024-08-14T23:10:03.422Z,1723677003.422 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-08-14T23:10:03.508Z,1723677003.508 [DATMMP] Loaded 2024-08-14T23:10:03.508Z,1723677003.508 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-08-14T23:10:03.509Z,1723677003.509 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409694E0 2024-08-14T23:10:03.510Z,1723677003.510 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 395 2024-08-14T23:10:03.579Z,1723677003.579 [DataOverHttps] Loaded 2024-08-14T23:10:03.580Z,1723677003.580 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-08-14T23:10:03.581Z,1723677003.581 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409994E0 2024-08-14T23:10:03.581Z,1723677003.581 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 396 2024-08-14T23:10:03.604Z,1723677003.604 [Depth_Keller] Loaded 2024-08-14T23:10:03.605Z,1723677003.605 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-08-14T23:10:03.644Z,1723677003.644 [DVL_micro] Loaded 2024-08-14T23:10:03.644Z,1723677003.644 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-08-14T23:10:03.707Z,1723677003.707 [NAL9602] Loaded 2024-08-14T23:10:03.707Z,1723677003.707 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-08-14T23:10:03.737Z,1723677003.737 [Onboard] Loaded 2024-08-14T23:10:03.738Z,1723677003.738 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-08-14T23:10:03.738Z,1723677003.738 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0 2024-08-14T23:10:03.739Z,1723677003.739 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 397 2024-08-14T23:10:03.753Z,1723677003.753 [Power24vConverter] Loaded 2024-08-14T23:10:03.753Z,1723677003.753 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-08-14T23:10:03.768Z,1723677003.768 [Radio_Surface] Loaded 2024-08-14T23:10:03.768Z,1723677003.768 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-08-14T23:10:03.769Z,1723677003.769 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0 2024-08-14T23:10:03.769Z,1723677003.769 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 398 2024-08-14T23:10:03.785Z,1723677003.785 [Sonardyne_Nano] Loaded 2024-08-14T23:10:03.785Z,1723677003.785 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-08-14T23:10:03.785Z,1723677003.785 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-08-14T23:10:03.786Z,1723677003.786 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-08-14T23:10:03.915Z,1723677003.915 [BuoyancyServo] Loaded 2024-08-14T23:10:03.915Z,1723677003.915 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-08-14T23:10:03.936Z,1723677003.936 [MassServo] Loaded 2024-08-14T23:10:03.937Z,1723677003.937 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-08-14T23:10:03.957Z,1723677003.957 [RudderServo] Loaded 2024-08-14T23:10:03.957Z,1723677003.957 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-08-14T23:10:03.973Z,1723677003.973 [ThrusterHE] Loaded 2024-08-14T23:10:03.973Z,1723677003.973 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-08-14T23:10:03.974Z,1723677003.974 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-08-14T23:10:03.974Z,1723677003.974 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-08-14T23:10:04.042Z,1723677004.042 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-08-14T23:10:04.042Z,1723677004.042 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-08-14T23:10:04.068Z,1723677004.068 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-08-14T23:10:04.069Z,1723677004.069 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-08-14T23:10:04.393Z,1723677004.393 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-08-14T23:10:04.393Z,1723677004.393 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-08-14T23:10:04.814Z,1723677004.814 [CTD_Seabird] Loaded 2024-08-14T23:10:04.815Z,1723677004.815 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-08-14T23:10:04.816Z,1723677004.816 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB34E0 2024-08-14T23:10:04.816Z,1723677004.816 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 402 2024-08-14T23:10:04.848Z,1723677004.848 [ESPComponent] Loaded 2024-08-14T23:10:04.849Z,1723677004.849 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-08-14T23:10:04.870Z,1723677004.870 [PAR_Licor] Loaded 2024-08-14T23:10:04.870Z,1723677004.870 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-08-14T23:10:04.871Z,1723677004.871 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-08-14T23:10:04.872Z,1723677004.872 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-08-14T23:10:05.301Z,1723677005.301 [SBIT](DEBUG): Construct Startup Built In Test. 2024-08-14T23:10:05.310Z,1723677005.310 [SBIT] Loaded 2024-08-14T23:10:05.310Z,1723677005.310 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-08-14T23:10:05.313Z,1723677005.313 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-08-14T23:10:05.326Z,1723677005.326 [IBIT] Loaded 2024-08-14T23:10:05.326Z,1723677005.326 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-08-14T23:10:05.332Z,1723677005.332 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-08-14T23:10:05.441Z,1723677005.441 [CBIT] Loaded 2024-08-14T23:10:05.441Z,1723677005.441 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-08-14T23:10:05.442Z,1723677005.442 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-08-14T23:10:05.448Z,1723677005.448 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-08-14T23:10:05.450Z,1723677005.450 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-08-14T23:10:05.462Z,1723677005.462 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-08-14T23:10:05.463Z,1723677005.463 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0 2024-08-14T23:10:05.463Z,1723677005.463 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 403 2024-08-14T23:10:05.467Z,1723677005.467 [Supervisor](INFO): Main Thread ID is 303 2024-08-14T23:10:05.467Z,1723677005.467 [Supervisor](DEBUG): Running supervisor. 2024-08-14T23:10:05.468Z,1723677005.468 [CommandExec ThreadHandler](INFO): Handler Thread ID is 404 2024-08-14T23:10:05.469Z,1723677005.469 [CommandExec](INFO): Initializing the command executive. 2024-08-14T23:10:05.470Z,1723677005.470 [CommandLine ThreadHandler](INFO): Handler Thread ID is 405 2024-08-14T23:10:05.472Z,1723677005.472 [controlThread ThreadHandler](INFO): Handler Thread ID is 406 2024-08-14T23:10:05.472Z,1723677005.472 [controlThread](DEBUG): Initializing ControlThread 2024-08-14T23:10:05.473Z,1723677005.473 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-08-14T23:10:05.474Z,1723677005.474 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-08-14T23:10:05.474Z,1723677005.474 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-08-14T23:10:05.474Z,1723677005.474 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-08-14T23:10:05.475Z,1723677005.475 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-08-14T23:10:05.475Z,1723677005.475 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-08-14T23:10:05.477Z,1723677005.477 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-08-14T23:10:05.478Z,1723677005.478 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-08-14T23:10:05.478Z,1723677005.478 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-08-14T23:10:05.479Z,1723677005.479 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-08-14T23:10:05.479Z,1723677005.479 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-08-14T23:10:05.488Z,1723677005.488 [SBIT](INFO): Initialize SBIT Component. 2024-08-14T23:10:05.488Z,1723677005.488 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60 2024-08-14T23:10:05.488Z,1723677005.488 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95 2024-08-14T23:10:05.489Z,1723677005.489 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-08-14T23:10:05.490Z,1723677005.490 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-08-14T23:10:05.491Z,1723677005.491 [SBIT](INFO): Beginning SBIT in 68.000000 seconds. 2024-08-14T23:10:05.492Z,1723677005.492 [IBIT](INFO): Initialize IBIT Component. 2024-08-14T23:10:05.492Z,1723677005.492 [CBIT](DEBUG): Initialize CBIT Component. 2024-08-14T23:10:05.493Z,1723677005.493 [logger ThreadHandler](INFO): Handler Thread ID is 407 2024-08-14T23:10:05.503Z,1723677005.503 [CBIT](DEBUG): Initialized mux pins. 2024-08-14T23:10:05.504Z,1723677005.504 [CBIT](DEBUG): Initializing the watchdog timer. 2024-08-14T23:10:05.512Z,1723677005.512 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 408 2024-08-14T23:10:05.524Z,1723677005.524 [DATMMP ThreadHandler](INFO): Handler Thread ID is 409 2024-08-14T23:10:05.525Z,1723677005.525 [DATMMP](INFO): Start 2024-08-14T23:10:05.525Z,1723677005.525 [DATMMP](INFO): Powering up 2024-08-14T23:10:05.525Z,1723677005.525 [DATMMP](DEBUG): Initializing DATMMP. 2024-08-14T23:10:05.528Z,1723677005.528 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-08-14T23:10:05.528Z,1723677005.528 [CBIT](DEBUG): Initializing heartbeat. 2024-08-14T23:10:05.530Z,1723677005.530 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 411 2024-08-14T23:10:05.531Z,1723677005.531 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-08-14T23:10:05.540Z,1723677005.540 [Onboard ThreadHandler](INFO): Handler Thread ID is 412 2024-08-14T23:10:05.558Z,1723677005.558 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 413 2024-08-14T23:10:05.574Z,1723677005.574 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 415 2024-08-14T23:10:05.574Z,1723677005.574 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-08-14T23:10:05.584Z,1723677005.584 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 417 2024-08-14T23:10:05.586Z,1723677005.586 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000 2024-08-14T23:10:05.586Z,1723677005.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000 2024-08-14T23:10:05.587Z,1723677005.587 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000 2024-08-14T23:10:05.587Z,1723677005.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000 2024-08-14T23:10:05.587Z,1723677005.587 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000 2024-08-14T23:10:05.587Z,1723677005.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000 2024-08-14T23:10:05.599Z,1723677005.599 [CBIT](DEBUG): Deactivating GF circuits. 2024-08-14T23:10:05.600Z,1723677005.600 [CBIT](DEBUG): Deactivating emergency mode. 2024-08-14T23:10:05.639Z,1723677005.639 [CBIT](DEBUG): Backplane powered. 2024-08-14T23:10:05.640Z,1723677005.640 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-08-14T23:10:05.641Z,1723677005.641 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-08-14T23:10:05.641Z,1723677005.641 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-08-14T23:10:05.642Z,1723677005.642 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-08-14T23:10:05.678Z,1723677005.678 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-08-14T23:10:05.683Z,1723677005.683 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-14T23:10:05.726Z,1723677005.726 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-08-14T23:10:05.727Z,1723677005.727 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-08-14T23:10:05.727Z,1723677005.727 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-08-14T23:10:05.728Z,1723677005.728 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-08-14T23:10:05.776Z,1723677005.776 [DATMMP](INFO): Starting 2024-08-14T23:10:05.964Z,1723677005.964 [Radio_Surface](INFO): Powering up 2024-08-14T23:10:06.010Z,1723677006.010 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-08-14T23:10:06.028Z,1723677006.028 [DATMMP](INFO): Starting 2024-08-14T23:10:06.044Z,1723677006.044 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-08-14T23:10:06.045Z,1723677006.045 [Default:A.Wait](DEBUG): Construct Wait. 2024-08-14T23:10:06.047Z,1723677006.047 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-14T23:10:06.088Z,1723677006.088 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-08-14T23:10:06.091Z,1723677006.091 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-08-14T23:10:06.104Z,1723677006.104 [Default:E.Execute](DEBUG): Construct Execute. 2024-08-14T23:10:06.124Z,1723677006.124 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-08-14T23:10:06.128Z,1723677006.128 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-14T23:10:06.140Z,1723677006.140 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-08-14T23:10:06.213Z,1723677006.213 [Power24vConverter](INFO): Powering up. 2024-08-14T23:10:06.213Z,1723677006.213 [Sonardyne_Nano](INFO): Initializing. 2024-08-14T23:10:06.280Z,1723677006.280 [DATMMP](INFO): Starting 2024-08-14T23:10:06.282Z,1723677006.282 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-08-14T23:10:06.288Z,1723677006.288 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-08-14T23:10:06.289Z,1723677006.289 [MassServo](DEBUG): Initializing EZServoServo. 2024-08-14T23:10:06.296Z,1723677006.296 [MassServo](DEBUG): Initializing MassServo. 2024-08-14T23:10:06.297Z,1723677006.297 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-14T23:10:06.304Z,1723677006.304 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-14T23:10:06.305Z,1723677006.305 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-08-14T23:10:06.312Z,1723677006.312 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-08-14T23:10:06.544Z,1723677006.544 [DATMMP](INFO): Starting 2024-08-14T23:10:06.796Z,1723677006.796 [DATMMP](INFO): Starting 2024-08-14T23:10:07.048Z,1723677007.048 [DATMMP](INFO): Starting 2024-08-14T23:10:07.300Z,1723677007.300 [DATMMP](INFO): Starting 2024-08-14T23:10:07.552Z,1723677007.552 [DATMMP](INFO): Starting 2024-08-14T23:10:07.804Z,1723677007.804 [DATMMP](INFO): Starting 2024-08-14T23:10:08.056Z,1723677008.056 [DATMMP](INFO): Starting 2024-08-14T23:10:08.170Z,1723677008.170 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-08-14T23:10:08.308Z,1723677008.308 [DATMMP](INFO): Starting 2024-08-14T23:10:08.560Z,1723677008.560 [DATMMP](INFO): Starting 2024-08-14T23:10:08.812Z,1723677008.812 [DATMMP](INFO): Starting 2024-08-14T23:10:09.065Z,1723677009.065 [DATMMP](INFO): Starting 2024-08-14T23:10:09.316Z,1723677009.316 [DATMMP](INFO): Starting 2024-08-14T23:10:09.568Z,1723677009.568 [DATMMP](INFO): Starting 2024-08-14T23:10:09.812Z,1723677009.812 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2024-08-14T23:10:09.820Z,1723677009.820 [DATMMP](INFO): Starting 2024-08-14T23:10:10.072Z,1723677010.072 [DATMMP](INFO): Starting 2024-08-14T23:10:10.324Z,1723677010.324 [DATMMP](INFO): Starting 2024-08-14T23:10:10.576Z,1723677010.576 [DATMMP](INFO): Starting 2024-08-14T23:10:10.828Z,1723677010.828 [DATMMP](INFO): Starting 2024-08-14T23:10:11.080Z,1723677011.080 [DATMMP](INFO): Starting 2024-08-14T23:10:11.332Z,1723677011.332 [DATMMP](INFO): Starting 2024-08-14T23:10:11.584Z,1723677011.584 [DATMMP](INFO): Starting 2024-08-14T23:10:11.836Z,1723677011.836 [DATMMP](INFO): Starting 2024-08-14T23:10:11.905Z,1723677011.905 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-08-14T23:10:12.088Z,1723677012.088 [DATMMP](INFO): Starting 2024-08-14T23:10:12.340Z,1723677012.340 [DATMMP](INFO): Starting 2024-08-14T23:10:12.592Z,1723677012.592 [DATMMP](INFO): Starting 2024-08-14T23:10:12.604Z,1723677012.604 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-08-14T23:10:12.844Z,1723677012.844 [DATMMP](INFO): Starting 2024-08-14T23:10:13.096Z,1723677013.096 [DATMMP](INFO): Starting 2024-08-14T23:10:13.348Z,1723677013.348 [DATMMP](INFO): Starting 2024-08-14T23:10:13.600Z,1723677013.600 [DATMMP](INFO): Starting 2024-08-14T23:10:13.852Z,1723677013.852 [DATMMP](INFO): Starting 2024-08-14T23:10:14.104Z,1723677014.104 [DATMMP](INFO): Starting 2024-08-14T23:10:14.356Z,1723677014.356 [DATMMP](INFO): Starting 2024-08-14T23:10:14.608Z,1723677014.608 [DATMMP](INFO): Starting 2024-08-14T23:10:14.860Z,1723677014.860 [DATMMP](INFO): Starting 2024-08-14T23:10:15.112Z,1723677015.112 [DATMMP](INFO): Starting 2024-08-14T23:10:15.364Z,1723677015.364 [DATMMP](INFO): Starting 2024-08-14T23:10:15.616Z,1723677015.616 [DATMMP](INFO): Starting 2024-08-14T23:10:15.868Z,1723677015.868 [DATMMP](INFO): Starting 2024-08-14T23:10:16.120Z,1723677016.120 [DATMMP](INFO): Starting 2024-08-14T23:10:16.372Z,1723677016.372 [DATMMP](INFO): Starting 2024-08-14T23:10:16.624Z,1723677016.624 [DATMMP](INFO): Starting 2024-08-14T23:10:16.876Z,1723677016.876 [DATMMP](INFO): Starting 2024-08-14T23:10:17.128Z,1723677017.128 [DATMMP](INFO): Starting 2024-08-14T23:10:17.380Z,1723677017.380 [DATMMP](INFO): Starting 2024-08-14T23:10:17.632Z,1723677017.632 [DATMMP](INFO): Starting 2024-08-14T23:10:17.632Z,1723677017.632 [DATMMP](INFO): DAT read: 2024-08-14T23:10:17.633Z,1723677017.633 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-08-14T23:10:17.884Z,1723677017.884 [DATMMP](INFO): Starting 2024-08-14T23:10:18.136Z,1723677018.136 [DATMMP](INFO): Starting 2024-08-14T23:10:18.388Z,1723677018.388 [DATMMP](INFO): Starting 2024-08-14T23:10:18.640Z,1723677018.640 [DATMMP](INFO): Starting 2024-08-14T23:10:18.690Z,1723677018.690 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007932 2024-08-14T23:10:18.892Z,1723677018.892 [DATMMP](INFO): Starting 2024-08-14T23:10:19.144Z,1723677019.144 [DATMMP](INFO): Starting 2024-08-14T23:10:19.145Z,1723677019.145 [DATMMP](INFO): DAT read: MF Frequency Band 2024-08-14T23:10:19.146Z,1723677019.146 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-08-14T23:10:19.147Z,1723677019.147 [DATMMP](INFO): DAT read: Jul 14 2000 23:10:14 2024-08-14T23:10:19.396Z,1723677019.396 [DATMMP](INFO): Starting 2024-08-14T23:10:19.648Z,1723677019.648 [DATMMP](INFO): Starting 2024-08-14T23:10:19.900Z,1723677019.900 [DATMMP](INFO): Starting 2024-08-14T23:10:20.152Z,1723677020.152 [DATMMP](INFO): Starting 2024-08-14T23:10:20.404Z,1723677020.404 [DATMMP](INFO): Starting 2024-08-14T23:10:20.405Z,1723677020.405 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-08-14T23:10:20.406Z,1723677020.406 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-08-14T23:10:20.406Z,1723677020.406 [DATMMP](INFO): commRate: 800 2024-08-14T23:10:20.656Z,1723677020.656 [DATMMP](INFO): Starting 2024-08-14T23:10:20.908Z,1723677020.908 [DATMMP](INFO): Starting 2024-08-14T23:10:21.160Z,1723677021.160 [DATMMP](INFO): Starting 2024-08-14T23:10:21.412Z,1723677021.412 [DATMMP](INFO): Starting 2024-08-14T23:10:21.664Z,1723677021.664 [DATMMP](INFO): Starting 2024-08-14T23:10:21.916Z,1723677021.916 [DATMMP](INFO): Starting 2024-08-14T23:10:22.168Z,1723677022.168 [DATMMP](INFO): Starting 2024-08-14T23:10:22.420Z,1723677022.420 [DATMMP](INFO): Starting 2024-08-14T23:10:22.472Z,1723677022.472 [DATMMP](INFO): entering command mode 2024-08-14T23:10:22.672Z,1723677022.672 [DATMMP](INFO): Starting 2024-08-14T23:10:22.672Z,1723677022.672 [DATMMP](INFO): DAT read: 2024-08-14T23:10:22.673Z,1723677022.673 [DATMMP](INFO): DAT read: user:1> 2024-08-14T23:10:22.673Z,1723677022.673 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-08-14T23:10:22.673Z,1723677022.673 [DATMMP](INFO): entering MMP mode 2024-08-14T23:10:22.924Z,1723677022.924 [DATMMP](INFO): Starting 2024-08-14T23:10:22.924Z,1723677022.924 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-14T23:10:22.926Z,1723677022.926 [DATMMP](INFO): status rx: x1 2024-08-14T23:10:22.926Z,1723677022.926 [DATMMP](INFO): MMP status: 1: started 2024-08-14T23:10:22.926Z,1723677022.926 [DATMMP](INFO): Received message type: status 2024-08-14T23:10:22.927Z,1723677022.927 [DATMMP](INFO): Handled 2024-08-14T23:10:23.176Z,1723677023.176 [DATMMP](INFO): Starting 2024-08-14T23:10:23.177Z,1723677023.177 [DATMMP](INFO): Sent Tx Power Config 8 2024-08-14T23:10:23.177Z,1723677023.177 [DATMMP](INFO): Setting local address: 11 2024-08-14T23:10:23.179Z,1723677023.179 [DATMMP](INFO): Setting time to: 23:10:23 And date to:8/14/2024 2024-08-14T23:10:23.428Z,1723677023.428 [DATMMP](INFO): Starting 2024-08-14T23:10:23.428Z,1723677023.428 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-14T23:10:23.429Z,1723677023.429 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-08-14T23:10:23.429Z,1723677023.429 [DATMMP](INFO): Received message type: mdmpower 2024-08-14T23:10:23.429Z,1723677023.429 [DATMMP](INFO): Handled 2024-08-14T23:10:23.680Z,1723677023.680 [DATMMP](INFO): Starting 2024-08-14T23:10:23.680Z,1723677023.680 [DATMMP](INFO): GSXN notify for xid: 10 2024-08-14T23:10:23.681Z,1723677023.681 [DATMMP](INFO): sreg_txpower rx: x8 2024-08-14T23:10:23.681Z,1723677023.681 [DATMMP](INFO): Received message type: sreg_txpower 2024-08-14T23:10:23.681Z,1723677023.681 [DATMMP](INFO): Match for : sreg_txpower 2024-08-14T23:10:23.681Z,1723677023.681 [DATMMP](INFO): ACK 2024-08-14T23:10:23.932Z,1723677023.932 [DATMMP](INFO): Starting 2024-08-14T23:10:23.932Z,1723677023.932 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-14T23:10:23.933Z,1723677023.933 [DATMMP](INFO): mdmlocal rx: xB x0 x0 x0 2024-08-14T23:10:23.933Z,1723677023.933 [DATMMP](INFO): Received message type: mdmlocal 2024-08-14T23:10:23.933Z,1723677023.933 [DATMMP](INFO): Handled 2024-08-14T23:10:24.184Z,1723677024.184 [DATMMP](INFO): Starting 2024-08-14T23:10:24.184Z,1723677024.184 [DATMMP](INFO): GSXN notify for xid: 11 2024-08-14T23:10:24.185Z,1723677024.185 [DATMMP](INFO): sreg_locaddr rx: xB 2024-08-14T23:10:24.185Z,1723677024.185 [DATMMP](INFO): Received message type: sreg_locaddr 2024-08-14T23:10:24.185Z,1723677024.185 [DATMMP](INFO): Match for : sreg_locaddr 2024-08-14T23:10:24.185Z,1723677024.185 [DATMMP](INFO): ACK 2024-08-14T23:10:24.436Z,1723677024.436 [DATMMP](INFO): Starting 2024-08-14T23:10:24.436Z,1723677024.436 [DATMMP](INFO): GSXN notify for xid: 12 2024-08-14T23:10:24.437Z,1723677024.437 [DATMMP](INFO): timedate rx: x0 x17 xA x17 x7 xE x7C x0 2024-08-14T23:10:24.437Z,1723677024.437 [DATMMP](INFO): Received message type: timedate 2024-08-14T23:10:24.438Z,1723677024.438 [DATMMP](INFO): Match for : timedate 2024-08-14T23:10:24.438Z,1723677024.438 [DATMMP](INFO): ACK 2024-08-14T23:10:24.688Z,1723677024.688 [DATMMP](INFO): Starting 2024-08-14T23:10:24.688Z,1723677024.688 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-14T23:10:24.689Z,1723677024.689 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-08-14T23:10:24.689Z,1723677024.689 [DATMMP](INFO): Received message type: sys_verb 2024-08-14T23:10:24.689Z,1723677024.689 [DATMMP](INFO): Handled 2024-08-14T23:10:24.940Z,1723677024.940 [DATMMP](INFO): Starting 2024-08-14T23:10:24.940Z,1723677024.940 [DATMMP](INFO): GSXN notify for xid: 13 2024-08-14T23:10:24.941Z,1723677024.941 [DATMMP](INFO): sreg_verbosity rx: x0 2024-08-14T23:10:24.941Z,1723677024.941 [DATMMP](INFO): Received message type: sreg_verbosity 2024-08-14T23:10:24.941Z,1723677024.941 [DATMMP](INFO): Match for : sreg_verbosity 2024-08-14T23:10:24.941Z,1723677024.941 [DATMMP](INFO): ACK 2024-08-14T23:10:25.192Z,1723677025.192 [DATMMP](INFO): Starting 2024-08-14T23:10:25.193Z,1723677025.193 [DATMMP](INFO): GSXN notify for xid: 14 2024-08-14T23:10:25.193Z,1723677025.193 [DATMMP](INFO): version rx: x2 x5 2024-08-14T23:10:25.194Z,1723677025.194 [DATMMP](INFO): DatMMPVersion: 2.5 2024-08-14T23:10:25.194Z,1723677025.194 [DATMMP](INFO): Received message type: version 2024-08-14T23:10:25.194Z,1723677025.194 [DATMMP](INFO): Match for : version 2024-08-14T23:10:25.194Z,1723677025.194 [DATMMP](INFO): ACK 2024-08-14T23:10:25.194Z,1723677025.194 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-08-14T23:10:34.030Z,1723677034.030 [NAL9602](INFO): Powering up NAL9602 2024-08-14T23:10:39.324Z,1723677039.324 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-14T23:10:39.325Z,1723677039.325 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-08-14T23:10:39.325Z,1723677039.325 [DATMMP](INFO): Received message type: timesync 2024-08-14T23:10:39.326Z,1723677039.326 [DATMMP](INFO): Handled 2024-08-14T23:10:44.938Z,1723677044.938 [NAL9602](INFO): NAL9602 initialized 2024-08-14T23:10:49.404Z,1723677049.404 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-14T23:10:49.405Z,1723677049.405 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-08-14T23:10:49.405Z,1723677049.405 [DATMMP](INFO): Received message type: timesync 2024-08-14T23:10:49.405Z,1723677049.405 [DATMMP](INFO): Handled 2024-08-14T23:11:14.062Z,1723677074.062 [SBIT](IMPORTANT): Beginning Startup BIT 2024-08-14T23:11:14.067Z,1723677074.067 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-14T23:11:25.182Z,1723677085.182 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005340 CHAN A1 (24V): -0.002409 CHAN A2 (12V): -0.000693 CHAN A3 (5V): 0.000093 CHAN B0 (3.3V): 0.000190 CHAN B1 (3.15aV): -0.000247 CHAN B2 (3.15bV): -0.000250 CHAN B3 (GND): 0.001152 OPEN: -0.001166 Full Scale: +/- 1 mA 2024-08-14T23:11:27.391Z,1723677087.391 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-08-14T23:11:54.085Z,1723677114.085 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-08-14T23:11:54.085Z,1723677114.085 [SBIT](FAULT): Control surface position failure. 2024-08-14T23:12:04.179Z,1723677124.179 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-14T23:12:07.779Z,1723677127.779 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-08-14T23:12:07.779Z,1723677127.779 [SBIT](FAULT): Control surface position failure. 2024-08-14T23:12:08.190Z,1723677128.190 [SBIT](CRITICAL): SBIT FAILED 2024-08-14T23:12:08.190Z,1723677128.190 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-08-14T23:12:08.191Z,1723677128.191 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-08-14T23:12:08.191Z,1723677128.191 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=5 meter; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-08-14T23:12:08.192Z,1723677128.192 [SBIT](IMPORTANT): ElevatorServo.currLimit=75 percent; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): ElevatorServo.limitHi=16369 count; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): ElevatorServo.limitLo=0 count; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=0 bool; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=1 bool; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): VerticalControl.massDefault=12.059213 millimeter; 2024-08-14T23:12:08.193Z,1723677128.193 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool; 2024-08-14T23:12:08.195Z,1723677128.195 [CommandExec](FAULT): Scheduling is paused 2024-08-14T23:12:08.195Z,1723677128.195 [CBIT](INFO): Critical error at 20240814T231208 2024-08-14T23:12:08.195Z,1723677128.195 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-08-14T23:12:08.586Z,1723677128.586 [MissionManager](IMPORTANT): Started mission Startup 2024-08-14T23:12:08.587Z,1723677128.587 [Startup] Running Loop=1 2024-08-14T23:12:08.587Z,1723677128.587 [Startup](DEBUG): Aggregate::initialize Startup 2024-08-14T23:12:08.587Z,1723677128.587 [Startup:A.GoToSurface] Running Loop=1 2024-08-14T23:12:08.587Z,1723677128.587 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-14T23:12:08.588Z,1723677128.588 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-14T23:12:08.588Z,1723677128.588 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-14T23:12:08.588Z,1723677128.588 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-14T23:12:08.589Z,1723677128.589 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-14T23:12:08.589Z,1723677128.589 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-14T23:12:08.589Z,1723677128.589 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-14T23:12:08.616Z,1723677128.616 [Startup:StartupSatComms] Running Loop=1 2024-08-14T23:12:08.616Z,1723677128.616 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-08-14T23:12:08.616Z,1723677128.616 [Startup:StartupSatComms:A] Running Loop=1 2024-08-14T23:12:08.986Z,1723677128.986 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-08-14T23:12:24.788Z,1723677144.788 [CommandExec](IMPORTANT): got command quit 2024-08-14T23:12:25.792Z,1723677145.792 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:25.792Z,1723677145.792 [CommandExec](INFO): Uninitializing the command executive. 2024-08-14T23:12:25.792Z,1723677145.792 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-14T23:12:25.793Z,1723677145.793 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:25.911Z,1723677145.911 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-14T23:12:25.912Z,1723677145.912 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-14T23:12:25.912Z,1723677145.912 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:25.913Z,1723677145.913 [NavChartDb](INFO): Join timeout helper Thread ID is 441 2024-08-14T23:12:26.152Z,1723677146.152 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:26.152Z,1723677146.152 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:26.153Z,1723677146.153 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-14T23:12:26.153Z,1723677146.153 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:26.153Z,1723677146.153 [CTD_Seabird](INFO): Join timeout helper Thread ID is 442 2024-08-14T23:12:27.136Z,1723677147.136 [CTD_Seabird](INFO): Powering down 2024-08-14T23:12:27.149Z,1723677147.149 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:27.150Z,1723677147.150 [CTD_Seabird](INFO): Powering down 2024-08-14T23:12:27.164Z,1723677147.164 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:27.194Z,1723677147.194 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-14T23:12:27.194Z,1723677147.194 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:27.195Z,1723677147.195 [Radio_Surface](INFO): Join timeout helper Thread ID is 443 2024-08-14T23:12:27.364Z,1723677147.364 [Radio_Surface](INFO): Powering down 2024-08-14T23:12:27.365Z,1723677147.365 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:27.365Z,1723677147.365 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:27.380Z,1723677147.380 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-14T23:12:27.380Z,1723677147.380 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:27.380Z,1723677147.380 [Onboard](INFO): Join timeout helper Thread ID is 444 2024-08-14T23:12:30.656Z,1723677150.656 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:30.656Z,1723677150.656 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:30.660Z,1723677150.660 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-08-14T23:12:30.660Z,1723677150.660 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:30.660Z,1723677150.660 [DataOverHttps](INFO): Join timeout helper Thread ID is 445 2024-08-14T23:12:31.212Z,1723677151.212 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:31.213Z,1723677151.213 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.221Z,1723677151.221 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2024-08-14T23:12:31.221Z,1723677151.221 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.221Z,1723677151.221 [DATMMP](INFO): Join timeout helper Thread ID is 446 2024-08-14T23:12:31.312Z,1723677151.312 [DATMMP](INFO): Stop 2024-08-14T23:12:31.312Z,1723677151.312 [DATMMP](INFO): uninitialize 2024-08-14T23:12:31.312Z,1723677151.312 [DATMMP](INFO): Powering down 2024-08-14T23:12:31.384Z,1723677151.384 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:31.384Z,1723677151.384 [DATMMP](INFO): uninitialize 2024-08-14T23:12:31.384Z,1723677151.384 [DATMMP](INFO): Powering down 2024-08-14T23:12:31.385Z,1723677151.385 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.404Z,1723677151.404 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-08-14T23:12:31.405Z,1723677151.405 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.405Z,1723677151.405 [BackseatComponent](INFO): Join timeout helper Thread ID is 447 2024-08-14T23:12:31.408Z,1723677151.408 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:31.408Z,1723677151.408 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.414Z,1723677151.414 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-08-14T23:12:31.414Z,1723677151.414 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.415Z,1723677151.415 [logger](INFO): Join timeout helper Thread ID is 448 2024-08-14T23:12:31.446Z,1723677151.446 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:31.446Z,1723677151.446 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.464Z,1723677151.464 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-08-14T23:12:31.465Z,1723677151.465 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.465Z,1723677151.465 [CommandLine](INFO): Join timeout helper Thread ID is 449 2024-08-14T23:12:31.560Z,1723677151.560 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:31.560Z,1723677151.560 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.564Z,1723677151.564 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-08-14T23:12:31.564Z,1723677151.564 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.565Z,1723677151.565 [CommandExec](INFO): Join timeout helper Thread ID is 450 2024-08-14T23:12:31.566Z,1723677151.566 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-08-14T23:12:31.566Z,1723677151.566 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.569Z,1723677151.569 [controlThread](INFO): Join timeout helper Thread ID is 451 2024-08-14T23:12:31.588Z,1723677151.588 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-14T23:12:31.588Z,1723677151.588 [controlThread](DEBUG): Uninitializing ControlThread 2024-08-14T23:12:31.589Z,1723677151.589 [AHRS_M2](INFO): Powering down 2024-08-14T23:12:31.756Z,1723677151.756 [DVL_micro](INFO): Powering down 2024-08-14T23:12:31.757Z,1723677151.757 [NAL9602](INFO): Powering down 2024-08-14T23:12:31.758Z,1723677151.758 [Sonardyne_Nano](INFO): Powering down 2024-08-14T23:12:31.877Z,1723677151.877 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-08-14T23:12:31.877Z,1723677151.877 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-08-14T23:12:31.878Z,1723677151.878 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-08-14T23:12:31.878Z,1723677151.878 [MissionManager](INFO): Uninitializing Mission Default 2024-08-14T23:12:31.881Z,1723677151.881 [MissionManager](INFO): Uninitializing Mission Startup 2024-08-14T23:12:31.881Z,1723677151.881 [Startup] Stopped 2024-08-14T23:12:31.881Z,1723677151.881 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-08-14T23:12:31.881Z,1723677151.881 [Startup:A.GoToSurface] Stopped 2024-08-14T23:12:31.881Z,1723677151.881 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-14T23:12:31.881Z,1723677151.881 [Startup:StartupSatComms] Stopped 2024-08-14T23:12:31.881Z,1723677151.881 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-08-14T23:12:31.881Z,1723677151.881 [Startup:StartupSatComms:A] Stopped 2024-08-14T23:12:31.883Z,1723677151.883 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-08-14T23:12:31.883Z,1723677151.883 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-08-14T23:12:31.883Z,1723677151.883 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-08-14T23:12:31.884Z,1723677151.884 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-08-14T23:12:31.884Z,1723677151.884 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-08-14T23:12:31.884Z,1723677151.884 [BuoyancyServo](INFO): Powering down 2024-08-14T23:12:31.896Z,1723677151.896 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-08-14T23:12:31.896Z,1723677151.896 [MassServo](INFO): Powering down 2024-08-14T23:12:31.897Z,1723677151.897 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-14T23:12:31.897Z,1723677151.897 [RudderServo](INFO): Powering down 2024-08-14T23:12:31.897Z,1723677151.897 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-08-14T23:12:31.898Z,1723677151.898 [ThrusterHE](INFO): Powering down 2024-08-14T23:12:31.898Z,1723677151.898 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-08-14T23:12:31.899Z,1723677151.899 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-08-14T23:12:31.899Z,1723677151.899 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-08-14T23:12:31.899Z,1723677151.899 [CBIT](DEBUG): Powering off loads. 2024-08-14T23:12:31.911Z,1723677151.911 [CBIT](DEBUG): Disabling WDT. 2024-08-14T23:12:31.923Z,1723677151.923 [CBIT](DEBUG): Opening all GF detection circuits. 2024-08-14T23:12:31.924Z,1723677151.924 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.966Z,1723677151.966 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.969Z,1723677151.969 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.975Z,1723677151.975 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:31.982Z,1723677151.982 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:32.022Z,1723677152.022 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:32.091Z,1723677152.091 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:32.114Z,1723677152.114 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-14T23:12:32.175Z,1723677152.175 [logger ThreadHandler](INFO): Thread cancelled.