2025-05-14T16:12:34.557Z,1747239154.557 [Supervisor](DEBUG): Initializing supervisor. 2025-05-14T16:12:34.562Z,1747239154.562 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-05-14T16:12:34.562Z,1747239154.562 [SyncHandler](INFO): Protected caller Thread ID is 1643 2025-05-14T16:12:34.563Z,1747239154.563 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-05-14T16:12:34.564Z,1747239154.564 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-05-14T16:12:34.564Z,1747239154.564 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1644 2025-05-14T16:12:34.568Z,1747239154.568 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-05-14T16:12:34.590Z,1747239154.590 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-05-14T16:12:34.591Z,1747239154.591 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-05-14T16:12:34.591Z,1747239154.591 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1645 2025-05-14T16:12:34.595Z,1747239154.595 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-05-14T16:12:34.596Z,1747239154.596 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-05-14T16:12:34.597Z,1747239154.597 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1646 2025-05-14T16:12:34.599Z,1747239154.599 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-05-14T16:12:34.600Z,1747239154.600 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-05-14T16:12:34.600Z,1747239154.600 [logger ThreadHandler](INFO): Protected caller Thread ID is 1647 2025-05-14T16:12:34.604Z,1747239154.604 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-05-14T16:12:34.605Z,1747239154.605 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-05-14T16:12:34.606Z,1747239154.606 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-05-14T16:12:35.253Z,1747239155.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-05-14T16:12:35.253Z,1747239155.253 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-05-14T16:12:35.607Z,1747239155.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-05-14T16:12:35.607Z,1747239155.607 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-05-14T16:12:36.139Z,1747239156.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-05-14T16:12:36.140Z,1747239156.140 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-05-14T16:12:36.397Z,1747239156.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-05-14T16:12:36.398Z,1747239156.398 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-05-14T16:12:36.652Z,1747239156.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-05-14T16:12:36.653Z,1747239156.653 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-05-14T16:12:37.501Z,1747239157.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-05-14T16:12:37.502Z,1747239157.502 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-05-14T16:12:37.897Z,1747239157.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-05-14T16:12:37.897Z,1747239157.897 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-05-14T16:12:38.025Z,1747239158.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-05-14T16:12:38.026Z,1747239158.026 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-05-14T16:12:38.119Z,1747239158.119 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-05-14T16:12:38.327Z,1747239158.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-05-14T16:12:38.328Z,1747239158.328 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-05-14T16:12:38.439Z,1747239158.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-05-14T16:12:38.439Z,1747239158.439 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-05-14T16:12:38.677Z,1747239158.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-05-14T16:12:38.678Z,1747239158.678 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-05-14T16:12:38.973Z,1747239158.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-05-14T16:12:38.974Z,1747239158.974 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-05-14T16:12:39.226Z,1747239159.226 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-05-14T16:12:40.532Z,1747239160.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-05-14T16:12:40.533Z,1747239160.533 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-05-14T16:12:41.119Z,1747239161.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-05-14T16:12:41.122Z,1747239161.122 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 2025-05-14T16:12:41.122Z,1747239161.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 2025-05-14T16:12:41.239Z,1747239161.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 2025-05-14T16:12:41.519Z,1747239161.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-05-14T16:12:41.519Z,1747239161.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 2025-05-14T16:12:41.635Z,1747239161.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 2025-05-14T16:12:41.744Z,1747239161.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 2025-05-14T16:12:41.866Z,1747239161.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 2025-05-14T16:12:42.015Z,1747239162.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 2025-05-14T16:12:42.127Z,1747239162.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 2025-05-14T16:12:42.249Z,1747239162.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 2025-05-14T16:12:42.345Z,1747239162.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 2025-05-14T16:12:42.473Z,1747239162.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 2025-05-14T16:12:42.572Z,1747239162.572 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/ 2025-05-14T16:12:42.573Z,1747239162.573 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-05-14T16:12:42.584Z,1747239162.584 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-05-14T16:12:42.596Z,1747239162.596 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-05-14T16:12:42.596Z,1747239162.596 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-05-14T16:12:42.714Z,1747239162.714 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-05-14T16:12:42.714Z,1747239162.714 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-05-14T16:12:42.754Z,1747239162.754 [DeadReckonWithRespectToSeafloor] Loaded 2025-05-14T16:12:42.754Z,1747239162.754 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2025-05-14T16:12:42.768Z,1747239162.768 [NavChart] Loaded 2025-05-14T16:12:42.768Z,1747239162.768 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-05-14T16:12:42.774Z,1747239162.774 [UniversalFixResidualReporter] Loaded 2025-05-14T16:12:42.774Z,1747239162.774 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-05-14T16:12:42.774Z,1747239162.774 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-05-14T16:12:42.775Z,1747239162.775 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-05-14T16:12:43.301Z,1747239163.301 [AHRS_M2] Loaded 2025-05-14T16:12:43.301Z,1747239163.301 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-05-14T16:12:43.344Z,1747239163.344 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3 2025-05-14T16:12:43.344Z,1747239163.344 [BackseatComponent] Loaded 2025-05-14T16:12:43.345Z,1747239163.345 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-05-14T16:12:43.346Z,1747239163.346 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0 2025-05-14T16:12:43.346Z,1747239163.346 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1730 2025-05-14T16:12:43.349Z,1747239163.349 [LcmUniversalReporter] Loaded 2025-05-14T16:12:43.349Z,1747239163.349 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-05-14T16:12:43.449Z,1747239163.449 [DAT] Loaded 2025-05-14T16:12:43.449Z,1747239163.449 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-05-14T16:12:43.450Z,1747239163.450 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0 2025-05-14T16:12:43.451Z,1747239163.451 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1731 2025-05-14T16:12:43.528Z,1747239163.528 [DataOverHttps] Loaded 2025-05-14T16:12:43.529Z,1747239163.529 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-05-14T16:12:43.530Z,1747239163.530 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0 2025-05-14T16:12:43.530Z,1747239163.530 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1732 2025-05-14T16:12:43.553Z,1747239163.553 [DepthKeller33X] Loaded 2025-05-14T16:12:43.553Z,1747239163.553 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread. 2025-05-14T16:12:43.558Z,1747239163.558 [DropWeight] Loaded 2025-05-14T16:12:43.558Z,1747239163.558 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-05-14T16:12:43.622Z,1747239163.622 [NAL9602] Loaded 2025-05-14T16:12:43.622Z,1747239163.622 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-05-14T16:12:43.652Z,1747239163.652 [Onboard] Loaded 2025-05-14T16:12:43.652Z,1747239163.652 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-05-14T16:12:43.654Z,1747239163.654 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0 2025-05-14T16:12:43.654Z,1747239163.654 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1733 2025-05-14T16:12:43.668Z,1747239163.668 [Power24vConverter] Loaded 2025-05-14T16:12:43.668Z,1747239163.668 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-05-14T16:12:43.685Z,1747239163.685 [Radio_Surface] Loaded 2025-05-14T16:12:43.686Z,1747239163.686 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-05-14T16:12:43.687Z,1747239163.687 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0 2025-05-14T16:12:43.687Z,1747239163.687 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1734 2025-05-14T16:12:43.734Z,1747239163.734 [RDI_Pathfinder] Loaded 2025-05-14T16:12:43.734Z,1747239163.734 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-05-14T16:12:43.749Z,1747239163.749 [Sonardyne_Nano] Loaded 2025-05-14T16:12:43.749Z,1747239163.749 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-05-14T16:12:43.750Z,1747239163.750 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-05-14T16:12:43.751Z,1747239163.751 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-05-14T16:12:43.820Z,1747239163.820 [DepthRateCalculator] Loaded 2025-05-14T16:12:43.820Z,1747239163.820 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-05-14T16:12:43.845Z,1747239163.845 [PitchRateCalculator] Loaded 2025-05-14T16:12:43.846Z,1747239163.846 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-05-14T16:12:43.881Z,1747239163.881 [SpeedCalculator] Loaded 2025-05-14T16:12:43.881Z,1747239163.881 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-05-14T16:12:43.886Z,1747239163.886 [YawRateCalculator] Loaded 2025-05-14T16:12:43.886Z,1747239163.886 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-05-14T16:12:43.908Z,1747239163.908 [ElevatorOffsetCalculator] Loaded 2025-05-14T16:12:43.908Z,1747239163.908 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-05-14T16:12:43.909Z,1747239163.909 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-05-14T16:12:43.909Z,1747239163.909 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-05-14T16:12:43.972Z,1747239163.972 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-05-14T16:12:43.972Z,1747239163.972 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-05-14T16:12:44.427Z,1747239164.427 [BuoyancyServo] Loaded 2025-05-14T16:12:44.427Z,1747239164.427 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-05-14T16:12:44.456Z,1747239164.456 [ElevatorServo] Loaded 2025-05-14T16:12:44.456Z,1747239164.456 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-05-14T16:12:44.479Z,1747239164.479 [MassServo] Loaded 2025-05-14T16:12:44.480Z,1747239164.480 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-05-14T16:12:44.502Z,1747239164.502 [RudderServo] Loaded 2025-05-14T16:12:44.503Z,1747239164.503 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-05-14T16:12:44.521Z,1747239164.521 [ThrusterHE] Loaded 2025-05-14T16:12:44.521Z,1747239164.521 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-05-14T16:12:44.522Z,1747239164.522 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-05-14T16:12:44.522Z,1747239164.522 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-05-14T16:12:44.699Z,1747239164.699 [CTD_Seabird] Loaded 2025-05-14T16:12:44.699Z,1747239164.699 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-05-14T16:12:44.703Z,1747239164.703 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A514E0 2025-05-14T16:12:44.703Z,1747239164.703 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1735 2025-05-14T16:12:44.724Z,1747239164.724 [RBRTridente] Loaded 2025-05-14T16:12:44.724Z,1747239164.724 [ComponentRegistry](DEBUG): Component "RBRTridente" handled in its own thread. 2025-05-14T16:12:44.725Z,1747239164.725 [RBRTridente ThreadHandler](DEBUG): Created PCaller Thread at 40A814E0 2025-05-14T16:12:44.726Z,1747239164.726 [RBRTridente ThreadHandler](INFO): Protected caller Thread ID is 1736 2025-05-14T16:12:44.727Z,1747239164.727 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-05-14T16:12:44.727Z,1747239164.727 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-05-14T16:12:44.864Z,1747239164.864 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-05-14T16:12:44.865Z,1747239164.865 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-05-14T16:12:45.047Z,1747239165.047 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-05-14T16:12:45.047Z,1747239165.047 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-05-14T16:12:45.402Z,1747239165.402 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-05-14T16:12:45.402Z,1747239165.402 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-05-14T16:12:45.457Z,1747239165.457 [VerticalControl](DEBUG): Construct VerticalControl. 2025-05-14T16:12:45.509Z,1747239165.509 [VerticalControl] Loaded 2025-05-14T16:12:45.510Z,1747239165.510 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-05-14T16:12:45.512Z,1747239165.512 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-05-14T16:12:45.559Z,1747239165.559 [HorizontalControl] Loaded 2025-05-14T16:12:45.560Z,1747239165.560 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-05-14T16:12:45.562Z,1747239165.562 [SpeedControl](DEBUG): Construct SpeedControl. 2025-05-14T16:12:45.564Z,1747239165.564 [SpeedControl] Loaded 2025-05-14T16:12:45.564Z,1747239165.564 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-05-14T16:12:45.567Z,1747239165.567 [LoopControl](DEBUG): Construct LoopControl. 2025-05-14T16:12:45.567Z,1747239165.567 [LoopControl] Loaded 2025-05-14T16:12:45.568Z,1747239165.568 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-05-14T16:12:45.568Z,1747239165.568 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-05-14T16:12:45.568Z,1747239165.568 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-05-14T16:12:45.736Z,1747239165.736 [SBIT](DEBUG): Construct Startup Built In Test. 2025-05-14T16:12:45.745Z,1747239165.745 [SBIT] Loaded 2025-05-14T16:12:45.745Z,1747239165.745 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-05-14T16:12:45.748Z,1747239165.748 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-05-14T16:12:45.762Z,1747239165.762 [IBIT] Loaded 2025-05-14T16:12:45.762Z,1747239165.762 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-05-14T16:12:45.767Z,1747239165.767 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-05-14T16:12:45.971Z,1747239165.971 [CBIT] Loaded 2025-05-14T16:12:45.971Z,1747239165.971 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-05-14T16:12:46.003Z,1747239166.003 [GFScanner] Loaded 2025-05-14T16:12:46.004Z,1747239166.004 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-05-14T16:12:46.004Z,1747239166.004 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-05-14T16:12:46.005Z,1747239166.005 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-05-14T16:12:46.035Z,1747239166.035 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-05-14T16:12:46.042Z,1747239166.042 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-05-14T16:12:46.045Z,1747239166.045 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-05-14T16:12:46.057Z,1747239166.057 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-05-14T16:12:46.060Z,1747239166.060 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C684E0 2025-05-14T16:12:46.060Z,1747239166.060 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1737 2025-05-14T16:12:46.065Z,1747239166.065 [Supervisor](INFO): Main Thread ID is 1642 2025-05-14T16:12:46.065Z,1747239166.065 [Supervisor](DEBUG): Running supervisor. 2025-05-14T16:12:46.066Z,1747239166.066 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1738 2025-05-14T16:12:46.067Z,1747239166.067 [CommandExec](INFO): Initializing the command executive. 2025-05-14T16:12:46.068Z,1747239166.068 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1739 2025-05-14T16:12:46.072Z,1747239166.072 [controlThread ThreadHandler](INFO): Handler Thread ID is 1740 2025-05-14T16:12:46.072Z,1747239166.072 [controlThread](DEBUG): Initializing ControlThread 2025-05-14T16:12:46.074Z,1747239166.074 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-05-14T16:12:46.075Z,1747239166.075 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-05-14T16:12:46.077Z,1747239166.077 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-05-14T16:12:46.078Z,1747239166.078 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-05-14T16:12:46.078Z,1747239166.078 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-05-14T16:12:46.078Z,1747239166.078 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-05-14T16:12:46.079Z,1747239166.079 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-05-14T16:12:46.080Z,1747239166.080 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-05-14T16:12:46.082Z,1747239166.082 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-05-14T16:12:46.082Z,1747239166.082 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-05-14T16:12:46.083Z,1747239166.083 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-05-14T16:12:46.083Z,1747239166.083 [SBIT](INFO): Initialize SBIT Component. 2025-05-14T16:12:46.083Z,1747239166.083 [SBIT](IMPORTANT): git: 2025-05-14 2025-05-14T16:12:46.084Z,1747239166.084 [SBIT](INFO): git hash: 1f60f00c6ae091ed6c2c24e661e610633377ab6f 2025-05-14T16:12:46.084Z,1747239166.084 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-05-14T16:12:46.085Z,1747239166.085 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023 2025-05-14T16:12:46.086Z,1747239166.086 [SBIT](INFO): Beginning SBIT in 34.000000 seconds. 2025-05-14T16:12:46.087Z,1747239166.087 [IBIT](INFO): Initialize IBIT Component. 2025-05-14T16:12:46.087Z,1747239166.087 [CBIT](DEBUG): Initialize CBIT Component. 2025-05-14T16:12:46.088Z,1747239166.088 [logger ThreadHandler](INFO): Handler Thread ID is 1741 2025-05-14T16:12:46.101Z,1747239166.101 [CBIT](DEBUG): Initialized mux pins. 2025-05-14T16:12:46.101Z,1747239166.101 [CBIT](DEBUG): Initializing the watchdog timer. 2025-05-14T16:12:46.109Z,1747239166.109 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1742 2025-05-14T16:12:46.125Z,1747239166.125 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2025-05-14T16:12:46.125Z,1747239166.125 [CBIT](DEBUG): Initializing heartbeat. 2025-05-14T16:12:46.126Z,1747239166.126 [DAT ThreadHandler](INFO): Handler Thread ID is 1743 2025-05-14T16:12:46.127Z,1747239166.127 [DAT](INFO): Powering up 2025-05-14T16:12:46.127Z,1747239166.127 [DAT](DEBUG): Initializing DAT. 2025-05-14T16:12:46.130Z,1747239166.130 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1745 2025-05-14T16:12:46.132Z,1747239166.132 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-05-14T16:12:46.153Z,1747239166.153 [Onboard ThreadHandler](INFO): Handler Thread ID is 1746 2025-05-14T16:12:46.171Z,1747239166.171 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1747 2025-05-14T16:12:46.187Z,1747239166.187 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1748 2025-05-14T16:12:46.187Z,1747239166.187 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-05-14T16:12:46.195Z,1747239166.195 [RBRTridente ThreadHandler](INFO): Handler Thread ID is 1750 2025-05-14T16:12:46.195Z,1747239166.195 [RBRTridente](INFO): Start 2025-05-14T16:12:46.197Z,1747239166.197 [CBIT](DEBUG): Deactivating emergency mode. 2025-05-14T16:12:46.198Z,1747239166.198 [RBRTridente](INFO): Powering up 2025-05-14T16:12:46.199Z,1747239166.199 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1752 2025-05-14T16:12:46.203Z,1747239166.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-05-14T16:12:46.203Z,1747239166.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-05-14T16:12:46.203Z,1747239166.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-05-14T16:12:46.203Z,1747239166.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-05-14T16:12:46.204Z,1747239166.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-05-14T16:12:46.204Z,1747239166.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-05-14T16:12:46.204Z,1747239166.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-05-14T16:12:46.204Z,1747239166.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-05-14T16:12:46.237Z,1747239166.237 [CBIT](DEBUG): Backplane powered. 2025-05-14T16:12:46.237Z,1747239166.237 [GFScanner](DEBUG): Initializing GFScanner 2025-05-14T16:12:46.237Z,1747239166.237 [GFScanner](DEBUG): Deactivating GF circuits. 2025-05-14T16:12:46.242Z,1747239166.242 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-05-14T16:12:46.243Z,1747239166.243 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-05-14T16:12:46.243Z,1747239166.243 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-05-14T16:12:46.244Z,1747239166.244 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-05-14T16:12:46.306Z,1747239166.306 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-05-14T16:12:46.311Z,1747239166.311 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-14T16:12:46.365Z,1747239166.365 [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 } } } 2025-05-14T16:12:46.366Z,1747239166.366 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-05-14T16:12:46.366Z,1747239166.366 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-05-14T16:12:46.367Z,1747239166.367 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-05-14T16:12:46.577Z,1747239166.577 [Radio_Surface](INFO): Powering up 2025-05-14T16:12:46.597Z,1747239166.597 [RBRTridente](INFO): Starting 2025-05-14T16:12:46.710Z,1747239166.710 [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 2025-05-14T16:12:46.729Z,1747239166.729 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-05-14T16:12:46.730Z,1747239166.730 [Default:A.Wait](DEBUG): Construct Wait. 2025-05-14T16:12:46.732Z,1747239166.732 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-14T16:12:46.781Z,1747239166.781 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-05-14T16:12:46.784Z,1747239166.784 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-05-14T16:12:46.806Z,1747239166.806 [Default:E.Execute](DEBUG): Construct Execute. 2025-05-14T16:12:46.825Z,1747239166.825 [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" } } 2025-05-14T16:12:46.830Z,1747239166.830 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DepthKeller33X,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-05-14T16:12:46.857Z,1747239166.857 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-05-14T16:12:46.894Z,1747239166.894 [DepthKeller33X](INFO): Initializing. 2025-05-14T16:12:46.914Z,1747239166.914 [Power24vConverter](INFO): Powering up. 2025-05-14T16:12:46.915Z,1747239166.915 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-05-14T16:12:46.922Z,1747239166.922 [Sonardyne_Nano](INFO): Initializing. 2025-05-14T16:12:46.941Z,1747239166.941 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-05-14T16:12:46.952Z,1747239166.952 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-05-14T16:12:46.954Z,1747239166.954 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-05-14T16:12:46.955Z,1747239166.955 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-05-14T16:12:46.957Z,1747239166.957 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-05-14T16:12:46.958Z,1747239166.958 [MassServo](DEBUG): Initializing EZServoServo. 2025-05-14T16:12:46.965Z,1747239166.965 [MassServo](DEBUG): Initializing MassServo. 2025-05-14T16:12:46.966Z,1747239166.966 [RudderServo](DEBUG): Initializing EZServoServo. 2025-05-14T16:12:46.973Z,1747239166.973 [RudderServo](DEBUG): Initializing RudderServo. 2025-05-14T16:12:46.974Z,1747239166.974 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T16:12:46.982Z,1747239166.982 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T16:12:46.984Z,1747239166.984 [CommandExec](FAULT): Scheduling is paused 2025-05-14T16:12:46.984Z,1747239166.984 [CBIT](INFO): Critical error at 20250514T161246 2025-05-14T16:12:46.984Z,1747239166.984 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-05-14T16:12:47.002Z,1747239167.002 [RBRTridente](INFO): Stop 2025-05-14T16:12:47.073Z,1747239167.073 [RBRTridente](INFO): Powering down 2025-05-14T16:12:47.273Z,1747239167.273 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-05-14T16:12:47.406Z,1747239167.406 [RBRTridente](INFO): Stopping 2025-05-14T16:12:47.646Z,1747239167.646 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-05-14T16:12:48.052Z,1747239168.052 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-05-14T16:12:48.475Z,1747239168.475 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-05-14T16:12:48.888Z,1747239168.888 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-05-14T16:12:50.505Z,1747239170.505 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2025-05-14T16:12:50.909Z,1747239170.909 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-05-14T16:12:52.598Z,1747239172.598 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-05-14T16:12:57.475Z,1747239177.475 [DAT](INFO): DAT read: 2025-05-14T16:12:57.477Z,1747239177.477 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-05-14T16:12:58.986Z,1747239178.986 [DAT](INFO): DAT read: MF Frequency Band 2025-05-14T16:12:58.987Z,1747239178.987 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.0 2025-05-14T16:12:58.988Z,1747239178.988 [DAT](INFO): DAT read: May 13 2025 22:30:24 2025-05-14T16:12:59.994Z,1747239179.994 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-05-14T16:12:59.995Z,1747239179.995 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-05-14T16:12:59.996Z,1747239179.996 [DAT](INFO): commRate: 600 2025-05-14T16:13:01.852Z,1747239181.852 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005061 2025-05-14T16:13:02.061Z,1747239182.061 [DAT](INFO): entering command mode 2025-05-14T16:13:02.262Z,1747239182.262 [DAT](INFO): DAT read: 2025-05-14T16:13:02.262Z,1747239182.262 [DAT](INFO): DAT read: user:1> 2025-05-14T16:13:02.263Z,1747239182.263 [DAT](INFO): setting verbose to 3 2025-05-14T16:13:02.514Z,1747239182.514 [DAT](INFO): DAT read: user:1> 2025-05-14T16:13:02.515Z,1747239182.515 [DAT](INFO): DAT read: Verbose | 3 2025-05-14T16:13:02.515Z,1747239182.515 [DAT](INFO): set verbose to 3 2025-05-14T16:13:02.515Z,1747239182.515 [DAT](INFO): setting DatVerbose to 27440 2025-05-14T16:13:02.766Z,1747239182.766 [DAT](INFO): DAT read: user:2> 2025-05-14T16:13:02.767Z,1747239182.767 [DAT](INFO): DAT read: DatVerbose | 27440 2025-05-14T16:13:02.767Z,1747239182.767 [DAT](INFO): set DatVerbose to 27440 2025-05-14T16:13:02.767Z,1747239182.767 [DAT](INFO): setting transmit power to 8 2025-05-14T16:13:03.018Z,1747239183.018 [DAT](INFO): DAT read: user:3> 2025-05-14T16:13:03.019Z,1747239183.019 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-05-14T16:13:03.019Z,1747239183.019 [DAT](INFO): set transmit power to 8 2025-05-14T16:13:03.019Z,1747239183.019 [DAT](INFO): setting local address to 3 2025-05-14T16:13:03.272Z,1747239183.272 [DAT](INFO): DAT read: user:4> 2025-05-14T16:13:03.274Z,1747239183.274 [DAT](INFO): DAT read: LocalAddr | 3 2025-05-14T16:13:03.274Z,1747239183.274 [DAT](INFO): set local address to 3 2025-05-14T16:13:03.275Z,1747239183.275 [DAT](INFO): Setting time to: 16:13:3 And date to:5/14/2025 2025-05-14T16:13:03.522Z,1747239183.522 [DAT](INFO): DAT read: user:5> 2025-05-14T16:13:03.523Z,1747239183.523 [DAT](INFO): DAT read: Wed May 14, 2025 16:13:03 2025-05-14T16:13:03.523Z,1747239183.523 [DAT](INFO): Local DAT time set to Wed May 14, 2025 16:13:03 2025-05-14T16:13:13.624Z,1747239193.624 [NAL9602](INFO): Powering up NAL9602 2025-05-14T16:13:20.875Z,1747239200.875 [SBIT](IMPORTANT): Beginning Startup BIT 2025-05-14T16:13:20.880Z,1747239200.880 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-05-14T16:13:24.500Z,1747239204.500 [NAL9602](INFO): NAL9602 initialized 2025-05-14T16:13:25.671Z,1747239205.671 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.001143 CHAN A1 (24V): 0.001037 CHAN A2 (12V): -0.000427 CHAN A3 (5V): -0.011982 CHAN B0 (3.3V): -0.000551 CHAN B1 (3.15aV): -0.000747 CHAN B2 (3.15bV): -0.000868 CHAN B3 (GND): -0.000216 OPEN: 0.008436 Full Scale: +/- 1 mA 2025-05-14T16:13:33.186Z,1747239213.186 [CommandExec](IMPORTANT): got command strobe off 2025-05-14T16:13:33.186Z,1747239213.186 [CommandExec](IMPORTANT): Deactivating strobe 2025-05-14T16:13:36.986Z,1747239216.986 [CommandExec](IMPORTANT): got command failComponent 2025-05-14T16:13:36.986Z,1747239216.986 [CommandExec](IMPORTANT): Failed components: 2025-05-14T16:13:36.986Z,1747239216.986 [CommandExec](IMPORTANT): No failed Components. 2025-05-14T16:14:15.164Z,1747239255.164 [SBIT](IMPORTANT): SBIT PASSED 2025-05-14T16:14:15.165Z,1747239255.165 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-05-14T16:14:15.166Z,1747239255.166 [SBIT](IMPORTANT): BPC1.loadAtStartup=0 bool; 2025-05-14T16:14:15.166Z,1747239255.166 [SBIT](IMPORTANT): BuoyancyServo.accel=800 none; 2025-05-14T16:14:15.166Z,1747239255.166 [SBIT](IMPORTANT): BuoyancyServo.checkingTimeout=30 minute; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): BuoyancyServo.currLimit=40 percent; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=4 none; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): BuoyancyServo.velocity=800 none; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): GFScanner.gf24Offset=156 microampere; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): GFScanner.gf5Offset=0 microampere; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): RudderServo.offsetAngle=-0.5 degree; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=1500.0 cubic_centimeter; 2025-05-14T16:14:15.167Z,1747239255.167 [SBIT](IMPORTANT): VerticalControl.massDefault=-3.5 millimeter; 2025-05-14T16:14:15.623Z,1747239255.623 [MissionManager](IMPORTANT): Started mission Startup 2025-05-14T16:14:15.623Z,1747239255.623 [Startup] Running Loop=1 2025-05-14T16:14:15.623Z,1747239255.623 [Startup](DEBUG): Aggregate::initialize Startup 2025-05-14T16:14:15.623Z,1747239255.623 [Startup:A.GoToSurface] Running Loop=1 2025-05-14T16:14:15.623Z,1747239255.623 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T16:14:15.624Z,1747239255.624 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-05-14T16:14:15.624Z,1747239255.624 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-05-14T16:14:15.624Z,1747239255.624 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-05-14T16:14:15.633Z,1747239255.633 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-05-14T16:14:15.633Z,1747239255.633 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T16:14:15.634Z,1747239255.634 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T16:14:15.636Z,1747239255.636 [Startup:StartupSatComms] Running Loop=1 2025-05-14T16:14:15.636Z,1747239255.636 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-05-14T16:14:15.636Z,1747239255.636 [Startup:StartupSatComms:A] Running Loop=1 2025-05-14T16:14:16.000Z,1747239256.000 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-05-14T16:15:15.775Z,1747239315.775 [Startup:StartupSatComms:A](INFO): Timed out from 2025-05-14T16:14:15.6Z 2025-05-14T16:15:15.775Z,1747239315.775 [Startup:StartupSatComms:A] Stopped 2025-05-14T16:15:15.775Z,1747239315.775 [Startup:StartupSatComms:B] Running Loop=1 2025-05-14T16:15:16.156Z,1747239316.156 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-05-14T16:15:23.096Z,1747239323.096 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250513T200939/Courier0046.lzma 2025-05-14T16:15:24.099Z,1747239324.099 [DataOverHttps](INFO): Moved sent file to Logs/20250513T200939/Courier0046.lzma.bak 2025-05-14T16:15:24.099Z,1747239324.099 [DataOverHttps](INFO): SBD MOMSN=24873054 2025-05-14T16:15:38.378Z,1747239338.378 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=10204, MT Status=1, MTMSN=1 2025-05-14T16:15:38.378Z,1747239338.378 [NAL9602](INFO): Data available in MT queue 2025-05-14T16:15:38.862Z,1747239338.862 [NAL9602](INFO): Received command: strobe off 2025-05-14T16:15:38.924Z,1747239338.924 [CommandExec](IMPORTANT): got command strobe off 2025-05-14T16:15:38.924Z,1747239338.924 [CommandExec](IMPORTANT): Deactivating strobe 2025-05-14T16:15:40.020Z,1747239340.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161536.00,A,3648.17222,N,12147.26677,W,1.730,121.34,140525,,,A*73 2025-05-14T16:15:40.024Z,1747239340.024 [NAL9602](INFO): GPS fix at 20250514T161536: (36.802870, -121.787779) 2025-05-14T16:15:42.023Z,1747239342.023 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20250513T222647/Courier0000.lzma 2025-05-14T16:15:43.024Z,1747239343.024 [DataOverHttps](INFO): Moved sent file to Logs/20250513T222647/Courier0000.lzma.bak 2025-05-14T16:15:43.024Z,1747239343.024 [DataOverHttps](INFO): SBD MOMSN=24873060 2025-05-14T16:15:46.111Z,1747239346.111 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T16:15:46.111Z,1747239346.111 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T16:15:46.155Z,1747239346.155 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T16:15:46.484Z,1747239346.484 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T16:15:46.484Z,1747239346.484 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T16:16:00.649Z,1747239360.649 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20250514T161234/Courier0000.lzma 2025-05-14T16:16:01.434Z,1747239361.434 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768,-327BE,-32768,-32768,-32768,V 2025-05-14T16:16:06.672Z,1747239366.672 [DataOverHttps](INFO): Moved sent file to Logs/20250514T161234/Courier0000.lzma.bak 2025-05-14T16:16:06.672Z,1747239366.672 [DataOverHttps](INFO): SBD MOMSN=24873064 2025-05-14T16:16:12.314Z,1747239372.314 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T16:16:15.996Z,1747239375.996 [Startup:StartupSatComms:B](INFO): Timed out from 2025-05-14T16:15:15.8Z 2025-05-14T16:16:15.996Z,1747239375.996 [Startup:StartupSatComms:B] Stopped 2025-05-14T16:16:15.996Z,1747239375.996 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-05-14T16:16:15.996Z,1747239375.996 [Startup:StartupSatComms] Stopped 2025-05-14T16:16:15.996Z,1747239375.996 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-05-14T16:16:15.997Z,1747239375.997 [Startup](INFO): Completed Startup 2025-05-14T16:16:15.998Z,1747239375.998 [MissionManager](INFO): Startup is completed. 2025-05-14T16:16:15.998Z,1747239375.998 [MissionManager](INFO): Uninitializing Mission Startup 2025-05-14T16:16:15.998Z,1747239375.998 [Startup] Stopped 2025-05-14T16:16:15.998Z,1747239375.998 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-05-14T16:16:15.998Z,1747239375.998 [Startup:A.GoToSurface] Stopped 2025-05-14T16:16:15.998Z,1747239375.998 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T16:16:16.417Z,1747239376.417 [MissionManager](IMPORTANT): Started mission Default 2025-05-14T16:16:16.417Z,1747239376.417 [Default] Running Loop=1 2025-05-14T16:16:16.417Z,1747239376.417 [Default](DEBUG): Aggregate::initialize Default 2025-05-14T16:16:16.417Z,1747239376.417 [Default:B.GoToSurface] Running Loop=1 2025-05-14T16:16:16.417Z,1747239376.417 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T16:16:16.418Z,1747239376.418 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-05-14T16:16:16.418Z,1747239376.418 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-05-14T16:16:16.418Z,1747239376.418 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-05-14T16:16:16.419Z,1747239376.419 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-05-14T16:16:16.419Z,1747239376.419 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T16:16:16.419Z,1747239376.419 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T16:16:16.419Z,1747239376.419 [Default:A.Wait] Running Loop=1 2025-05-14T16:16:16.420Z,1747239376.420 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-05-14T16:16:29.727Z,1747239389.727 [Default:A.Wait](INFO): Done Waiting. 2025-05-14T16:16:29.727Z,1747239389.727 [Default:A.Wait] Stopped 2025-05-14T16:16:29.727Z,1747239389.727 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T16:16:30.112Z,1747239390.112 [Default:CheckIn] Running Loop=1 2025-05-14T16:16:30.112Z,1747239390.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T16:16:30.112Z,1747239390.112 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T16:16:30.517Z,1747239390.517 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-05-14T16:16:32.116Z,1747239392.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161631.00,A,3648.15562,N,12147.25440,W,1.769,209.09,140525,,,A*78 2025-05-14T16:16:32.118Z,1747239392.118 [NAL9602](INFO): GPS fix at 20250514T161631: (36.802594, -121.787573) 2025-05-14T16:16:32.162Z,1747239392.162 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T16:16:32.162Z,1747239392.162 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T16:16:32.562Z,1747239392.562 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-05-14T16:16:39.781Z,1747239399.781 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20250513T200939/Express0047.lzma 2025-05-14T16:16:41.795Z,1747239401.795 [DataOverHttps](INFO): Moved sent file to Logs/20250513T200939/Express0047.lzma.bak 2025-05-14T16:16:41.795Z,1747239401.795 [DataOverHttps](INFO): SBD MOMSN=24873069 2025-05-14T16:16:50.399Z,1747239410.399 [CommandExec](IMPORTANT): got command restart system 2025-05-14T16:16:52.493Z,1747239412.493 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-14T16:16:52.493Z,1747239412.493 [CommandExec](INFO): Uninitializing the command executive. 2025-05-14T16:16:52.493Z,1747239412.493 [CommandExec](INFO): Uninitializing the command scheduler. 2025-05-14T16:16:52.493Z,1747239412.493 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:52.508Z,1747239412.508 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-05-14T16:16:52.509Z,1747239412.509 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-05-14T16:16:52.509Z,1747239412.509 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:52.510Z,1747239412.510 [NavChartDb](INFO): Join timeout helper Thread ID is 1823 2025-05-14T16:16:52.641Z,1747239412.641 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-14T16:16:52.641Z,1747239412.641 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:52.656Z,1747239412.656 [ComponentRegistry](INFO): Shutting down RBRTridente ThreadHandler 2025-05-14T16:16:52.656Z,1747239412.656 [RBRTridente ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:52.657Z,1747239412.657 [RBRTridente](INFO): Join timeout helper Thread ID is 1824 2025-05-14T16:16:52.813Z,1747239412.813 [RBRTridente ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-14T16:16:52.813Z,1747239412.813 [RBRTridente](INFO): Powering down 2025-05-14T16:16:52.814Z,1747239412.814 [RBRTridente ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:52.824Z,1747239412.824 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-05-14T16:16:52.825Z,1747239412.825 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:52.825Z,1747239412.825 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1825 2025-05-14T16:16:53.237Z,1747239413.237 [CTD_Seabird](INFO): Powering down 2025-05-14T16:16:53.249Z,1747239413.249 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-14T16:16:53.250Z,1747239413.250 [CTD_Seabird](INFO): Powering down 2025-05-14T16:16:53.265Z,1747239413.265 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:53.266Z,1747239413.266 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-05-14T16:16:53.267Z,1747239413.267 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:53.268Z,1747239413.268 [Radio_Surface](INFO): Join timeout helper Thread ID is 1826 2025-05-14T16:16:53.609Z,1747239413.609 [Radio_Surface](INFO): Powering down 2025-05-14T16:16:53.610Z,1747239413.610 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-14T16:16:53.610Z,1747239413.610 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:53.616Z,1747239413.616 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-05-14T16:16:53.617Z,1747239413.617 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-14T16:16:53.617Z,1747239413.617 [Onboard](INFO): Join timeout helper Thread ID is 1827 2025-05-14T16:16:56.349Z,1747239416.349 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-14T16:16:56.349Z,1747239416.349 [Onboard