2025-05-14T19:20:04.307Z,1747250404.307 [Supervisor](DEBUG): Initializing supervisor.
2025-05-14T19:20:04.311Z,1747250404.311 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-14T19:20:04.311Z,1747250404.311 [SyncHandler](INFO): Protected caller Thread ID is 835
2025-05-14T19:20:04.312Z,1747250404.312 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-14T19:20:04.313Z,1747250404.313 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-14T19:20:04.313Z,1747250404.313 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2025-05-14T19:20:04.317Z,1747250404.317 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-14T19:20:04.338Z,1747250404.338 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-14T19:20:04.339Z,1747250404.339 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-14T19:20:04.339Z,1747250404.339 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2025-05-14T19:20:04.344Z,1747250404.344 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-14T19:20:04.345Z,1747250404.345 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-14T19:20:04.345Z,1747250404.345 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2025-05-14T19:20:04.347Z,1747250404.347 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-14T19:20:04.348Z,1747250404.348 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-14T19:20:04.349Z,1747250404.349 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2025-05-14T19:20:04.353Z,1747250404.353 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-14T19:20:04.353Z,1747250404.353 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-14T19:20:04.357Z,1747250404.357 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-14T19:20:04.714Z,1747250404.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-14T19:20:04.716Z,1747250404.716 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-14T19:20:04.996Z,1747250404.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-14T19:20:04.997Z,1747250404.997 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-14T19:20:05.516Z,1747250405.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-14T19:20:05.518Z,1747250405.518 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-14T19:20:05.777Z,1747250405.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-14T19:20:05.779Z,1747250405.779 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-14T19:20:06.021Z,1747250406.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-14T19:20:06.022Z,1747250406.022 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-14T19:20:06.848Z,1747250406.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-14T19:20:06.850Z,1747250406.850 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-14T19:20:07.235Z,1747250407.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-14T19:20:07.235Z,1747250407.235 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-14T19:20:07.358Z,1747250407.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-14T19:20:07.358Z,1747250407.358 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-14T19:20:07.450Z,1747250407.450 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-14T19:20:07.658Z,1747250407.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-14T19:20:07.658Z,1747250407.658 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-14T19:20:08.406Z,1747250408.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-14T19:20:08.408Z,1747250408.408 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-14T19:20:08.642Z,1747250408.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-14T19:20:08.643Z,1747250408.643 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-14T19:20:08.793Z,1747250408.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-14T19:20:08.794Z,1747250408.794 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-14T19:20:08.886Z,1747250408.886 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-14T19:20:09.375Z,1747250409.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-14T19:20:09.375Z,1747250409.375 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-14T19:20:09.956Z,1747250409.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-14T19:20:09.958Z,1747250409.958 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
2025-05-14T19:20:09.960Z,1747250409.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
2025-05-14T19:20:10.077Z,1747250410.077 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
2025-05-14T19:20:10.357Z,1747250410.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-14T19:20:10.359Z,1747250410.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
2025-05-14T19:20:10.473Z,1747250410.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
2025-05-14T19:20:10.581Z,1747250410.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
2025-05-14T19:20:10.703Z,1747250410.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
2025-05-14T19:20:10.852Z,1747250410.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
2025-05-14T19:20:10.962Z,1747250410.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
2025-05-14T19:20:11.082Z,1747250411.082 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
2025-05-14T19:20:11.176Z,1747250411.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
2025-05-14T19:20:11.301Z,1747250411.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
2025-05-14T19:20:11.397Z,1747250411.397 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-05-14T19:20:11.401Z,1747250411.401 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-14T19:20:11.418Z,1747250411.418 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-14T19:20:11.419Z,1747250411.419 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-14T19:20:11.562Z,1747250411.562 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-14T19:20:11.562Z,1747250411.562 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-14T19:20:11.603Z,1747250411.603 [DeadReckonWithRespectToSeafloor] Loaded
2025-05-14T19:20:11.603Z,1747250411.603 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2025-05-14T19:20:11.617Z,1747250411.617 [NavChart] Loaded
2025-05-14T19:20:11.617Z,1747250411.617 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-14T19:20:11.623Z,1747250411.623 [UniversalFixResidualReporter] Loaded
2025-05-14T19:20:11.623Z,1747250411.623 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-14T19:20:11.624Z,1747250411.624 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-14T19:20:11.625Z,1747250411.625 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-14T19:20:12.481Z,1747250412.481 [AHRS_M2] Loaded
2025-05-14T19:20:12.481Z,1747250412.481 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-14T19:20:12.734Z,1747250412.734 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3
2025-05-14T19:20:12.735Z,1747250412.735 [BackseatComponent] Loaded
2025-05-14T19:20:12.735Z,1747250412.735 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-14T19:20:12.736Z,1747250412.736 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0
2025-05-14T19:20:12.737Z,1747250412.737 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2025-05-14T19:20:12.740Z,1747250412.740 [LcmUniversalReporter] Loaded
2025-05-14T19:20:12.740Z,1747250412.740 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-14T19:20:13.931Z,1747250413.931 [BPC1] Loaded
2025-05-14T19:20:13.931Z,1747250413.931 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-14T19:20:14.029Z,1747250414.029 [DAT] Loaded
2025-05-14T19:20:14.029Z,1747250414.029 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-14T19:20:14.030Z,1747250414.030 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0
2025-05-14T19:20:14.031Z,1747250414.031 [DAT ThreadHandler](INFO): Protected caller Thread ID is 923
2025-05-14T19:20:14.171Z,1747250414.171 [DataOverHttps] Loaded
2025-05-14T19:20:14.172Z,1747250414.172 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-14T19:20:14.173Z,1747250414.173 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2025-05-14T19:20:14.173Z,1747250414.173 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2025-05-14T19:20:14.195Z,1747250414.195 [DepthKeller33X] Loaded
2025-05-14T19:20:14.196Z,1747250414.196 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread.
2025-05-14T19:20:14.201Z,1747250414.201 [DropWeight] Loaded
2025-05-14T19:20:14.201Z,1747250414.201 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-05-14T19:20:14.266Z,1747250414.266 [NAL9602] Loaded
2025-05-14T19:20:14.267Z,1747250414.267 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-14T19:20:14.297Z,1747250414.297 [Onboard] Loaded
2025-05-14T19:20:14.297Z,1747250414.297 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-14T19:20:14.298Z,1747250414.298 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2025-05-14T19:20:14.298Z,1747250414.298 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2025-05-14T19:20:14.312Z,1747250414.312 [Power24vConverter] Loaded
2025-05-14T19:20:14.313Z,1747250414.313 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-14T19:20:14.330Z,1747250414.330 [Radio_Surface] Loaded
2025-05-14T19:20:14.330Z,1747250414.330 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-14T19:20:14.331Z,1747250414.331 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0
2025-05-14T19:20:14.332Z,1747250414.332 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2025-05-14T19:20:14.379Z,1747250414.379 [RDI_Pathfinder] Loaded
2025-05-14T19:20:14.380Z,1747250414.380 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-05-14T19:20:14.395Z,1747250414.395 [Sonardyne_Nano] Loaded
2025-05-14T19:20:14.395Z,1747250414.395 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-05-14T19:20:14.396Z,1747250414.396 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-14T19:20:14.397Z,1747250414.397 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-14T19:20:14.469Z,1747250414.469 [DepthRateCalculator] Loaded
2025-05-14T19:20:14.470Z,1747250414.470 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-14T19:20:14.475Z,1747250414.475 [PitchRateCalculator] Loaded
2025-05-14T19:20:14.476Z,1747250414.476 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-14T19:20:14.485Z,1747250414.485 [SpeedCalculator] Loaded
2025-05-14T19:20:14.486Z,1747250414.486 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-14T19:20:14.490Z,1747250414.490 [YawRateCalculator] Loaded
2025-05-14T19:20:14.491Z,1747250414.491 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-14T19:20:14.514Z,1747250414.514 [ElevatorOffsetCalculator] Loaded
2025-05-14T19:20:14.514Z,1747250414.514 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-14T19:20:14.514Z,1747250414.514 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-14T19:20:14.515Z,1747250414.515 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-14T19:20:14.602Z,1747250414.602 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-14T19:20:14.603Z,1747250414.603 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-14T19:20:14.775Z,1747250414.775 [BuoyancyServo] Loaded
2025-05-14T19:20:14.775Z,1747250414.775 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-14T19:20:14.799Z,1747250414.799 [ElevatorServo] Loaded
2025-05-14T19:20:14.799Z,1747250414.799 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-14T19:20:14.823Z,1747250414.823 [MassServo] Loaded
2025-05-14T19:20:14.824Z,1747250414.824 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-14T19:20:14.847Z,1747250414.847 [RudderServo] Loaded
2025-05-14T19:20:14.847Z,1747250414.847 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-14T19:20:14.865Z,1747250414.865 [ThrusterHE] Loaded
2025-05-14T19:20:14.866Z,1747250414.866 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-14T19:20:14.866Z,1747250414.866 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-14T19:20:14.867Z,1747250414.867 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-14T19:20:15.092Z,1747250415.092 [CTD_Seabird] Loaded
2025-05-14T19:20:15.092Z,1747250415.092 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-14T19:20:15.093Z,1747250415.093 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A514E0
2025-05-14T19:20:15.094Z,1747250415.094 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2025-05-14T19:20:15.115Z,1747250415.115 [RBRTridente] Loaded
2025-05-14T19:20:15.115Z,1747250415.115 [ComponentRegistry](DEBUG): Component "RBRTridente" handled in its own thread.
2025-05-14T19:20:15.116Z,1747250415.116 [RBRTridente ThreadHandler](DEBUG): Created PCaller Thread at 40A814E0
2025-05-14T19:20:15.116Z,1747250415.116 [RBRTridente ThreadHandler](INFO): Protected caller Thread ID is 928
2025-05-14T19:20:15.118Z,1747250415.118 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-14T19:20:15.118Z,1747250415.118 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-14T19:20:15.285Z,1747250415.285 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-14T19:20:15.286Z,1747250415.286 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-14T19:20:15.510Z,1747250415.510 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-14T19:20:15.511Z,1747250415.511 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-14T19:20:16.154Z,1747250416.154 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-14T19:20:16.155Z,1747250416.155 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-14T19:20:16.229Z,1747250416.229 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-14T19:20:16.285Z,1747250416.285 [VerticalControl] Loaded
2025-05-14T19:20:16.285Z,1747250416.285 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-14T19:20:16.288Z,1747250416.288 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-14T19:20:16.337Z,1747250416.337 [HorizontalControl] Loaded
2025-05-14T19:20:16.337Z,1747250416.337 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-14T19:20:16.340Z,1747250416.340 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-14T19:20:16.341Z,1747250416.341 [SpeedControl] Loaded
2025-05-14T19:20:16.342Z,1747250416.342 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-14T19:20:16.344Z,1747250416.344 [LoopControl](DEBUG): Construct LoopControl.
2025-05-14T19:20:16.345Z,1747250416.345 [LoopControl] Loaded
2025-05-14T19:20:16.345Z,1747250416.345 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-14T19:20:16.346Z,1747250416.346 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-14T19:20:16.348Z,1747250416.348 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-14T19:20:16.552Z,1747250416.552 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-14T19:20:16.562Z,1747250416.562 [SBIT] Loaded
2025-05-14T19:20:16.562Z,1747250416.562 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-14T19:20:16.565Z,1747250416.565 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-14T19:20:16.579Z,1747250416.579 [IBIT] Loaded
2025-05-14T19:20:16.579Z,1747250416.579 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-14T19:20:16.584Z,1747250416.584 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-14T19:20:16.667Z,1747250416.667 [CBIT] Loaded
2025-05-14T19:20:16.668Z,1747250416.668 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-14T19:20:16.701Z,1747250416.701 [GFScanner] Loaded
2025-05-14T19:20:16.701Z,1747250416.701 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-05-14T19:20:16.702Z,1747250416.702 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-14T19:20:16.703Z,1747250416.703 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-14T19:20:16.752Z,1747250416.752 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-14T19:20:16.758Z,1747250416.758 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-14T19:20:16.761Z,1747250416.761 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-14T19:20:16.773Z,1747250416.773 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-14T19:20:16.774Z,1747250416.774 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C684E0
2025-05-14T19:20:16.774Z,1747250416.774 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2025-05-14T19:20:16.779Z,1747250416.779 [Supervisor](INFO): Main Thread ID is 826
2025-05-14T19:20:16.779Z,1747250416.779 [Supervisor](DEBUG): Running supervisor.
2025-05-14T19:20:16.780Z,1747250416.780 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2025-05-14T19:20:16.780Z,1747250416.780 [CommandExec](INFO): Initializing the command executive.
2025-05-14T19:20:16.782Z,1747250416.782 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2025-05-14T19:20:16.784Z,1747250416.784 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2025-05-14T19:20:16.784Z,1747250416.784 [controlThread](DEBUG): Initializing ControlThread
2025-05-14T19:20:16.786Z,1747250416.786 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-14T19:20:16.786Z,1747250416.786 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-14T19:20:16.790Z,1747250416.790 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-14T19:20:16.790Z,1747250416.790 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-14T19:20:16.791Z,1747250416.791 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-14T19:20:16.791Z,1747250416.791 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-14T19:20:16.791Z,1747250416.791 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-14T19:20:16.793Z,1747250416.793 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-14T19:20:16.795Z,1747250416.795 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-14T19:20:16.795Z,1747250416.795 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-14T19:20:16.796Z,1747250416.796 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-14T19:20:16.796Z,1747250416.796 [SBIT](INFO): Initialize SBIT Component.
2025-05-14T19:20:16.797Z,1747250416.797 [SBIT](IMPORTANT): git: 2025-05-14
2025-05-14T19:20:16.797Z,1747250416.797 [SBIT](INFO): git hash: 1f60f00c6ae091ed6c2c24e661e610633377ab6f
2025-05-14T19:20:16.797Z,1747250416.797 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-14T19:20:16.798Z,1747250416.798 [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-14T19:20:16.799Z,1747250416.799 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2025-05-14T19:20:16.800Z,1747250416.800 [IBIT](INFO): Initialize IBIT Component.
2025-05-14T19:20:16.801Z,1747250416.801 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-14T19:20:16.802Z,1747250416.802 [logger ThreadHandler](INFO): Handler Thread ID is 933
2025-05-14T19:20:16.813Z,1747250416.813 [CBIT](DEBUG): Initialized mux pins.
2025-05-14T19:20:16.813Z,1747250416.813 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-05-14T19:20:16.813Z,1747250416.813 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-14T19:20:16.821Z,1747250416.821 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2025-05-14T19:20:16.837Z,1747250416.837 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2025-05-14T19:20:16.837Z,1747250416.837 [CBIT](DEBUG): Initializing heartbeat.
2025-05-14T19:20:16.838Z,1747250416.838 [DAT ThreadHandler](INFO): Handler Thread ID is 935
2025-05-14T19:20:16.839Z,1747250416.839 [DAT](INFO): Powering up
2025-05-14T19:20:16.839Z,1747250416.839 [DAT](DEBUG): Initializing DAT.
2025-05-14T19:20:16.842Z,1747250416.842 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2025-05-14T19:20:16.844Z,1747250416.844 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-14T19:20:16.865Z,1747250416.865 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2025-05-14T19:20:16.883Z,1747250416.883 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2025-05-14T19:20:16.901Z,1747250416.901 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2025-05-14T19:20:16.902Z,1747250416.902 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-14T19:20:16.906Z,1747250416.906 [RBRTridente ThreadHandler](INFO): Handler Thread ID is 942
2025-05-14T19:20:16.907Z,1747250416.907 [RBRTridente](INFO): Start
2025-05-14T19:20:16.909Z,1747250416.909 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-14T19:20:16.910Z,1747250416.910 [RBRTridente](INFO): Powering up
2025-05-14T19:20:16.911Z,1747250416.911 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2025-05-14T19:20:16.922Z,1747250416.922 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-14T19:20:16.922Z,1747250416.922 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-14T19:20:16.922Z,1747250416.922 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-14T19:20:16.922Z,1747250416.922 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-14T19:20:16.922Z,1747250416.922 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-14T19:20:16.922Z,1747250416.922 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-14T19:20:16.923Z,1747250416.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-14T19:20:16.923Z,1747250416.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-14T19:20:16.949Z,1747250416.949 [CBIT](DEBUG): Backplane powered.
2025-05-14T19:20:16.949Z,1747250416.949 [GFScanner](DEBUG): Initializing GFScanner
2025-05-14T19:20:16.949Z,1747250416.949 [GFScanner](DEBUG): Deactivating GF circuits.
2025-05-14T19:20:16.954Z,1747250416.954 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-14T19:20:16.955Z,1747250416.955 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-14T19:20:16.955Z,1747250416.955 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-14T19:20:16.959Z,1747250416.959 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-14T19:20:17.018Z,1747250417.018 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-05-14T19:20:17.050Z,1747250417.050 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-14T19:20:17.072Z,1747250417.072 [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-14T19:20:17.072Z,1747250417.072 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-14T19:20:17.072Z,1747250417.072 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-14T19:20:17.100Z,1747250417.100 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-14T19:20:17.289Z,1747250417.289 [Radio_Surface](INFO): Powering up
2025-05-14T19:20:17.309Z,1747250417.309 [RBRTridente](INFO): Starting
2025-05-14T19:20:17.411Z,1747250417.411 [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-14T19:20:17.416Z,1747250417.416 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-14T19:20:17.434Z,1747250417.434 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-14T19:20:17.436Z,1747250417.436 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-14T19:20:17.481Z,1747250417.481 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-14T19:20:17.484Z,1747250417.484 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-14T19:20:17.506Z,1747250417.506 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-14T19:20:17.509Z,1747250417.509 [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-14T19:20:17.529Z,1747250417.529 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,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-14T19:20:17.536Z,1747250417.536 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-14T19:20:17.709Z,1747250417.709 [DepthKeller33X](INFO): Initializing.
2025-05-14T19:20:17.714Z,1747250417.714 [RBRTridente](INFO): Stop
2025-05-14T19:20:17.730Z,1747250417.730 [Power24vConverter](INFO): Powering up.
2025-05-14T19:20:17.730Z,1747250417.730 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-05-14T19:20:17.738Z,1747250417.738 [Sonardyne_Nano](INFO): Initializing.
2025-05-14T19:20:17.757Z,1747250417.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-14T19:20:17.769Z,1747250417.769 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-14T19:20:17.777Z,1747250417.777 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-14T19:20:17.778Z,1747250417.778 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-14T19:20:17.785Z,1747250417.785 [RBRTridente](INFO): Powering down
2025-05-14T19:20:17.787Z,1747250417.787 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-14T19:20:17.788Z,1747250417.788 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-14T19:20:17.793Z,1747250417.793 [MassServo](DEBUG): Initializing MassServo.
2025-05-14T19:20:17.794Z,1747250417.794 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-14T19:20:17.801Z,1747250417.801 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-14T19:20:17.802Z,1747250417.802 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-14T19:20:17.809Z,1747250417.809 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-14T19:20:17.811Z,1747250417.811 [CommandExec](FAULT): Scheduling is paused
2025-05-14T19:20:17.812Z,1747250417.812 [CBIT](INFO): Critical error at 20250514T192016
2025-05-14T19:20:17.812Z,1747250417.812 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-05-14T19:20:17.975Z,1747250417.975 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-14T19:20:18.117Z,1747250418.117 [RBRTridente](INFO): Stopping
2025-05-14T19:20:18.344Z,1747250418.344 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-14T19:20:18.751Z,1747250418.751 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-14T19:20:19.153Z,1747250419.153 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-14T19:20:19.579Z,1747250419.579 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-14T19:20:19.967Z,1747250419.967 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-05-14T19:20:21.216Z,1747250421.216 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:21.216Z,1747250421.216 [Power24vConverter] Hardware Fault, FailCount= 1
2025-05-14T19:20:21.216Z,1747250421.216 [Power24vConverter](ERROR): Hardware Fault
2025-05-14T19:20:21.217Z,1747250421.217 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:21.218Z,1747250421.218 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-05-14T19:20:21.223Z,1747250421.223 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-05-14T19:20:21.451Z,1747250421.451 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-05-14T19:20:21.617Z,1747250421.617 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:22.082Z,1747250422.082 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:22.436Z,1747250422.436 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:22.855Z,1747250422.855 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:23.263Z,1747250423.263 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:23.298Z,1747250423.298 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-05-14T19:20:23.642Z,1747250423.642 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:24.041Z,1747250424.041 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:24.450Z,1747250424.450 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:24.866Z,1747250424.866 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:25.275Z,1747250425.275 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:25.686Z,1747250425.686 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:26.067Z,1747250426.067 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:26.466Z,1747250426.466 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:26.835Z,1747250426.835 [Power24vConverter](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-14T19:20:27.242Z,1747250427.242 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:28.067Z,1747250428.067 [Power24vConverter](INFO): Powering up.
2025-05-14T19:20:28.106Z,1747250428.106 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-05-14T19:20:28.106Z,1747250428.106 [Power24vConverter] No Fault, FailCount= 1
2025-05-14T19:20:31.687Z,1747250431.687 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:31.687Z,1747250431.687 [Power24vConverter] Hardware Fault, FailCount= 1
2025-05-14T19:20:31.687Z,1747250431.687 [Power24vConverter](ERROR): Hardware Fault
2025-05-14T19:20:31.687Z,1747250431.687 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:31.688Z,1747250431.688 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-05-14T19:20:31.735Z,1747250431.735 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-05-14T19:20:32.100Z,1747250432.100 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:32.519Z,1747250432.519 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:32.895Z,1747250432.895 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:33.299Z,1747250433.299 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:33.706Z,1747250433.706 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:34.114Z,1747250434.114 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:34.522Z,1747250434.522 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:34.935Z,1747250434.935 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:35.346Z,1747250435.346 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:35.728Z,1747250435.728 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:36.133Z,1747250436.133 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:36.537Z,1747250436.537 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:36.955Z,1747250436.955 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:37.360Z,1747250437.360 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:37.770Z,1747250437.770 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:38.199Z,1747250438.199 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:38.557Z,1747250438.557 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:38.961Z,1747250438.961 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:39.366Z,1747250439.366 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:39.783Z,1747250439.783 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:40.198Z,1747250440.198 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:40.200Z,1747250440.200 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-05-14T19:20:40.200Z,1747250440.200 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-05-14T19:20:40.200Z,1747250440.200 [RDI_Pathfinder](ERROR): Communications Fault
2025-05-14T19:20:40.234Z,1747250440.234 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-05-14T19:20:40.575Z,1747250440.575 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:40.645Z,1747250440.645 [RDI_Pathfinder](INFO): Powering down
2025-05-14T19:20:40.980Z,1747250440.980 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:41.386Z,1747250441.386 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:41.399Z,1747250441.399 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-05-14T19:20:41.399Z,1747250441.399 [RDI_Pathfinder] No Fault, FailCount= 1
2025-05-14T19:20:41.802Z,1747250441.802 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:41.803Z,1747250441.803 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-05-14T19:20:42.214Z,1747250442.214 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:42.620Z,1747250442.620 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:42.997Z,1747250442.997 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:43.404Z,1747250443.404 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:43.807Z,1747250443.807 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:44.231Z,1747250444.231 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:44.639Z,1747250444.639 [NAL9602](INFO): Powering up NAL9602
2025-05-14T19:20:44.640Z,1747250444.640 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:45.023Z,1747250445.023 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:45.421Z,1747250445.421 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:45.834Z,1747250445.834 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:46.242Z,1747250446.242 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:46.659Z,1747250446.659 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:47.035Z,1747250447.035 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:47.440Z,1747250447.440 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:47.845Z,1747250447.845 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:48.258Z,1747250448.258 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:48.670Z,1747250448.670 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:49.090Z,1747250449.090 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:49.459Z,1747250449.459 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:49.867Z,1747250449.867 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:50.269Z,1747250450.269 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:50.682Z,1747250450.682 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:51.102Z,1747250451.102 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:51.478Z,1747250451.478 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:51.883Z,1747250451.883 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:52.290Z,1747250452.290 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:52.706Z,1747250452.706 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:53.119Z,1747250453.119 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:53.522Z,1747250453.522 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:53.906Z,1747250453.906 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:54.307Z,1747250454.307 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:54.714Z,1747250454.714 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:55.142Z,1747250455.142 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:55.535Z,1747250455.535 [NAL9602](INFO): NAL9602 initialized
2025-05-14T19:20:55.535Z,1747250455.535 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:55.951Z,1747250455.951 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:56.341Z,1747250456.341 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:56.365Z,1747250456.365 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-14T19:20:56.378Z,1747250456.378 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-14T19:20:56.746Z,1747250456.746 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:57.153Z,1747250457.153 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:57.553Z,1747250457.553 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:57.596Z,1747250457.596 [GFScanner](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2025-05-14T19:20:57.971Z,1747250457.971 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:58.391Z,1747250458.391 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:20:58.777Z,1747250458.777 [Power24vConverter](INFO): Powering down.
2025-05-14T19:20:59.574Z,1747250459.574 [Power24vConverter](INFO): Powering up.
2025-05-14T19:20:59.694Z,1747250459.694 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-05-14T19:20:59.694Z,1747250459.694 [Power24vConverter] No Fault, FailCount= 1
2025-05-14T19:21:00.892Z,1747250460.892 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.035463
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): 0.025408
CHAN A3 (5V): -0.021754
CHAN B0 (3.3V): 0.001001
CHAN B1 (3.15aV): -0.000361
CHAN B2 (3.15bV): 0.000033
CHAN B3 (GND): -0.007840
OPEN: 0.009416
Full Scale: +/- 1 mA
2025-05-14T19:21:04.020Z,1747250464.020 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-05-14T19:21:04.020Z,1747250464.020 [RDI_Pathfinder] Communications Fault, FailCount= 2
2025-05-14T19:21:04.020Z,1747250464.020 [RDI_Pathfinder](ERROR): Communications Fault
2025-05-14T19:21:04.154Z,1747250464.154 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-05-14T19:21:04.505Z,1747250464.505 [RDI_Pathfinder](INFO): Powering down
2025-05-14T19:21:05.343Z,1747250465.343 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-05-14T19:21:05.343Z,1747250465.343 [RDI_Pathfinder] No Fault, FailCount= 2
2025-05-14T19:21:05.634Z,1747250465.634 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:05.634Z,1747250465.634 [Power24vConverter] Hardware Fault, FailCount= 1
2025-05-14T19:21:05.634Z,1747250465.634 [Power24vConverter](ERROR): Hardware Fault
2025-05-14T19:21:05.634Z,1747250465.634 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:05.635Z,1747250465.635 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-05-14T19:21:05.636Z,1747250465.636 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-05-14T19:21:05.653Z,1747250465.653 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-05-14T19:21:06.036Z,1747250466.036 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:06.441Z,1747250466.441 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:06.869Z,1747250466.869 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:07.277Z,1747250467.277 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:07.687Z,1747250467.687 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:08.056Z,1747250468.056 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:08.462Z,1747250468.462 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:08.867Z,1747250468.867 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:09.284Z,1747250469.284 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:09.698Z,1747250469.698 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:10.075Z,1747250470.075 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:10.483Z,1747250470.483 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:10.897Z,1747250470.897 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:11.314Z,1747250471.314 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:11.719Z,1747250471.719 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:12.181Z,1747250472.181 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:12.591Z,1747250472.591 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:13.419Z,1747250473.419 [Power24vConverter](INFO): Powering up.
2025-05-14T19:21:13.517Z,1747250473.517 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-05-14T19:21:13.518Z,1747250473.518 [Power24vConverter] No Fault, FailCount= 1
2025-05-14T19:21:14.603Z,1747250474.603 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:14.603Z,1747250474.603 [Power24vConverter] Hardware Fault, FailCount= 1
2025-05-14T19:21:14.603Z,1747250474.603 [Power24vConverter](ERROR): Hardware Fault
2025-05-14T19:21:14.603Z,1747250474.603 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:14.604Z,1747250474.604 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-05-14T19:21:14.699Z,1747250474.699 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-05-14T19:21:15.015Z,1747250475.015 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:15.435Z,1747250475.435 [Power24vConverter](FAULT): LCB fault: Current Limiter Activated.
2025-05-14T19:21:15.813Z,1747250475.813 [Power24vConverter](INFO): Powering down.
2025-05-14T19:21:16.624Z,1747250476.624 [Power24vConverter](INFO): Powering up.
2025-05-14T19:21:16.721Z,1747250476.721 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-05-14T19:21:16.721Z,1747250476.721 [Power24vConverter] No Fault, FailCount= 1
2025-05-14T19:21:16.842Z,1747250476.842 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2025-05-14T19:21:16.842Z,1747250476.842 [DAT] Communications Fault, FailCount= 1
2025-05-14T19:21:16.842Z,1747250476.842 [DAT](ERROR): Communications Fault
2025-05-14T19:21:17.245Z,1747250477.245 [DAT](INFO): Powering down
2025-05-14T19:21:17.335Z,1747250477.335 [CBIT](ERROR): Communications Fault in component: DAT
2025-05-14T19:21:18.311Z,1747250478.311 [CBIT](INFO): Clearing failed state for component DAT
2025-05-14T19:21:18.311Z,1747250478.311 [DAT] No Fault, FailCount= 1
2025-05-14T19:21:20.297Z,1747250480.297 [DAT](INFO): Powering up
2025-05-14T19:21:20.297Z,1747250480.297 [DAT](DEBUG): Initializing DAT.
2025-05-14T19:21:24.716Z,1747250484.716 [BPC1](ERROR): BPC1B: No match for serial number 012A in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.718Z,1747250484.718 [BPC1](ERROR): BPC1B: No match for serial number 0121 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.720Z,1747250484.720 [BPC1](ERROR): BPC1B: No match for serial number 009E in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.721Z,1747250484.721 [BPC1](ERROR): BPC1B: No match for serial number 014C in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.722Z,1747250484.722 [BPC1](ERROR): BPC1B: No match for serial number 0111 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.724Z,1747250484.724 [BPC1](ERROR): BPC1B: No match for serial number 0110 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.725Z,1747250484.725 [BPC1](ERROR): BPC1B: No match for serial number 0132 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:24.726Z,1747250484.726 [BPC1](ERROR): BPC1B: No match for serial number 0148 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:21:25.101Z,1747250485.101 [BPC1](ERROR): BPC1A: No match for serial number 0102 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.102Z,1747250485.102 [BPC1](ERROR): BPC1A: No match for serial number 0108 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.103Z,1747250485.103 [BPC1](ERROR): BPC1A: No match for serial number 0093 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.104Z,1747250485.104 [BPC1](ERROR): BPC1A: No match for serial number 00AA in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.106Z,1747250485.106 [BPC1](ERROR): BPC1A: No match for serial number 00B8 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.107Z,1747250485.107 [BPC1](ERROR): BPC1A: No match for serial number 00D8 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.109Z,1747250485.109 [BPC1](ERROR): BPC1A: No match for serial number 00DB in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.110Z,1747250485.110 [BPC1](ERROR): BPC1A: No match for serial number 015C in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:21:25.130Z,1747250485.130 [BPC1](FAULT): Failed to parse data from all battery packs.
2025-05-14T19:21:25.130Z,1747250485.130 [BPC1] Data Fault, FailCount= 1
2025-05-14T19:21:25.130Z,1747250485.130 [BPC1](ERROR): Data Fault
2025-05-14T19:21:25.206Z,1747250485.206 [CBIT](ERROR): Data Fault in component: BPC1
2025-05-14T19:21:27.940Z,1747250487.940 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-05-14T19:21:27.940Z,1747250487.940 [RDI_Pathfinder] Communications Fault, FailCount= 3
2025-05-14T19:21:27.940Z,1747250487.940 [RDI_Pathfinder](ERROR): Communications Fault
2025-05-14T19:21:28.075Z,1747250488.075 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-05-14T19:21:28.075Z,1747250488.075 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2025-05-14T19:21:28.429Z,1747250488.429 [RDI_Pathfinder](INFO): Powering down
2025-05-14T19:21:28.444Z,1747250488.444 [CBIT](INFO): Critical error at 20250514T192128
2025-05-14T19:21:30.763Z,1747250490.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=10232, MT Status=0, MTMSN=0
2025-05-14T19:21:30.763Z,1747250490.763 [NAL9602](INFO): No messages in MT queue
2025-05-14T19:21:31.642Z,1747250491.642 [DAT](INFO): DAT read:
2025-05-14T19:21:31.643Z,1747250491.643 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-05-14T19:21:33.154Z,1747250493.154 [DAT](INFO): DAT read: MF Frequency Band
2025-05-14T19:21:33.156Z,1747250493.156 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.0
2025-05-14T19:21:33.156Z,1747250493.156 [DAT](INFO): DAT read: May 14 2025 19:21:25
2025-05-14T19:21:34.162Z,1747250494.162 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-05-14T19:21:34.163Z,1747250494.163 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-05-14T19:21:34.164Z,1747250494.164 [DAT](INFO): commRate: 600
2025-05-14T19:21:35.206Z,1747250495.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192130.00,A,3647.80622,N,12150.63357,W,0.933,0.00,140525,,,A*71
2025-05-14T19:21:35.209Z,1747250495.209 [NAL9602](INFO): GPS fix at 20250514T192130: (36.796770, -121.843893)
2025-05-14T19:21:36.229Z,1747250496.229 [DAT](INFO): entering command mode
2025-05-14T19:21:36.430Z,1747250496.430 [DAT](INFO): DAT read:
2025-05-14T19:21:36.431Z,1747250496.431 [DAT](INFO): DAT read: user:1>
2025-05-14T19:21:36.431Z,1747250496.431 [DAT](INFO): setting verbose to 3
2025-05-14T19:21:36.682Z,1747250496.682 [DAT](INFO): DAT read: user:1>
2025-05-14T19:21:36.683Z,1747250496.683 [DAT](INFO): DAT read: Verbose | 3
2025-05-14T19:21:36.683Z,1747250496.683 [DAT](INFO): set verbose to 3
2025-05-14T19:21:36.683Z,1747250496.683 [DAT](INFO): setting DatVerbose to 27440
2025-05-14T19:21:36.934Z,1747250496.934 [DAT](INFO): DAT read: user:2>
2025-05-14T19:21:36.935Z,1747250496.935 [DAT](INFO): DAT read: DatVerbose | 27440
2025-05-14T19:21:36.935Z,1747250496.935 [DAT](INFO): set DatVerbose to 27440
2025-05-14T19:21:36.936Z,1747250496.936 [DAT](INFO): setting transmit power to 8
2025-05-14T19:21:37.186Z,1747250497.186 [DAT](INFO): DAT read: user:3>
2025-05-14T19:21:37.187Z,1747250497.187 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-05-14T19:21:37.187Z,1747250497.187 [DAT](INFO): set transmit power to 8
2025-05-14T19:21:37.187Z,1747250497.187 [DAT](INFO): setting local address to 3
2025-05-14T19:21:37.438Z,1747250497.438 [DAT](INFO): DAT read: user:4>
2025-05-14T19:21:37.439Z,1747250497.439 [DAT](INFO): DAT read: LocalAddr | 3
2025-05-14T19:21:37.439Z,1747250497.439 [DAT](INFO): set local address to 3
2025-05-14T19:21:37.440Z,1747250497.440 [DAT](INFO): Setting time to: 19:21:37 And date to:5/14/2025
2025-05-14T19:21:37.695Z,1747250497.695 [DAT](INFO): DAT read: user:5>
2025-05-14T19:21:37.696Z,1747250497.696 [DAT](INFO): DAT read: Wed May 14, 2025 19:21:37
2025-05-14T19:21:37.696Z,1747250497.696 [DAT](INFO): Local DAT time set to Wed May 14, 2025 19:21:37
2025-05-14T19:21:50.596Z,1747250510.596 [SBIT](IMPORTANT): SBIT PASSED
2025-05-14T19:21:50.596Z,1747250510.596 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-05-14T19:21:51.001Z,1747250511.001 [MissionManager](IMPORTANT): Started mission Startup
2025-05-14T19:21:51.002Z,1747250511.002 [Startup] Running Loop=1
2025-05-14T19:21:51.002Z,1747250511.002 [Startup](DEBUG): Aggregate::initialize Startup
2025-05-14T19:21:51.002Z,1747250511.002 [Startup:A.GoToSurface] Running Loop=1
2025-05-14T19:21:51.002Z,1747250511.002 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-14T19:21:51.003Z,1747250511.003 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-14T19:21:51.003Z,1747250511.003 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-14T19:21:51.003Z,1747250511.003 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-14T19:21:51.004Z,1747250511.004 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-14T19:21:51.004Z,1747250511.004 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-14T19:21:51.004Z,1747250511.004 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-14T19:21:51.006Z,1747250511.006 [Startup:StartupSatComms] Running Loop=1
2025-05-14T19:21:51.006Z,1747250511.006 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-05-14T19:21:51.006Z,1747250511.006 [Startup:StartupSatComms:A] Running Loop=1
2025-05-14T19:21:51.402Z,1747250511.402 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-05-14T19:21:53.011Z,1747250513.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192147.00,A,3647.80339,N,12150.63192,W,2.391,181.09,140525,,,A*74
2025-05-14T19:21:53.014Z,1747250513.014 [NAL9602](INFO): GPS fix at 20250514T192147: (36.796723, -121.843865)
2025-05-14T19:21:53.026Z,1747250513.026 [Startup:StartupSatComms:A] Stopped
2025-05-14T19:21:53.026Z,1747250513.026 [Startup:StartupSatComms:B] Running Loop=1
2025-05-14T19:21:53.443Z,1747250513.443 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-14T19:22:14.770Z,1747250534.770 [DAT](INFO): DAT read: user:6>Rx Time:19:22:13.8191
2025-05-14T19:22:14.771Z,1747250534.771 [DAT](INFO): Rx dataTimestamp_ set to:1747250534.770304
2025-05-14T19:22:15.279Z,1747250535.279 [DAT](INFO): DAT read: 19:22:13.8191 LVL= 32752, 32753, 32754, 32755, AGC= 63, IDX= 343,-0.22,-0.842,-1.021,-2.399,-1.529, PHS= 0.721, 0.536,-0.798, RAW= 36.4, -6.5, CAL= 38.9, -9.7, ROT= 111.1, 9.7
2025-05-14T19:22:15.280Z,1747250535.280 [DAT](INFO): got valid direction response:
19:22:13.8191 LVL= 32752, 32753, 32754, 32755, AGC= 63, IDX= 343,-0.22,-0.842,-1.021,-2.399,-1.529, PHS= 0.721, 0.536,-0.798, RAW= 36.4, -6.5, CAL= 38.9, -9.7, ROT= 111.1, 9.7
2025-05-14T19:22:15.281Z,1747250535.281 [DAT](INFO): DAT read:
2025-05-14T19:22:15.281Z,1747250535.281 [DAT](INFO): DAT read: $Packet for address 5
2025-05-14T19:22:15.282Z,1747250535.282 [DAT](INFO): received a packet notification
2025-05-14T19:22:15.293Z,1747250535.293 [DAT](INFO): #Rx 1: Read direction message, but no range.
2025-05-14T19:22:15.294Z,1747250535.294 [DAT](INFO): direction in FSK: [-0.354850,0.919616,-0.168489]
2025-05-14T19:22:16.495Z,1747250536.495 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004132
2025-05-14T19:22:18.267Z,1747250538.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=10233, MT Status=2, MTMSN=0
2025-05-14T19:22:18.267Z,1747250538.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-14T19:22:24.080Z,1747250544.080 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250514T182757/Courier0024.lzma
2025-05-14T19:22:25.083Z,1747250545.083 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0024.lzma.bak
2025-05-14T19:22:25.083Z,1747250545.083 [DataOverHttps](INFO): SBD MOMSN=24875156
2025-05-14T19:22:38.249Z,1747250558.249 [DAT](INFO): DAT read: Rx Time:19:22:38.7652
2025-05-14T19:22:38.249Z,1747250558.249 [DAT](INFO): Rx dataTimestamp_ set to:1747250558.248994
2025-05-14T19:22:38.757Z,1747250558.757 [DAT](INFO): DAT read: 19:22:38.7652 LVL= 18144, 21729, 27298, 17299, AGC= 65, IDX= 418,-0.45, 2.315, 1.739, 0.780, 1.234, PHS= 1.115, 0.534,-0.382, RAW= 52.6, -18.9, CAL= 58.6, -19.4, ROT= 91.4, 19.4
2025-05-14T19:22:38.759Z,1747250558.759 [DAT](INFO): got valid direction response:
19:22:38.7652 LVL= 18144, 21729, 27298, 17299, AGC= 65, IDX= 418,-0.45, 2.315, 1.739, 0.780, 1.234, PHS= 1.115, 0.534,-0.382, RAW= 52.6, -18.9, CAL= 58.6, -19.4, ROT= 91.4, 19.4
2025-05-14T19:22:38.759Z,1747250558.759 [DAT](INFO): DAT read:
2025-05-14T19:22:38.760Z,1747250558.760 [DAT](INFO): DAT read: $Packet for address 5
2025-05-14T19:22:38.760Z,1747250558.760 [DAT](INFO): received a packet notification
2025-05-14T19:22:38.772Z,1747250558.772 [DAT](INFO): #Rx 2: Read direction message, but no range.
2025-05-14T19:22:38.773Z,1747250558.773 [DAT](INFO): direction in FSK: [-0.023045,0.942941,-0.332161]
2025-05-14T19:22:39.577Z,1747250559.577 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20250514T192004/Courier0000.lzma
2025-05-14T19:22:40.578Z,1747250560.578 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Courier0000.lzma.bak
2025-05-14T19:22:40.578Z,1747250560.578 [DataOverHttps](INFO): SBD MOMSN=24875159
2025-05-14T19:22:48.974Z,1747250568.974 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-14T19:22:53.448Z,1747250573.448 [Startup:StartupSatComms:B](INFO): Timed out from 2025-05-14T19:21:53.0Z
2025-05-14T19:22:53.448Z,1747250573.448 [Startup:StartupSatComms:B] Stopped
2025-05-14T19:22:53.448Z,1747250573.448 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-05-14T19:22:53.448Z,1747250573.448 [Startup:StartupSatComms] Stopped
2025-05-14T19:22:53.449Z,1747250573.449 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-05-14T19:22:53.450Z,1747250573.450 [Startup](INFO): Completed Startup
2025-05-14T19:22:53.450Z,1747250573.450 [MissionManager](INFO): Startup is completed.
2025-05-14T19:22:53.450Z,1747250573.450 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-14T19:22:53.450Z,1747250573.450 [Startup] Stopped
2025-05-14T19:22:53.450Z,1747250573.450 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-05-14T19:22:53.450Z,1747250573.450 [Startup:A.GoToSurface] Stopped
2025-05-14T19:22:53.450Z,1747250573.450 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-14T19:22:53.839Z,1747250573.839 [MissionManager](IMPORTANT): Started mission Default
2025-05-14T19:22:53.839Z,1747250573.839 [Default] Running Loop=1
2025-05-14T19:22:53.839Z,1747250573.839 [Default](DEBUG): Aggregate::initialize Default
2025-05-14T19:22:53.839Z,1747250573.839 [Default:B.GoToSurface] Running Loop=1
2025-05-14T19:22:53.839Z,1747250573.839 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-14T19:22:53.856Z,1747250573.856 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-14T19:22:53.856Z,1747250573.856 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-14T19:22:53.856Z,1747250573.856 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-14T19:22:53.857Z,1747250573.857 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-14T19:22:53.857Z,1747250573.857 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-14T19:22:53.857Z,1747250573.857 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-14T19:22:53.857Z,1747250573.857 [Default:A.Wait] Running Loop=1
2025-05-14T19:22:53.858Z,1747250573.858 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-14T19:22:54.129Z,1747250574.129 [DAT](INFO): DAT read: Rx Time:19:22:54.5142
2025-05-14T19:22:54.129Z,1747250574.129 [DAT](INFO): Rx dataTimestamp_ set to:1747250574.129021
2025-05-14T19:22:54.641Z,1747250574.641 [DAT](INFO): DAT read: 19:22:54.5142 LVL= 19792, 21473, 22386, 14851, AGC= 71, IDX= 464, 0.13, 2.828, 2.505, 1.237, 2.075, PHS= 0.787, 0.458,-0.766, RAW= 41.6, -6.8, CAL= 44.3, -9.8, ROT= 105.7, 9.8
2025-05-14T19:22:54.643Z,1747250574.643 [DAT](INFO): got valid direction response:
19:22:54.5142 LVL= 19792, 21473, 22386, 14851, AGC= 71, IDX= 464, 0.13, 2.828, 2.505, 1.237, 2.075, PHS= 0.787, 0.458,-0.766, RAW= 41.6, -6.8, CAL= 44.3, -9.8, ROT= 105.7, 9.8
2025-05-14T19:22:54.697Z,1747250574.697 [DAT](INFO): DAT read: PEAK , IDX= 472,-0.25, 0.793, 0.418,-0.841, 0.037, PHS= 0.789, 0.409,-0.806, RAW= 43.2, -5.5, CAL= 45.7, -8.6, ROT= 104.3, 8.6, FLG=0x0001
2025-05-14T19:22:54.698Z,1747250574.698 [DAT](INFO): unknown deviceResponse_: PEAK , IDX= 472,-0.25, 0.793, 0.418,-0.841, 0.037, PHS= 0.789, 0.409,-0.806, RAW= 43.2, -5.5, CAL= 45.7, -8.6, ROT= 104.3, 8.6, FLG=0x0001
2025-05-14T19:22:54.698Z,1747250574.698 [DAT](INFO): DAT read:
2025-05-14T19:22:54.699Z,1747250574.699 [DAT](INFO): DAT read: $Packet for address 5
2025-05-14T19:22:54.699Z,1747250574.699 [DAT](INFO): received a packet notification
2025-05-14T19:22:54.712Z,1747250574.712 [DAT](INFO): #Rx 3: Read direction message, but no range.
2025-05-14T19:22:54.713Z,1747250574.713 [DAT](INFO): direction in FSK: [-0.266652,0.948644,-0.170209]
2025-05-14T19:22:57.489Z,1747250577.489 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250514T182757/Express0025.lzma
2025-05-14T19:22:58.490Z,1747250578.490 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0025.lzma.bak
2025-05-14T19:22:58.490Z,1747250578.490 [DataOverHttps](INFO): SBD MOMSN=24875167
2025-05-14T19:23:07.165Z,1747250587.165 [Default:A.Wait](INFO): Done Waiting.
2025-05-14T19:23:07.165Z,1747250587.165 [Default:A.Wait] Stopped
2025-05-14T19:23:07.166Z,1747250587.166 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-14T19:23:07.582Z,1747250587.582 [Default:CheckIn] Running Loop=1
2025-05-14T19:23:07.582Z,1747250587.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-14T19:23:07.582Z,1747250587.582 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-14T19:23:08.007Z,1747250588.007 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-14T19:23:09.587Z,1747250589.587 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192305.00,A,3647.80295,N,12150.62499,W,0.603,142.79,140525,,,A*7C
2025-05-14T19:23:09.590Z,1747250589.590 [NAL9602](INFO): GPS fix at 20250514T192305: (36.796716, -121.843750)
2025-05-14T19:23:09.621Z,1747250589.621 [Default:CheckIn:Read_GPS] Stopped
2025-05-14T19:23:09.621Z,1747250589.621 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-14T19:23:10.016Z,1747250590.016 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-14T19:23:16.883Z,1747250596.883 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2025-05-14T19:23:16.883Z,1747250596.883 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-05-14T19:23:16.911Z,1747250596.911 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-05-14T19:23:17.287Z,1747250597.287 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-05-14T19:23:17.287Z,1747250597.287 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2025-05-14T19:23:18.151Z,1747250598.151 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250514T192004/Courier0004.lzma
2025-05-14T19:23:18.593Z,1747250598.593 [DAT](INFO): DAT read: Rx Time:19:23:19.2110
2025-05-14T19:23:18.594Z,1747250598.594 [DAT](INFO): Rx dataTimestamp_ set to:1747250598.593212
2025-05-14T19:23:19.154Z,1747250599.154 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Courier0004.lzma.bak
2025-05-14T19:23:19.154Z,1747250599.154 [DataOverHttps](INFO): SBD MOMSN=24875172
2025-05-14T19:23:19.349Z,1747250599.349 [DAT](INFO): DAT read:
2025-05-14T19:23:19.349Z,1747250599.349 [DAT](INFO): DAT read: $Packet for address 5
2025-05-14T19:23:19.350Z,1747250599.350 [DAT](INFO): received a packet notification
2025-05-14T19:23:35.803Z,1747250615.803 [DataOverHttps](INFO): Sending 1366 bytes from file Logs/20250514T192004/Express0001.lzma
2025-05-14T19:23:36.914Z,1747250616.914 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Express0001.lzma.bak
2025-05-14T19:23:36.914Z,1747250616.914 [DataOverHttps](INFO): SBD MOMSN=24875175
2025-05-14T19:23:39.785Z,1747250619.785 [DAT](INFO): DAT read: Rx Time:19:23:40.1581
2025-05-14T19:23:39.786Z,1747250619.786 [DAT](INFO): Rx dataTimestamp_ set to:1747250619.785712
2025-05-14T19:23:40.301Z,1747250620.301 [DAT](INFO): DAT read: $Error in header
2025-05-14T19:23:40.302Z,1747250620.302 [DAT](INFO): Received a bad header
2025-05-14T19:23:53.653Z,1747250633.653 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20250514T192004/Express0005.lzma
2025-05-14T19:23:54.654Z,1747250634.654 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Express0005.lzma.bak
2025-05-14T19:23:54.655Z,1747250634.655 [DataOverHttps](INFO): SBD MOMSN=24875253
2025-05-14T19:23:56.070Z,1747250636.070 [Default:CheckIn:Read_Iridium] Stopped
2025-05-14T19:23:56.070Z,1747250636.070 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-14T19:23:56.071Z,1747250636.071 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-14T19:24:04.517Z,1747250644.517 [DAT](INFO): DAT read: Rx Time:19:24:04.9060
2025-05-14T19:24:04.517Z,1747250644.517 [DAT](INFO): Rx dataTimestamp_ set to:1747250644.516920
2025-05-14T19:24:05.026Z,1747250645.026 [DAT](INFO): DAT read: 19:24:04.9060 LVL= 9424, 14769, 13586, 8435, AGC= 66, IDX= 456, 0.37,-2.311,-2.916, 2.010, 2.704, PHS= 1.302, 0.692,-0.622, RAW= 48.1, -15.9, CAL= 53.2, -17.3, ROT= 96.8, 17.3
2025-05-14T19:24:05.027Z,1747250645.027 [DAT](INFO): got valid direction response:
19:24:04.9060 LVL= 9424, 14769, 13586, 8435, AGC= 66, IDX= 456, 0.37,-2.311,-2.916, 2.010, 2.704, PHS= 1.302, 0.692,-0.622, RAW= 48.1, -15.9, CAL= 53.2, -17.3, ROT= 96.8, 17.3
2025-05-14T19:24:05.027Z,1747250645.027 [DAT](INFO): DAT read:
2025-05-14T19:24:05.028Z,1747250645.028 [DAT](INFO): DAT read: $Packet for address 5
2025-05-14T19:24:05.029Z,1747250645.029 [DAT](INFO): received a packet notification
2025-05-14T19:24:05.063Z,1747250645.063 [DAT](INFO): #Rx 4: Read direction message, but no range.
2025-05-14T19:24:05.105Z,1747250645.105 [DAT](INFO): direction in FSK: [-0.113048,0.948045,-0.297375]
2025-05-14T19:24:28.037Z,1747250668.037 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-05-14T19:24:28.037Z,1747250668.037 [RDI_Pathfinder] No Fault, FailCount= 3
2025-05-14T19:24:28.391Z,1747250668.391 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-05-14T19:24:40.917Z,1747250680.917 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2025-05-14T19:24:40.918Z,1747250680.918 [RDI_Pathfinder] Data Fault, FailCount= 1
2025-05-14T19:24:40.918Z,1747250680.918 [RDI_Pathfinder](ERROR): Data Fault
2025-05-14T19:24:40.939Z,1747250680.939 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2025-05-14T19:24:41.384Z,1747250681.384 [RDI_Pathfinder](INFO): Powering down
2025-05-14T19:24:42.157Z,1747250682.157 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-05-14T19:24:42.157Z,1747250682.157 [RDI_Pathfinder] No Fault, FailCount= 1
2025-05-14T19:24:42.550Z,1747250682.550 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-05-14T19:24:44.979Z,1747250684.979 [NAL9602](INFO): SBD MO Status=1, MOMSN=10233, MT Status=0, MTMSN=0
2025-05-14T19:24:44.979Z,1747250684.979 [NAL9602](INFO): No messages in MT queue
2025-05-14T19:25:00.910Z,1747250700.910 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder
2025-05-14T19:25:15.722Z,1747250715.722 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-14T19:26:17.950Z,1747250777.950 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2025-05-14T19:26:17.950Z,1747250777.950 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-05-14T19:26:17.983Z,1747250777.983 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-05-14T19:26:18.346Z,1747250778.346 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-05-14T19:26:18.346Z,1747250778.346 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2025-05-14T19:27:28.449Z,1747250848.449 [DAT](INFO): DAT read: Rx Time:19:27:28.9489
2025-05-14T19:27:28.449Z,1747250848.449 [DAT](INFO): Rx dataTimestamp_ set to:1747250848.449042
2025-05-14T19:27:28.962Z,1747250848.962 [DAT](INFO): DAT read: 19:27:28.9489 LVL= 14640, 14257, 9666, 8083, AGC= 68, IDX= 401,-0.41, 2.087, 1.123, 0.692, 1.244, PHS= 0.877,-0.092,-0.480, RAW= 73.9, -5.1, CAL= 75.6, -7.8, ROT= 74.4, 7.8
2025-05-14T19:27:28.963Z,1747250848.963 [DAT](INFO): got valid direction response:
19:27:28.9489 LVL= 14640, 14257, 9666, 8083, AGC= 68, IDX= 401,-0.41, 2.087, 1.123, 0.692, 1.244, PHS= 0.877,-0.092,-0.480, RAW= 73.9, -5.1, CAL= 75.6, -7.8, ROT= 74.4, 7.8
2025-05-14T19:27:28.965Z,1747250848.965 [DAT](INFO): DAT read: PEAK , IDX= 406, 0.36, 1.571, 1.285, 0.773, 1.260, PHS= 0.345, 0.054,-0.415, FLG=0x0001
2025-05-14T19:27:28.966Z,1747250848.966 [DAT](INFO): unknown deviceResponse_: PEAK , IDX= 406, 0.36, 1.571, 1.285, 0.773, 1.260, PHS= 0.345, 0.054,-0.415, FLG=0x0001
2025-05-14T19:27:28.966Z,1747250848.966 [DAT](INFO): #Rx 5: Read direction message, but no range.
2025-05-14T19:27:28.967Z,1747250848.967 [DAT](INFO): direction in FSK: [0.266432,0.954251,-0.135716]
2025-05-14T19:27:29.213Z,1747250849.213 [DAT](INFO): DAT read: Tx time:19:27:30.1384
2025-05-14T19:27:29.214Z,1747250849.214 [DAT](INFO): Ping request sent.
2025-05-14T19:27:29.214Z,1747250849.214 [DAT](INFO): transmitted an acoustic signal
2025-05-14T19:27:29.214Z,1747250849.214 [DAT](INFO): publishing transmit ping time
2025-05-14T19:28:56.651Z,1747250936.651 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-14T19:28:56.651Z,1747250936.651 [Default:CheckIn:C.Wait] Stopped
2025-05-14T19:28:56.651Z,1747250936.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-14T19:28:56.652Z,1747250936.652 [Default:CheckIn:D] Running Loop=1
2025-05-14T19:28:57.083Z,1747250937.083 [Default:CheckIn:D] Stopped
2025-05-14T19:28:57.083Z,1747250937.083 [Default:CheckIn:E] Running Loop=1
2025-05-14T19:28:57.464Z,1747250937.464 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.054063 min
2025-05-14T19:28:57.464Z,1747250937.464 [Default:CheckIn:E] Stopped
2025-05-14T19:28:57.464Z,1747250937.464 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-14T19:28:57.464Z,1747250937.464 [Default:CheckIn] Stopped
2025-05-14T19:28:57.464Z,1747250937.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-14T19:28:57.464Z,1747250937.464 [Default:CheckIn](INFO): Running loop #2
2025-05-14T19:28:57.465Z,1747250937.465 [Default:CheckIn] Running Loop=2
2025-05-14T19:28:57.465Z,1747250937.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-14T19:28:57.465Z,1747250937.465 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-14T19:28:59.464Z,1747250939.464 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192858.00,A,3647.80077,N,12150.59570,W,0.641,163.31,140525,,,D*73
2025-05-14T19:28:59.466Z,1747250939.466 [NAL9602](INFO): GPS fix at 20250514T192858: (36.796680, -121.843262)
2025-05-14T19:28:59.502Z,1747250939.502 [Default:CheckIn:Read_GPS] Stopped
2025-05-14T19:28:59.502Z,1747250939.502 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-14T19:29:10.844Z,1747250950.844 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250514T192004/Courier0007.lzma
2025-05-14T19:29:11.848Z,1747250951.848 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Courier0007.lzma.bak
2025-05-14T19:29:11.848Z,1747250951.848 [DataOverHttps](INFO): SBD MOMSN=24875364
2025-05-14T19:29:18.072Z,1747250958.072 [NAL9602](INFO): SBD MO Status=1, MOMSN=10234, MT Status=0, MTMSN=0
2025-05-14T19:29:18.072Z,1747250958.072 [NAL9602](INFO): No messages in MT queue
2025-05-14T19:29:18.923Z,1747250958.923 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2025-05-14T19:29:18.923Z,1747250958.923 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-05-14T19:29:18.935Z,1747250958.935 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-05-14T19:29:19.304Z,1747250959.304 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-05-14T19:29:19.304Z,1747250959.304 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2025-05-14T19:29:29.485Z,1747250969.485 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250514T192004/Express0008.lzma
2025-05-14T19:29:30.487Z,1747250970.487 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Express0008.lzma.bak
2025-05-14T19:29:30.487Z,1747250970.487 [DataOverHttps](INFO): SBD MOMSN=24875367
2025-05-14T19:29:32.220Z,1747250972.220 [Default:CheckIn:Read_Iridium] Stopped
2025-05-14T19:29:32.220Z,1747250972.220 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-14T19:29:32.220Z,1747250972.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-14T19:29:48.850Z,1747250988.850 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-14T19:31:26.438Z,1747251086.438 [CBIT](INFO): Clearing failed state for component BPC1
2025-05-14T19:31:26.438Z,1747251086.438 [BPC1] No Fault, FailCount= 1
2025-05-14T19:32:20.078Z,1747251140.078 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2025-05-14T19:32:20.078Z,1747251140.078 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-05-14T19:32:20.106Z,1747251140.106 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-05-14T19:32:20.503Z,1747251140.503 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-05-14T19:32:20.503Z,1747251140.503 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2025-05-14T19:32:34.167Z,1747251154.167 [BPC1](ERROR): BPC1A: No match for serial number 0102 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.171Z,1747251154.171 [BPC1](ERROR): BPC1A: No match for serial number 0108 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.174Z,1747251154.174 [BPC1](ERROR): BPC1A: No match for serial number 0093 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.180Z,1747251154.180 [BPC1](ERROR): BPC1A: No match for serial number 00AA in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.183Z,1747251154.183 [BPC1](ERROR): BPC1A: No match for serial number 00B8 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.187Z,1747251154.187 [BPC1](ERROR): BPC1A: No match for serial number 00D8 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.206Z,1747251154.206 [BPC1](ERROR): BPC1A: No match for serial number 00DB in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.215Z,1747251154.215 [BPC1](ERROR): BPC1A: No match for serial number 015C in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-14T19:32:34.227Z,1747251154.227 [BPC1](ERROR): BPC1B: No match for serial number 012A in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.235Z,1747251154.235 [BPC1](ERROR): BPC1B: No match for serial number 0121 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.245Z,1747251154.245 [BPC1](ERROR): BPC1B: No match for serial number 009E in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.247Z,1747251154.247 [BPC1](ERROR): BPC1B: No match for serial number 014C in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.248Z,1747251154.248 [BPC1](ERROR): BPC1B: No match for serial number 0111 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.254Z,1747251154.254 [BPC1](ERROR): BPC1B: No match for serial number 0110 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.255Z,1747251154.255 [BPC1](ERROR): BPC1B: No match for serial number 0132 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.256Z,1747251154.256 [BPC1](ERROR): BPC1B: No match for serial number 0148 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-05-14T19:32:34.256Z,1747251154.256 [BPC1](FAULT): Failed to parse data from all battery packs.
2025-05-14T19:32:34.256Z,1747251154.256 [BPC1] Data Fault, FailCount= 2
2025-05-14T19:32:34.256Z,1747251154.256 [BPC1](ERROR): Data Fault
2025-05-14T19:32:34.397Z,1747251154.397 [CBIT](ERROR): Data Fault in component: BPC1
2025-05-14T19:34:32.677Z,1747251272.677 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-14T19:34:32.677Z,1747251272.677 [Default:CheckIn:C.Wait] Stopped
2025-05-14T19:34:32.677Z,1747251272.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-14T19:34:32.678Z,1747251272.678 [Default:CheckIn:D] Running Loop=1
2025-05-14T19:34:33.010Z,1747251273.010 [Default:CheckIn:D] Stopped
2025-05-14T19:34:33.010Z,1747251273.010 [Default:CheckIn:E] Running Loop=1
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.652852 min
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn:E] Stopped
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn] Stopped
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn](INFO): Running loop #3
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn] Running Loop=3
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-14T19:34:33.419Z,1747251273.419 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-14T19:34:35.428Z,1747251275.428 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193434.00,A,3647.79459,N,12150.56399,W,1.108,66.98,140525,,,D*48
2025-05-14T19:34:35.431Z,1747251275.431 [NAL9602](INFO): GPS fix at 20250514T193434: (36.796577, -121.842733)
2025-05-14T19:34:35.442Z,1747251275.442 [Default:CheckIn:Read_GPS] Stopped
2025-05-14T19:34:35.442Z,1747251275.442 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-14T19:34:45.105Z,1747251285.105 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250514T192004/Courier0010.lzma
2025-05-14T19:34:50.400Z,1747251290.400 [NAL9602](INFO): SBD MO Status=1, MOMSN=10235, MT Status=0, MTMSN=0
2025-05-14T19:34:50.400Z,1747251290.400 [NAL9602](INFO): No messages in MT queue
2025-05-14T19:34:52.137Z,1747251292.137 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Courier0010.lzma.bak
2025-05-14T19:34:52.138Z,1747251292.138 [DataOverHttps](INFO): SBD MOMSN=24875400
2025-05-14T19:35:09.013Z,1747251309.013 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2025-05-14T19:35:09.013Z,1747251309.013 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2025-05-14T19:35:09.013Z,1747251309.013 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-05-14T19:35:09.062Z,1747251309.062 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-05-14T19:35:09.404Z,1747251309.404 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-05-14T19:35:09.404Z,1747251309.404 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2025-05-14T19:35:10.185Z,1747251310.185 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2025-05-14T19:35:10.185Z,1747251310.185 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2025-05-14T19:35:10.185Z,1747251310.185 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-05-14T19:35:10.200Z,1747251310.200 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-05-14T19:35:10.668Z,1747251310.668 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-05-14T19:35:10.668Z,1747251310.668 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2025-05-14T19:35:11.035Z,1747251311.035 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250514T192004/Express0011.lzma
2025-05-14T19:35:12.039Z,1747251312.039 [DataOverHttps](INFO): Moved sent file to Logs/20250514T192004/Express0011.lzma.bak
2025-05-14T19:35:12.040Z,1747251312.040 [DataOverHttps](INFO): SBD MOMSN=24875405
2025-05-14T19:35:13.822Z,1747251313.822 [Default:CheckIn:Read_Iridium] Stopped
2025-05-14T19:35:13.822Z,1747251313.822 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-14T19:35:13.822Z,1747251313.822 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-14T19:35:21.097Z,1747251321.097 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-14T19:35:54.109Z,1747251354.109 [DataOverHttps](IMPORTANT): SBD MTMSN=20250514T193552
2025-05-14T19:36:03.197Z,1747251363.197 [DataOverHttps](INFO): Received command: restart sys
2025-05-14T19:36:03.246Z,1747251363.246 [CommandExec](IMPORTANT): got command restart system
2025-05-14T19:36:05.320Z,1747251365.320 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-14T19:36:05.320Z,1747251365.320 [CommandExec](INFO): Uninitializing the command executive.