2024-10-29T15:12:05.768Z,1730214725.768 [Supervisor](DEBUG): Initializing supervisor. 2024-10-29T15:12:05.772Z,1730214725.772 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-10-29T15:12:05.773Z,1730214725.773 [SyncHandler](INFO): Protected caller Thread ID is 6582 2024-10-29T15:12:05.773Z,1730214725.773 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-10-29T15:12:05.774Z,1730214725.774 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-10-29T15:12:05.775Z,1730214725.775 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6583 2024-10-29T15:12:05.778Z,1730214725.778 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-10-29T15:12:05.796Z,1730214725.796 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-10-29T15:12:05.797Z,1730214725.797 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-10-29T15:12:05.798Z,1730214725.798 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6584 2024-10-29T15:12:05.802Z,1730214725.802 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-10-29T15:12:05.803Z,1730214725.803 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-10-29T15:12:05.803Z,1730214725.803 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6585 2024-10-29T15:12:05.805Z,1730214725.805 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-10-29T15:12:05.806Z,1730214725.806 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-10-29T15:12:05.807Z,1730214725.807 [logger ThreadHandler](INFO): Protected caller Thread ID is 6586 2024-10-29T15:12:05.810Z,1730214725.810 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-10-29T15:12:05.811Z,1730214725.811 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-10-29T15:12:05.812Z,1730214725.812 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-10-29T15:12:06.102Z,1730214726.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-10-29T15:12:06.103Z,1730214726.103 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-10-29T15:12:06.196Z,1730214726.196 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-10-29T15:12:06.759Z,1730214726.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-10-29T15:12:06.760Z,1730214726.760 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-10-29T15:12:07.203Z,1730214727.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-10-29T15:12:07.204Z,1730214727.204 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-10-29T15:12:07.313Z,1730214727.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-10-29T15:12:07.314Z,1730214727.314 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-10-29T15:12:07.747Z,1730214727.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-10-29T15:12:07.747Z,1730214727.747 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-10-29T15:12:07.979Z,1730214727.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-10-29T15:12:07.980Z,1730214727.980 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-10-29T15:12:08.541Z,1730214728.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-10-29T15:12:08.541Z,1730214728.541 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-10-29T15:12:08.690Z,1730214728.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-10-29T15:12:08.691Z,1730214728.691 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-10-29T15:12:08.782Z,1730214728.782 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-10-29T15:12:09.654Z,1730214729.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-10-29T15:12:09.654Z,1730214729.654 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-10-29T15:12:10.061Z,1730214730.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-10-29T15:12:10.062Z,1730214730.062 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-10-29T15:12:10.266Z,1730214730.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-10-29T15:12:10.266Z,1730214730.266 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-10-29T15:12:10.391Z,1730214730.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-10-29T15:12:10.391Z,1730214730.391 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-10-29T15:12:10.662Z,1730214730.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-10-29T15:12:10.663Z,1730214730.663 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-10-29T15:12:11.284Z,1730214731.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-10-29T15:12:11.286Z,1730214731.286 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-10-29T15:12:11.286Z,1730214731.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-10-29T15:12:11.390Z,1730214731.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-10-29T15:12:11.488Z,1730214731.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-10-29T15:12:11.603Z,1730214731.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-10-29T15:12:11.699Z,1730214731.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-10-29T15:12:11.815Z,1730214731.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-10-29T15:12:11.921Z,1730214731.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-10-29T15:12:12.052Z,1730214732.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-10-29T15:12:12.221Z,1730214732.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-10-29T15:12:12.386Z,1730214732.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-10-29T15:12:12.509Z,1730214732.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-10-29T15:12:12.787Z,1730214732.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-10-29T15:12:12.789Z,1730214732.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-10-29T15:12:12.789Z,1730214732.789 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-10-29T15:12:12.797Z,1730214732.797 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-10-29T15:12:12.849Z,1730214732.849 [VerticalControl](DEBUG): Construct VerticalControl. 2024-10-29T15:12:12.909Z,1730214732.909 [VerticalControl] Loaded 2024-10-29T15:12:12.909Z,1730214732.909 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-10-29T15:12:12.912Z,1730214732.912 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-10-29T15:12:12.958Z,1730214732.958 [HorizontalControl] Loaded 2024-10-29T15:12:12.959Z,1730214732.959 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-10-29T15:12:12.961Z,1730214732.961 [SpeedControl](DEBUG): Construct SpeedControl. 2024-10-29T15:12:12.964Z,1730214732.964 [SpeedControl] Loaded 2024-10-29T15:12:12.964Z,1730214732.964 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-10-29T15:12:12.967Z,1730214732.967 [LoopControl](DEBUG): Construct LoopControl. 2024-10-29T15:12:12.967Z,1730214732.967 [LoopControl] Loaded 2024-10-29T15:12:12.968Z,1730214732.968 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-10-29T15:12:12.968Z,1730214732.968 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-10-29T15:12:12.969Z,1730214732.969 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-10-29T15:12:12.979Z,1730214732.979 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-10-29T15:12:12.979Z,1730214732.979 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-10-29T15:12:13.145Z,1730214733.145 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-10-29T15:12:13.145Z,1730214733.145 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-10-29T15:12:13.273Z,1730214733.273 [BuoyancyServo] Loaded 2024-10-29T15:12:13.274Z,1730214733.274 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-10-29T15:12:13.296Z,1730214733.296 [ElevatorServo] Loaded 2024-10-29T15:12:13.296Z,1730214733.296 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-10-29T15:12:13.317Z,1730214733.317 [MassServo] Loaded 2024-10-29T15:12:13.317Z,1730214733.317 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-10-29T15:12:13.338Z,1730214733.338 [RudderServo] Loaded 2024-10-29T15:12:13.338Z,1730214733.338 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-10-29T15:12:13.355Z,1730214733.355 [ThrusterHE] Loaded 2024-10-29T15:12:13.355Z,1730214733.355 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-10-29T15:12:13.355Z,1730214733.355 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-10-29T15:12:13.356Z,1730214733.356 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-10-29T15:12:13.465Z,1730214733.465 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-10-29T15:12:13.465Z,1730214733.465 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-10-29T15:12:13.479Z,1730214733.479 [NavChart] Loaded 2024-10-29T15:12:13.479Z,1730214733.479 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-10-29T15:12:13.485Z,1730214733.485 [UniversalFixResidualReporter] Loaded 2024-10-29T15:12:13.485Z,1730214733.485 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-10-29T15:12:13.486Z,1730214733.486 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-10-29T15:12:13.486Z,1730214733.486 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-10-29T15:12:13.542Z,1730214733.542 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-10-29T15:12:13.542Z,1730214733.542 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-10-29T15:12:13.983Z,1730214733.983 [AHRS_M2] Loaded 2024-10-29T15:12:13.984Z,1730214733.984 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-10-29T15:12:14.815Z,1730214734.815 [BPC1] Loaded 2024-10-29T15:12:14.816Z,1730214734.816 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-10-29T15:12:14.891Z,1730214734.891 [DataOverHttps] Loaded 2024-10-29T15:12:14.891Z,1730214734.891 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-10-29T15:12:14.892Z,1730214734.892 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2024-10-29T15:12:14.893Z,1730214734.893 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6669 2024-10-29T15:12:14.916Z,1730214734.916 [Depth_Keller] Loaded 2024-10-29T15:12:14.916Z,1730214734.916 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-10-29T15:12:14.921Z,1730214734.921 [DropWeight] Loaded 2024-10-29T15:12:14.922Z,1730214734.922 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-10-29T15:12:14.986Z,1730214734.986 [NAL9602] Loaded 2024-10-29T15:12:14.986Z,1730214734.986 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-10-29T15:12:15.016Z,1730214735.016 [Onboard] Loaded 2024-10-29T15:12:15.016Z,1730214735.016 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-10-29T15:12:15.017Z,1730214735.017 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0 2024-10-29T15:12:15.018Z,1730214735.018 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6670 2024-10-29T15:12:15.033Z,1730214735.033 [Power24vConverter] Loaded 2024-10-29T15:12:15.033Z,1730214735.033 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-10-29T15:12:15.049Z,1730214735.049 [Radio_Surface] Loaded 2024-10-29T15:12:15.049Z,1730214735.049 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-10-29T15:12:15.050Z,1730214735.050 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0 2024-10-29T15:12:15.050Z,1730214735.050 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6671 2024-10-29T15:12:15.066Z,1730214735.066 [Sonardyne_Nano] Loaded 2024-10-29T15:12:15.066Z,1730214735.066 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-10-29T15:12:15.067Z,1730214735.067 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-10-29T15:12:15.067Z,1730214735.067 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-10-29T15:12:15.118Z,1730214735.118 [DepthRateCalculator] Loaded 2024-10-29T15:12:15.118Z,1730214735.118 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-10-29T15:12:15.123Z,1730214735.123 [PitchRateCalculator] Loaded 2024-10-29T15:12:15.123Z,1730214735.123 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-10-29T15:12:15.132Z,1730214735.132 [SpeedCalculator] Loaded 2024-10-29T15:12:15.132Z,1730214735.132 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-10-29T15:12:15.137Z,1730214735.137 [YawRateCalculator] Loaded 2024-10-29T15:12:15.137Z,1730214735.137 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-10-29T15:12:15.156Z,1730214735.156 [ElevatorOffsetCalculator] Loaded 2024-10-29T15:12:15.156Z,1730214735.156 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-10-29T15:12:15.157Z,1730214735.157 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-10-29T15:12:15.157Z,1730214735.157 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-10-29T15:12:15.292Z,1730214735.292 [CANONSampler] Loaded 2024-10-29T15:12:15.292Z,1730214735.292 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-10-29T15:12:15.355Z,1730214735.355 [CTD_Seabird] Loaded 2024-10-29T15:12:15.355Z,1730214735.355 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-10-29T15:12:15.356Z,1730214735.356 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0 2024-10-29T15:12:15.357Z,1730214735.357 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6672 2024-10-29T15:12:15.381Z,1730214735.381 [PAR_Licor] Loaded 2024-10-29T15:12:15.381Z,1730214735.381 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-10-29T15:12:15.428Z,1730214735.428 [WetLabsBB2FL] Loaded 2024-10-29T15:12:15.428Z,1730214735.428 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-10-29T15:12:15.429Z,1730214735.429 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0 2024-10-29T15:12:15.429Z,1730214735.429 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6673 2024-10-29T15:12:15.430Z,1730214735.430 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-10-29T15:12:15.431Z,1730214735.431 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-10-29T15:12:15.456Z,1730214735.456 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-10-29T15:12:15.457Z,1730214735.457 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-10-29T15:12:15.790Z,1730214735.790 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-10-29T15:12:15.791Z,1730214735.791 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-10-29T15:12:16.185Z,1730214736.185 [SBIT](DEBUG): Construct Startup Built In Test. 2024-10-29T15:12:16.194Z,1730214736.194 [SBIT] Loaded 2024-10-29T15:12:16.194Z,1730214736.194 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-10-29T15:12:16.197Z,1730214736.197 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-10-29T15:12:16.209Z,1730214736.209 [IBIT] Loaded 2024-10-29T15:12:16.210Z,1730214736.210 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-10-29T15:12:16.215Z,1730214736.215 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-10-29T15:12:16.446Z,1730214736.446 [CBIT] Loaded 2024-10-29T15:12:16.446Z,1730214736.446 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-10-29T15:12:16.446Z,1730214736.446 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-10-29T15:12:16.447Z,1730214736.447 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-10-29T15:12:16.552Z,1730214736.552 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-10-29T15:12:16.559Z,1730214736.559 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-10-29T15:12:16.562Z,1730214736.562 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-10-29T15:12:16.573Z,1730214736.573 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-10-29T15:12:16.574Z,1730214736.574 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0 2024-10-29T15:12:16.575Z,1730214736.575 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6674 2024-10-29T15:12:16.579Z,1730214736.579 [Supervisor](INFO): Main Thread ID is 6581 2024-10-29T15:12:16.579Z,1730214736.579 [Supervisor](DEBUG): Running supervisor. 2024-10-29T15:12:16.580Z,1730214736.580 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6675 2024-10-29T15:12:16.581Z,1730214736.581 [CommandExec](INFO): Initializing the command executive. 2024-10-29T15:12:16.584Z,1730214736.584 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6676 2024-10-29T15:12:16.586Z,1730214736.586 [controlThread ThreadHandler](INFO): Handler Thread ID is 6677 2024-10-29T15:12:16.587Z,1730214736.587 [controlThread](DEBUG): Initializing ControlThread 2024-10-29T15:12:16.587Z,1730214736.587 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-10-29T15:12:16.589Z,1730214736.589 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-10-29T15:12:16.590Z,1730214736.590 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-10-29T15:12:16.590Z,1730214736.590 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-10-29T15:12:16.592Z,1730214736.592 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-10-29T15:12:16.593Z,1730214736.593 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-10-29T15:12:16.599Z,1730214736.599 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-10-29T15:12:16.599Z,1730214736.599 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-10-29T15:12:16.600Z,1730214736.600 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-10-29T15:12:16.600Z,1730214736.600 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-10-29T15:12:16.600Z,1730214736.600 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-10-29T15:12:16.605Z,1730214736.605 [SBIT](INFO): Initialize SBIT Component. 2024-10-29T15:12:16.605Z,1730214736.605 [SBIT](IMPORTANT): git: 2024-10-25 2024-10-29T15:12:16.606Z,1730214736.606 [SBIT](INFO): git hash: fb21fbef08ef638e26610dd09246b1b72c2e8aa9 2024-10-29T15:12:16.606Z,1730214736.606 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-10-29T15:12:16.607Z,1730214736.607 [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-10-29T15:12:16.608Z,1730214736.608 [SBIT](INFO): Beginning SBIT in 21.000000 seconds. 2024-10-29T15:12:16.609Z,1730214736.609 [IBIT](INFO): Initialize IBIT Component. 2024-10-29T15:12:16.609Z,1730214736.609 [CBIT](DEBUG): Initialize CBIT Component. 2024-10-29T15:12:16.610Z,1730214736.610 [logger ThreadHandler](INFO): Handler Thread ID is 6678 2024-10-29T15:12:16.621Z,1730214736.621 [CBIT](DEBUG): Initialized mux pins. 2024-10-29T15:12:16.621Z,1730214736.621 [CBIT](DEBUG): Initializing the watchdog timer. 2024-10-29T15:12:16.629Z,1730214736.629 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6679 2024-10-29T15:12:16.631Z,1730214736.631 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-10-29T15:12:16.641Z,1730214736.641 [Onboard ThreadHandler](INFO): Handler Thread ID is 6680 2024-10-29T15:12:16.645Z,1730214736.645 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-10-29T15:12:16.646Z,1730214736.646 [CBIT](DEBUG): Initializing heartbeat. 2024-10-29T15:12:16.662Z,1730214736.662 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6681 2024-10-29T15:12:16.677Z,1730214736.677 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6682 2024-10-29T15:12:16.678Z,1730214736.678 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-10-29T15:12:16.683Z,1730214736.683 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6684 2024-10-29T15:12:16.686Z,1730214736.686 [WetLabsBB2FL](INFO): Powering up 2024-10-29T15:12:16.693Z,1730214736.693 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6686 2024-10-29T15:12:16.696Z,1730214736.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-10-29T15:12:16.696Z,1730214736.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-10-29T15:12:16.697Z,1730214736.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-10-29T15:12:16.697Z,1730214736.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-10-29T15:12:16.697Z,1730214736.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-10-29T15:12:16.697Z,1730214736.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-10-29T15:12:16.697Z,1730214736.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-10-29T15:12:16.697Z,1730214736.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-10-29T15:12:16.717Z,1730214736.717 [CBIT](DEBUG): Deactivating GF circuits. 2024-10-29T15:12:16.717Z,1730214736.717 [CBIT](DEBUG): Deactivating emergency mode. 2024-10-29T15:12:16.756Z,1730214736.756 [CBIT](DEBUG): Backplane powered. 2024-10-29T15:12:16.757Z,1730214736.757 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-10-29T15:12:16.758Z,1730214736.758 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-10-29T15:12:16.758Z,1730214736.758 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-10-29T15:12:16.759Z,1730214736.759 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-10-29T15:12:16.794Z,1730214736.794 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-10-29T15:12:16.799Z,1730214736.799 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-10-29T15:12:16.844Z,1730214736.844 [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-10-29T15:12:16.845Z,1730214736.845 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-10-29T15:12:16.845Z,1730214736.845 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-10-29T15:12:16.846Z,1730214736.846 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-10-29T15:12:17.065Z,1730214737.065 [Radio_Surface](INFO): Powering up 2024-10-29T15:12:17.169Z,1730214737.169 [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-10-29T15:12:17.174Z,1730214737.174 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-10-29T15:12:17.175Z,1730214737.175 [Default:A.Wait](DEBUG): Construct Wait. 2024-10-29T15:12:17.206Z,1730214737.206 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-10-29T15:12:17.247Z,1730214737.247 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-10-29T15:12:17.249Z,1730214737.249 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-10-29T15:12:17.278Z,1730214737.278 [Default:E.Execute](DEBUG): Construct Execute. 2024-10-29T15:12:17.281Z,1730214737.281 [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-10-29T15:12:17.296Z,1730214737.296 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,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,Reporter,LogSplitter, 2024-10-29T15:12:17.309Z,1730214737.309 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-10-29T15:12:17.373Z,1730214737.373 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m 2024-10-29T15:12:17.375Z,1730214737.375 [Power24vConverter](INFO): Powering up. 2024-10-29T15:12:17.375Z,1730214737.375 [Sonardyne_Nano](INFO): Initializing. 2024-10-29T15:12:17.393Z,1730214737.393 [CANONSampler](INFO): Powering down 2024-10-29T15:12:17.414Z,1730214737.414 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-10-29T15:12:17.438Z,1730214737.438 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-10-29T15:12:17.445Z,1730214737.445 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-10-29T15:12:17.446Z,1730214737.446 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-10-29T15:12:17.453Z,1730214737.453 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-10-29T15:12:17.454Z,1730214737.454 [MassServo](DEBUG): Initializing EZServoServo. 2024-10-29T15:12:17.461Z,1730214737.461 [MassServo](DEBUG): Initializing MassServo. 2024-10-29T15:12:17.462Z,1730214737.462 [RudderServo](DEBUG): Initializing EZServoServo. 2024-10-29T15:12:17.469Z,1730214737.469 [RudderServo](DEBUG): Initializing RudderServo. 2024-10-29T15:12:17.470Z,1730214737.470 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-10-29T15:12:17.481Z,1730214737.481 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-10-29T15:12:19.297Z,1730214739.297 [WetLabsBB2FL](INFO): Powering down 2024-10-29T15:12:19.327Z,1730214739.327 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-10-29T15:12:22.198Z,1730214742.198 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2024-10-29T15:12:23.190Z,1730214743.190 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-10-29T15:12:29.447Z,1730214749.447 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005365 2024-10-29T15:12:38.347Z,1730214758.347 [SBIT](IMPORTANT): Beginning Startup BIT 2024-10-29T15:12:38.359Z,1730214758.359 [CBIT](IMPORTANT): Beginning ground fault scan 2024-10-29T15:12:45.186Z,1730214765.186 [NAL9602](INFO): Powering up NAL9602 2024-10-29T15:12:49.289Z,1730214769.289 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-10-29T15:12:49.691Z,1730214769.691 [CBIT](IMPORTANT): Beginning ground fault scan 2024-10-29T15:12:56.094Z,1730214776.094 [NAL9602](INFO): NAL9602 initialized 2024-10-29T15:13:00.674Z,1730214780.674 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-10-29T15:13:01.082Z,1730214781.082 [CBIT](IMPORTANT): Beginning ground fault scan 2024-10-29T15:13:12.318Z,1730214792.318 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-10-29T15:13:12.318Z,1730214792.318 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-10-29T15:13:14.654Z,1730214794.654 [CommandExec](IMPORTANT): got command strobe off 2024-10-29T15:13:14.654Z,1730214794.654 [CommandExec](IMPORTANT): Deactivating strobe 2024-10-29T15:13:32.475Z,1730214812.475 [SBIT](IMPORTANT): SBIT PASSED 2024-10-29T15:13:32.475Z,1730214812.475 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-10-29T15:13:32.476Z,1730214812.476 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-10-29T15:13:32.493Z,1730214812.493 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-10-29T15:13:32.493Z,1730214812.493 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-10-29T15:13:32.493Z,1730214812.493 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2024-10-29T15:13:32.493Z,1730214812.493 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-10-29T15:13:32.493Z,1730214812.493 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=284.301317 cubic_centimeter; 2024-10-29T15:13:32.493Z,1730214812.493 [SBIT](IMPORTANT): VerticalControl.massDefault=0.396365 millimeter; 2024-10-29T15:13:32.878Z,1730214812.878 [MissionManager](IMPORTANT): Started mission Startup 2024-10-29T15:13:32.878Z,1730214812.878 [Startup] Running Loop=1 2024-10-29T15:13:32.878Z,1730214812.878 [Startup](DEBUG): Aggregate::initialize Startup 2024-10-29T15:13:32.878Z,1730214812.878 [Startup:A.GoToSurface] Running Loop=1 2024-10-29T15:13:32.878Z,1730214812.878 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-10-29T15:13:32.879Z,1730214812.879 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-10-29T15:13:32.879Z,1730214812.879 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-10-29T15:13:32.880Z,1730214812.880 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-10-29T15:13:32.880Z,1730214812.880 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-10-29T15:13:32.881Z,1730214812.881 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-10-29T15:13:32.881Z,1730214812.881 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-10-29T15:13:32.882Z,1730214812.882 [Startup:StartupSatComms] Running Loop=1 2024-10-29T15:13:32.883Z,1730214812.883 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-10-29T15:13:32.883Z,1730214812.883 [Startup:StartupSatComms:A] Running Loop=1 2024-10-29T15:13:33.282Z,1730214813.282 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-10-29T15:13:49.371Z,1730214829.371 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-10-29T15:13:49.375Z,1730214829.375 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 88.000000 % 2024-10-29T15:14:33.071Z,1730214873.071 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-29T15:13:32.9Z 2024-10-29T15:14:33.071Z,1730214873.071 [Startup:StartupSatComms:A] Stopped 2024-10-29T15:14:33.071Z,1730214873.071 [Startup:StartupSatComms:B] Running Loop=1 2024-10-29T15:14:33.475Z,1730214873.475 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-10-29T15:14:39.998Z,1730214879.998 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241028T151631/Courier0004.lzma 2024-10-29T15:14:40.999Z,1730214880.999 [DataOverHttps](INFO): Moved sent file to Logs/20241028T151631/Courier0004.lzma.bak 2024-10-29T15:14:40.999Z,1730214880.999 [DataOverHttps](INFO): SBD MOMSN=23347746 2024-10-29T15:14:56.557Z,1730214896.557 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241029T151205/Courier0000.lzma 2024-10-29T15:14:57.546Z,1730214897.546 [DataOverHttps](INFO): Moved sent file to Logs/20241029T151205/Courier0000.lzma.bak 2024-10-29T15:14:57.547Z,1730214897.547 [DataOverHttps](INFO): SBD MOMSN=23347748 2024-10-29T15:15:13.094Z,1730214913.094 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20241028T151631/Express0005.lzma 2024-10-29T15:15:14.094Z,1730214914.094 [DataOverHttps](INFO): Moved sent file to Logs/20241028T151631/Express0005.lzma.bak 2024-10-29T15:15:14.094Z,1730214914.094 [DataOverHttps](INFO): SBD MOMSN=23347751 2024-10-29T15:15:16.751Z,1730214916.751 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-10-29T15:15:16.751Z,1730214916.751 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-29T15:15:16.836Z,1730214916.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-29T15:15:17.169Z,1730214917.169 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-29T15:15:17.169Z,1730214917.169 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-10-29T15:15:29.750Z,1730214929.750 [DataOverHttps](INFO): Sending 866 bytes from file Logs/20241029T151205/Express0001.lzma 2024-10-29T15:15:30.750Z,1730214930.750 [DataOverHttps](INFO): Moved sent file to Logs/20241029T151205/Express0001.lzma.bak 2024-10-29T15:15:30.751Z,1730214930.751 [DataOverHttps](INFO): SBD MOMSN=23347756 2024-10-29T15:15:32.487Z,1730214932.487 [Startup:StartupSatComms:B] Stopped 2024-10-29T15:15:32.488Z,1730214932.488 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-10-29T15:15:32.488Z,1730214932.488 [Startup:StartupSatComms] Stopped 2024-10-29T15:15:32.488Z,1730214932.488 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-10-29T15:15:32.489Z,1730214932.489 [Startup](INFO): Completed Startup 2024-10-29T15:15:32.489Z,1730214932.489 [MissionManager](INFO): Startup is completed. 2024-10-29T15:15:32.489Z,1730214932.489 [MissionManager](INFO): Uninitializing Mission Startup 2024-10-29T15:15:32.489Z,1730214932.489 [Startup] Stopped 2024-10-29T15:15:32.489Z,1730214932.489 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-10-29T15:15:32.489Z,1730214932.489 [Startup:A.GoToSurface] Stopped 2024-10-29T15:15:32.490Z,1730214932.490 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-10-29T15:15:32.882Z,1730214932.882 [MissionManager](IMPORTANT): Started mission Default 2024-10-29T15:15:32.883Z,1730214932.883 [Default] Running Loop=1 2024-10-29T15:15:32.883Z,1730214932.883 [Default](DEBUG): Aggregate::initialize Default 2024-10-29T15:15:32.883Z,1730214932.883 [Default:B.GoToSurface] Running Loop=1 2024-10-29T15:15:32.883Z,1730214932.883 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-10-29T15:15:32.883Z,1730214932.883 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-10-29T15:15:32.883Z,1730214932.883 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-10-29T15:15:32.884Z,1730214932.884 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-10-29T15:15:32.884Z,1730214932.884 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-10-29T15:15:32.885Z,1730214932.885 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-10-29T15:15:32.885Z,1730214932.885 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-10-29T15:15:32.885Z,1730214932.885 [Default:A.Wait] Running Loop=1 2024-10-29T15:15:32.885Z,1730214932.885 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-10-29T15:15:46.188Z,1730214946.188 [Default:A.Wait](INFO): Done Waiting. 2024-10-29T15:15:46.188Z,1730214946.188 [Default:A.Wait] Stopped 2024-10-29T15:15:46.188Z,1730214946.188 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-10-29T15:15:46.601Z,1730214946.601 [Default:CheckIn] Running Loop=1 2024-10-29T15:15:46.601Z,1730214946.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-29T15:15:46.601Z,1730214946.601 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-29T15:15:47.014Z,1730214947.014 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-10-29T15:17:16.331Z,1730215036.331 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:17:29.662Z,1730215049.662 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:17:42.994Z,1730215062.994 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:17:56.326Z,1730215076.326 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:17:59.971Z,1730215079.971 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-10-29T15:18:10.062Z,1730215090.062 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:18:17.765Z,1730215097.765 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-10-29T15:18:17.765Z,1730215097.765 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-29T15:18:17.775Z,1730215097.775 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-29T15:18:18.167Z,1730215098.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-29T15:18:18.167Z,1730215098.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-10-29T15:18:23.802Z,1730215103.802 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:18:37.130Z,1730215117.130 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:18:50.057Z,1730215130.057 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:19:03.391Z,1730215143.391 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:19:16.722Z,1730215156.722 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:19:30.054Z,1730215170.054 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-29T15:20:46.851Z,1730215246.851 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-29T15:15:46.6Z 2024-10-29T15:20:46.851Z,1730215246.851 [Default:CheckIn:Read_GPS] Stopped 2024-10-29T15:20:46.851Z,1730215246.851 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-29T15:20:47.258Z,1730215247.258 [Default:CheckIn:Read_Iridium](DEBUG)