2024-03-28T18:32:29.947Z,1711650749.947 [Supervisor](DEBUG): Initializing supervisor. 2024-03-28T18:32:29.951Z,1711650749.951 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-03-28T18:32:29.952Z,1711650749.952 [SyncHandler](INFO): Protected caller Thread ID is 4422 2024-03-28T18:32:29.952Z,1711650749.952 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-03-28T18:32:29.953Z,1711650749.953 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-03-28T18:32:29.954Z,1711650749.954 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4423 2024-03-28T18:32:29.957Z,1711650749.957 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-03-28T18:32:29.975Z,1711650749.975 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-03-28T18:32:29.976Z,1711650749.976 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-03-28T18:32:29.976Z,1711650749.976 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4424 2024-03-28T18:32:29.980Z,1711650749.980 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-03-28T18:32:29.981Z,1711650749.981 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-03-28T18:32:29.982Z,1711650749.982 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4425 2024-03-28T18:32:29.984Z,1711650749.984 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-03-28T18:32:29.984Z,1711650749.984 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-03-28T18:32:29.985Z,1711650749.985 [logger ThreadHandler](INFO): Protected caller Thread ID is 4426 2024-03-28T18:32:29.988Z,1711650749.988 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-03-28T18:32:29.989Z,1711650749.989 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-03-28T18:32:29.990Z,1711650749.990 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-03-28T18:32:30.322Z,1711650750.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-03-28T18:32:30.323Z,1711650750.323 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-03-28T18:32:31.065Z,1711650751.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-03-28T18:32:31.066Z,1711650751.066 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-03-28T18:32:31.176Z,1711650751.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-03-28T18:32:31.176Z,1711650751.176 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-03-28T18:32:31.761Z,1711650751.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-03-28T18:32:31.762Z,1711650751.762 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-03-28T18:32:31.860Z,1711650751.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-03-28T18:32:31.861Z,1711650751.861 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-03-28T18:32:31.943Z,1711650751.943 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-03-28T18:32:32.364Z,1711650752.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-03-28T18:32:32.365Z,1711650752.365 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-03-28T18:32:32.590Z,1711650752.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-03-28T18:32:32.590Z,1711650752.590 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-03-28T18:32:32.731Z,1711650752.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-03-28T18:32:32.731Z,1711650752.731 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-03-28T18:32:32.978Z,1711650752.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-03-28T18:32:32.978Z,1711650752.978 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-03-28T18:32:33.533Z,1711650753.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-03-28T18:32:33.534Z,1711650753.534 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-03-28T18:32:34.162Z,1711650754.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-03-28T18:32:34.163Z,1711650754.163 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-03-28T18:32:34.243Z,1711650754.243 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-03-28T18:32:34.443Z,1711650754.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-03-28T18:32:34.444Z,1711650754.444 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-03-28T18:32:34.679Z,1711650754.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-03-28T18:32:34.680Z,1711650754.680 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-03-28T18:32:34.985Z,1711650754.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-03-28T18:32:34.987Z,1711650754.987 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2024-03-28T18:32:34.987Z,1711650754.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2024-03-28T18:32:35.099Z,1711650755.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2024-03-28T18:32:35.253Z,1711650755.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2024-03-28T18:32:35.342Z,1711650755.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2024-03-28T18:32:35.427Z,1711650755.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2024-03-28T18:32:35.540Z,1711650755.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2024-03-28T18:32:35.653Z,1711650755.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2024-03-28T18:32:35.926Z,1711650755.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-03-28T18:32:35.927Z,1711650755.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2024-03-28T18:32:36.062Z,1711650756.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2024-03-28T18:32:36.158Z,1711650756.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2024-03-28T18:32:36.265Z,1711650756.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2024-03-28T18:32:36.370Z,1711650756.370 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2024-03-28T18:32:36.371Z,1711650756.371 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-03-28T18:32:36.379Z,1711650756.379 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-03-28T18:32:36.503Z,1711650756.503 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-03-28T18:32:36.504Z,1711650756.504 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-03-28T18:32:36.514Z,1711650756.514 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-03-28T18:32:36.515Z,1711650756.515 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-03-28T18:32:36.570Z,1711650756.570 [DepthRateCalculator] Loaded 2024-03-28T18:32:36.570Z,1711650756.570 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-03-28T18:32:36.576Z,1711650756.576 [PitchRateCalculator] Loaded 2024-03-28T18:32:36.576Z,1711650756.576 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-03-28T18:32:36.586Z,1711650756.586 [SpeedCalculator] Loaded 2024-03-28T18:32:36.586Z,1711650756.586 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-03-28T18:32:36.591Z,1711650756.591 [YawRateCalculator] Loaded 2024-03-28T18:32:36.591Z,1711650756.591 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-03-28T18:32:36.612Z,1711650756.612 [ElevatorOffsetCalculator] Loaded 2024-03-28T18:32:36.613Z,1711650756.613 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-03-28T18:32:36.613Z,1711650756.613 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-03-28T18:32:36.614Z,1711650756.614 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-03-28T18:32:36.670Z,1711650756.670 [VerticalControl](DEBUG): Construct VerticalControl. 2024-03-28T18:32:36.724Z,1711650756.724 [VerticalControl] Loaded 2024-03-28T18:32:36.724Z,1711650756.724 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-03-28T18:32:36.727Z,1711650756.727 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-03-28T18:32:36.769Z,1711650756.769 [HorizontalControl] Loaded 2024-03-28T18:32:36.769Z,1711650756.769 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-03-28T18:32:36.772Z,1711650756.772 [SpeedControl](DEBUG): Construct SpeedControl. 2024-03-28T18:32:36.774Z,1711650756.774 [SpeedControl] Loaded 2024-03-28T18:32:36.775Z,1711650756.775 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-03-28T18:32:36.777Z,1711650756.777 [LoopControl](DEBUG): Construct LoopControl. 2024-03-28T18:32:36.778Z,1711650756.778 [LoopControl] Loaded 2024-03-28T18:32:36.778Z,1711650756.778 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-03-28T18:32:36.778Z,1711650756.778 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-03-28T18:32:36.779Z,1711650756.779 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-03-28T18:32:36.894Z,1711650756.894 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-03-28T18:32:36.895Z,1711650756.895 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-03-28T18:32:36.908Z,1711650756.908 [NavChart] Loaded 2024-03-28T18:32:36.909Z,1711650756.909 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-03-28T18:32:36.914Z,1711650756.914 [UniversalFixResidualReporter] Loaded 2024-03-28T18:32:36.914Z,1711650756.914 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-03-28T18:32:36.915Z,1711650756.915 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-03-28T18:32:36.915Z,1711650756.915 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-03-28T18:32:37.078Z,1711650757.078 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-03-28T18:32:37.079Z,1711650757.079 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-03-28T18:32:37.534Z,1711650757.534 [AHRS_M2] Loaded 2024-03-28T18:32:37.534Z,1711650757.534 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-03-28T18:32:37.571Z,1711650757.571 [BackseatComponent] Loaded 2024-03-28T18:32:37.571Z,1711650757.571 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-03-28T18:32:37.573Z,1711650757.573 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-03-28T18:32:37.573Z,1711650757.573 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4509 2024-03-28T18:32:37.576Z,1711650757.576 [LcmUniversalReporter] Loaded 2024-03-28T18:32:37.576Z,1711650757.576 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-03-28T18:32:38.419Z,1711650758.419 [BPC1] Loaded 2024-03-28T18:32:38.419Z,1711650758.419 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-03-28T18:32:38.492Z,1711650758.492 [DataOverHttps] Loaded 2024-03-28T18:32:38.492Z,1711650758.492 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-03-28T18:32:38.493Z,1711650758.493 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2024-03-28T18:32:38.494Z,1711650758.494 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4510 2024-03-28T18:32:38.515Z,1711650758.515 [Depth_Keller] Loaded 2024-03-28T18:32:38.515Z,1711650758.515 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-03-28T18:32:38.520Z,1711650758.520 [DropWeight] Loaded 2024-03-28T18:32:38.520Z,1711650758.520 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-03-28T18:32:38.577Z,1711650758.577 [DVL_micro] Loaded 2024-03-28T18:32:38.577Z,1711650758.577 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-03-28T18:32:38.773Z,1711650758.773 [NAL9602] Loaded 2024-03-28T18:32:38.774Z,1711650758.774 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-03-28T18:32:38.851Z,1711650758.851 [Onboard] Loaded 2024-03-28T18:32:38.852Z,1711650758.852 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-03-28T18:32:38.853Z,1711650758.853 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2024-03-28T18:32:38.853Z,1711650758.853 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4511 2024-03-28T18:32:38.896Z,1711650758.896 [Power24vConverter] Loaded 2024-03-28T18:32:38.897Z,1711650758.897 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-03-28T18:32:38.910Z,1711650758.910 [Radio_Surface] Loaded 2024-03-28T18:32:38.911Z,1711650758.911 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-03-28T18:32:38.912Z,1711650758.912 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099F4E0 2024-03-28T18:32:38.912Z,1711650758.912 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4512 2024-03-28T18:32:39.147Z,1711650759.147 [DAT] Loaded 2024-03-28T18:32:39.147Z,1711650759.147 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-03-28T18:32:39.148Z,1711650759.148 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409CF4E0 2024-03-28T18:32:39.148Z,1711650759.148 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4513 2024-03-28T18:32:39.149Z,1711650759.149 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-03-28T18:32:39.149Z,1711650759.149 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-03-28T18:32:39.525Z,1711650759.525 [BuoyancyServo] Loaded 2024-03-28T18:32:39.525Z,1711650759.525 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-03-28T18:32:39.546Z,1711650759.546 [ElevatorServo] Loaded 2024-03-28T18:32:39.546Z,1711650759.546 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-03-28T18:32:39.567Z,1711650759.567 [MassServo] Loaded 2024-03-28T18:32:39.567Z,1711650759.567 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-03-28T18:32:39.586Z,1711650759.586 [RudderServo] Loaded 2024-03-28T18:32:39.586Z,1711650759.586 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-03-28T18:32:39.601Z,1711650759.601 [ThrusterHE] Loaded 2024-03-28T18:32:39.601Z,1711650759.601 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-03-28T18:32:39.602Z,1711650759.602 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-03-28T18:32:39.602Z,1711650759.602 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-03-28T18:32:39.663Z,1711650759.663 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-03-28T18:32:39.664Z,1711650759.664 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-03-28T18:32:39.692Z,1711650759.692 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-03-28T18:32:39.693Z,1711650759.693 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-03-28T18:32:40.061Z,1711650760.061 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-03-28T18:32:40.062Z,1711650760.062 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-03-28T18:32:40.245Z,1711650760.245 [CTD_Seabird] Loaded 2024-03-28T18:32:40.245Z,1711650760.245 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-03-28T18:32:40.246Z,1711650760.246 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B7C4E0 2024-03-28T18:32:40.246Z,1711650760.246 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4514 2024-03-28T18:32:40.281Z,1711650760.281 [ESPComponent] Loaded 2024-03-28T18:32:40.281Z,1711650760.281 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-03-28T18:32:40.301Z,1711650760.301 [PAR_Licor] Loaded 2024-03-28T18:32:40.302Z,1711650760.302 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-03-28T18:32:40.343Z,1711650760.343 [WetLabsBB2FL] Loaded 2024-03-28T18:32:40.343Z,1711650760.343 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-03-28T18:32:40.344Z,1711650760.344 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BAC4E0 2024-03-28T18:32:40.344Z,1711650760.344 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4515 2024-03-28T18:32:40.345Z,1711650760.345 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-03-28T18:32:40.346Z,1711650760.346 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-03-28T18:32:40.500Z,1711650760.500 [SBIT](DEBUG): Construct Startup Built In Test. 2024-03-28T18:32:40.509Z,1711650760.509 [SBIT] Loaded 2024-03-28T18:32:40.509Z,1711650760.509 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-03-28T18:32:40.512Z,1711650760.512 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-03-28T18:32:40.525Z,1711650760.525 [IBIT] Loaded 2024-03-28T18:32:40.525Z,1711650760.525 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-03-28T18:32:40.531Z,1711650760.531 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-03-28T18:32:40.636Z,1711650760.636 [CBIT] Loaded 2024-03-28T18:32:40.637Z,1711650760.637 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-03-28T18:32:40.637Z,1711650760.637 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-03-28T18:32:40.643Z,1711650760.643 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-03-28T18:32:40.646Z,1711650760.646 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-03-28T18:32:40.658Z,1711650760.658 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-03-28T18:32:40.659Z,1711650760.659 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C1B4E0 2024-03-28T18:32:40.659Z,1711650760.659 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4516 2024-03-28T18:32:40.664Z,1711650760.664 [Supervisor](INFO): Main Thread ID is 4421 2024-03-28T18:32:40.664Z,1711650760.664 [Supervisor](DEBUG): Running supervisor. 2024-03-28T18:32:40.664Z,1711650760.664 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4517 2024-03-28T18:32:40.665Z,1711650760.665 [CommandExec](INFO): Initializing the command executive. 2024-03-28T18:32:40.667Z,1711650760.667 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4518 2024-03-28T18:32:40.669Z,1711650760.669 [controlThread ThreadHandler](INFO): Handler Thread ID is 4519 2024-03-28T18:32:40.669Z,1711650760.669 [controlThread](DEBUG): Initializing ControlThread 2024-03-28T18:32:40.670Z,1711650760.670 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-03-28T18:32:40.670Z,1711650760.670 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-03-28T18:32:40.671Z,1711650760.671 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-03-28T18:32:40.671Z,1711650760.671 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-03-28T18:32:40.671Z,1711650760.671 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-03-28T18:32:40.672Z,1711650760.672 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-03-28T18:32:40.674Z,1711650760.674 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-03-28T18:32:40.674Z,1711650760.674 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-03-28T18:32:40.675Z,1711650760.675 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-03-28T18:32:40.675Z,1711650760.675 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-03-28T18:32:40.676Z,1711650760.676 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-03-28T18:32:40.682Z,1711650760.682 [SBIT](INFO): Initialize SBIT Component. 2024-03-28T18:32:40.682Z,1711650760.682 [SBIT](IMPORTANT): git: 2024-02-28-17-g4f75234a9 2024-03-28T18:32:40.683Z,1711650760.683 [SBIT](INFO): git hash: 4f75234a965187dd794fe24c8347fa55f83c72b3 2024-03-28T18:32:40.683Z,1711650760.683 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-03-28T18:32:40.684Z,1711650760.684 [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-03-28T18:32:40.685Z,1711650760.685 [SBIT](INFO): Beginning SBIT in 104.000000 seconds. 2024-03-28T18:32:40.686Z,1711650760.686 [IBIT](INFO): Initialize IBIT Component. 2024-03-28T18:32:40.687Z,1711650760.687 [CBIT](DEBUG): Initialize CBIT Component. 2024-03-28T18:32:40.687Z,1711650760.687 [logger ThreadHandler](INFO): Handler Thread ID is 4520 2024-03-28T18:32:40.698Z,1711650760.698 [CBIT](DEBUG): Initialized mux pins. 2024-03-28T18:32:40.698Z,1711650760.698 [CBIT](DEBUG): Initializing the watchdog timer. 2024-03-28T18:32:40.706Z,1711650760.706 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4521 2024-03-28T18:32:40.718Z,1711650760.718 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4522 2024-03-28T18:32:40.720Z,1711650760.720 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-03-28T18:32:40.722Z,1711650760.722 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-03-28T18:32:40.722Z,1711650760.722 [CBIT](DEBUG): Initializing heartbeat. 2024-03-28T18:32:40.730Z,1711650760.730 [Onboard ThreadHandler](INFO): Handler Thread ID is 4523 2024-03-28T18:32:40.748Z,1711650760.748 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4524 2024-03-28T18:32:40.767Z,1711650760.767 [DAT ThreadHandler](INFO): Handler Thread ID is 4525 2024-03-28T18:32:40.767Z,1711650760.767 [DAT](INFO): Powering up 2024-03-28T18:32:40.768Z,1711650760.768 [DAT](DEBUG): Initializing DAT. 2024-03-28T18:32:40.771Z,1711650760.771 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4527 2024-03-28T18:32:40.772Z,1711650760.772 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-03-28T18:32:40.775Z,1711650760.775 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4529 2024-03-28T18:32:40.778Z,1711650760.778 [WetLabsBB2FL](INFO): Powering up 2024-03-28T18:32:40.779Z,1711650760.779 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4531 2024-03-28T18:32:40.782Z,1711650760.782 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-03-28T18:32:40.782Z,1711650760.782 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-03-28T18:32:40.783Z,1711650760.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-03-28T18:32:40.783Z,1711650760.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-03-28T18:32:40.783Z,1711650760.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-03-28T18:32:40.783Z,1711650760.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-03-28T18:32:40.783Z,1711650760.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-03-28T18:32:40.783Z,1711650760.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-03-28T18:32:40.794Z,1711650760.794 [CBIT](DEBUG): Deactivating GF circuits. 2024-03-28T18:32:40.794Z,1711650760.794 [CBIT](DEBUG): Deactivating emergency mode. 2024-03-28T18:32:40.834Z,1711650760.834 [CBIT](DEBUG): Backplane powered. 2024-03-28T18:32:40.835Z,1711650760.835 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-03-28T18:32:40.835Z,1711650760.835 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-03-28T18:32:40.847Z,1711650760.847 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-28T18:32:40.867Z,1711650760.867 [MissionManager](DEBUG): 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-03-28T18:32:40.867Z,1711650760.867 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-03-28T18:32:40.868Z,1711650760.868 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-03-28T18:32:40.937Z,1711650760.937 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-03-28T18:32:40.955Z,1711650760.955 [Default:A.Wait](DEBUG): Construct Wait. 2024-03-28T18:32:40.957Z,1711650760.957 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-28T18:32:40.973Z,1711650760.973 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-03-28T18:32:41.005Z,1711650761.005 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-03-28T18:32:41.011Z,1711650761.011 [Default:E.Execute](DEBUG): Construct Execute. 2024-03-28T18:32:41.035Z,1711650761.035 [MissionManager](DEBUG): 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-03-28T18:32:41.039Z,1711650761.039 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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-03-28T18:32:41.050Z,1711650761.050 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-28T18:32:41.119Z,1711650761.119 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1829.799438 decibar, 0.000000 m 2024-03-28T18:32:41.127Z,1711650761.127 [Power24vConverter](INFO): Powering up. 2024-03-28T18:32:41.148Z,1711650761.148 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-03-28T18:32:41.154Z,1711650761.154 [Radio_Surface](INFO): Powering up 2024-03-28T18:32:41.169Z,1711650761.169 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:41.174Z,1711650761.174 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-03-28T18:32:41.175Z,1711650761.175 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:41.182Z,1711650761.182 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-28T18:32:41.183Z,1711650761.183 [MassServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:41.194Z,1711650761.194 [MassServo](DEBUG): Initializing MassServo. 2024-03-28T18:32:41.195Z,1711650761.195 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:41.202Z,1711650761.202 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-28T18:32:41.203Z,1711650761.203 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:41.210Z,1711650761.210 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-03-28T18:32:41.488Z,1711650761.488 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-03-28T18:32:41.488Z,1711650761.488 [DropWeight] Hardware Fault, FailCount= 1 2024-03-28T18:32:41.488Z,1711650761.488 [DropWeight](ERROR): Hardware Fault 2024-03-28T18:32:41.534Z,1711650761.534 [CommandExec](FAULT): Scheduling is paused 2024-03-28T18:32:41.534Z,1711650761.534 [CBIT](INFO): Critical error at 20240328T183241 2024-03-28T18:32:41.534Z,1711650761.534 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-03-28T18:32:41.537Z,1711650761.537 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-03-28T18:32:41.546Z,1711650761.546 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-03-28T18:32:41.875Z,1711650761.875 [CBIT](INFO): Critical error at 20240328T183241 2024-03-28T18:32:41.878Z,1711650761.878 [WetLabsBB2FL](INFO): Powering down 2024-03-28T18:32:46.422Z,1711650766.422 [RudderServo](FAULT): Overload Error 2024-03-28T18:32:46.422Z,1711650766.422 [RudderServo] Hardware Fault, FailCount= 1 2024-03-28T18:32:46.422Z,1711650766.422 [RudderServo](ERROR): Hardware Fault 2024-03-28T18:32:46.461Z,1711650766.461 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-03-28T18:32:46.831Z,1711650766.831 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-28T18:32:46.831Z,1711650766.831 [RudderServo](INFO): Powering down 2024-03-28T18:32:46.832Z,1711650766.832 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-03-28T18:32:47.627Z,1711650767.627 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:47.747Z,1711650767.747 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-28T18:32:47.752Z,1711650767.752 [CBIT](INFO): Clearing failed state for component RudderServo 2024-03-28T18:32:47.752Z,1711650767.752 [RudderServo] No Fault, FailCount= 1 2024-03-28T18:32:47.804Z,1711650767.804 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-03-28T18:32:52.610Z,1711650772.610 [DAT](INFO): DAT read: 2024-03-28T18:32:52.611Z,1711650772.611 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-03-28T18:32:52.926Z,1711650772.926 [RudderServo](FAULT): Overload Error 2024-03-28T18:32:52.926Z,1711650772.926 [RudderServo] Hardware Fault, FailCount= 2 2024-03-28T18:32:52.926Z,1711650772.926 [RudderServo](ERROR): Hardware Fault 2024-03-28T18:32:52.954Z,1711650772.954 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-03-28T18:32:53.246Z,1711650773.246 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-28T18:32:53.246Z,1711650773.246 [RudderServo](INFO): Powering down 2024-03-28T18:32:54.046Z,1711650774.046 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:32:54.167Z,1711650774.167 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-28T18:32:54.172Z,1711650774.172 [CBIT](INFO): Clearing failed state for component RudderServo 2024-03-28T18:32:54.172Z,1711650774.172 [RudderServo] No Fault, FailCount= 2 2024-03-28T18:32:54.374Z,1711650774.374 [DAT](INFO): DAT read: MF Frequency Band 2024-03-28T18:32:54.376Z,1711650774.376 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-03-28T18:32:54.376Z,1711650774.376 [DAT](INFO): DAT read: Mar 28 2024 18:32:48 2024-03-28T18:32:55.383Z,1711650775.383 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-03-28T18:32:55.384Z,1711650775.384 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-03-28T18:32:55.384Z,1711650775.384 [DAT](INFO): commRate: 800 2024-03-28T18:32:57.450Z,1711650777.450 [DAT](INFO): entering command mode 2024-03-28T18:32:57.650Z,1711650777.650 [DAT](INFO): DAT read: 2024-03-28T18:32:57.651Z,1711650777.651 [DAT](INFO): DAT read: user:1> 2024-03-28T18:32:57.651Z,1711650777.651 [DAT](INFO): setting verbose to 3 2024-03-28T18:32:57.902Z,1711650777.902 [DAT](INFO): DAT read: user:1> 2024-03-28T18:32:57.903Z,1711650777.903 [DAT](INFO): DAT read: Verbose | 3 2024-03-28T18:32:57.904Z,1711650777.904 [DAT](INFO): set verbose to 3 2024-03-28T18:32:57.904Z,1711650777.904 [DAT](INFO): setting DatVerbose to 27440 2024-03-28T18:32:58.154Z,1711650778.154 [DAT](INFO): DAT read: user:2> 2024-03-28T18:32:58.155Z,1711650778.155 [DAT](INFO): DAT read: DatVerbose | 27440 2024-03-28T18:32:58.156Z,1711650778.156 [DAT](INFO): set DatVerbose to 27440 2024-03-28T18:32:58.156Z,1711650778.156 [DAT](INFO): setting transmit power to 8 2024-03-28T18:32:58.406Z,1711650778.406 [DAT](INFO): DAT read: user:3> 2024-03-28T18:32:58.407Z,1711650778.407 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-03-28T18:32:58.407Z,1711650778.407 [DAT](INFO): set transmit power to 8 2024-03-28T18:32:58.408Z,1711650778.408 [DAT](INFO): setting local address to 11 2024-03-28T18:32:58.658Z,1711650778.658 [DAT](INFO): DAT read: user:4> 2024-03-28T18:32:58.659Z,1711650778.659 [DAT](INFO): DAT read: LocalAddr | 11 2024-03-28T18:32:58.660Z,1711650778.660 [DAT](INFO): set local address to 11 2024-03-28T18:32:58.661Z,1711650778.661 [DAT](INFO): Setting time to: 18:32:58 And date to:3/28/2024 2024-03-28T18:32:58.911Z,1711650778.911 [DAT](INFO): DAT read: user:5> 2024-03-28T18:32:58.913Z,1711650778.913 [DAT](INFO): DAT read: Thu Mar 28, 2024 18:32:58 2024-03-28T18:32:58.913Z,1711650778.913 [DAT](INFO): Local DAT time set to Thu Mar 28, 2024 18:32:58 2024-03-28T18:32:59.356Z,1711650779.356 [RudderServo](FAULT): Overload Error 2024-03-28T18:32:59.356Z,1711650779.356 [RudderServo] Hardware Fault, FailCount= 3 2024-03-28T18:32:59.356Z,1711650779.356 [RudderServo](ERROR): Hardware Fault 2024-03-28T18:32:59.386Z,1711650779.386 [CBIT](ERROR): Hardware Fault in component: RudderServo 2024-03-28T18:32:59.386Z,1711650779.386 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2024-03-28T18:32:59.718Z,1711650779.718 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-28T18:32:59.718Z,1711650779.718 [RudderServo](INFO): Powering down 2024-03-28T18:32:59.721Z,1711650779.721 [CBIT](INFO): Critical error at 20240328T183259 2024-03-28T18:33:00.507Z,1711650780.507 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:33:00.627Z,1711650780.627 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-28T18:33:08.934Z,1711650788.934 [NAL9602](INFO): Powering up NAL9602 2024-03-28T18:33:19.848Z,1711650799.848 [NAL9602](INFO): NAL9602 initialized 2024-03-28T18:33:23.255Z,1711650803.255 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004080 2024-03-28T18:34:25.330Z,1711650865.330 [CommandExec](IMPORTANT): got command failComponent 2024-03-28T18:34:25.330Z,1711650865.330 [CommandExec](IMPORTANT): Failed components: 2024-03-28T18:34:25.331Z,1711650865.331 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2024-03-28T18:34:25.331Z,1711650865.331 [CommandExec](IMPORTANT): RudderServo: Hardware Fault 2024-03-28T18:34:25.339Z,1711650865.339 [SBIT](IMPORTANT): Beginning Startup BIT 2024-03-28T18:34:25.344Z,1711650865.344 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-28T18:34:27.362Z,1711650867.362 [ElevatorServo](FAULT): Overload Error 2024-03-28T18:34:27.362Z,1711650867.362 [ElevatorServo] Hardware Fault, FailCount= 1 2024-03-28T18:34:27.362Z,1711650867.362 [ElevatorServo](ERROR): Hardware Fault 2024-03-28T18:34:27.418Z,1711650867.418 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2024-03-28T18:34:27.735Z,1711650867.735 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-28T18:34:27.736Z,1711650867.736 [ElevatorServo](INFO): Powering down 2024-03-28T18:34:28.543Z,1711650868.543 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:34:28.663Z,1711650868.663 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-28T18:34:28.698Z,1711650868.698 [CBIT](INFO): Clearing failed state for component ElevatorServo 2024-03-28T18:34:28.698Z,1711650868.698 [ElevatorServo] No Fault, FailCount= 1 2024-03-28T18:34:29.075Z,1711650869.075 [CommandExec](IMPORTANT): got command show variable ahrs_m2 2024-03-28T18:34:29.184Z,1711650869.184 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup (bool) 2024-03-28T18:34:29.184Z,1711650869.184 [CommandExec](IMPORTANT): AHRS_M2.simulateHardware (bool) 2024-03-28T18:34:29.185Z,1711650869.185 [CommandExec](IMPORTANT): AHRS_M2.boresightMatrix (none) 2024-03-28T18:34:29.185Z,1711650869.185 [CommandExec](IMPORTANT): AHRS_M2.magDeviation (degree) 2024-03-28T18:34:29.190Z,1711650869.190 [CommandExec](IMPORTANT): AHRS_M2.minNumPointsCal (count) 2024-03-28T18:34:29.191Z,1711650869.191 [CommandExec](IMPORTANT): AHRS_M2.power (watt) 2024-03-28T18:34:29.191Z,1711650869.191 [CommandExec](IMPORTANT): AHRS_M2.readAccelerations (bool) 2024-03-28T18:34:29.192Z,1711650869.192 [CommandExec](IMPORTANT): AHRS_M2.readAngularVelocities (bool) 2024-03-28T18:34:29.192Z,1711650869.192 [CommandExec](IMPORTANT): AHRS_M2.readMagnetics (bool) 2024-03-28T18:34:29.192Z,1711650869.192 [CommandExec](IMPORTANT): AHRS_M2.verbosity (count) 2024-03-28T18:34:29.417Z,1711650869.417 [CommandExec](IMPORTANT): AHRS_M2.loadControl (none) 2024-03-28T18:34:29.422Z,1711650869.422 [CommandExec](IMPORTANT): AHRS_M2.uart (none) 2024-03-28T18:34:29.422Z,1711650869.422 [CommandExec](IMPORTANT): AHRS_M2.baud (bit_per_second) 2024-03-28T18:34:29.569Z,1711650869.569 [CommandExec](IMPORTANT): AHRS_M2.enableBroadcast (bool) 2024-03-28T18:34:29.569Z,1711650869.569 [CommandExec](IMPORTANT): AHRS_M2.component_voltage (volt) 2024-03-28T18:34:29.569Z,1711650869.569 [CommandExec](IMPORTANT): AHRS_M2.component_avgVoltage (volt) 2024-03-28T18:34:29.574Z,1711650869.574 [CommandExec](IMPORTANT): AHRS_M2.component_current (milliampere) 2024-03-28T18:34:29.574Z,1711650869.574 [CommandExec](IMPORTANT): AHRS_M2.component_avgCurrent (milliampere) 2024-03-28T18:34:29.574Z,1711650869.574 [CommandExec](IMPORTANT): AHRS_M2.platform_magnetic_orientation (radian) 2024-03-28T18:34:29.575Z,1711650869.575 [CommandExec](IMPORTANT): AHRS_M2.platform_pitch_angle (radian) 2024-03-28T18:34:29.575Z,1711650869.575 [CommandExec](IMPORTANT): AHRS_M2.platform_roll_angle (radian) 2024-03-28T18:34:29.575Z,1711650869.575 [CommandExec](IMPORTANT): AHRS_M2.platform_orientation (radian) 2024-03-28T18:34:29.576Z,1711650869.576 [CommandExec](IMPORTANT): AHRS_M2.platform_orientation_matrix (none) 2024-03-28T18:34:29.576Z,1711650869.576 [CommandExec](IMPORTANT): AHRS_M2.calibration_state (count) 2024-03-28T18:34:29.576Z,1711650869.576 [CommandExec](IMPORTANT): AHRS_M2.orientation_error (radian) 2024-03-28T18:34:29.577Z,1711650869.577 [CommandExec](IMPORTANT): AHRS_M2.orientation (radian) 2024-03-28T18:34:29.577Z,1711650869.577 [CommandExec](IMPORTANT): AHRS_M2.temperature (celsius) 2024-03-28T18:34:29.582Z,1711650869.582 [CommandExec](IMPORTANT): AHRS_M2.acceleration_along_xyz (meter_per_second_squared) 2024-03-28T18:34:29.582Z,1711650869.582 [CommandExec](IMPORTANT): AHRS_M2.angular_velocity_along_xyz (radian_per_second) 2024-03-28T18:34:29.582Z,1711650869.582 [CommandExec](IMPORTANT): AHRS_M2.magnetic_field_along_xyz (milligauss) 2024-03-28T18:34:29.583Z,1711650869.583 [CommandExec](IMPORTANT): AHRS_M2.number_of_calbration_points (count) 2024-03-28T18:34:33.846Z,1711650873.846 [ElevatorServo](FAULT): Overload Error 2024-03-28T18:34:33.846Z,1711650873.846 [ElevatorServo] Hardware Fault, FailCount= 2 2024-03-28T18:34:33.846Z,1711650873.846 [ElevatorServo](ERROR): Hardware Fault 2024-03-28T18:34:33.911Z,1711650873.911 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2024-03-28T18:34:34.208Z,1711650874.208 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-28T18:34:34.208Z,1711650874.208 [ElevatorServo](INFO): Powering down 2024-03-28T18:34:35.012Z,1711650875.012 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:34:35.127Z,1711650875.127 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-28T18:34:35.159Z,1711650875.159 [CBIT](INFO): Clearing failed state for component ElevatorServo 2024-03-28T18:34:35.159Z,1711650875.159 [ElevatorServo] No Fault, FailCount= 2 2024-03-28T18:34:36.366Z,1711650876.366 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005359 CHAN A1 (24V): -0.002707 CHAN A2 (12V): -0.000449 CHAN A3 (5V): 0.000342 CHAN B0 (3.3V): 0.001007 CHAN B1 (3.15aV): 0.000022 CHAN B2 (3.15bV): 0.000345 CHAN B3 (GND): 0.000959 OPEN: 0.004944 Full Scale: +/- 1 mA 2024-03-28T18:34:38.425Z,1711650878.425 [SBIT](ERROR): Could not read rudderAngleReader_. 2024-03-28T18:34:40.314Z,1711650880.314 [ElevatorServo](FAULT): Overload Error 2024-03-28T18:34:40.314Z,1711650880.314 [ElevatorServo] Hardware Fault, FailCount= 3 2024-03-28T18:34:40.314Z,1711650880.314 [ElevatorServo](ERROR): Hardware Fault 2024-03-28T18:34:40.395Z,1711650880.395 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2024-03-28T18:34:40.395Z,1711650880.395 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2024-03-28T18:34:40.443Z,1711650880.443 [CommandExec](IMPORTANT): got command get platform_magnetic_orientation 2024-03-28T18:34:40.443Z,1711650880.443 [CommandExec](IMPORTANT): platform_magnetic_orientation 6.088336 rad 2024-03-28T18:34:40.668Z,1711650880.668 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-28T18:34:40.668Z,1711650880.668 [ElevatorServo](INFO): Powering down 2024-03-28T18:34:40.700Z,1711650880.700 [CBIT](INFO): Critical error at 20240328T183440 2024-03-28T18:34:41.492Z,1711650881.492 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-28T18:34:41.607Z,1711650881.607 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-28T18:34:52.056Z,1711650892.056 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-03-28T18:34:55.536Z,1711650895.536 [CommandExec](IMPORTANT): got command get AHRS_M2.platform_magnetic_orientation 2024-03-28T18:34:55.537Z,1711650895.537 [CommandExec](IMPORTANT): AHRS_M2.platform_magnetic_orientation 6.090540 rad 2024-03-28T18:34:58.422Z,1711650898.422 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:35:04.952Z,1711650904.952 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-03-28T18:35:04.952Z,1711650904.952 [SBIT](ERROR): Could not read rudderAngleReader_. 2024-03-28T18:35:04.952Z,1711650904.952 [SBIT](FAULT): Control surface position failure. 2024-03-28T18:35:05.406Z,1711650905.406 [CommandExec](IMPORTANT): got command get AHRS_M2.platform_magnetic_orientation 2024-03-28T18:35:05.407Z,1711650905.407 [CommandExec](IMPORTANT): AHRS_M2.platform_magnetic_orientation 3.583613 rad 2024-03-28T18:35:17.822Z,1711650917.822 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:35:18.681Z,1711650918.681 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-03-28T18:35:18.681Z,1711650918.681 [SBIT](ERROR): Could not read rudderAngleReader_. 2024-03-28T18:35:18.681Z,1711650918.681 [SBIT](FAULT): Control surface position failure. 2024-03-28T18:35:19.047Z,1711650919.047 [SBIT](CRITICAL): SBIT FAILED 2024-03-28T18:35:19.048Z,1711650919.048 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-03-28T18:35:19.048Z,1711650919.048 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2024-03-28T18:35:19.049Z,1711650919.049 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count; 2024-03-28T18:35:19.049Z,1711650919.049 [SBIT](IMPORTANT): DVL_micro.baud=19200 bit_per_second; 2024-03-28T18:35:19.049Z,1711650919.049 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-03-28T18:35:19.049Z,1711650919.049 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-03-28T18:35:19.049Z,1711650919.049 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2024-03-28T18:35:19.049Z,1711650919.049 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=75.046417 cubic_centimeter; 2024-03-28T18:35:19.050Z,1711650919.050 [SBIT](IMPORTANT): VerticalControl.massDefault=21.239223 millimeter; 2024-03-28T18:35:19.051Z,1711650919.051 [CBIT](INFO): Critical error at 20240328T183519 2024-03-28T18:35:19.465Z,1711650919.465 [MissionManager](IMPORTANT): Started mission Startup 2024-03-28T18:35:19.466Z,1711650919.466 [Startup] Running Loop=1 2024-03-28T18:35:19.466Z,1711650919.466 [Startup](DEBUG): Aggregate::initialize Startup 2024-03-28T18:35:19.466Z,1711650919.466 [Startup:A.GoToSurface] Running Loop=1 2024-03-28T18:35:19.466Z,1711650919.466 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-28T18:35:19.467Z,1711650919.467 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-28T18:35:19.467Z,1711650919.467 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-28T18:35:19.467Z,1711650919.467 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-28T18:35:19.468Z,1711650919.468 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-28T18:35:19.468Z,1711650919.468 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-28T18:35:19.470Z,1711650919.470 [Startup:StartupSatComms] Running Loop=1 2024-03-28T18:35:19.470Z,1711650919.470 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-03-28T18:35:19.470Z,1711650919.470 [Startup:StartupSatComms:A] Running Loop=1 2024-03-28T18:35:19.857Z,1711650919.857 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-03-28T18:35:37.214Z,1711650937.214 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:35:37.639Z,1711650937.639 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-03-28T18:35:37.639Z,1711650937.639 [DVL_micro] Communications Fault, FailCount= 1 2024-03-28T18:35:37.639Z,1711650937.639 [DVL_micro](ERROR): Communications Fault 2024-03-28T18:35:37.680Z,1711650937.680 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-03-28T18:35:38.142Z,1711650938.142 [DVL_micro](INFO): Powering down 2024-03-28T18:35:38.879Z,1711650938.879 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-03-28T18:35:38.879Z,1711650938.879 [DVL_micro] No Fault, FailCount= 1 2024-03-28T18:35:40.863Z,1711650940.863 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-03-28T18:35:40.863Z,1711650940.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-28T18:35:40.877Z,1711650940.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-28T18:35:41.313Z,1711650941.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-28T18:35:41.313Z,1711650941.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-03-28T18:35:42.061Z,1711650942.061 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-03-28T18:35:42.062Z,1711650942.062 [AHRS_M2] Data Fault, FailCount= 1 2024-03-28T18:35:42.062Z,1711650942.062 [AHRS_M2](ERROR): Data Fault 2024-03-28T18:35:42.119Z,1711650942.119 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-03-28T18:35:42.455Z,1711650942.455 [AHRS_M2](INFO): Powering down 2024-03-28T18:35:43.356Z,1711650943.356 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-03-28T18:35:43.356Z,1711650943.356 [AHRS_M2] No Fault, FailCount= 1 2024-03-28T18:35:43.666Z,1711650943.666 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-28T18:35:55.798Z,1711650955.798 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:35:56.643Z,1711650956.643 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-03-28T18:35:56.643Z,1711650956.643 [DVL_micro] Communications Fault, FailCount= 2 2024-03-28T18:35:56.643Z,1711650956.643 [DVL_micro](ERROR): Communications Fault 2024-03-28T18:35:56.690Z,1711650956.690 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-03-28T18:35:57.009Z,1711650957.009 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-03-28T18:35:57.010Z,1711650957.010 [AHRS_M2] Data Fault, FailCount= 2 2024-03-28T18:35:57.010Z,1711650957.010 [AHRS_M2](ERROR): Data Fault 2024-03-28T18:35:57.142Z,1711650957.142 [DVL_micro](INFO): Powering down 2024-03-28T18:35:57.166Z,1711650957.166 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-03-28T18:35:57.402Z,1711650957.402 [AHRS_M2](INFO): Powering down 2024-03-28T18:35:57.832Z,1711650957.832 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-03-28T18:35:57.832Z,1711650957.832 [DVL_micro] No Fault, FailCount= 2 2024-03-28T18:35:58.244Z,1711650958.244 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-03-28T18:35:58.244Z,1711650958.244 [AHRS_M2] No Fault, FailCount= 2 2024-03-28T18:35:58.615Z,1711650958.615 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-28T18:36:11.957Z,1711650971.957 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-03-28T18:36:11.958Z,1711650971.958 [AHRS_M2] Data Fault, FailCount= 3 2024-03-28T18:36:11.958Z,1711650971.958 [AHRS_M2](ERROR): Data Fault 2024-03-28T18:36:11.983Z,1711650971.983 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-03-28T18:36:12.351Z,1711650972.351 [AHRS_M2](INFO): Powering down 2024-03-28T18:36:13.182Z,1711650973.182 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-03-28T18:36:13.182Z,1711650973.182 [AHRS_M2] No Fault, FailCount= 3 2024-03-28T18:36:13.563Z,1711650973.563 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-28T18:36:14.775Z,1711650974.775 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:36:15.589Z,1711650975.589 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-03-28T18:36:15.589Z,1711650975.589 [DVL_micro] Communications Fault, FailCount= 3 2024-03-28T18:36:15.589Z,1711650975.589 [DVL_micro](ERROR): Communications Fault 2024-03-28T18:36:15.675Z,1711650975.675 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-03-28T18:36:15.675Z,1711650975.675 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-03-28T18:36:16.098Z,1711650976.098 [DVL_micro](INFO): Powering down 2024-03-28T18:36:16.133Z,1711650976.133 [CBIT](INFO): Critical error at 20240328T183615 2024-03-28T18:36:19.672Z,1711650979.672 [Startup:StartupSatComms:A](INFO): Timed out from 2024-03-28T18:35:19.5Z 2024-03-28T18:36:19.672Z,1711650979.672 [Startup:StartupSatComms:A] Stopped 2024-03-28T18:36:19.672Z,1711650979.672 [Startup:StartupSatComms:B] Running Loop=1 2024-03-28T18:36:20.079Z,1711650980.079 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-28T18:36:26.907Z,1711650986.907 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-03-28T18:36:26.907Z,1711650986.907 [AHRS_M2] Data Fault, FailCount= 4 2024-03-28T18:36:26.907Z,1711650986.907 [AHRS_M2](ERROR): Data Fault 2024-03-28T18:36:26.934Z,1711650986.934 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-03-28T18:36:27.053Z,1711650987.053 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240328T182605/Courier0004.lzma 2024-03-28T18:36:27.298Z,1711650987.298 [AHRS_M2](INFO): Powering down 2024-03-28T18:36:28.056Z,1711650988.056 [DataOverHttps](INFO): Moved sent file to Logs/20240328T182605/Courier0004.lzma.bak 2024-03-28T18:36:28.056Z,1711650988.056 [DataOverHttps](INFO): SBD MOMSN=19361258 2024-03-28T18:36:28.135Z,1711650988.135 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-03-28T18:36:28.135Z,1711650988.135 [AHRS_M2] No Fault, FailCount= 4 2024-03-28T18:36:28.510Z,1711650988.510 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-28T18:36:34.179Z,1711650994.179 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:36:41.854Z,1711651001.854 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2024-03-28T18:36:41.854Z,1711651001.854 [AHRS_M2] Data Fault, FailCount= 5 2024-03-28T18:36:41.854Z,1711651001.854 [AHRS_M2](ERROR): Data Fault 2024-03-28T18:36:41.931Z,1711651001.931 [CBIT](ERROR): Data Fault in component: AHRS_M2 2024-03-28T18:36:41.931Z,1711651001.931 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2024-03-28T18:36:42.247Z,1711651002.247 [AHRS_M2](INFO): Powering down 2024-03-28T18:36:42.354Z,1711651002.354 [CBIT](INFO): Critical error at 20240328T183641 2024-03-28T18:36:43.794Z,1711651003.794 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20240328T183229/Courier0000.lzma 2024-03-28T18:36:44.795Z,1711651004.795 [DataOverHttps](INFO): Moved sent file to Logs/20240328T183229/Courier0000.lzma.bak 2024-03-28T18:36:44.795Z,1711651004.795 [DataOverHttps](INFO): SBD MOMSN=19361260 2024-03-28T18:36:53.559Z,1711651013.559 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:37:00.110Z,1711651020.110 [DataOverHttps](INFO): Sending 570 bytes from file Logs/20240328T181712/Express0005.lzma 2024-03-28T18:37:01.111Z,1711651021.111 [DataOverHttps](INFO): Moved sent file to Logs/20240328T181712/Express0005.lzma.bak 2024-03-28T18:37:01.111Z,1711651021.111 [DataOverHttps](INFO): SBD MOMSN=19361269 2024-03-28T18:37:04.223Z,1711651024.223 [DAT](INFO): DAT read: user:6>Response Not Received 2024-03-28T18:37:04.223Z,1711651024.223 [DAT](INFO): response not received 2024-03-28T18:37:04.223Z,1711651024.223 [DAT](ERROR): No response from remote modem. 2024-03-28T18:37:12.143Z,1711651032.143 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-28T18:37:16.276Z,1711651036.276 [DataOverHttps](INFO): Sending 1146 bytes from file Logs/20240328T182605/Express0001.lzma 2024-03-28T18:37:17.275Z,1711651037.275 [DataOverHttps](INFO): Moved sent file to Logs/20240328T182605/Express0001.lzma.bak 2024-03-28T18:37:17.275Z,1711651037.275 [DataOverHttps](INFO): SBD MOMSN=19361293 2024-03-28T18:37:19.835Z,1711651039.835 [Startup:StartupSatComms:B](INFO): Timed out from 2024-03-28T18:36:19.7Z 2024-03-28T18:37:19.835Z,1711651039.835 [Startup:StartupSatComms:B] Stopped 2024-03-28T18:37:19.835Z,1711651039.835 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-03-28T18:37:19.835Z,1711651039.835 [Startup:StartupSatComms] Stopped 2024-03-28T18:37:19.835Z,1711651039.835 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-03-28T18:37:19.836Z,1711651039.836 [Startup](INFO): Completed Startup 2024-03-28T18:37:19.836Z,1711651039.836 [MissionManager](INFO): Startup is completed. 2024-03-28T18:37:19.836Z,1711651039.836 [MissionManager](INFO): Uninitializing Mission Startup 2024-03-28T18:37:19.837Z,1711651039.837 [Startup] Stopped 2024-03-28T18:37:19.837Z,1711651039.837 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-03-28T18:37:19.837Z,1711651039.837 [Startup:A.GoToSurface] Stopped 2024-03-28T18:37:19.837Z,1711651039.837 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-28T18:37:20.248Z,1711651040.248 [MissionManager](IMPORTANT): Started mission Default 2024-03-28T18:37:20.249Z,1711651040.249 [Default] Running Loop=1 2024-03-28T18:37:20.249Z,1711651040.249 [Default](DEBUG): Aggregate::initialize Default 2024-03-28T18:37:20.249Z,1711651040.249 [Default:B.GoToSurface] Running Loop=1 2024-03-28T18:37:20.249Z,1711651040.249 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-28T18:37:20.249Z,1711651040.249 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-28T18:37:20.250Z,1711651040.250 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-28T18:37:20.250Z,1711651040.250 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-28T18:37:20.250Z,1711651040.250 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-28T18:37:20.251Z,1711651040.251 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-28T18:37:20.251Z,1711651040.251 [Default:A.Wait] Running Loop=1 2024-03-28T18:37:20.251Z,1711651040.251 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-03-28T18:37:32.493Z,1711651052.493 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240328T182605/Express0005.lzma 2024-03-28T18:37:33.495Z,1711651053.495 [DataOverHttps](INFO): Moved sent file to Logs/20240328T182605/Express0005.lzma.bak 2024-03-28T18:37:33.495Z,1711651053.495 [DataOverHttps](INFO): SBD MOMSN=19361328 2024-03-28T18:37:33.584Z,1711651053.584 [Default:A.Wait](INFO): Done Waiting. 2024-03-28T18:37:33.584Z,1711651053.584 [Default:A.Wait] Stopped 2024-03-28T18:37:33.584Z,1711651053.584 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-03-28T18:37:33.998Z,1711651053.998 [Default:CheckIn] Running Loop=1 2024-03-28T18:37:33.998Z,1711651053.998 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-28T18:37:33.999Z,1711651053.999 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-28T18:37:34.396Z,1711651054.396 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-03-28T18:37:51.962Z,1711651071.962 [CommandExec](IMPORTANT): got command quit 2024-03-28T18:37:52.966Z,1711651072.966 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:52.966Z,1711651072.966 [CommandExec](INFO): Uninitializing the command executive. 2024-03-28T18:37:52.966Z,1711651072.966 [CommandExec](INFO): Uninitializing the command scheduler. 2024-03-28T18:37:52.966Z,1711651072.966 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:53.125Z,1711651073.125 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-03-28T18:37:53.126Z,1711651073.126 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-03-28T18:37:53.126Z,1711651073.126 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:53.126Z,1711651073.126 [NavChartDb](INFO): Join timeout helper Thread ID is 4566 2024-03-28T18:37:53.198Z,1711651073.198 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:53.198Z,1711651073.198 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:53.210Z,1711651073.210 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-03-28T18:37:53.210Z,1711651073.210 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:53.210Z,1711651073.210 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4567 2024-03-28T18:37:53.362Z,1711651073.362 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:53.362Z,1711651073.362 [WetLabsBB2FL](INFO): Powering down 2024-03-28T18:37:53.363Z,1711651073.363 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:53.378Z,1711651073.378 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-03-28T18:37:53.378Z,1711651073.378 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:53.378Z,1711651073.378 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4568 2024-03-28T18:37:54.082Z,1711651074.082 [CTD_Seabird](INFO): Powering down 2024-03-28T18:37:54.094Z,1711651074.094 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:54.094Z,1711651074.094 [CTD_Seabird](INFO): Powering down 2024-03-28T18:37:54.105Z,1711651074.105 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:54.121Z,1711651074.121 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-03-28T18:37:54.122Z,1711651074.122 [DAT ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:54.122Z,1711651074.122 [DAT](INFO): Join timeout helper Thread ID is 4569 2024-03-28T18:37:54.162Z,1711651074.162 [DAT](INFO): Powering down 2024-03-28T18:37:54.234Z,1711651074.234 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:54.234Z,1711651074.234 [DAT](INFO): Powering down 2024-03-28T18:37:54.235Z,1711651074.235 [DAT ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:54.246Z,1711651074.246 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-03-28T18:37:54.246Z,1711651074.246 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:54.246Z,1711651074.246 [Radio_Surface](INFO): Join timeout helper Thread ID is 4570 2024-03-28T18:37:54.298Z,1711651074.298 [Radio_Surface](INFO): Powering down 2024-03-28T18:37:54.299Z,1711651074.299 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:54.299Z,1711651074.299 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:54.321Z,1711651074.321 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-03-28T18:37:54.321Z,1711651074.321 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:54.322Z,1711651074.322 [Onboard](INFO): Join timeout helper Thread ID is 4571 2024-03-28T18:37:54.578Z,1711651074.578 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-03-28T18:37:55.994Z,1711651075.994 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:55.994Z,1711651075.994 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:55.998Z,1711651075.998 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-03-28T18:37:55.998Z,1711651075.998 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:55.999Z,1711651075.999 [DataOverHttps](INFO): Join timeout helper Thread ID is 4572 2024-03-28T18:37:56.586Z,1711651076.586 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:56.586Z,1711651076.586 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.599Z,1711651076.599 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-03-28T18:37:56.599Z,1711651076.599 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.599Z,1711651076.599 [BackseatComponent](INFO): Join timeout helper Thread ID is 4573 2024-03-28T18:37:56.694Z,1711651076.694 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:56.694Z,1711651076.694 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.706Z,1711651076.706 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-03-28T18:37:56.706Z,1711651076.706 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.707Z,1711651076.707 [logger](INFO): Join timeout helper Thread ID is 4574 2024-03-28T18:37:56.742Z,1711651076.742 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:56.742Z,1711651076.742 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.746Z,1711651076.746 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-03-28T18:37:56.746Z,1711651076.746 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.747Z,1711651076.747 [CommandLine](INFO): Join timeout helper Thread ID is 4575 2024-03-28T18:37:56.798Z,1711651076.798 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:56.798Z,1711651076.798 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.806Z,1711651076.806 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-03-28T18:37:56.806Z,1711651076.806 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.807Z,1711651076.807 [CommandExec](INFO): Join timeout helper Thread ID is 4576 2024-03-28T18:37:56.808Z,1711651076.808 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-03-28T18:37:56.808Z,1711651076.808 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:56.808Z,1711651076.808 [controlThread](INFO): Join timeout helper Thread ID is 4577 2024-03-28T18:37:56.982Z,1711651076.982 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-28T18:37:56.982Z,1711651076.982 [controlThread](DEBUG): Uninitializing ControlThread 2024-03-28T18:37:56.982Z,1711651076.982 [AHRS_M2](INFO): Powering down 2024-03-28T18:37:56.984Z,1711651076.984 [DVL_micro](INFO): Powering down 2024-03-28T18:37:56.985Z,1711651076.985 [NAL9602](INFO): Powering down 2024-03-28T18:37:56.988Z,1711651076.988 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-03-28T18:37:56.988Z,1711651076.988 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-03-28T18:37:56.989Z,1711651076.989 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-03-28T18:37:56.989Z,1711651076.989 [MissionManager](INFO): Uninitializing Mission Default 2024-03-28T18:37:56.989Z,1711651076.989 [Default] Stopped 2024-03-28T18:37:56.990Z,1711651076.990 [Default](DEBUG): Aggregate::uninitialize Default 2024-03-28T18:37:56.990Z,1711651076.990 [Default:B.GoToSurface] Stopped 2024-03-28T18:37:56.990Z,1711651076.990 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-28T18:37:56.990Z,1711651076.990 [Default:CheckIn] Stopped 2024-03-28T18:37:56.990Z,1711651076.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-28T18:37:56.990Z,1711651076.990 [Default:CheckIn:Read_GPS] Stopped 2024-03-28T18:37:56.993Z,1711651076.993 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-03-28T18:37:56.993Z,1711651076.993 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-03-28T18:37:56.993Z,1711651076.993 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-03-28T18:37:56.993Z,1711651076.993 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-03-28T18:37:56.994Z,1711651076.994 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-03-28T18:37:56.994Z,1711651076.994 [BuoyancyServo](INFO): Powering down 2024-03-28T18:37:57.006Z,1711651077.006 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-28T18:37:57.006Z,1711651077.006 [ElevatorServo](INFO): Powering down 2024-03-28T18:37:57.007Z,1711651077.007 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-03-28T18:37:57.007Z,1711651077.007 [MassServo](INFO): Powering down 2024-03-28T18:37:57.008Z,1711651077.008 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-28T18:37:57.008Z,1711651077.008 [RudderServo](INFO): Powering down 2024-03-28T18:37:57.008Z,1711651077.008 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-03-28T18:37:57.009Z,1711651077.009 [ThrusterHE](INFO): Powering down 2024-03-28T18:37:57.010Z,1711651077.010 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-03-28T18:37:57.010Z,1711651077.010 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-03-28T18:37:57.010Z,1711651077.010 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-03-28T18:37:57.010Z,1711651077.010 [CBIT](DEBUG): Powering off loads. 2024-03-28T18:37:57.021Z,1711651077.021 [CBIT](DEBUG): Disabling WDT. 2024-03-28T18:37:57.033Z,1711651077.033 [CBIT](DEBUG): Opening all GF detection circuits. 2024-03-28T18:37:57.035Z,1711651077.035 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.090Z,1711651077.090 [DAT ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.094Z,1711651077.094 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.096Z,1711651077.096 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.103Z,1711651077.103 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.146Z,1711651077.146 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.433Z,1711651077.433 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.439Z,1711651077.439 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.461Z,1711651077.461 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-28T18:37:57.524Z,1711651077.524 [logger ThreadHandler](INFO): Thread cancelled.