2024-08-13T18:14:34.794Z,1723572874.794 [Supervisor](DEBUG): Initializing supervisor. 2024-08-13T18:14:34.798Z,1723572874.798 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-08-13T18:14:34.798Z,1723572874.798 [SyncHandler](INFO): Protected caller Thread ID is 1465 2024-08-13T18:14:34.799Z,1723572874.799 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-08-13T18:14:34.800Z,1723572874.800 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-08-13T18:14:34.800Z,1723572874.800 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1466 2024-08-13T18:14:34.804Z,1723572874.804 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-08-13T18:14:34.821Z,1723572874.821 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-08-13T18:14:34.822Z,1723572874.822 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-08-13T18:14:34.823Z,1723572874.823 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1467 2024-08-13T18:14:34.827Z,1723572874.827 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-08-13T18:14:34.828Z,1723572874.828 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-08-13T18:14:34.828Z,1723572874.828 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1468 2024-08-13T18:14:34.830Z,1723572874.830 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-08-13T18:14:34.831Z,1723572874.831 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-08-13T18:14:34.831Z,1723572874.831 [logger ThreadHandler](INFO): Protected caller Thread ID is 1469 2024-08-13T18:14:34.835Z,1723572874.835 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-08-13T18:14:34.835Z,1723572874.835 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-08-13T18:14:34.837Z,1723572874.837 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-08-13T18:14:35.190Z,1723572875.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-08-13T18:14:35.191Z,1723572875.191 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-08-13T18:14:35.978Z,1723572875.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-08-13T18:14:35.979Z,1723572875.979 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-08-13T18:14:36.101Z,1723572876.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-08-13T18:14:36.102Z,1723572876.102 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-08-13T18:14:36.679Z,1723572876.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-08-13T18:14:36.680Z,1723572876.680 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-08-13T18:14:36.791Z,1723572876.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-08-13T18:14:36.791Z,1723572876.791 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-08-13T18:14:36.885Z,1723572876.885 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-08-13T18:14:37.322Z,1723572877.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-08-13T18:14:37.323Z,1723572877.323 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-08-13T18:14:37.565Z,1723572877.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-08-13T18:14:37.565Z,1723572877.565 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-08-13T18:14:37.718Z,1723572877.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-08-13T18:14:37.719Z,1723572877.719 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-08-13T18:14:37.982Z,1723572877.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-08-13T18:14:37.982Z,1723572877.982 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-08-13T18:14:38.556Z,1723572878.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-08-13T18:14:38.556Z,1723572878.556 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-08-13T18:14:38.964Z,1723572878.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-08-13T18:14:38.964Z,1723572878.964 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-08-13T18:14:39.114Z,1723572879.114 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-08-13T18:14:39.708Z,1723572879.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-08-13T18:14:39.709Z,1723572879.709 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-08-13T18:14:40.114Z,1723572880.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-08-13T18:14:40.115Z,1723572880.115 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-08-13T18:14:40.435Z,1723572880.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-08-13T18:14:40.437Z,1723572880.437 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2024-08-13T18:14:40.438Z,1723572880.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2024-08-13T18:14:40.560Z,1723572880.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2024-08-13T18:14:40.728Z,1723572880.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2024-08-13T18:14:40.827Z,1723572880.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2024-08-13T18:14:40.924Z,1723572880.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2024-08-13T18:14:41.048Z,1723572881.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2024-08-13T18:14:41.146Z,1723572881.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2024-08-13T18:14:41.266Z,1723572881.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2024-08-13T18:14:41.543Z,1723572881.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-08-13T18:14:41.543Z,1723572881.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2024-08-13T18:14:41.693Z,1723572881.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2024-08-13T18:14:41.800Z,1723572881.800 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2024-08-13T18:14:41.907Z,1723572881.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2024-08-13T18:14:42.026Z,1723572882.026 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-08-13T18:14:42.039Z,1723572882.039 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-08-13T18:14:42.145Z,1723572882.145 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-08-13T18:14:42.146Z,1723572882.146 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-08-13T18:14:42.156Z,1723572882.156 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-08-13T18:14:42.156Z,1723572882.156 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-08-13T18:14:42.204Z,1723572882.204 [DepthRateCalculator] Loaded 2024-08-13T18:14:42.205Z,1723572882.205 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-08-13T18:14:42.210Z,1723572882.210 [PitchRateCalculator] Loaded 2024-08-13T18:14:42.210Z,1723572882.210 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-08-13T18:14:42.221Z,1723572882.221 [SpeedCalculator] Loaded 2024-08-13T18:14:42.221Z,1723572882.221 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-08-13T18:14:42.225Z,1723572882.225 [YawRateCalculator] Loaded 2024-08-13T18:14:42.226Z,1723572882.226 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-08-13T18:14:42.247Z,1723572882.247 [ElevatorOffsetCalculator] Loaded 2024-08-13T18:14:42.247Z,1723572882.247 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-08-13T18:14:42.248Z,1723572882.248 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-08-13T18:14:42.248Z,1723572882.248 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-08-13T18:14:42.298Z,1723572882.298 [VerticalControl](DEBUG): Construct VerticalControl. 2024-08-13T18:14:42.352Z,1723572882.352 [VerticalControl] Loaded 2024-08-13T18:14:42.352Z,1723572882.352 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-08-13T18:14:42.355Z,1723572882.355 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-08-13T18:14:42.400Z,1723572882.400 [HorizontalControl] Loaded 2024-08-13T18:14:42.401Z,1723572882.401 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-08-13T18:14:42.403Z,1723572882.403 [SpeedControl](DEBUG): Construct SpeedControl. 2024-08-13T18:14:42.406Z,1723572882.406 [SpeedControl] Loaded 2024-08-13T18:14:42.406Z,1723572882.406 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-08-13T18:14:42.409Z,1723572882.409 [LoopControl](DEBUG): Construct LoopControl. 2024-08-13T18:14:42.409Z,1723572882.409 [LoopControl] Loaded 2024-08-13T18:14:42.409Z,1723572882.409 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-08-13T18:14:42.410Z,1723572882.410 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-08-13T18:14:42.410Z,1723572882.410 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-08-13T18:14:42.517Z,1723572882.517 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-08-13T18:14:42.518Z,1723572882.518 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-08-13T18:14:42.531Z,1723572882.531 [NavChart] Loaded 2024-08-13T18:14:42.532Z,1723572882.532 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-08-13T18:14:42.537Z,1723572882.537 [UniversalFixResidualReporter] Loaded 2024-08-13T18:14:42.537Z,1723572882.537 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-08-13T18:14:42.538Z,1723572882.538 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-08-13T18:14:42.538Z,1723572882.538 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-08-13T18:14:42.703Z,1723572882.703 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-08-13T18:14:42.704Z,1723572882.704 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-08-13T18:14:43.169Z,1723572883.169 [AHRS_M2] Loaded 2024-08-13T18:14:43.169Z,1723572883.169 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-08-13T18:14:43.207Z,1723572883.207 [BackseatComponent] Loaded 2024-08-13T18:14:43.207Z,1723572883.207 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-08-13T18:14:43.208Z,1723572883.208 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0 2024-08-13T18:14:43.209Z,1723572883.209 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1554 2024-08-13T18:14:43.212Z,1723572883.212 [LcmUniversalReporter] Loaded 2024-08-13T18:14:43.212Z,1723572883.212 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-08-13T18:14:44.031Z,1723572884.031 [BPC1] Loaded 2024-08-13T18:14:44.032Z,1723572884.032 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-08-13T18:14:44.128Z,1723572884.128 [DATMMP] Loaded 2024-08-13T18:14:44.128Z,1723572884.128 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-08-13T18:14:44.151Z,1723572884.151 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409694E0 2024-08-13T18:14:44.151Z,1723572884.151 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1555 2024-08-13T18:14:44.396Z,1723572884.396 [DataOverHttps] Loaded 2024-08-13T18:14:44.396Z,1723572884.396 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-08-13T18:14:44.397Z,1723572884.397 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409994E0 2024-08-13T18:14:44.398Z,1723572884.398 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1556 2024-08-13T18:14:44.460Z,1723572884.460 [Depth_Keller] Loaded 2024-08-13T18:14:44.460Z,1723572884.460 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-08-13T18:14:44.579Z,1723572884.579 [DVL_micro] Loaded 2024-08-13T18:14:44.579Z,1723572884.579 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-08-13T18:14:44.736Z,1723572884.736 [NAL9602] Loaded 2024-08-13T18:14:44.737Z,1723572884.737 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-08-13T18:14:44.766Z,1723572884.766 [Onboard] Loaded 2024-08-13T18:14:44.766Z,1723572884.766 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-08-13T18:14:44.767Z,1723572884.767 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0 2024-08-13T18:14:44.768Z,1723572884.768 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1557 2024-08-13T18:14:44.781Z,1723572884.781 [Power24vConverter] Loaded 2024-08-13T18:14:44.782Z,1723572884.782 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-08-13T18:14:44.799Z,1723572884.799 [Radio_Surface] Loaded 2024-08-13T18:14:44.799Z,1723572884.799 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-08-13T18:14:44.800Z,1723572884.800 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0 2024-08-13T18:14:44.802Z,1723572884.802 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1558 2024-08-13T18:14:44.818Z,1723572884.818 [Sonardyne_Nano] Loaded 2024-08-13T18:14:44.819Z,1723572884.819 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-08-13T18:14:44.819Z,1723572884.819 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-08-13T18:14:44.820Z,1723572884.820 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-08-13T18:14:44.946Z,1723572884.946 [BuoyancyServo] Loaded 2024-08-13T18:14:44.946Z,1723572884.946 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-08-13T18:14:44.967Z,1723572884.967 [MassServo] Loaded 2024-08-13T18:14:44.968Z,1723572884.968 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-08-13T18:14:44.988Z,1723572884.988 [RudderServo] Loaded 2024-08-13T18:14:44.989Z,1723572884.989 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-08-13T18:14:45.004Z,1723572885.004 [ThrusterHE] Loaded 2024-08-13T18:14:45.005Z,1723572885.005 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-08-13T18:14:45.005Z,1723572885.005 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-08-13T18:14:45.005Z,1723572885.005 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-08-13T18:14:45.072Z,1723572885.072 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-08-13T18:14:45.073Z,1723572885.073 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-08-13T18:14:45.098Z,1723572885.098 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-08-13T18:14:45.099Z,1723572885.099 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-08-13T18:14:45.420Z,1723572885.420 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-08-13T18:14:45.421Z,1723572885.421 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-08-13T18:14:45.591Z,1723572885.591 [CTD_Seabird] Loaded 2024-08-13T18:14:45.592Z,1723572885.592 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-08-13T18:14:45.593Z,1723572885.593 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB34E0 2024-08-13T18:14:45.593Z,1723572885.593 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1559 2024-08-13T18:14:45.625Z,1723572885.625 [ESPComponent] Loaded 2024-08-13T18:14:45.626Z,1723572885.626 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-08-13T18:14:45.648Z,1723572885.648 [PAR_Licor] Loaded 2024-08-13T18:14:45.648Z,1723572885.648 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-08-13T18:14:45.649Z,1723572885.649 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-08-13T18:14:45.649Z,1723572885.649 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-08-13T18:14:45.785Z,1723572885.785 [SBIT](DEBUG): Construct Startup Built In Test. 2024-08-13T18:14:45.793Z,1723572885.793 [SBIT] Loaded 2024-08-13T18:14:45.794Z,1723572885.794 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-08-13T18:14:45.797Z,1723572885.797 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-08-13T18:14:45.809Z,1723572885.809 [IBIT] Loaded 2024-08-13T18:14:45.809Z,1723572885.809 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-08-13T18:14:45.815Z,1723572885.815 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-08-13T18:14:45.920Z,1723572885.920 [CBIT] Loaded 2024-08-13T18:14:45.920Z,1723572885.920 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-08-13T18:14:45.921Z,1723572885.921 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-08-13T18:14:45.927Z,1723572885.927 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-08-13T18:14:45.929Z,1723572885.929 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-08-13T18:14:45.941Z,1723572885.941 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-08-13T18:14:45.942Z,1723572885.942 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0 2024-08-13T18:14:45.942Z,1723572885.942 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1560 2024-08-13T18:14:45.947Z,1723572885.947 [Supervisor](INFO): Main Thread ID is 7713 2024-08-13T18:14:45.947Z,1723572885.947 [Supervisor](DEBUG): Running supervisor. 2024-08-13T18:14:45.947Z,1723572885.947 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1561 2024-08-13T18:14:45.948Z,1723572885.948 [CommandExec](INFO): Initializing the command executive. 2024-08-13T18:14:45.949Z,1723572885.949 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1562 2024-08-13T18:14:45.951Z,1723572885.951 [controlThread ThreadHandler](INFO): Handler Thread ID is 1563 2024-08-13T18:14:45.952Z,1723572885.952 [controlThread](DEBUG): Initializing ControlThread 2024-08-13T18:14:45.953Z,1723572885.953 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-08-13T18:14:45.953Z,1723572885.953 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-08-13T18:14:45.953Z,1723572885.953 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-08-13T18:14:45.954Z,1723572885.954 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-08-13T18:14:45.954Z,1723572885.954 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-08-13T18:14:45.955Z,1723572885.955 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-08-13T18:14:45.956Z,1723572885.956 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-08-13T18:14:45.957Z,1723572885.957 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-08-13T18:14:45.957Z,1723572885.957 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-08-13T18:14:45.958Z,1723572885.958 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-08-13T18:14:45.959Z,1723572885.959 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-08-13T18:14:45.967Z,1723572885.967 [SBIT](INFO): Initialize SBIT Component. 2024-08-13T18:14:45.967Z,1723572885.967 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60 2024-08-13T18:14:45.967Z,1723572885.967 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95 2024-08-13T18:14:45.968Z,1723572885.968 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-08-13T18:14:45.969Z,1723572885.969 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-08-13T18:14:45.970Z,1723572885.970 [SBIT](INFO): Beginning SBIT in 68.000000 seconds. 2024-08-13T18:14:45.971Z,1723572885.971 [IBIT](INFO): Initialize IBIT Component. 2024-08-13T18:14:45.971Z,1723572885.971 [CBIT](DEBUG): Initialize CBIT Component. 2024-08-13T18:14:45.972Z,1723572885.972 [logger ThreadHandler](INFO): Handler Thread ID is 1564 2024-08-13T18:14:45.982Z,1723572885.982 [CBIT](DEBUG): Initialized mux pins. 2024-08-13T18:14:45.983Z,1723572885.983 [CBIT](DEBUG): Initializing the watchdog timer. 2024-08-13T18:14:45.991Z,1723572885.991 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1565 2024-08-13T18:14:46.003Z,1723572886.003 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1566 2024-08-13T18:14:46.003Z,1723572886.003 [DATMMP](INFO): Start 2024-08-13T18:14:46.004Z,1723572886.004 [DATMMP](INFO): Powering up 2024-08-13T18:14:46.004Z,1723572886.004 [DATMMP](DEBUG): Initializing DATMMP. 2024-08-13T18:14:46.006Z,1723572886.006 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-08-13T18:14:46.007Z,1723572886.007 [CBIT](DEBUG): Initializing heartbeat. 2024-08-13T18:14:46.009Z,1723572886.009 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1568 2024-08-13T18:14:46.010Z,1723572886.010 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-08-13T18:14:46.019Z,1723572886.019 [Onboard ThreadHandler](INFO): Handler Thread ID is 1569 2024-08-13T18:14:46.037Z,1723572886.037 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1570 2024-08-13T18:14:46.059Z,1723572886.059 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1571 2024-08-13T18:14:46.059Z,1723572886.059 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-08-13T18:14:46.064Z,1723572886.064 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1573 2024-08-13T18:14:46.066Z,1723572886.066 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000 2024-08-13T18:14:46.066Z,1723572886.066 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000 2024-08-13T18:14:46.066Z,1723572886.066 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000 2024-08-13T18:14:46.067Z,1723572886.067 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000 2024-08-13T18:14:46.067Z,1723572886.067 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000 2024-08-13T18:14:46.067Z,1723572886.067 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000 2024-08-13T18:14:46.078Z,1723572886.078 [CBIT](DEBUG): Deactivating GF circuits. 2024-08-13T18:14:46.079Z,1723572886.079 [CBIT](DEBUG): Deactivating emergency mode. 2024-08-13T18:14:46.118Z,1723572886.118 [CBIT](DEBUG): Backplane powered. 2024-08-13T18:14:46.119Z,1723572886.119 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-08-13T18:14:46.120Z,1723572886.120 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-08-13T18:14:46.120Z,1723572886.120 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-08-13T18:14:46.121Z,1723572886.121 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-08-13T18:14:46.157Z,1723572886.157 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-08-13T18:14:46.162Z,1723572886.162 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-13T18:14:46.209Z,1723572886.209 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-08-13T18:14:46.210Z,1723572886.210 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-08-13T18:14:46.210Z,1723572886.210 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-08-13T18:14:46.211Z,1723572886.211 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-08-13T18:14:46.255Z,1723572886.255 [DATMMP](INFO): Starting 2024-08-13T18:14:46.443Z,1723572886.443 [Radio_Surface](INFO): Powering up 2024-08-13T18:14:46.506Z,1723572886.506 [DATMMP](INFO): Starting 2024-08-13T18:14:46.508Z,1723572886.508 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-08-13T18:14:46.514Z,1723572886.514 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-08-13T18:14:46.531Z,1723572886.531 [Default:A.Wait](DEBUG): Construct Wait. 2024-08-13T18:14:46.533Z,1723572886.533 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-13T18:14:46.569Z,1723572886.569 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-08-13T18:14:46.580Z,1723572886.580 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-08-13T18:14:46.586Z,1723572886.586 [Default:E.Execute](DEBUG): Construct Execute. 2024-08-13T18:14:46.605Z,1723572886.605 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-08-13T18:14:46.610Z,1723572886.610 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-13T18:14:46.632Z,1723572886.632 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-08-13T18:14:46.712Z,1723572886.712 [Power24vConverter](INFO): Powering up. 2024-08-13T18:14:46.713Z,1723572886.713 [Sonardyne_Nano](INFO): Initializing. 2024-08-13T18:14:46.758Z,1723572886.758 [DATMMP](INFO): Starting 2024-08-13T18:14:46.788Z,1723572886.788 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-08-13T18:14:46.795Z,1723572886.795 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-08-13T18:14:46.796Z,1723572886.796 [MassServo](DEBUG): Initializing EZServoServo. 2024-08-13T18:14:46.803Z,1723572886.803 [MassServo](DEBUG): Initializing MassServo. 2024-08-13T18:14:46.804Z,1723572886.804 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-13T18:14:46.811Z,1723572886.811 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-13T18:14:46.812Z,1723572886.812 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-08-13T18:14:46.819Z,1723572886.819 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-08-13T18:14:47.035Z,1723572887.035 [DATMMP](INFO): Starting 2024-08-13T18:14:47.287Z,1723572887.287 [DATMMP](INFO): Starting 2024-08-13T18:14:47.539Z,1723572887.539 [DATMMP](INFO): Starting 2024-08-13T18:14:47.791Z,1723572887.791 [DATMMP](INFO): Starting 2024-08-13T18:14:48.043Z,1723572888.043 [DATMMP](INFO): Starting 2024-08-13T18:14:48.295Z,1723572888.295 [DATMMP](INFO): Starting 2024-08-13T18:14:48.546Z,1723572888.546 [DATMMP](INFO): Starting 2024-08-13T18:14:48.637Z,1723572888.637 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-08-13T18:14:48.799Z,1723572888.799 [DATMMP](INFO): Starting 2024-08-13T18:14:49.050Z,1723572889.050 [DATMMP](INFO): Starting 2024-08-13T18:14:49.303Z,1723572889.303 [DATMMP](INFO): Starting 2024-08-13T18:14:49.556Z,1723572889.556 [DATMMP](INFO): Starting 2024-08-13T18:14:49.807Z,1723572889.807 [DATMMP](INFO): Starting 2024-08-13T18:14:49.927Z,1723572889.927 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2024-08-13T18:14:50.059Z,1723572890.059 [DATMMP](INFO): Starting 2024-08-13T18:14:50.311Z,1723572890.311 [DATMMP](INFO): Starting 2024-08-13T18:14:50.563Z,1723572890.563 [DATMMP](INFO): Starting 2024-08-13T18:14:50.815Z,1723572890.815 [DATMMP](INFO): Starting 2024-08-13T18:14:51.068Z,1723572891.068 [DATMMP](INFO): Starting 2024-08-13T18:14:51.319Z,1723572891.319 [DATMMP](INFO): Starting 2024-08-13T18:14:51.571Z,1723572891.571 [DATMMP](INFO): Starting 2024-08-13T18:14:51.823Z,1723572891.823 [DATMMP](INFO): Starting 2024-08-13T18:14:52.075Z,1723572892.075 [DATMMP](INFO): Starting 2024-08-13T18:14:52.327Z,1723572892.327 [DATMMP](INFO): Starting 2024-08-13T18:14:52.372Z,1723572892.372 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-08-13T18:14:52.579Z,1723572892.579 [DATMMP](INFO): Starting 2024-08-13T18:14:52.831Z,1723572892.831 [DATMMP](INFO): Starting 2024-08-13T18:14:53.083Z,1723572893.083 [DATMMP](INFO): Starting 2024-08-13T18:14:53.335Z,1723572893.335 [DATMMP](INFO): Starting 2024-08-13T18:14:53.587Z,1723572893.587 [DATMMP](INFO): Starting 2024-08-13T18:14:53.839Z,1723572893.839 [DATMMP](INFO): Starting 2024-08-13T18:14:54.091Z,1723572894.091 [DATMMP](INFO): Starting 2024-08-13T18:14:54.343Z,1723572894.343 [DATMMP](INFO): Starting 2024-08-13T18:14:54.595Z,1723572894.595 [DATMMP](INFO): Starting 2024-08-13T18:14:54.847Z,1723572894.847 [DATMMP](INFO): Starting 2024-08-13T18:14:55.099Z,1723572895.099 [DATMMP](INFO): Starting 2024-08-13T18:14:55.351Z,1723572895.351 [DATMMP](INFO): Starting 2024-08-13T18:14:55.603Z,1723572895.603 [DATMMP](INFO): Starting 2024-08-13T18:14:55.855Z,1723572895.855 [DATMMP](INFO): Starting 2024-08-13T18:14:56.107Z,1723572896.107 [DATMMP](INFO): Starting 2024-08-13T18:14:56.359Z,1723572896.359 [DATMMP](INFO): Starting 2024-08-13T18:14:56.611Z,1723572896.611 [DATMMP](INFO): Starting 2024-08-13T18:14:56.863Z,1723572896.863 [DATMMP](INFO): Starting 2024-08-13T18:14:57.115Z,1723572897.115 [DATMMP](INFO): Starting 2024-08-13T18:14:57.367Z,1723572897.367 [DATMMP](INFO): Starting 2024-08-13T18:14:57.619Z,1723572897.619 [DATMMP](INFO): Starting 2024-08-13T18:14:57.871Z,1723572897.871 [DATMMP](INFO): Starting 2024-08-13T18:14:57.873Z,1723572897.873 [DATMMP](INFO): DAT read: 2024-08-13T18:14:58.123Z,1723572898.123 [DATMMP](INFO): Starting 2024-08-13T18:14:58.123Z,1723572898.123 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-08-13T18:14:58.375Z,1723572898.375 [DATMMP](INFO): Starting 2024-08-13T18:14:58.627Z,1723572898.627 [DATMMP](INFO): Starting 2024-08-13T18:14:58.879Z,1723572898.879 [DATMMP](INFO): Starting 2024-08-13T18:14:59.131Z,1723572899.131 [DATMMP](INFO): Starting 2024-08-13T18:14:59.383Z,1723572899.383 [DATMMP](INFO): Starting 2024-08-13T18:14:59.635Z,1723572899.635 [DATMMP](INFO): Starting 2024-08-13T18:14:59.636Z,1723572899.636 [DATMMP](INFO): DAT read: MF Frequency Band 2024-08-13T18:14:59.637Z,1723572899.637 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-08-13T18:14:59.638Z,1723572899.638 [DATMMP](INFO): DAT read: Jul 13 2000 18:14:54 2024-08-13T18:14:59.887Z,1723572899.887 [DATMMP](INFO): Starting 2024-08-13T18:15:00.139Z,1723572900.139 [DATMMP](INFO): Starting 2024-08-13T18:15:00.391Z,1723572900.391 [DATMMP](INFO): Starting 2024-08-13T18:15:00.643Z,1723572900.643 [DATMMP](INFO): Starting 2024-08-13T18:15:00.895Z,1723572900.895 [DATMMP](INFO): Starting 2024-08-13T18:15:00.896Z,1723572900.896 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-08-13T18:15:00.897Z,1723572900.897 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-08-13T18:15:00.897Z,1723572900.897 [DATMMP](INFO): commRate: 800 2024-08-13T18:15:01.147Z,1723572901.147 [DATMMP](INFO): Starting 2024-08-13T18:15:01.399Z,1723572901.399 [DATMMP](INFO): Starting 2024-08-13T18:15:01.651Z,1723572901.651 [DATMMP](INFO): Starting 2024-08-13T18:15:01.903Z,1723572901.903 [DATMMP](INFO): Starting 2024-08-13T18:15:02.155Z,1723572902.155 [DATMMP](INFO): Starting 2024-08-13T18:15:02.407Z,1723572902.407 [DATMMP](INFO): Starting 2024-08-13T18:15:02.659Z,1723572902.659 [DATMMP](INFO): Starting 2024-08-13T18:15:02.911Z,1723572902.911 [DATMMP](INFO): Starting 2024-08-13T18:15:02.962Z,1723572902.962 [DATMMP](INFO): entering command mode 2024-08-13T18:15:03.163Z,1723572903.163 [DATMMP](INFO): Starting 2024-08-13T18:15:03.163Z,1723572903.163 [DATMMP](INFO): DAT read: 2024-08-13T18:15:03.163Z,1723572903.163 [DATMMP](INFO): DAT read: user:1> 2024-08-13T18:15:03.164Z,1723572903.164 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-08-13T18:15:03.164Z,1723572903.164 [DATMMP](INFO): entering MMP mode 2024-08-13T18:15:03.415Z,1723572903.415 [DATMMP](INFO): Starting 2024-08-13T18:15:03.415Z,1723572903.415 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-13T18:15:03.417Z,1723572903.417 [DATMMP](INFO): status rx: x1 2024-08-13T18:15:03.417Z,1723572903.417 [DATMMP](INFO): MMP status: 1: started 2024-08-13T18:15:03.417Z,1723572903.417 [DATMMP](INFO): Received message type: status 2024-08-13T18:15:03.418Z,1723572903.418 [DATMMP](INFO): Handled 2024-08-13T18:15:03.667Z,1723572903.667 [DATMMP](INFO): Starting 2024-08-13T18:15:03.668Z,1723572903.668 [DATMMP](INFO): Sent Tx Power Config 8 2024-08-13T18:15:03.668Z,1723572903.668 [DATMMP](INFO): Setting local address: 11 2024-08-13T18:15:03.670Z,1723572903.670 [DATMMP](INFO): Setting time to: 18:15:3 And date to:8/13/2024 2024-08-13T18:15:03.920Z,1723572903.920 [DATMMP](INFO): Starting 2024-08-13T18:15:03.921Z,1723572903.921 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-13T18:15:03.922Z,1723572903.922 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-08-13T18:15:03.922Z,1723572903.922 [DATMMP](INFO): Received message type: mdmpower 2024-08-13T18:15:03.922Z,1723572903.922 [DATMMP](INFO): Handled 2024-08-13T18:15:04.171Z,1723572904.171 [DATMMP](INFO): Starting 2024-08-13T18:15:04.171Z,1723572904.171 [DATMMP](INFO): GSXN notify for xid: 10 2024-08-13T18:15:04.172Z,1723572904.172 [DATMMP](INFO): sreg_txpower rx: x8 2024-08-13T18:15:04.172Z,1723572904.172 [DATMMP](INFO): Received message type: sreg_txpower 2024-08-13T18:15:04.172Z,1723572904.172 [DATMMP](INFO): Match for : sreg_txpower 2024-08-13T18:15:04.172Z,1723572904.172 [DATMMP](INFO): ACK 2024-08-13T18:15:04.424Z,1723572904.424 [DATMMP](INFO): Starting 2024-08-13T18:15:04.426Z,1723572904.426 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-13T18:15:04.430Z,1723572904.430 [DATMMP](INFO): mdmlocal rx: xB x0 x0 x0 2024-08-13T18:15:04.438Z,1723572904.438 [DATMMP](INFO): Received message type: mdmlocal 2024-08-13T18:15:04.439Z,1723572904.439 [DATMMP](INFO): Handled 2024-08-13T18:15:04.675Z,1723572904.675 [DATMMP](INFO): Starting 2024-08-13T18:15:04.675Z,1723572904.675 [DATMMP](INFO): GSXN notify for xid: 11 2024-08-13T18:15:04.676Z,1723572904.676 [DATMMP](INFO): sreg_locaddr rx: xB 2024-08-13T18:15:04.676Z,1723572904.676 [DATMMP](INFO): Received message type: sreg_locaddr 2024-08-13T18:15:04.676Z,1723572904.676 [DATMMP](INFO): Match for : sreg_locaddr 2024-08-13T18:15:04.676Z,1723572904.676 [DATMMP](INFO): ACK 2024-08-13T18:15:04.927Z,1723572904.927 [DATMMP](INFO): Starting 2024-08-13T18:15:04.927Z,1723572904.927 [DATMMP](INFO): GSXN notify for xid: 12 2024-08-13T18:15:04.928Z,1723572904.928 [DATMMP](INFO): timedate rx: x0 x12 xF x3 x7 xD x7C x0 2024-08-13T18:15:04.928Z,1723572904.928 [DATMMP](INFO): Received message type: timedate 2024-08-13T18:15:04.928Z,1723572904.928 [DATMMP](INFO): Match for : timedate 2024-08-13T18:15:04.929Z,1723572904.929 [DATMMP](INFO): ACK 2024-08-13T18:15:05.179Z,1723572905.179 [DATMMP](INFO): Starting 2024-08-13T18:15:05.179Z,1723572905.179 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-13T18:15:05.180Z,1723572905.180 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-08-13T18:15:05.180Z,1723572905.180 [DATMMP](INFO): Received message type: sys_verb 2024-08-13T18:15:05.180Z,1723572905.180 [DATMMP](INFO): Handled 2024-08-13T18:15:05.431Z,1723572905.431 [DATMMP](INFO): Starting 2024-08-13T18:15:05.431Z,1723572905.431 [DATMMP](INFO): GSXN notify for xid: 13 2024-08-13T18:15:05.432Z,1723572905.432 [DATMMP](INFO): sreg_verbosity rx: x0 2024-08-13T18:15:05.432Z,1723572905.432 [DATMMP](INFO): Received message type: sreg_verbosity 2024-08-13T18:15:05.432Z,1723572905.432 [DATMMP](INFO): Match for : sreg_verbosity 2024-08-13T18:15:05.432Z,1723572905.432 [DATMMP](INFO): ACK 2024-08-13T18:15:05.683Z,1723572905.683 [DATMMP](INFO): Starting 2024-08-13T18:15:05.683Z,1723572905.683 [DATMMP](INFO): GSXN notify for xid: 14 2024-08-13T18:15:05.684Z,1723572905.684 [DATMMP](INFO): version rx: x2 x5 2024-08-13T18:15:05.684Z,1723572905.684 [DATMMP](INFO): DatMMPVersion: 2.5 2024-08-13T18:15:05.684Z,1723572905.684 [DATMMP](INFO): Received message type: version 2024-08-13T18:15:05.684Z,1723572905.684 [DATMMP](INFO): Match for : version 2024-08-13T18:15:05.684Z,1723572905.684 [DATMMP](INFO): ACK 2024-08-13T18:15:05.684Z,1723572905.684 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-08-13T18:15:14.900Z,1723572914.900 [NAL9602](INFO): Powering up NAL9602 2024-08-13T18:15:19.799Z,1723572919.799 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-13T18:15:19.800Z,1723572919.800 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-08-13T18:15:19.800Z,1723572919.800 [DATMMP](INFO): Received message type: timesync 2024-08-13T18:15:19.800Z,1723572919.800 [DATMMP](INFO): Handled 2024-08-13T18:15:25.822Z,1723572925.822 [NAL9602](INFO): NAL9602 initialized 2024-08-13T18:15:29.909Z,1723572929.909 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-13T18:15:29.910Z,1723572929.910 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-08-13T18:15:29.910Z,1723572929.910 [DATMMP](INFO): Received message type: timesync 2024-08-13T18:15:29.910Z,1723572929.910 [DATMMP](INFO): Handled 2024-08-13T18:15:54.537Z,1723572954.537 [SBIT](IMPORTANT): Beginning Startup BIT 2024-08-13T18:15:54.541Z,1723572954.541 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-13T18:16:05.503Z,1723572965.503 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003950 CHAN A1 (24V): -0.002588 CHAN A2 (12V): -0.000520 CHAN A3 (5V): 0.000301 CHAN B0 (3.3V): 0.000920 CHAN B1 (3.15aV): 0.000650 CHAN B2 (3.15bV): 0.000783 CHAN B3 (GND): 0.000220 OPEN: -0.001231 Full Scale: +/- 1 mA 2024-08-13T18:16:07.869Z,1723572967.869 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-08-13T18:16:34.529Z,1723572994.529 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-08-13T18:16:34.529Z,1723572994.529 [SBIT](FAULT): Control surface position failure. 2024-08-13T18:16:38.829Z,1723572998.829 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005194 2024-08-13T18:16:48.243Z,1723573008.243 [SBIT](ERROR): Could not read elevatorAngleReader_. 2024-08-13T18:16:48.243Z,1723573008.243 [SBIT](FAULT): Control surface position failure. 2024-08-13T18:16:48.650Z,1723573008.650 [SBIT](CRITICAL): SBIT FAILED 2024-08-13T18:16:48.650Z,1723573008.650 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-08-13T18:16:48.651Z,1723573008.651 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-08-13T18:16:48.651Z,1723573008.651 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool; 2024-08-13T18:16:48.651Z,1723573008.651 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): ElevatorServo.currLimit=75 percent; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): ElevatorServo.limitHi=16369 count; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): ElevatorServo.limitLo=0 count; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=0 bool; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2024-08-13T18:16:48.652Z,1723573008.652 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2024-08-13T18:16:48.653Z,1723573008.653 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=1 bool; 2024-08-13T18:16:48.653Z,1723573008.653 [SBIT](IMPORTANT): VerticalControl.massDefault=12.059213 millimeter; 2024-08-13T18:16:48.653Z,1723573008.653 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool; 2024-08-13T18:16:48.654Z,1723573008.654 [CommandExec](FAULT): Scheduling is paused 2024-08-13T18:16:48.655Z,1723573008.655 [CBIT](INFO): Critical error at 20240813T181648 2024-08-13T18:16:48.655Z,1723573008.655 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-08-13T18:16:49.049Z,1723573009.049 [MissionManager](IMPORTANT): Started mission Startup 2024-08-13T18:16:49.050Z,1723573009.050 [Startup] Running Loop=1 2024-08-13T18:16:49.050Z,1723573009.050 [Startup](DEBUG): Aggregate::initialize Startup 2024-08-13T18:16:49.050Z,1723573009.050 [Startup:A.GoToSurface] Running Loop=1 2024-08-13T18:16:49.050Z,1723573009.050 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-13T18:16:49.067Z,1723573009.067 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-13T18:16:49.067Z,1723573009.067 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-13T18:16:49.067Z,1723573009.067 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-13T18:16:49.068Z,1723573009.068 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-13T18:16:49.068Z,1723573009.068 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-13T18:16:49.068Z,1723573009.068 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-13T18:16:49.070Z,1723573009.070 [Startup:StartupSatComms] Running Loop=1 2024-08-13T18:16:49.074Z,1723573009.074 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-08-13T18:16:49.075Z,1723573009.075 [Startup:StartupSatComms:A] Running Loop=1 2024-08-13T18:16:49.443Z,1723573009.443 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-08-13T18:17:46.025Z,1723573066.025 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-08-13T18:17:46.025Z,1723573066.025 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:17:46.076Z,1723573066.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:17:46.436Z,1723573066.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:17:46.436Z,1723573066.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-08-13T18:17:49.305Z,1723573069.305 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-13T18:16:49.1Z 2024-08-13T18:17:49.305Z,1723573069.305 [Startup:StartupSatComms:A] Stopped 2024-08-13T18:17:49.305Z,1723573069.305 [Startup:StartupSatComms:B] Running Loop=1 2024-08-13T18:17:49.672Z,1723573069.672 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-13T18:17:58.170Z,1723573078.170 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240813T180925/Courier0004.lzma 2024-08-13T18:17:59.172Z,1723573079.172 [DataOverHttps](INFO): Moved sent file to Logs/20240813T180925/Courier0004.lzma.bak 2024-08-13T18:17:59.173Z,1723573079.173 [DataOverHttps](INFO): SBD MOMSN=21496785 2024-08-13T18:18:17.938Z,1723573097.938 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20240813T181434/Courier0000.lzma 2024-08-13T18:18:18.940Z,1723573098.940 [DataOverHttps](INFO): Moved sent file to Logs/20240813T181434/Courier0000.lzma.bak 2024-08-13T18:18:18.941Z,1723573098.941 [DataOverHttps](INFO): SBD MOMSN=21496789 2024-08-13T18:18:37.177Z,1723573117.177 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240813T180925/Express0005.lzma 2024-08-13T18:18:38.164Z,1723573118.164 [DataOverHttps](INFO): Moved sent file to Logs/20240813T180925/Express0005.lzma.bak 2024-08-13T18:18:38.164Z,1723573118.164 [DataOverHttps](INFO): SBD MOMSN=21496797 2024-08-13T18:18:49.637Z,1723573129.637 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-13T18:17:49.3Z 2024-08-13T18:18:49.637Z,1723573129.637 [Startup:StartupSatComms:B] Stopped 2024-08-13T18:18:49.637Z,1723573129.637 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-08-13T18:18:49.637Z,1723573129.637 [Startup:StartupSatComms] Stopped 2024-08-13T18:18:49.637Z,1723573129.637 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-08-13T18:18:49.638Z,1723573129.638 [Startup](INFO): Completed Startup 2024-08-13T18:18:49.638Z,1723573129.638 [MissionManager](INFO): Startup is completed. 2024-08-13T18:18:49.643Z,1723573129.643 [MissionManager](INFO): Uninitializing Mission Startup 2024-08-13T18:18:49.643Z,1723573129.643 [Startup] Stopped 2024-08-13T18:18:49.643Z,1723573129.643 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-08-13T18:18:49.643Z,1723573129.643 [Startup:A.GoToSurface] Stopped 2024-08-13T18:18:49.643Z,1723573129.643 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-13T18:18:50.021Z,1723573130.021 [MissionManager](IMPORTANT): Started mission Default 2024-08-13T18:18:50.021Z,1723573130.021 [Default] Running Loop=1 2024-08-13T18:18:50.021Z,1723573130.021 [Default](DEBUG): Aggregate::initialize Default 2024-08-13T18:18:50.021Z,1723573130.021 [Default:B.GoToSurface] Running Loop=1 2024-08-13T18:18:50.021Z,1723573130.021 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-13T18:18:50.022Z,1723573130.022 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-13T18:18:50.022Z,1723573130.022 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-13T18:18:50.022Z,1723573130.022 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-13T18:18:50.023Z,1723573130.023 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-13T18:18:50.023Z,1723573130.023 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-13T18:18:50.023Z,1723573130.023 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-13T18:18:50.024Z,1723573130.024 [Default:A.Wait] Running Loop=1 2024-08-13T18:18:50.024Z,1723573130.024 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-08-13T18:18:57.205Z,1723573137.205 [DataOverHttps](INFO): Sending 1046 bytes from file Logs/20240813T181434/Express0001.lzma 2024-08-13T18:18:58.204Z,1723573138.204 [DataOverHttps](INFO): Moved sent file to Logs/20240813T181434/Express0001.lzma.bak 2024-08-13T18:18:58.204Z,1723573138.204 [DataOverHttps](INFO): SBD MOMSN=21496813 2024-08-13T18:19:03.391Z,1723573143.391 [Default:A.Wait](INFO): Done Waiting. 2024-08-13T18:19:03.391Z,1723573143.391 [Default:A.Wait] Stopped 2024-08-13T18:19:03.391Z,1723573143.391 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-13T18:19:03.770Z,1723573143.770 [Default:CheckIn] Running Loop=1 2024-08-13T18:19:03.770Z,1723573143.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-13T18:19:03.770Z,1723573143.770 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-13T18:19:04.175Z,1723573144.175 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-13T18:20:20.500Z,1723573220.500 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:20:28.588Z,1723573228.588 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-13T18:20:41.511Z,1723573241.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:20:47.179Z,1723573247.179 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-08-13T18:20:47.179Z,1723573247.179 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:20:47.210Z,1723573247.210 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:20:47.593Z,1723573247.593 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:20:47.593Z,1723573247.593 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-08-13T18:21:02.920Z,1723573262.920 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:21:24.375Z,1723573284.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:21:45.787Z,1723573305.787 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:22:06.836Z,1723573326.836 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-13T18:22:15.698Z,1723573335.698 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-13T18:22:28.208Z,1723573348.208 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:22:44.428Z,1723573364.428 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-08-13T18:22:44.442Z,1723573364.442 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 4. 2024-08-13T18:22:44.445Z,1723573364.445 [BPC1](INFO): Received data from all battery sticks. 2024-08-13T18:23:48.244Z,1723573428.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-08-13T18:23:48.244Z,1723573428.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:23:48.254Z,1723573428.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:23:48.639Z,1723573428.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:23:48.639Z,1723573428.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-08-13T18:24:03.988Z,1723573443.988 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-13T18:19:03.8Z 2024-08-13T18:24:03.988Z,1723573443.988 [Default:CheckIn:Read_GPS] Stopped 2024-08-13T18:24:03.989Z,1723573443.989 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-13T18:24:04.396Z,1723573444.396 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-13T18:24:13.246Z,1723573453.246 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240813T181434/Courier0004.lzma 2024-08-13T18:24:14.248Z,1723573454.248 [DataOverHttps](INFO): Moved sent file to Logs/20240813T181434/Courier0004.lzma.bak 2024-08-13T18:24:14.249Z,1723573454.249 [DataOverHttps](INFO): SBD MOMSN=21496883 2024-08-13T18:24:35.995Z,1723573475.995 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20240813T181434/Express0005.lzma 2024-08-13T18:24:36.996Z,1723573476.996 [DataOverHttps](INFO): Moved sent file to Logs/20240813T181434/Express0005.lzma.bak 2024-08-13T18:24:36.996Z,1723573476.996 [DataOverHttps](INFO): SBD MOMSN=21496888 2024-08-13T18:24:41.582Z,1723573481.582 [Default:CheckIn:Read_Iridium] Stopped 2024-08-13T18:24:41.603Z,1723573481.603 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-13T18:24:41.603Z,1723573481.603 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-13T18:25:28.410Z,1723573528.410 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-08-13T18:25:28.410Z,1723573528.410 [NAL9602] Data Fault, FailCount= 1 2024-08-13T18:25:28.410Z,1723573528.410 [NAL9602](ERROR): Data Fault 2024-08-13T18:25:28.466Z,1723573528.466 [CBIT](ERROR): Data Fault in component: NAL9602 2024-08-13T18:25:28.823Z,1723573528.823 [NAL9602](INFO): Powering down 2024-08-13T18:25:29.655Z,1723573529.655 [CBIT](INFO): Clearing failed state for component NAL9602 2024-08-13T18:25:29.655Z,1723573529.655 [NAL9602] No Fault, FailCount= 1 2024-08-13T18:25:59.116Z,1723573559.116 [NAL9602](INFO): Powering up NAL9602 2024-08-13T18:26:10.028Z,1723573570.028 [NAL9602](INFO): NAL9602 initialized 2024-08-13T18:26:49.260Z,1723573609.260 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-08-13T18:26:49.260Z,1723573609.260 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:26:49.271Z,1723573609.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:26:49.646Z,1723573609.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:26:49.646Z,1723573609.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-08-13T18:29:42.375Z,1723573782.375 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-13T18:29:42.375Z,1723573782.375 [Default:CheckIn:C.Wait] Stopped 2024-08-13T18:29:42.375Z,1723573782.375 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-13T18:29:42.375Z,1723573782.375 [Default:CheckIn:D] Running Loop=1 2024-08-13T18:29:42.729Z,1723573782.729 [Default:CheckIn:D] Stopped 2024-08-13T18:29:42.729Z,1723573782.729 [Default:CheckIn:E] Running Loop=1 2024-08-13T18:29:43.132Z,1723573783.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.878468 min 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn:E] Stopped 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn] Stopped 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn](INFO): Running loop #2 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn] Running Loop=2 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-13T18:29:43.149Z,1723573783.149 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-13T18:29:50.394Z,1723573790.394 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-08-13T18:29:50.394Z,1723573790.394 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:29:50.404Z,1723573790.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:29:50.829Z,1723573790.829 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:29:50.829Z,1723573790.829 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-08-13T18:32:51.413Z,1723573971.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-08-13T18:32:51.413Z,1723573971.413 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:32:51.423Z,1723573971.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:32:51.820Z,1723573971.820 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:32:51.820Z,1723573971.820 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-08-13T18:34:43.295Z,1723574083.295 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-13T18:29:43.1Z 2024-08-13T18:34:43.295Z,1723574083.295 [Default:CheckIn:Read_GPS] Stopped 2024-08-13T18:34:43.295Z,1723574083.295 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-13T18:34:52.156Z,1723574092.156 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240813T181434/Courier0007.lzma 2024-08-13T18:34:53.158Z,1723574093.158 [DataOverHttps](INFO): Moved sent file to Logs/20240813T181434/Courier0007.lzma.bak 2024-08-13T18:34:53.159Z,1723574093.159 [DataOverHttps](INFO): SBD MOMSN=21496910 2024-08-13T18:35:12.333Z,1723574112.333 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20240813T181434/Express0008.lzma 2024-08-13T18:35:13.335Z,1723574113.335 [DataOverHttps](INFO): Moved sent file to Logs/20240813T181434/Express0008.lzma.bak 2024-08-13T18:35:13.336Z,1723574113.336 [DataOverHttps](INFO): SBD MOMSN=21496914 2024-08-13T18:35:18.050Z,1723574118.050 [Default:CheckIn:Read_Iridium] Stopped 2024-08-13T18:35:18.050Z,1723574118.050 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-13T18:35:18.050Z,1723574118.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-13T18:35:52.378Z,1723574152.378 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-08-13T18:35:52.378Z,1723574152.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:35:52.388Z,1723574152.388 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:35:52.792Z,1723574152.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:35:52.792Z,1723574152.792 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-08-13T18:36:12.163Z,1723574172.163 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-08-13T18:36:12.163Z,1723574172.163 [NAL9602] Data Fault, FailCount= 2 2024-08-13T18:36:12.163Z,1723574172.163 [NAL9602](ERROR): Data Fault 2024-08-13T18:36:12.200Z,1723574172.200 [CBIT](ERROR): Data Fault in component: NAL9602 2024-08-13T18:36:12.570Z,1723574172.570 [NAL9602](INFO): Powering down 2024-08-13T18:36:13.395Z,1723574173.395 [CBIT](INFO): Clearing failed state for component NAL9602 2024-08-13T18:36:13.395Z,1723574173.395 [NAL9602] No Fault, FailCount= 2 2024-08-13T18:36:42.866Z,1723574202.866 [NAL9602](INFO): Powering up NAL9602 2024-08-13T18:36:53.779Z,1723574213.779 [NAL9602](INFO): NAL9602 initialized 2024-08-13T18:38:53.391Z,1723574333.391 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-08-13T18:38:53.391Z,1723574333.391 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-13T18:38:53.401Z,1723574333.401 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-13T18:38:53.783Z,1723574333.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-13T18:38:53.783Z,1723574333.783 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-08-13T18:40:18.621Z,1723574418.621 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-13T18:40:18.621Z,1723574418.621 [Default:CheckIn:C.Wait] Stopped 2024-08-13T18:40:18.621Z,1723574418.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-13T18:40:18.621Z,1723574418.621 [Default:CheckIn:D] Running Loop=1 2024-08-13T18:40:19.036Z,1723574419.036 [Default:CheckIn:D] Stopped 2024-08-13T18:40:19.036Z,1723574419.036 [Default:CheckIn:E] Running Loop=1 2024-08-13T18:40:19.446Z,1723574419.446 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.483582 min 2024-08-13T18:40:19.446Z,1723574419.446 [Default:CheckIn:E] Stopped 2024-08-13T18:40:19.446Z,1723574419.446 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-13T18:40:19.446Z,1723574419.446 [Default:CheckIn] Stopped 2024-08-13T18:40:19.447Z,1723574419.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-13T18:40:19.447Z,1723574419.447 [Default:CheckIn](INFO): Running loop #3 2024-08-13T18:40:19.447Z,1723574419.447 [Default:CheckIn] Running Loop=3 2024-08-13T18:40:19.447Z,1723574419.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-13T18:40:19.447Z,1723574419.447 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-13T18:40:19.952Z,1723574419.952 [DataOverHttps](IMPORTANT): SBD MTMSN=20240813T184019 2024-08-13T18:40:27.307Z,1723574427.307 [DataOverHttps](INFO): Received command: restart sys 2024-08-13T18:40:27.380Z,1723574427.380 [CommandExec](IMPORTANT): got command restart system 2024-08-13T18:40:29.455Z,1723574429.455 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:29.455Z,1723574429.455 [CommandExec](INFO): Uninitializing the command executive. 2024-08-13T18:40:29.455Z,1723574429.455 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-13T18:40:29.455Z,1723574429.455 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:29.578Z,1723574429.578 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-13T18:40:29.579Z,1723574429.579 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-13T18:40:29.579Z,1723574429.579 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:29.579Z,1723574429.579 [NavChartDb](INFO): Join timeout helper Thread ID is 1617 2024-08-13T18:40:29.839Z,1723574429.839 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:29.839Z,1723574429.839 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:29.858Z,1723574429.858 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-13T18:40:29.859Z,1723574429.859 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:29.859Z,1723574429.859 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1618 2024-08-13T18:40:30.503Z,1723574430.503 [CTD_Seabird](INFO): Powering down 2024-08-13T18:40:30.515Z,1723574430.515 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:30.515Z,1723574430.515 [CTD_Seabird](INFO): Powering down 2024-08-13T18:40:30.526Z,1723574430.526 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:30.538Z,1723574430.538 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-13T18:40:30.539Z,1723574430.539 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:30.539Z,1723574430.539 [Radio_Surface](INFO): Join timeout helper Thread ID is 1619 2024-08-13T18:40:30.855Z,1723574430.855 [Radio_Surface](INFO): Powering down 2024-08-13T18:40:30.856Z,1723574430.856 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:30.856Z,1723574430.856 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:30.862Z,1723574430.862 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-13T18:40:30.863Z,1723574430.863 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:30.863Z,1723574430.863 [Onboard](INFO): Join timeout helper Thread ID is 1620 2024-08-13T18:40:31.323Z,1723574431.323 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-08-13T18:40:33.267Z,1723574433.267 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.267Z,1723574433.267 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.282Z,1723574433.282 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-08-13T18:40:33.283Z,1723574433.283 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.283Z,1723574433.283 [DataOverHttps](INFO): Join timeout helper Thread ID is 1621 2024-08-13T18:40:33.331Z,1723574433.331 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.331Z,1723574433.331 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.343Z,1723574433.343 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2024-08-13T18:40:33.343Z,1723574433.343 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.344Z,1723574433.344 [DATMMP](INFO): Join timeout helper Thread ID is 1622 2024-08-13T18:40:33.415Z,1723574433.415 [DATMMP](INFO): Stop 2024-08-13T18:40:33.415Z,1723574433.415 [DATMMP](INFO): uninitialize 2024-08-13T18:40:33.415Z,1723574433.415 [DATMMP](INFO): Powering down 2024-08-13T18:40:33.487Z,1723574433.487 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.488Z,1723574433.488 [DATMMP](INFO): uninitialize 2024-08-13T18:40:33.488Z,1723574433.488 [DATMMP](INFO): Powering down 2024-08-13T18:40:33.489Z,1723574433.489 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.491Z,1723574433.491 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-08-13T18:40:33.491Z,1723574433.491 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.492Z,1723574433.492 [BackseatComponent](INFO): Join timeout helper Thread ID is 1623 2024-08-13T18:40:33.679Z,1723574433.679 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.679Z,1723574433.679 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.695Z,1723574433.695 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-08-13T18:40:33.696Z,1723574433.696 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.696Z,1723574433.696 [logger](INFO): Join timeout helper Thread ID is 1624 2024-08-13T18:40:33.727Z,1723574433.727 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.727Z,1723574433.727 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.735Z,1723574433.735 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-08-13T18:40:33.735Z,1723574433.735 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.736Z,1723574433.736 [CommandLine](INFO): Join timeout helper Thread ID is 1625 2024-08-13T18:40:33.803Z,1723574433.803 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.803Z,1723574433.803 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.815Z,1723574433.815 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-08-13T18:40:33.815Z,1723574433.815 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.816Z,1723574433.816 [CommandExec](INFO): Join timeout helper Thread ID is 1626 2024-08-13T18:40:33.817Z,1723574433.817 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-08-13T18:40:33.817Z,1723574433.817 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-13T18:40:33.818Z,1723574433.818 [controlThread](INFO): Join timeout helper Thread ID is 1627 2024-08-13T18:40:33.939Z,1723574433.939 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-13T18:40:33.939Z,1723574433.939 [controlThread](DEBUG): Uninitializing ControlThread 2024-08-13T18:40:33.939Z,1723574433.939 [AHRS_M2](INFO): Powering down 2024-08-13T18:40:34.107Z,1723574434.107 [DVL_micro](INFO): Powering down 2024-08-13T18:40:34.108Z,1723574434.108 [NAL9602](INFO): Powering down 2024-08-13T18:40:34.109Z,1723574434.109 [Sonardyne_Nano](INFO): Powering down 2024-08-13T18:40:34.228Z,1723574434.228 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-08-13T18:40:34.228Z,1723574434.228 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-08-13T18:40:34.229Z,1723574434.229 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-08-13T18:40:34.229Z,1723574434.229 [MissionManager](INFO): Uninitializing Mission Default 2024-08-13T18:40:34.229Z,1723574434.229 [Default] Stopped 2024-08-13T18:40:34.229Z,1723574434.229 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-13T18:40:34.230Z,1723574434.230 [Default:B.GoToSurface] Stopped 2024-08-13T18:40:34.230Z,1723574434.230 [Default:B.GoToSurface](DEB