2024-08-26T22:12:07.781Z,1724710327.781 [Supervisor](DEBUG): Initializing supervisor. 2024-08-26T22:12:07.786Z,1724710327.786 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-08-26T22:12:07.787Z,1724710327.787 [SyncHandler](INFO): Protected caller Thread ID is 4266 2024-08-26T22:12:07.787Z,1724710327.787 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-08-26T22:12:07.788Z,1724710327.788 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-08-26T22:12:07.789Z,1724710327.789 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4267 2024-08-26T22:12:07.793Z,1724710327.793 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-08-26T22:12:07.810Z,1724710327.810 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-08-26T22:12:07.811Z,1724710327.811 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-08-26T22:12:07.811Z,1724710327.811 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4268 2024-08-26T22:12:07.815Z,1724710327.815 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-08-26T22:12:07.816Z,1724710327.816 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-08-26T22:12:07.817Z,1724710327.817 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4269 2024-08-26T22:12:07.819Z,1724710327.819 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-08-26T22:12:07.820Z,1724710327.820 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-08-26T22:12:07.820Z,1724710327.820 [logger ThreadHandler](INFO): Protected caller Thread ID is 4270 2024-08-26T22:12:07.824Z,1724710327.824 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-08-26T22:12:07.824Z,1724710327.824 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-08-26T22:12:07.826Z,1724710327.826 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-08-26T22:12:07.933Z,1724710327.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-08-26T22:12:07.933Z,1724710327.933 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-08-26T22:12:08.181Z,1724710328.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-08-26T22:12:08.182Z,1724710328.182 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-08-26T22:12:08.460Z,1724710328.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-08-26T22:12:08.461Z,1724710328.461 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-08-26T22:12:08.582Z,1724710328.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-08-26T22:12:08.582Z,1724710328.582 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-08-26T22:12:09.367Z,1724710329.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-08-26T22:12:09.368Z,1724710329.368 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-08-26T22:12:09.890Z,1724710329.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-08-26T22:12:09.890Z,1724710329.890 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-08-26T22:12:10.480Z,1724710330.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-08-26T22:12:10.481Z,1724710330.481 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-08-26T22:12:11.139Z,1724710331.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-08-26T22:12:11.140Z,1724710331.140 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-08-26T22:12:11.497Z,1724710331.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-08-26T22:12:11.497Z,1724710331.497 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-08-26T22:12:11.897Z,1724710331.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-08-26T22:12:11.897Z,1724710331.897 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-08-26T22:12:12.142Z,1724710332.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-08-26T22:12:12.143Z,1724710332.143 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-08-26T22:12:12.295Z,1724710332.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-08-26T22:12:12.296Z,1724710332.296 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-08-26T22:12:12.389Z,1724710332.389 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-08-26T22:12:12.480Z,1724710332.480 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-08-26T22:12:12.680Z,1724710332.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-08-26T22:12:12.681Z,1724710332.681 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-08-26T22:12:12.927Z,1724710332.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-08-26T22:12:12.929Z,1724710332.929 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2024-08-26T22:12:12.930Z,1724710332.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2024-08-26T22:12:13.025Z,1724710333.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2024-08-26T22:12:13.297Z,1724710333.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-08-26T22:12:13.298Z,1724710333.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2024-08-26T22:12:13.406Z,1724710333.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2024-08-26T22:12:13.585Z,1724710333.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2024-08-26T22:12:13.695Z,1724710333.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2024-08-26T22:12:13.818Z,1724710333.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2024-08-26T22:12:13.943Z,1724710333.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2024-08-26T22:12:14.061Z,1724710334.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2024-08-26T22:12:14.235Z,1724710334.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2024-08-26T22:12:14.344Z,1724710334.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2024-08-26T22:12:14.440Z,1724710334.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2024-08-26T22:12:14.550Z,1724710334.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2024-08-26T22:12:14.654Z,1724710334.654 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2024-08-26T22:12:14.654Z,1724710334.654 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-08-26T22:12:14.661Z,1724710334.661 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-08-26T22:12:14.828Z,1724710334.828 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-08-26T22:12:14.829Z,1724710334.829 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-08-26T22:12:14.982Z,1724710334.982 [BuoyancyServo] Loaded 2024-08-26T22:12:14.982Z,1724710334.982 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-08-26T22:12:15.005Z,1724710335.005 [ElevatorServo] Loaded 2024-08-26T22:12:15.005Z,1724710335.005 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-08-26T22:12:15.027Z,1724710335.027 [MassServo] Loaded 2024-08-26T22:12:15.027Z,1724710335.027 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-08-26T22:12:15.048Z,1724710335.048 [RudderServo] Loaded 2024-08-26T22:12:15.048Z,1724710335.048 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-08-26T22:12:15.065Z,1724710335.065 [ThrusterHE] Loaded 2024-08-26T22:12:15.065Z,1724710335.065 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-08-26T22:12:15.066Z,1724710335.066 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-08-26T22:12:15.066Z,1724710335.066 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-08-26T22:12:15.199Z,1724710335.199 [SBIT](DEBUG): Construct Startup Built In Test. 2024-08-26T22:12:15.213Z,1724710335.213 [SBIT] Loaded 2024-08-26T22:12:15.214Z,1724710335.214 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-08-26T22:12:15.216Z,1724710335.216 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-08-26T22:12:15.237Z,1724710335.237 [IBIT] Loaded 2024-08-26T22:12:15.237Z,1724710335.237 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-08-26T22:12:15.242Z,1724710335.242 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-08-26T22:12:15.459Z,1724710335.459 [CBIT] Loaded 2024-08-26T22:12:15.459Z,1724710335.459 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-08-26T22:12:15.459Z,1724710335.459 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-08-26T22:12:15.460Z,1724710335.460 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-08-26T22:12:15.810Z,1724710335.810 [CTD_Seabird] Loaded 2024-08-26T22:12:15.810Z,1724710335.810 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-08-26T22:12:15.811Z,1724710335.811 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0 2024-08-26T22:12:15.811Z,1724710335.811 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4358 2024-08-26T22:12:15.845Z,1724710335.845 [ESPComponent] Loaded 2024-08-26T22:12:15.845Z,1724710335.845 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-08-26T22:12:15.866Z,1724710335.866 [PAR_Licor] Loaded 2024-08-26T22:12:15.867Z,1724710335.867 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-08-26T22:12:15.997Z,1724710335.997 [WetLabsBB2FL] Loaded 2024-08-26T22:12:15.997Z,1724710335.997 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-08-26T22:12:15.998Z,1724710335.998 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0 2024-08-26T22:12:15.998Z,1724710335.998 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4359 2024-08-26T22:12:15.999Z,1724710335.999 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-08-26T22:12:15.999Z,1724710335.999 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-08-26T22:12:16.151Z,1724710336.151 [DepthRateCalculator] Loaded 2024-08-26T22:12:16.151Z,1724710336.151 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-08-26T22:12:16.156Z,1724710336.156 [PitchRateCalculator] Loaded 2024-08-26T22:12:16.156Z,1724710336.156 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-08-26T22:12:16.166Z,1724710336.166 [SpeedCalculator] Loaded 2024-08-26T22:12:16.166Z,1724710336.166 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-08-26T22:12:16.171Z,1724710336.171 [YawRateCalculator] Loaded 2024-08-26T22:12:16.171Z,1724710336.171 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-08-26T22:12:16.190Z,1724710336.190 [ElevatorOffsetCalculator] Loaded 2024-08-26T22:12:16.190Z,1724710336.190 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-08-26T22:12:16.191Z,1724710336.191 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-08-26T22:12:16.191Z,1724710336.191 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-08-26T22:12:16.296Z,1724710336.296 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-08-26T22:12:16.297Z,1724710336.297 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-08-26T22:12:16.322Z,1724710336.322 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-08-26T22:12:16.323Z,1724710336.323 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-08-26T22:12:16.430Z,1724710336.430 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-08-26T22:12:16.431Z,1724710336.431 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-08-26T22:12:16.444Z,1724710336.444 [NavChart] Loaded 2024-08-26T22:12:16.444Z,1724710336.444 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-08-26T22:12:16.450Z,1724710336.450 [UniversalFixResidualReporter] Loaded 2024-08-26T22:12:16.450Z,1724710336.450 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-08-26T22:12:16.450Z,1724710336.450 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-08-26T22:12:16.451Z,1724710336.451 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-08-26T22:12:16.897Z,1724710336.897 [AHRS_M2] Loaded 2024-08-26T22:12:16.897Z,1724710336.897 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-08-26T22:12:16.935Z,1724710336.935 [BackseatComponent] Loaded 2024-08-26T22:12:16.935Z,1724710336.935 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-08-26T22:12:16.936Z,1724710336.936 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A694E0 2024-08-26T22:12:16.937Z,1724710336.937 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4360 2024-08-26T22:12:16.939Z,1724710336.939 [LcmUniversalReporter] Loaded 2024-08-26T22:12:16.940Z,1724710336.940 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-08-26T22:12:17.673Z,1724710337.673 [BPC1] Loaded 2024-08-26T22:12:17.673Z,1724710337.673 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-08-26T22:12:17.762Z,1724710337.762 [DATMMP] Loaded 2024-08-26T22:12:17.762Z,1724710337.762 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-08-26T22:12:17.763Z,1724710337.763 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0 2024-08-26T22:12:17.763Z,1724710337.763 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 4361 2024-08-26T22:12:17.833Z,1724710337.833 [DataOverHttps] Loaded 2024-08-26T22:12:17.833Z,1724710337.833 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-08-26T22:12:17.834Z,1724710337.834 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0 2024-08-26T22:12:17.835Z,1724710337.835 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4362 2024-08-26T22:12:17.858Z,1724710337.858 [Depth_Keller] Loaded 2024-08-26T22:12:17.858Z,1724710337.858 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-08-26T22:12:17.863Z,1724710337.863 [DropWeight] Loaded 2024-08-26T22:12:17.863Z,1724710337.863 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-08-26T22:12:17.904Z,1724710337.904 [DVL_micro] Loaded 2024-08-26T22:12:17.904Z,1724710337.904 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-08-26T22:12:17.965Z,1724710337.965 [NAL9602] Loaded 2024-08-26T22:12:17.965Z,1724710337.965 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-08-26T22:12:17.990Z,1724710337.990 [Onboard] Loaded 2024-08-26T22:12:17.991Z,1724710337.991 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-08-26T22:12:17.991Z,1724710337.991 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0 2024-08-26T22:12:17.992Z,1724710337.992 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4363 2024-08-26T22:12:18.006Z,1724710338.006 [Power24vConverter] Loaded 2024-08-26T22:12:18.006Z,1724710338.006 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-08-26T22:12:18.021Z,1724710338.021 [Radio_Surface] Loaded 2024-08-26T22:12:18.021Z,1724710338.021 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-08-26T22:12:18.022Z,1724710338.022 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0 2024-08-26T22:12:18.023Z,1724710338.023 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4364 2024-08-26T22:12:18.038Z,1724710338.038 [Sonardyne_Nano] Loaded 2024-08-26T22:12:18.038Z,1724710338.038 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-08-26T22:12:18.039Z,1724710338.039 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-08-26T22:12:18.039Z,1724710338.039 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-08-26T22:12:18.106Z,1724710338.106 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-08-26T22:12:18.106Z,1724710338.106 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-08-26T22:12:18.156Z,1724710338.156 [VerticalControl](DEBUG): Construct VerticalControl. 2024-08-26T22:12:18.205Z,1724710338.205 [VerticalControl] Loaded 2024-08-26T22:12:18.206Z,1724710338.206 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-08-26T22:12:18.209Z,1724710338.209 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-08-26T22:12:18.251Z,1724710338.251 [HorizontalControl] Loaded 2024-08-26T22:12:18.252Z,1724710338.252 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-08-26T22:12:18.254Z,1724710338.254 [SpeedControl](DEBUG): Construct SpeedControl. 2024-08-26T22:12:18.256Z,1724710338.256 [SpeedControl] Loaded 2024-08-26T22:12:18.256Z,1724710338.256 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-08-26T22:12:18.259Z,1724710338.259 [LoopControl](DEBUG): Construct LoopControl. 2024-08-26T22:12:18.259Z,1724710338.259 [LoopControl] Loaded 2024-08-26T22:12:18.259Z,1724710338.259 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-08-26T22:12:18.260Z,1724710338.260 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-08-26T22:12:18.260Z,1724710338.260 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-08-26T22:12:18.270Z,1724710338.270 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-08-26T22:12:18.271Z,1724710338.271 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-08-26T22:12:18.591Z,1724710338.591 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-08-26T22:12:18.597Z,1724710338.597 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-08-26T22:12:18.600Z,1724710338.600 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-08-26T22:12:18.611Z,1724710338.611 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-08-26T22:12:18.613Z,1724710338.613 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0 2024-08-26T22:12:18.613Z,1724710338.613 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4365 2024-08-26T22:12:18.617Z,1724710338.617 [Supervisor](INFO): Main Thread ID is 4062 2024-08-26T22:12:18.617Z,1724710338.617 [Supervisor](DEBUG): Running supervisor. 2024-08-26T22:12:18.618Z,1724710338.618 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4366 2024-08-26T22:12:18.618Z,1724710338.618 [CommandExec](INFO): Initializing the command executive. 2024-08-26T22:12:18.620Z,1724710338.620 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4367 2024-08-26T22:12:18.622Z,1724710338.622 [controlThread ThreadHandler](INFO): Handler Thread ID is 4368 2024-08-26T22:12:18.622Z,1724710338.622 [controlThread](DEBUG): Initializing ControlThread 2024-08-26T22:12:18.624Z,1724710338.624 [SBIT](INFO): Initialize SBIT Component. 2024-08-26T22:12:18.625Z,1724710338.625 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60 2024-08-26T22:12:18.625Z,1724710338.625 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95 2024-08-26T22:12:18.625Z,1724710338.625 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-08-26T22:12:18.627Z,1724710338.627 [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 2024-08-26T22:12:18.628Z,1724710338.628 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2024-08-26T22:12:18.628Z,1724710338.628 [IBIT](INFO): Initialize IBIT Component. 2024-08-26T22:12:18.629Z,1724710338.629 [CBIT](DEBUG): Initialize CBIT Component. 2024-08-26T22:12:18.630Z,1724710338.630 [logger ThreadHandler](INFO): Handler Thread ID is 4369 2024-08-26T22:12:18.640Z,1724710338.640 [CBIT](DEBUG): Initialized mux pins. 2024-08-26T22:12:18.641Z,1724710338.641 [CBIT](DEBUG): Initializing the watchdog timer. 2024-08-26T22:12:18.649Z,1724710338.649 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4370 2024-08-26T22:12:18.650Z,1724710338.650 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-08-26T22:12:18.654Z,1724710338.654 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4372 2024-08-26T22:12:18.657Z,1724710338.657 [WetLabsBB2FL](INFO): Powering up 2024-08-26T22:12:18.658Z,1724710338.658 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4374 2024-08-26T22:12:18.665Z,1724710338.665 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-08-26T22:12:18.665Z,1724710338.665 [CBIT](DEBUG): Initializing heartbeat. 2024-08-26T22:12:18.666Z,1724710338.666 [DATMMP ThreadHandler](INFO): Handler Thread ID is 4375 2024-08-26T22:12:18.667Z,1724710338.667 [DATMMP](INFO): Start 2024-08-26T22:12:18.667Z,1724710338.667 [DATMMP](INFO): Powering up 2024-08-26T22:12:18.667Z,1724710338.667 [DATMMP](DEBUG): Initializing DATMMP. 2024-08-26T22:12:18.670Z,1724710338.670 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4377 2024-08-26T22:12:18.671Z,1724710338.671 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-08-26T22:12:18.681Z,1724710338.681 [Onboard ThreadHandler](INFO): Handler Thread ID is 4378 2024-08-26T22:12:18.711Z,1724710338.711 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4379 2024-08-26T22:12:18.722Z,1724710338.722 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4380 2024-08-26T22:12:18.724Z,1724710338.724 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000 2024-08-26T22:12:18.724Z,1724710338.724 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000 2024-08-26T22:12:18.724Z,1724710338.724 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000 2024-08-26T22:12:18.724Z,1724710338.724 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000 2024-08-26T22:12:18.725Z,1724710338.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000 2024-08-26T22:12:18.725Z,1724710338.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000 2024-08-26T22:12:18.736Z,1724710338.736 [CBIT](DEBUG): Deactivating GF circuits. 2024-08-26T22:12:18.737Z,1724710338.737 [CBIT](DEBUG): Deactivating emergency mode. 2024-08-26T22:12:18.777Z,1724710338.777 [CBIT](DEBUG): Backplane powered. 2024-08-26T22:12:18.781Z,1724710338.781 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-08-26T22:12:18.781Z,1724710338.781 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-08-26T22:12:18.782Z,1724710338.782 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-08-26T22:12:18.782Z,1724710338.782 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-08-26T22:12:18.782Z,1724710338.782 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-08-26T22:12:18.783Z,1724710338.783 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-08-26T22:12:18.784Z,1724710338.784 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-08-26T22:12:18.798Z,1724710338.798 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-08-26T22:12:18.800Z,1724710338.800 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-08-26T22:12:18.800Z,1724710338.800 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-08-26T22:12:18.801Z,1724710338.801 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-08-26T22:12:18.802Z,1724710338.802 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-08-26T22:12:18.802Z,1724710338.802 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-08-26T22:12:18.803Z,1724710338.803 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-08-26T22:12:18.804Z,1724710338.804 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-08-26T22:12:18.878Z,1724710338.878 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-08-26T22:12:18.899Z,1724710338.899 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-26T22:12:18.921Z,1724710338.921 [DATMMP](INFO): Starting 2024-08-26T22:12:18.964Z,1724710338.964 [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-26T22:12:18.965Z,1724710338.965 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-08-26T22:12:18.965Z,1724710338.965 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-08-26T22:12:18.966Z,1724710338.966 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-08-26T22:12:19.113Z,1724710339.113 [Radio_Surface](INFO): Powering up 2024-08-26T22:12:19.173Z,1724710339.173 [DATMMP](INFO): Starting 2024-08-26T22:12:19.299Z,1724710339.299 [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-26T22:12:19.341Z,1724710339.341 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-08-26T22:12:19.343Z,1724710339.343 [Default:A.Wait](DEBUG): Construct Wait. 2024-08-26T22:12:19.353Z,1724710339.353 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-26T22:12:19.381Z,1724710339.381 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-08-26T22:12:19.384Z,1724710339.384 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-08-26T22:12:19.406Z,1724710339.406 [Default:E.Execute](DEBUG): Construct Execute. 2024-08-26T22:12:19.425Z,1724710339.425 [DATMMP](INFO): Starting 2024-08-26T22:12:19.425Z,1724710339.425 [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-26T22:12:19.430Z,1724710339.430 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-26T22:12:19.439Z,1724710339.439 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-08-26T22:12:19.502Z,1724710339.502 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m 2024-08-26T22:12:19.510Z,1724710339.510 [Power24vConverter](INFO): Powering up. 2024-08-26T22:12:19.511Z,1724710339.511 [Sonardyne_Nano](INFO): Initializing. 2024-08-26T22:12:19.537Z,1724710339.537 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-08-26T22:12:19.558Z,1724710339.558 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:19.565Z,1724710339.565 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-08-26T22:12:19.566Z,1724710339.566 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:19.573Z,1724710339.573 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-08-26T22:12:19.574Z,1724710339.574 [MassServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:19.581Z,1724710339.581 [MassServo](DEBUG): Initializing MassServo. 2024-08-26T22:12:19.582Z,1724710339.582 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:19.589Z,1724710339.589 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-26T22:12:19.590Z,1724710339.590 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:19.597Z,1724710339.597 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-08-26T22:12:19.677Z,1724710339.677 [DATMMP](INFO): Starting 2024-08-26T22:12:19.873Z,1724710339.873 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-08-26T22:12:19.873Z,1724710339.873 [DropWeight] Hardware Fault, FailCount= 1 2024-08-26T22:12:19.873Z,1724710339.873 [DropWeight](ERROR): Hardware Fault 2024-08-26T22:12:19.901Z,1724710339.901 [CommandExec](FAULT): Scheduling is paused 2024-08-26T22:12:19.901Z,1724710339.901 [CBIT](INFO): Critical error at 20240826T221219 2024-08-26T22:12:19.901Z,1724710339.901 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-08-26T22:12:19.904Z,1724710339.904 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-08-26T22:12:19.904Z,1724710339.904 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-08-26T22:12:19.929Z,1724710339.929 [DATMMP](INFO): Starting 2024-08-26T22:12:20.181Z,1724710340.181 [DATMMP](INFO): Starting 2024-08-26T22:12:20.266Z,1724710340.266 [CBIT](INFO): Critical error at 20240826T221219 2024-08-26T22:12:20.433Z,1724710340.433 [DATMMP](INFO): Starting 2024-08-26T22:12:20.685Z,1724710340.685 [DATMMP](INFO): Starting 2024-08-26T22:12:20.766Z,1724710340.766 [WetLabsBB2FL](INFO): Powering down 2024-08-26T22:12:20.937Z,1724710340.937 [DATMMP](INFO): Starting 2024-08-26T22:12:21.189Z,1724710341.189 [DATMMP](INFO): Starting 2024-08-26T22:12:21.441Z,1724710341.441 [DATMMP](INFO): Starting 2024-08-26T22:12:21.463Z,1724710341.463 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-08-26T22:12:21.693Z,1724710341.693 [DATMMP](INFO): Starting 2024-08-26T22:12:21.945Z,1724710341.945 [DATMMP](INFO): Starting 2024-08-26T22:12:22.197Z,1724710342.197 [DATMMP](INFO): Starting 2024-08-26T22:12:22.449Z,1724710342.449 [DATMMP](INFO): Starting 2024-08-26T22:12:22.702Z,1724710342.702 [DATMMP](INFO): Starting 2024-08-26T22:12:22.755Z,1724710342.755 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2024-08-26T22:12:22.953Z,1724710342.953 [DATMMP](INFO): Starting 2024-08-26T22:12:23.205Z,1724710343.205 [DATMMP](INFO): Starting 2024-08-26T22:12:23.457Z,1724710343.457 [DATMMP](INFO): Starting 2024-08-26T22:12:23.709Z,1724710343.709 [DATMMP](INFO): Starting 2024-08-26T22:12:23.961Z,1724710343.961 [DATMMP](INFO): Starting 2024-08-26T22:12:24.213Z,1724710344.213 [DATMMP](INFO): Starting 2024-08-26T22:12:24.465Z,1724710344.465 [DATMMP](INFO): Starting 2024-08-26T22:12:24.717Z,1724710344.717 [DATMMP](INFO): Starting 2024-08-26T22:12:24.817Z,1724710344.817 [RudderServo](FAULT): Overload Error 2024-08-26T22:12:24.817Z,1724710344.817 [RudderServo] Hardware Fault, FailCount= 1 2024-08-26T22:12:24.817Z,1724710344.817 [RudderServo](ERROR): Hardware Fault 2024-08-26T22:12:24.856Z,1724710344.856 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-08-26T22:12:24.969Z,1724710344.969 [DATMMP](INFO): Starting 2024-08-26T22:12:25.221Z,1724710345.221 [DATMMP](INFO): Starting 2024-08-26T22:12:25.337Z,1724710345.337 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-26T22:12:25.337Z,1724710345.337 [RudderServo](INFO): Powering down 2024-08-26T22:12:25.473Z,1724710345.473 [DATMMP](INFO): Starting 2024-08-26T22:12:25.725Z,1724710345.725 [DATMMP](INFO): Starting 2024-08-26T22:12:25.977Z,1724710345.977 [DATMMP](INFO): Starting 2024-08-26T22:12:26.014Z,1724710346.014 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:26.134Z,1724710346.134 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-26T22:12:26.138Z,1724710346.138 [CBIT](INFO): Clearing failed state for component RudderServo 2024-08-26T22:12:26.138Z,1724710346.138 [RudderServo] No Fault, FailCount= 1 2024-08-26T22:12:26.229Z,1724710346.229 [DATMMP](INFO): Starting 2024-08-26T22:12:26.481Z,1724710346.481 [DATMMP](INFO): Starting 2024-08-26T22:12:26.734Z,1724710346.734 [DATMMP](INFO): Starting 2024-08-26T22:12:26.985Z,1724710346.985 [DATMMP](INFO): Starting 2024-08-26T22:12:27.238Z,1724710347.238 [DATMMP](INFO): Starting 2024-08-26T22:12:27.489Z,1724710347.489 [DATMMP](INFO): Starting 2024-08-26T22:12:27.741Z,1724710347.741 [DATMMP](INFO): Starting 2024-08-26T22:12:27.993Z,1724710347.993 [DATMMP](INFO): Starting 2024-08-26T22:12:28.245Z,1724710348.245 [DATMMP](INFO): Starting 2024-08-26T22:12:28.497Z,1724710348.497 [DATMMP](INFO): Starting 2024-08-26T22:12:28.749Z,1724710348.749 [DATMMP](INFO): Starting 2024-08-26T22:12:29.001Z,1724710349.001 [DATMMP](INFO): Starting 2024-08-26T22:12:29.253Z,1724710349.253 [DATMMP](INFO): Starting 2024-08-26T22:12:29.505Z,1724710349.505 [DATMMP](INFO): Starting 2024-08-26T22:12:29.757Z,1724710349.757 [DATMMP](INFO): Starting 2024-08-26T22:12:30.009Z,1724710350.009 [DATMMP](INFO): Starting 2024-08-26T22:12:30.261Z,1724710350.261 [DATMMP](INFO): Starting 2024-08-26T22:12:30.513Z,1724710350.513 [DATMMP](INFO): Starting 2024-08-26T22:12:30.765Z,1724710350.765 [DATMMP](INFO): Starting 2024-08-26T22:12:30.765Z,1724710350.765 [DATMMP](INFO): DAT read: 2024-08-26T22:12:30.766Z,1724710350.766 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-08-26T22:12:31.017Z,1724710351.017 [DATMMP](INFO): Starting 2024-08-26T22:12:31.237Z,1724710351.237 [RudderServo](FAULT): Overload Error 2024-08-26T22:12:31.237Z,1724710351.237 [RudderServo] Hardware Fault, FailCount= 2 2024-08-26T22:12:31.237Z,1724710351.237 [RudderServo](ERROR): Hardware Fault 2024-08-26T22:12:31.265Z,1724710351.265 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-08-26T22:12:31.269Z,1724710351.269 [DATMMP](INFO): Starting 2024-08-26T22:12:31.521Z,1724710351.521 [DATMMP](INFO): Starting 2024-08-26T22:12:31.637Z,1724710351.637 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-26T22:12:31.637Z,1724710351.637 [RudderServo](INFO): Powering down 2024-08-26T22:12:31.773Z,1724710351.773 [DATMMP](INFO): Starting 2024-08-26T22:12:32.025Z,1724710352.025 [DATMMP](INFO): Starting 2024-08-26T22:12:32.277Z,1724710352.277 [DATMMP](INFO): Starting 2024-08-26T22:12:32.278Z,1724710352.278 [DATMMP](INFO): DAT read: MF Frequency Band 2024-08-26T22:12:32.279Z,1724710352.279 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-08-26T22:12:32.279Z,1724710352.279 [DATMMP](INFO): DAT read: Jul 26 2000 22:12:27 2024-08-26T22:12:32.425Z,1724710352.425 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:32.529Z,1724710352.529 [DATMMP](INFO): Starting 2024-08-26T22:12:32.546Z,1724710352.546 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-26T22:12:32.550Z,1724710352.550 [CBIT](INFO): Clearing failed state for component RudderServo 2024-08-26T22:12:32.550Z,1724710352.550 [RudderServo] No Fault, FailCount= 2 2024-08-26T22:12:32.781Z,1724710352.781 [DATMMP](INFO): Starting 2024-08-26T22:12:33.033Z,1724710353.033 [DATMMP](INFO): Starting 2024-08-26T22:12:33.285Z,1724710353.285 [DATMMP](INFO): Starting 2024-08-26T22:12:33.537Z,1724710353.537 [DATMMP](INFO): Starting 2024-08-26T22:12:33.538Z,1724710353.538 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-08-26T22:12:33.539Z,1724710353.539 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-08-26T22:12:33.539Z,1724710353.539 [DATMMP](INFO): commRate: 1200 2024-08-26T22:12:33.789Z,1724710353.789 [DATMMP](INFO): Starting 2024-08-26T22:12:34.041Z,1724710354.041 [DATMMP](INFO): Starting 2024-08-26T22:12:34.293Z,1724710354.293 [DATMMP](INFO): Starting 2024-08-26T22:12:34.545Z,1724710354.545 [DATMMP](INFO): Starting 2024-08-26T22:12:34.797Z,1724710354.797 [DATMMP](INFO): Starting 2024-08-26T22:12:35.049Z,1724710355.049 [DATMMP](INFO): Starting 2024-08-26T22:12:35.301Z,1724710355.301 [DATMMP](INFO): Starting 2024-08-26T22:12:35.553Z,1724710355.553 [DATMMP](INFO): Starting 2024-08-26T22:12:35.605Z,1724710355.605 [DATMMP](INFO): entering command mode 2024-08-26T22:12:35.805Z,1724710355.805 [DATMMP](INFO): Starting 2024-08-26T22:12:35.805Z,1724710355.805 [DATMMP](INFO): DAT read: 2024-08-26T22:12:35.805Z,1724710355.805 [DATMMP](INFO): DAT read: user:1> 2024-08-26T22:12:35.806Z,1724710355.806 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-08-26T22:12:35.806Z,1724710355.806 [DATMMP](INFO): entering MMP mode 2024-08-26T22:12:36.057Z,1724710356.057 [DATMMP](INFO): Starting 2024-08-26T22:12:36.058Z,1724710356.058 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-26T22:12:36.060Z,1724710356.060 [DATMMP](INFO): status rx: x1 2024-08-26T22:12:36.060Z,1724710356.060 [DATMMP](INFO): MMP status: 1: started 2024-08-26T22:12:36.060Z,1724710356.060 [DATMMP](INFO): Received message type: status 2024-08-26T22:12:36.061Z,1724710356.061 [DATMMP](INFO): Handled 2024-08-26T22:12:36.309Z,1724710356.309 [DATMMP](INFO): Starting 2024-08-26T22:12:36.314Z,1724710356.314 [DATMMP](INFO): Sent Tx Power Config 8 2024-08-26T22:12:36.314Z,1724710356.314 [DATMMP](INFO): Setting local address: 5 2024-08-26T22:12:36.316Z,1724710356.316 [DATMMP](INFO): Setting time to: 22:12:36 And date to:8/26/2024 2024-08-26T22:12:36.561Z,1724710356.561 [DATMMP](INFO): Starting 2024-08-26T22:12:36.561Z,1724710356.561 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-26T22:12:36.562Z,1724710356.562 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-08-26T22:12:36.562Z,1724710356.562 [DATMMP](INFO): Received message type: mdmpower 2024-08-26T22:12:36.562Z,1724710356.562 [DATMMP](INFO): Handled 2024-08-26T22:12:36.813Z,1724710356.813 [DATMMP](INFO): Starting 2024-08-26T22:12:36.813Z,1724710356.813 [DATMMP](INFO): GSXN notify for xid: 10 2024-08-26T22:12:36.814Z,1724710356.814 [DATMMP](INFO): sreg_txpower rx: x8 2024-08-26T22:12:36.814Z,1724710356.814 [DATMMP](INFO): Received message type: sreg_txpower 2024-08-26T22:12:36.814Z,1724710356.814 [DATMMP](INFO): Match for : sreg_txpower 2024-08-26T22:12:36.815Z,1724710356.815 [DATMMP](INFO): ACK 2024-08-26T22:12:37.065Z,1724710357.065 [DATMMP](INFO): Starting 2024-08-26T22:12:37.066Z,1724710357.066 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-26T22:12:37.068Z,1724710357.068 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0 2024-08-26T22:12:37.068Z,1724710357.068 [DATMMP](INFO): Received message type: mdmlocal 2024-08-26T22:12:37.068Z,1724710357.068 [DATMMP](INFO): Handled 2024-08-26T22:12:37.318Z,1724710357.318 [DATMMP](INFO): Starting 2024-08-26T22:12:37.320Z,1724710357.320 [DATMMP](INFO): GSXN notify for xid: 11 2024-08-26T22:12:37.323Z,1724710357.323 [DATMMP](INFO): sreg_locaddr rx: x5 2024-08-26T22:12:37.324Z,1724710357.324 [DATMMP](INFO): Received message type: sreg_locaddr 2024-08-26T22:12:37.325Z,1724710357.325 [DATMMP](INFO): Match for : sreg_locaddr 2024-08-26T22:12:37.325Z,1724710357.325 [DATMMP](INFO): ACK 2024-08-26T22:12:37.570Z,1724710357.570 [DATMMP](INFO): Starting 2024-08-26T22:12:37.571Z,1724710357.571 [DATMMP](INFO): GSXN notify for xid: 12 2024-08-26T22:12:37.572Z,1724710357.572 [DATMMP](INFO): timedate rx: x0 x16 xC x24 x7 x1A x7C x0 2024-08-26T22:12:37.572Z,1724710357.572 [DATMMP](INFO): Received message type: timedate 2024-08-26T22:12:37.573Z,1724710357.573 [DATMMP](INFO): Match for : timedate 2024-08-26T22:12:37.573Z,1724710357.573 [DATMMP](INFO): ACK 2024-08-26T22:12:37.721Z,1724710357.721 [RudderServo](FAULT): Overload Error 2024-08-26T22:12:37.721Z,1724710357.721 [RudderServo] Hardware Fault, FailCount= 3 2024-08-26T22:12:37.721Z,1724710357.721 [RudderServo](ERROR): Hardware Fault 2024-08-26T22:12:37.761Z,1724710357.761 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-08-26T22:12:37.761Z,1724710357.761 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-08-26T22:12:37.821Z,1724710357.821 [DATMMP](INFO): Starting 2024-08-26T22:12:37.821Z,1724710357.821 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-26T22:12:37.822Z,1724710357.822 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-08-26T22:12:37.822Z,1724710357.822 [DATMMP](INFO): Received message type: sys_verb 2024-08-26T22:12:37.822Z,1724710357.822 [DATMMP](INFO): Handled 2024-08-26T22:12:38.073Z,1724710358.073 [DATMMP](INFO): Starting 2024-08-26T22:12:38.073Z,1724710358.073 [DATMMP](INFO): GSXN notify for xid: 13 2024-08-26T22:12:38.074Z,1724710358.074 [DATMMP](INFO): sreg_verbosity rx: x0 2024-08-26T22:12:38.074Z,1724710358.074 [DATMMP](INFO): Received message type: sreg_verbosity 2024-08-26T22:12:38.074Z,1724710358.074 [DATMMP](INFO): Match for : sreg_verbosity 2024-08-26T22:12:38.074Z,1724710358.074 [DATMMP](INFO): ACK 2024-08-26T22:12:38.077Z,1724710358.077 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-26T22:12:38.078Z,1724710358.078 [RudderServo](INFO): Powering down 2024-08-26T22:12:38.080Z,1724710358.080 [CBIT](INFO): Critical error at 20240826T221237 2024-08-26T22:12:38.325Z,1724710358.325 [DATMMP](INFO): Starting 2024-08-26T22:12:38.325Z,1724710358.325 [DATMMP](INFO): GSXN notify for xid: 14 2024-08-26T22:12:38.326Z,1724710358.326 [DATMMP](INFO): version rx: x2 x5 2024-08-26T22:12:38.326Z,1724710358.326 [DATMMP](INFO): DatMMPVersion: 2.5 2024-08-26T22:12:38.326Z,1724710358.326 [DATMMP](INFO): Received message type: version 2024-08-26T22:12:38.326Z,1724710358.326 [DATMMP](INFO): Match for : version 2024-08-26T22:12:38.327Z,1724710358.327 [DATMMP](INFO): ACK 2024-08-26T22:12:38.327Z,1724710358.327 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-08-26T22:12:38.893Z,1724710358.893 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:12:39.014Z,1724710359.014 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-26T22:12:48.131Z,1724710368.131 [NAL9602](INFO): Powering up NAL9602 2024-08-26T22:12:52.449Z,1724710372.449 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-26T22:12:52.450Z,1724710372.450 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-08-26T22:12:52.450Z,1724710372.450 [DATMMP](INFO): Received message type: timesync 2024-08-26T22:12:52.450Z,1724710372.450 [DATMMP](INFO): Handled 2024-08-26T22:12:59.039Z,1724710379.039 [NAL9602](INFO): NAL9602 initialized 2024-08-26T22:13:02.517Z,1724710382.517 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-26T22:13:02.518Z,1724710382.518 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-08-26T22:13:02.518Z,1724710382.518 [DATMMP](INFO): Received message type: timesync 2024-08-26T22:13:02.518Z,1724710382.518 [DATMMP](INFO): Handled 2024-08-26T22:13:17.585Z,1724710397.585 [CommandExec](IMPORTANT): got command failComponent 2024-08-26T22:13:17.585Z,1724710397.585 [CommandExec](IMPORTANT): Failed components: 2024-08-26T22:13:17.586Z,1724710397.586 [CommandExec](IMPORTANT): RudderServo: Hardware Fault 2024-08-26T22:13:17.586Z,1724710397.586 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2024-08-26T22:13:18.063Z,1724710398.063 [SBIT](IMPORTANT): Beginning Startup BIT 2024-08-26T22:13:18.067Z,1724710398.067 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-26T22:13:29.034Z,1724710409.034 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006690 CHAN A1 (24V): 0.000971 CHAN A2 (12V): -0.005025 CHAN A3 (5V): -0.002823 CHAN B0 (3.3V): -0.001078 CHAN B1 (3.15aV): -0.000796 CHAN B2 (3.15bV): -0.000634 CHAN B3 (GND): -0.000101 OPEN: 0.006638 Full Scale: +/- 1 mA 2024-08-26T22:13:31.494Z,1724710411.494 [SBIT](ERROR): Could not read rudderAngleReader_. 2024-08-26T22:13:38.965Z,1724710418.965 [CommandExec](IMPORTANT): got command failComponent none RudderServo 2024-08-26T22:13:38.965Z,1724710418.965 [RudderServo] No Fault, FailCount= 3 2024-08-26T22:13:38.965Z,1724710418.965 [CommandExec](IMPORTANT): RudderServo failureMode is No Fault 2024-08-26T22:13:43.149Z,1724710423.149 [RudderServo](FAULT): Overload Error 2024-08-26T22:13:43.149Z,1724710423.149 [RudderServo] Hardware Fault, FailCount= 4 2024-08-26T22:13:43.149Z,1724710423.149 [RudderServo](ERROR): Hardware Fault 2024-08-26T22:13:43.177Z,1724710423.177 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-08-26T22:13:43.177Z,1724710423.177 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-08-26T22:13:43.541Z,1724710423.541 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-26T22:13:43.541Z,1724710423.541 [RudderServo](INFO): Powering down 2024-08-26T22:13:43.544Z,1724710423.544 [CBIT](INFO): Critical error at 20240826T221343 2024-08-26T22:13:44.349Z,1724710424.349 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-26T22:13:44.470Z,1724710424.470 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-26T22:13:58.054Z,1724710438.054 [SBIT](ERROR): Could not read rudderAngleReader_. 2024-08-26T22:13:58.054Z,1724710438.054 [SBIT](FAULT): Control surface position failure. 2024-08-26T22:14:11.783Z,1724710451.783 [SBIT](ERROR): Could not read rudderAngleReader_. 2024-08-26T22:14:11.783Z,1724710451.783 [SBIT](FAULT): Control surface position failure. 2024-08-26T22:14:12.171Z,1724710452.171 [SBIT](CRITICAL): SBIT FAILED 2024-08-26T22:14:12.171Z,1724710452.171 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-08-26T22:14:12.172Z,1724710452.172 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-08-26T22:14:12.172Z,1724710452.172 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-08-26T22:14:12.172Z,1724710452.172 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-08-26T22:14:12.172Z,1724710452.172 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-08-26T22:14:12.173Z,1724710452.173 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=88.359306 cubic_centimeter; 2024-08-26T22:14:12.173Z,1724710452.173 [SBIT](IMPORTANT): VerticalControl.massDefault=1 centimeter; 2024-08-26T22:14:12.174Z,1724710452.174 [CBIT](INFO): Critical error at 20240826T221412 2024-08-26T22:14:12.579Z,1724710452.579 [MissionManager](IMPORTANT): Started mission Startup 2024-08-26T22:14:12.579Z,1724710452.579 [Startup] Running Loop=1 2024-08-26T22:14:12.579Z,1724710452.579 [Startup](DEBUG): Aggregate::initialize Startup 2024-08-26T22:14:12.579Z,1724710452.579 [Startup:A.GoToSurface] Running Loop=1 2024-08-26T22:14:12.579Z,1724710452.579 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-26T22:14:12.580Z,1724710452.580 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-26T22:14:12.580Z,1724710452.580 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-26T22:14:12.581Z,1724710452.581 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-26T22:14:12.581Z,1724710452.581 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-26T22:14:12.581Z,1724710452.581 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-26T22:14:12.582Z,1724710452.582 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-26T22:14:12.583Z,1724710452.583 [Startup:StartupSatComms] Running Loop=1 2024-08-26T22:14:12.583Z,1724710452.583 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-08-26T22:14:12.584Z,1724710452.584 [Startup:StartupSatComms:A] Running Loop=1 2024-08-26T22:14:12.980Z,1724710452.980 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-08-26T22:14:16.933Z,1724710456.933 [CommandExec](IMPORTANT): got command failComponent 2024-08-26T22:14:16.933Z,1724710456.933 [CommandExec](IMPORTANT): Failed components: 2024-08-26T22:14:16.934Z,1724710456.934 [CommandExec](IMPORTANT): RudderServo: Hardware Fault 2024-08-26T22:14:16.934Z,1724710456.934 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2024-08-26T22:14:27.233Z,1724710467.233 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-26T22:14:30.595Z,1724710470.595 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005005 2024-08-26T22:15:12.806Z,1724710512.806 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-26T22:14:12.6Z 2024-08-26T22:15:12.806Z,1724710512.806 [Startup:StartupSatComms:A] Stopped 2024-08-26T22:15:12.806Z,1724710512.806 [Startup:StartupSatComms:B] Running Loop=1 2024-08-26T22:15:13.206Z,1724710513.206 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-26T22:15:18.890Z,1724710518.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-08-26T22:15:18.890Z,1724710518.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-26T22:15:18.913Z,1724710518.913 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-26T22:15:19.284Z,1724710519.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-26T22:15:19.284Z,1724710519.284 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-08-26T22:15:20.461Z,1724710520.461 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20240826T210033/Courier0022.lzma 2024-08-26T22:15:21.463Z,1724710521.463 [DataOverHttps](INFO): Moved sent file to Logs/20240826T210033/Courier0022.lzma.bak 2024-08-26T22:15:21.463Z,1724710521.463 [DataOverHttps](INFO): SBD MOMSN=23067492 2024-08-26T22:15:35.145Z,1724710535.145 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-26T22:15:37.012Z,1724710537.012 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-26T22:15:38.537Z,1724710538.537 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20240826T221207/Courier0000.lzma 2024-08-26T22:15:39.538Z,1724710539.538 [DataOverHttps](INFO): Moved sent file to Logs/20240826T221207/Courier0000.lzma.bak 2024-08-26T22:15:39.539Z,1724710539.539 [DataOverHttps](INFO): SBD MOMSN=23067495 2024-08-26T22:15:56.818Z,1724710556.818 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20240826T210033/Express0023.lzma 2024-08-26T22:15:57.819Z,1724710557.819 [DataOverHttps](INFO): Moved sent file to Logs/20240826T210033/Express0023.lzma.bak 2024-08-26T22:15:57.819Z,1724710557.819 [DataOverHttps](INFO): SBD MOMSN=23067504 2024-08-26T22:16:12.986Z,1724710572.986 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-26T22:15:12.8Z 2024-08-26T22:16:12.986Z,1724710572.986 [Startup:StartupSatComms:B] Stopped 2024-08-26T22:16:12.987Z,1724710572.987 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-08-26T22:16:12.987Z,1724710572.987 [Startup:StartupSatComms] Stopped 2024-08-26T22:16:12.987Z,1724710572.987 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-08-26T22:16:12.988Z,1724710572.988 [Startup](INFO): Completed Startup 2024-08-26T22:16:12.988Z,1724710572.988 [MissionManager](INFO): Startup is completed. 2024-08-26T22:16:12.988Z,1724710572.988 [MissionManager](INFO): Uninitializing Mission Startup 2024-08-26T22:16:12.988Z,1724710572.988 [Startup] Stopped 2024-08-26T22:16:12.988Z,1724710572.988 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-08-26T22:16:12.988Z,1724710572.988 [Startup:A.GoToSurface] Stopped 2024-08-26T22:16:12.988Z,1724710572.988 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-26T22:16:13.390Z,1724710573.390 [MissionManager](IMPORTANT): Started mission Default 2024-08-26T22:16:13.390Z,1724710573.390 [Default] Running Loop=1 2024-08-26T22:16:13.390Z,1724710573.390 [Default](DEBUG): Aggregate::initialize Default 2024-08-26T22:16:13.390Z,1724710573.390 [Default:B.GoToSurface] Running Loop=1 2024-08-26T22:16:13.390Z,1724710573.390 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-26T22:16:13.391Z,1724710573.391 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-26T22:16:13.391Z,1724710573.391 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-26T22:16:13.391Z,1724710573.391 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-26T22:16:13.391Z,1724710573.391 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-26T22:16:13.392Z,1724710573.392 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-26T22:16:13.392Z,1724710573.392 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-26T22:16:13.396Z,1724710573.396 [Default:A.Wait] Running Loop=1 2024-08-26T22:16:13.397Z,1724710573.397 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-08-26T22:16:15.633Z,1724710575.633 [DataOverHttps](INFO): Sending 1027 bytes from file Logs/20240826T221207/Express0001.lzma 2024-08-26T22:16:16.518Z,1724710576.518 [DataOverHttps](INFO): Moved sent file to Logs/20240826T221207/Express0001.lzma.bak 2024-08-26T22:16:16.518Z,1724710576.519 [DataOverHttps](INFO): SBD MOMSN=23067516 2024-08-26T22:16:17.466Z,1724710577.466 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-26T22:16:17.849Z,1724710577.849 [CommandExec](IMPORTANT): got command restart system 2024-08-26T22:16:19.933Z,1724710579.933 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-26T22:16:19.933Z,1724710579.933 [CommandExec](INFO): Uninitializing the command executive. 2024-08-26T22:16:19.933Z,1724710579.933 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-26T22:16:19.934Z,1724710579.934 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-26T22:16:20.001Z,1724710580.001 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-26T22:16:20.001Z,1724710580.001 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-26T22:16:20.001Z,1724710580.001 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-26T22:16:20.002Z,1724710580.002 [NavChartDb](INFO): Join timeout helper Thread ID is 4401 2024-08-26T22:16:20.341Z,1724710580.341 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-26T22:16:20.342Z,1724710580.342 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-26T22:16:20.357Z,1724710580.357 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-26T22:16:20.357Z,1724710580.357 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-26T22:16:20.358Z,1724710580.358 [Radio_Surface](INFO): Join timeout helper Thread ID is 4402 2024-08-26T22:16:20.741Z,1724710580.741 [Radio_Surface](INFO): Powering down 2024-08-26T22:16:20.742Z,1724710580.742 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-26T22:16:20.742Z,1724710580.742 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-26T22:16