2024-10-14T21:21:38.367Z,1728940898.367 [Supervisor](DEBUG): Initializing supervisor.
2024-10-14T21:21:38.371Z,1728940898.371 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-10-14T21:21:38.372Z,1728940898.372 [SyncHandler](INFO): Protected caller Thread ID is 1277
2024-10-14T21:21:38.372Z,1728940898.372 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-10-14T21:21:38.373Z,1728940898.373 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-10-14T21:21:38.374Z,1728940898.374 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1278
2024-10-14T21:21:38.389Z,1728940898.389 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-10-14T21:21:38.408Z,1728940898.408 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-10-14T21:21:38.409Z,1728940898.409 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-10-14T21:21:38.409Z,1728940898.409 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1279
2024-10-14T21:21:38.414Z,1728940898.414 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-10-14T21:21:38.415Z,1728940898.415 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-10-14T21:21:38.415Z,1728940898.415 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1280
2024-10-14T21:21:38.417Z,1728940898.417 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-10-14T21:21:38.418Z,1728940898.418 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-10-14T21:21:38.418Z,1728940898.418 [logger ThreadHandler](INFO): Protected caller Thread ID is 1281
2024-10-14T21:21:38.422Z,1728940898.422 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-10-14T21:21:38.423Z,1728940898.423 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-10-14T21:21:38.427Z,1728940898.427 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-10-14T21:21:38.953Z,1728940898.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-10-14T21:21:38.953Z,1728940898.953 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-10-14T21:21:39.056Z,1728940899.056 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-10-14T21:21:39.187Z,1728940899.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-10-14T21:21:39.188Z,1728940899.188 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-10-14T21:21:39.342Z,1728940899.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-10-14T21:21:39.343Z,1728940899.343 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-10-14T21:21:39.744Z,1728940899.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-10-14T21:21:39.744Z,1728940899.744 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-10-14T21:21:39.862Z,1728940899.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-10-14T21:21:39.863Z,1728940899.863 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-10-14T21:21:40.346Z,1728940900.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-10-14T21:21:40.347Z,1728940900.347 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-10-14T21:21:40.603Z,1728940900.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-10-14T21:21:40.605Z,1728940900.605 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-10-14T21:21:40.703Z,1728940900.703 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-10-14T21:21:40.999Z,1728940900.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-10-14T21:21:41.001Z,1728940901.001 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-10-14T21:21:41.263Z,1728940901.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-10-14T21:21:41.264Z,1728940901.264 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-10-14T21:21:41.880Z,1728940901.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-10-14T21:21:41.881Z,1728940901.881 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-10-14T21:21:42.134Z,1728940902.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-10-14T21:21:42.136Z,1728940902.136 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-10-14T21:21:42.471Z,1728940902.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-10-14T21:21:42.473Z,1728940902.473 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-10-14T21:21:43.449Z,1728940903.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-10-14T21:21:43.451Z,1728940903.451 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-10-14T21:21:44.109Z,1728940904.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-10-14T21:21:44.112Z,1728940904.112 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-10-14T21:21:44.115Z,1728940904.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-10-14T21:21:44.238Z,1728940904.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-10-14T21:21:44.379Z,1728940904.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-10-14T21:21:44.481Z,1728940904.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-10-14T21:21:44.614Z,1728940904.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-10-14T21:21:44.720Z,1728940904.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-10-14T21:21:44.999Z,1728940904.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-10-14T21:21:45.001Z,1728940905.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-10-14T21:21:45.134Z,1728940905.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-10-14T21:21:45.254Z,1728940905.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-10-14T21:21:45.395Z,1728940905.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-10-14T21:21:45.566Z,1728940905.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-10-14T21:21:45.671Z,1728940905.671 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-10-14T21:21:45.696Z,1728940905.696 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-10-14T21:21:45.836Z,1728940905.836 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-10-14T21:21:45.837Z,1728940905.837 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-10-14T21:21:45.908Z,1728940905.908 [VerticalControl](DEBUG): Construct VerticalControl.
2024-10-14T21:21:45.969Z,1728940905.969 [VerticalControl] Loaded
2024-10-14T21:21:45.969Z,1728940905.969 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-10-14T21:21:45.972Z,1728940905.972 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-10-14T21:21:46.020Z,1728940906.020 [HorizontalControl] Loaded
2024-10-14T21:21:46.020Z,1728940906.020 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-10-14T21:21:46.023Z,1728940906.023 [SpeedControl](DEBUG): Construct SpeedControl.
2024-10-14T21:21:46.026Z,1728940906.026 [SpeedControl] Loaded
2024-10-14T21:21:46.026Z,1728940906.026 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-10-14T21:21:46.029Z,1728940906.029 [LoopControl](DEBUG): Construct LoopControl.
2024-10-14T21:21:46.029Z,1728940906.029 [LoopControl] Loaded
2024-10-14T21:21:46.030Z,1728940906.030 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-10-14T21:21:46.030Z,1728940906.030 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-10-14T21:21:46.031Z,1728940906.031 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-10-14T21:21:46.127Z,1728940906.127 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-10-14T21:21:46.128Z,1728940906.128 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-10-14T21:21:46.952Z,1728940906.952 [AHRS_M2] Loaded
2024-10-14T21:21:46.952Z,1728940906.952 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-10-14T21:21:47.296Z,1728940907.296 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4
2024-10-14T21:21:47.297Z,1728940907.297 [BackseatComponent] Loaded
2024-10-14T21:21:47.299Z,1728940907.299 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-10-14T21:21:47.300Z,1728940907.300 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2024-10-14T21:21:47.300Z,1728940907.300 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1365
2024-10-14T21:21:47.306Z,1728940907.306 [LcmUniversalReporter] Loaded
2024-10-14T21:21:47.306Z,1728940907.306 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-10-14T21:21:48.122Z,1728940908.122 [BPC1] Loaded
2024-10-14T21:21:48.122Z,1728940908.122 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-10-14T21:21:48.209Z,1728940908.209 [DATMMP] Loaded
2024-10-14T21:21:48.210Z,1728940908.210 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-10-14T21:21:48.211Z,1728940908.211 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2024-10-14T21:21:48.211Z,1728940908.211 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1366
2024-10-14T21:21:48.307Z,1728940908.307 [DataOverHttps] Loaded
2024-10-14T21:21:48.307Z,1728940908.307 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-10-14T21:21:48.308Z,1728940908.308 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2024-10-14T21:21:48.309Z,1728940908.309 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1367
2024-10-14T21:21:48.332Z,1728940908.332 [Depth_Keller] Loaded
2024-10-14T21:21:48.332Z,1728940908.332 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-10-14T21:21:48.337Z,1728940908.337 [DropWeight] Loaded
2024-10-14T21:21:48.337Z,1728940908.337 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-10-14T21:21:48.405Z,1728940908.405 [NAL9602] Loaded
2024-10-14T21:21:48.405Z,1728940908.405 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-10-14T21:21:48.435Z,1728940908.435 [Onboard] Loaded
2024-10-14T21:21:48.435Z,1728940908.435 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-10-14T21:21:48.436Z,1728940908.436 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2024-10-14T21:21:48.437Z,1728940908.437 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1368
2024-10-14T21:21:48.452Z,1728940908.452 [Power24vConverter] Loaded
2024-10-14T21:21:48.453Z,1728940908.453 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-10-14T21:21:48.468Z,1728940908.468 [Radio_Surface] Loaded
2024-10-14T21:21:48.468Z,1728940908.468 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-10-14T21:21:48.469Z,1728940908.469 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2024-10-14T21:21:48.469Z,1728940908.469 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1369
2024-10-14T21:21:48.485Z,1728940908.485 [Sonardyne_Nano] Loaded
2024-10-14T21:21:48.485Z,1728940908.485 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-10-14T21:21:48.510Z,1728940908.510 [Waterlinked] Loaded
2024-10-14T21:21:48.510Z,1728940908.510 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-10-14T21:21:48.510Z,1728940908.510 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-10-14T21:21:48.511Z,1728940908.511 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-10-14T21:21:48.527Z,1728940908.527 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-10-14T21:21:48.527Z,1728940908.527 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-10-14T21:21:48.571Z,1728940908.571 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-10-14T21:21:48.572Z,1728940908.572 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-10-14T21:21:48.742Z,1728940908.742 [SBIT](DEBUG): Construct Startup Built In Test.
2024-10-14T21:21:48.753Z,1728940908.753 [SBIT] Loaded
2024-10-14T21:21:48.753Z,1728940908.753 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-10-14T21:21:48.756Z,1728940908.756 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-10-14T21:21:48.770Z,1728940908.770 [IBIT] Loaded
2024-10-14T21:21:48.770Z,1728940908.770 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-10-14T21:21:48.776Z,1728940908.776 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-10-14T21:21:48.883Z,1728940908.883 [CBIT] Loaded
2024-10-14T21:21:48.884Z,1728940908.884 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-10-14T21:21:48.884Z,1728940908.884 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-10-14T21:21:48.885Z,1728940908.885 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-10-14T21:21:49.288Z,1728940909.288 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-10-14T21:21:49.289Z,1728940909.289 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-10-14T21:21:49.359Z,1728940909.359 [DepthRateCalculator] Loaded
2024-10-14T21:21:49.360Z,1728940909.360 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-10-14T21:21:49.365Z,1728940909.365 [PitchRateCalculator] Loaded
2024-10-14T21:21:49.365Z,1728940909.365 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-10-14T21:21:49.376Z,1728940909.376 [SpeedCalculator] Loaded
2024-10-14T21:21:49.376Z,1728940909.376 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-10-14T21:21:49.380Z,1728940909.380 [YawRateCalculator] Loaded
2024-10-14T21:21:49.381Z,1728940909.381 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-10-14T21:21:49.401Z,1728940909.401 [ElevatorOffsetCalculator] Loaded
2024-10-14T21:21:49.401Z,1728940909.401 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-10-14T21:21:49.401Z,1728940909.401 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-10-14T21:21:49.402Z,1728940909.402 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-10-14T21:21:49.619Z,1728940909.619 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-10-14T21:21:49.620Z,1728940909.620 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-10-14T21:21:49.758Z,1728940909.758 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-10-14T21:21:49.758Z,1728940909.758 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-10-14T21:21:49.802Z,1728940909.802 [DeadReckonUsingSpeedCalculator] Loaded
2024-10-14T21:21:49.802Z,1728940909.802 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-10-14T21:21:49.846Z,1728940909.846 [DeadReckonWithRespectToSeafloor] Loaded
2024-10-14T21:21:49.846Z,1728940909.846 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-10-14T21:21:49.861Z,1728940909.861 [NavChart] Loaded
2024-10-14T21:21:49.861Z,1728940909.861 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-10-14T21:21:49.867Z,1728940909.867 [UniversalFixResidualReporter] Loaded
2024-10-14T21:21:49.867Z,1728940909.867 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-10-14T21:21:49.867Z,1728940909.867 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-10-14T21:21:49.868Z,1728940909.868 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-10-14T21:21:50.032Z,1728940910.032 [BuoyancyServo] Loaded
2024-10-14T21:21:50.033Z,1728940910.033 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-10-14T21:21:50.056Z,1728940910.056 [ElevatorServo] Loaded
2024-10-14T21:21:50.056Z,1728940910.056 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-10-14T21:21:50.078Z,1728940910.078 [MassServo] Loaded
2024-10-14T21:21:50.078Z,1728940910.078 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-10-14T21:21:50.100Z,1728940910.100 [RudderServo] Loaded
2024-10-14T21:21:50.100Z,1728940910.100 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-10-14T21:21:50.117Z,1728940910.117 [ThrusterHE] Loaded
2024-10-14T21:21:50.117Z,1728940910.117 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-10-14T21:21:50.118Z,1728940910.118 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-10-14T21:21:50.118Z,1728940910.118 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-10-14T21:21:50.346Z,1728940910.346 [CTD_Seabird] Loaded
2024-10-14T21:21:50.346Z,1728940910.346 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-10-14T21:21:50.348Z,1728940910.348 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0
2024-10-14T21:21:50.348Z,1728940910.348 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1370
2024-10-14T21:21:50.385Z,1728940910.385 [ESPComponent] Loaded
2024-10-14T21:21:50.385Z,1728940910.385 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-10-14T21:21:50.408Z,1728940910.408 [PAR_Licor] Loaded
2024-10-14T21:21:50.408Z,1728940910.408 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-10-14T21:21:50.456Z,1728940910.456 [WetLabsBB2FL] Loaded
2024-10-14T21:21:50.456Z,1728940910.456 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-10-14T21:21:50.458Z,1728940910.458 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0
2024-10-14T21:21:50.458Z,1728940910.458 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1371
2024-10-14T21:21:50.459Z,1728940910.459 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-10-14T21:21:50.465Z,1728940910.465 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-10-14T21:21:50.468Z,1728940910.468 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-10-14T21:21:50.480Z,1728940910.480 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-10-14T21:21:50.481Z,1728940910.481 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-10-14T21:21:50.481Z,1728940910.481 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1372
2024-10-14T21:21:50.486Z,1728940910.486 [Supervisor](INFO): Main Thread ID is 1276
2024-10-14T21:21:50.486Z,1728940910.486 [Supervisor](DEBUG): Running supervisor.
2024-10-14T21:21:50.487Z,1728940910.487 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1373
2024-10-14T21:21:50.487Z,1728940910.487 [CommandExec](INFO): Initializing the command executive.
2024-10-14T21:21:50.489Z,1728940910.489 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1374
2024-10-14T21:21:50.492Z,1728940910.492 [controlThread ThreadHandler](INFO): Handler Thread ID is 1375
2024-10-14T21:21:50.493Z,1728940910.493 [controlThread](DEBUG): Initializing ControlThread
2024-10-14T21:21:50.494Z,1728940910.494 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-10-14T21:21:50.495Z,1728940910.495 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-10-14T21:21:50.496Z,1728940910.496 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-10-14T21:21:50.496Z,1728940910.496 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-10-14T21:21:50.503Z,1728940910.503 [SBIT](INFO): Initialize SBIT Component.
2024-10-14T21:21:50.504Z,1728940910.504 [SBIT](IMPORTANT): git: 2024-09-16_D
2024-10-14T21:21:50.504Z,1728940910.504 [SBIT](INFO): git hash: 0de9f8b4fab077d10194b536abee104cbdf12c92
2024-10-14T21:21:50.504Z,1728940910.504 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-10-14T21:21:50.506Z,1728940910.506 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2024-10-14T21:21:50.507Z,1728940910.507 [SBIT](INFO): Beginning SBIT in 73.000000 seconds.
2024-10-14T21:21:50.507Z,1728940910.507 [IBIT](INFO): Initialize IBIT Component.
2024-10-14T21:21:50.508Z,1728940910.508 [CBIT](DEBUG): Initialize CBIT Component.
2024-10-14T21:21:50.509Z,1728940910.509 [logger ThreadHandler](INFO): Handler Thread ID is 1376
2024-10-14T21:21:50.521Z,1728940910.521 [CBIT](DEBUG): Initialized mux pins.
2024-10-14T21:21:50.521Z,1728940910.521 [CBIT](DEBUG): Initializing the watchdog timer.
2024-10-14T21:21:50.531Z,1728940910.531 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1377
2024-10-14T21:21:50.542Z,1728940910.542 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1378
2024-10-14T21:21:50.542Z,1728940910.542 [DATMMP](INFO): Start
2024-10-14T21:21:50.543Z,1728940910.543 [DATMMP](INFO): Powering up
2024-10-14T21:21:50.543Z,1728940910.543 [DATMMP](DEBUG): Initializing DATMMP.
2024-10-14T21:21:50.545Z,1728940910.545 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-10-14T21:21:50.546Z,1728940910.546 [CBIT](DEBUG): Initializing heartbeat.
2024-10-14T21:21:50.548Z,1728940910.548 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1380
2024-10-14T21:21:50.549Z,1728940910.549 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-10-14T21:21:50.558Z,1728940910.558 [Onboard ThreadHandler](INFO): Handler Thread ID is 1381
2024-10-14T21:21:50.575Z,1728940910.575 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1382
2024-10-14T21:21:50.594Z,1728940910.594 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1383
2024-10-14T21:21:50.595Z,1728940910.595 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-10-14T21:21:50.599Z,1728940910.599 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1385
2024-10-14T21:21:50.602Z,1728940910.602 [WetLabsBB2FL](INFO): Powering up
2024-10-14T21:21:50.603Z,1728940910.603 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1387
2024-10-14T21:21:50.610Z,1728940910.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-10-14T21:21:50.610Z,1728940910.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-10-14T21:21:50.610Z,1728940910.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-10-14T21:21:50.610Z,1728940910.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-10-14T21:21:50.611Z,1728940910.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-10-14T21:21:50.611Z,1728940910.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-10-14T21:21:50.611Z,1728940910.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-10-14T21:21:50.611Z,1728940910.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-10-14T21:21:50.611Z,1728940910.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-10-14T21:21:50.611Z,1728940910.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-10-14T21:21:50.612Z,1728940910.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-10-14T21:21:50.612Z,1728940910.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-10-14T21:21:50.617Z,1728940910.617 [CBIT](DEBUG): Deactivating GF circuits.
2024-10-14T21:21:50.617Z,1728940910.617 [CBIT](DEBUG): Deactivating emergency mode.
2024-10-14T21:21:50.657Z,1728940910.657 [CBIT](DEBUG): Backplane powered.
2024-10-14T21:21:50.658Z,1728940910.658 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-10-14T21:21:50.658Z,1728940910.658 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-10-14T21:21:50.658Z,1728940910.658 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-10-14T21:21:50.659Z,1728940910.659 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-10-14T21:21:50.659Z,1728940910.659 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-10-14T21:21:50.660Z,1728940910.660 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:21:50.661Z,1728940910.661 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-10-14T21:21:50.662Z,1728940910.662 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-10-14T21:21:50.666Z,1728940910.666 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-10-14T21:21:50.667Z,1728940910.667 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-10-14T21:21:50.667Z,1728940910.667 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-10-14T21:21:50.676Z,1728940910.676 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-10-14T21:21:50.736Z,1728940910.736 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-10-14T21:21:50.741Z,1728940910.741 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-14T21:21:50.784Z,1728940910.784 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-10-14T21:21:50.785Z,1728940910.785 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-10-14T21:21:50.785Z,1728940910.785 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-10-14T21:21:50.793Z,1728940910.793 [DATMMP](INFO): Starting
2024-10-14T21:21:50.796Z,1728940910.796 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-10-14T21:21:50.981Z,1728940910.981 [Radio_Surface](INFO): Powering up
2024-10-14T21:21:51.045Z,1728940911.045 [DATMMP](INFO): Starting
2024-10-14T21:21:51.102Z,1728940911.102 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-10-14T21:21:51.108Z,1728940911.108 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-10-14T21:21:51.125Z,1728940911.125 [Default:A.Wait](DEBUG): Construct Wait.
2024-10-14T21:21:51.128Z,1728940911.128 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-14T21:21:51.188Z,1728940911.188 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-10-14T21:21:51.206Z,1728940911.206 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-10-14T21:21:51.212Z,1728940911.212 [Default:E.Execute](DEBUG): Construct Execute.
2024-10-14T21:21:51.227Z,1728940911.227 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-10-14T21:21:51.232Z,1728940911.232 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-10-14T21:21:51.254Z,1728940911.254 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-10-14T21:21:51.298Z,1728940911.298 [DATMMP](INFO): Starting
2024-10-14T21:21:51.310Z,1728940911.310 [Depth_Keller](ERROR): Pressure or depth reading out of range: 863.095886 decibar, 0.000000 m
2024-10-14T21:21:51.311Z,1728940911.311 [Power24vConverter](INFO): Powering up.
2024-10-14T21:21:51.312Z,1728940911.312 [Sonardyne_Nano](INFO): Initializing.
2024-10-14T21:21:51.370Z,1728940911.370 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-10-14T21:21:51.440Z,1728940911.440 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-10-14T21:21:51.446Z,1728940911.446 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-10-14T21:21:51.447Z,1728940911.447 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-10-14T21:21:51.454Z,1728940911.454 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-10-14T21:21:51.455Z,1728940911.455 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-14T21:21:51.462Z,1728940911.462 [MassServo](DEBUG): Initializing MassServo.
2024-10-14T21:21:51.463Z,1728940911.463 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-14T21:21:51.470Z,1728940911.470 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-14T21:21:51.470Z,1728940911.470 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-10-14T21:21:51.478Z,1728940911.478 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-10-14T21:21:51.551Z,1728940911.551 [DATMMP](INFO): Starting
2024-10-14T21:21:51.801Z,1728940911.801 [DATMMP](INFO): Starting
2024-10-14T21:21:52.053Z,1728940912.053 [DATMMP](INFO): Starting
2024-10-14T21:21:52.305Z,1728940912.305 [DATMMP](INFO): Starting
2024-10-14T21:21:52.557Z,1728940912.557 [DATMMP](INFO): Starting
2024-10-14T21:21:52.709Z,1728940912.709 [WetLabsBB2FL](INFO): Powering down
2024-10-14T21:21:52.809Z,1728940912.809 [DATMMP](INFO): Starting
2024-10-14T21:21:53.061Z,1728940913.061 [DATMMP](INFO): Starting
2024-10-14T21:21:53.264Z,1728940913.264 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-10-14T21:21:53.313Z,1728940913.313 [DATMMP](INFO): Starting
2024-10-14T21:21:53.565Z,1728940913.565 [DATMMP](INFO): Starting
2024-10-14T21:21:53.817Z,1728940913.817 [DATMMP](INFO): Starting
2024-10-14T21:21:54.069Z,1728940914.069 [DATMMP](INFO): Starting
2024-10-14T21:21:54.321Z,1728940914.321 [DATMMP](INFO): Starting
2024-10-14T21:21:54.546Z,1728940914.546 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-10-14T21:21:54.574Z,1728940914.574 [DATMMP](INFO): Starting
2024-10-14T21:21:54.825Z,1728940914.825 [DATMMP](INFO): Starting
2024-10-14T21:21:55.078Z,1728940915.078 [DATMMP](INFO): Starting
2024-10-14T21:21:55.330Z,1728940915.330 [DATMMP](INFO): Starting
2024-10-14T21:21:55.581Z,1728940915.581 [DATMMP](INFO): Starting
2024-10-14T21:21:55.834Z,1728940915.834 [DATMMP](INFO): Starting
2024-10-14T21:21:56.085Z,1728940916.085 [DATMMP](INFO): Starting
2024-10-14T21:21:56.337Z,1728940916.337 [DATMMP](INFO): Starting
2024-10-14T21:21:56.590Z,1728940916.590 [DATMMP](INFO): Starting
2024-10-14T21:21:56.841Z,1728940916.841 [DATMMP](INFO): Starting
2024-10-14T21:21:57.093Z,1728940917.093 [DATMMP](INFO): Starting
2024-10-14T21:21:57.158Z,1728940917.158 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-10-14T21:21:57.345Z,1728940917.345 [DATMMP](INFO): Starting
2024-10-14T21:21:57.597Z,1728940917.597 [DATMMP](INFO): Starting
2024-10-14T21:21:57.849Z,1728940917.849 [DATMMP](INFO): Starting
2024-10-14T21:21:58.103Z,1728940918.103 [DATMMP](INFO): Starting
2024-10-14T21:21:58.353Z,1728940918.353 [DATMMP](INFO): Starting
2024-10-14T21:21:58.606Z,1728940918.606 [DATMMP](INFO): Starting
2024-10-14T21:21:58.857Z,1728940918.857 [DATMMP](INFO): Starting
2024-10-14T21:21:59.113Z,1728940919.113 [DATMMP](INFO): Starting
2024-10-14T21:21:59.365Z,1728940919.365 [DATMMP](INFO): Starting
2024-10-14T21:21:59.617Z,1728940919.617 [DATMMP](INFO): Starting
2024-10-14T21:21:59.869Z,1728940919.869 [DATMMP](INFO): Starting
2024-10-14T21:22:00.123Z,1728940920.123 [DATMMP](INFO): Starting
2024-10-14T21:22:00.373Z,1728940920.373 [DATMMP](INFO): Starting
2024-10-14T21:22:00.625Z,1728940920.625 [DATMMP](INFO): Starting
2024-10-14T21:22:00.877Z,1728940920.877 [DATMMP](INFO): Starting
2024-10-14T21:22:01.129Z,1728940921.129 [DATMMP](INFO): Starting
2024-10-14T21:22:01.381Z,1728940921.381 [DATMMP](INFO): Starting
2024-10-14T21:22:01.633Z,1728940921.633 [DATMMP](INFO): Starting
2024-10-14T21:22:01.885Z,1728940921.885 [DATMMP](INFO): Starting
2024-10-14T21:22:02.137Z,1728940922.137 [DATMMP](INFO): Starting
2024-10-14T21:22:02.389Z,1728940922.389 [DATMMP](INFO): Starting
2024-10-14T21:22:02.641Z,1728940922.641 [DATMMP](INFO): Starting
2024-10-14T21:22:02.893Z,1728940922.893 [DATMMP](INFO): Starting
2024-10-14T21:22:03.145Z,1728940923.145 [DATMMP](INFO): Starting
2024-10-14T21:22:03.397Z,1728940923.397 [DATMMP](INFO): Starting
2024-10-14T21:22:03.513Z,1728940923.513 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.053645
2024-10-14T21:22:03.649Z,1728940923.649 [DATMMP](INFO): Starting
2024-10-14T21:22:03.650Z,1728940923.650 [DATMMP](INFO): DAT read:
2024-10-14T21:22:03.651Z,1728940923.651 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-10-14T21:22:03.901Z,1728940923.901 [DATMMP](INFO): Starting
2024-10-14T21:22:04.154Z,1728940924.154 [DATMMP](INFO): Starting
2024-10-14T21:22:04.406Z,1728940924.406 [DATMMP](INFO): Starting
2024-10-14T21:22:04.657Z,1728940924.657 [DATMMP](INFO): Starting
2024-10-14T21:22:04.909Z,1728940924.909 [DATMMP](INFO): Starting
2024-10-14T21:22:05.161Z,1728940925.161 [DATMMP](INFO): Starting
2024-10-14T21:22:05.413Z,1728940925.413 [DATMMP](INFO): Starting
2024-10-14T21:22:05.414Z,1728940925.414 [DATMMP](INFO): DAT read: MF Frequency Band
2024-10-14T21:22:05.415Z,1728940925.415 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-10-14T21:22:05.416Z,1728940925.416 [DATMMP](INFO): DAT read: Aug 27 2000 20:02:06
2024-10-14T21:22:05.665Z,1728940925.665 [DATMMP](INFO): Starting
2024-10-14T21:22:05.917Z,1728940925.917 [DATMMP](INFO): Starting
2024-10-14T21:22:06.169Z,1728940926.169 [DATMMP](INFO): Starting
2024-10-14T21:22:06.421Z,1728940926.421 [DATMMP](INFO): Starting
2024-10-14T21:22:06.422Z,1728940926.422 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-10-14T21:22:06.423Z,1728940926.423 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-10-14T21:22:06.424Z,1728940926.424 [DATMMP](INFO): commRate: 800
2024-10-14T21:22:06.673Z,1728940926.673 [DATMMP](INFO): Starting
2024-10-14T21:22:06.925Z,1728940926.925 [DATMMP](INFO): Starting
2024-10-14T21:22:07.177Z,1728940927.177 [DATMMP](INFO): Starting
2024-10-14T21:22:07.429Z,1728940927.429 [DATMMP](INFO): Starting
2024-10-14T21:22:07.681Z,1728940927.681 [DATMMP](INFO): Starting
2024-10-14T21:22:07.934Z,1728940927.934 [DATMMP](INFO): Starting
2024-10-14T21:22:08.185Z,1728940928.185 [DATMMP](INFO): Starting
2024-10-14T21:22:08.437Z,1728940928.437 [DATMMP](INFO): Starting
2024-10-14T21:22:08.489Z,1728940928.489 [DATMMP](INFO): entering command mode
2024-10-14T21:22:08.689Z,1728940928.689 [DATMMP](INFO): Starting
2024-10-14T21:22:08.690Z,1728940928.690 [DATMMP](INFO): DAT read:
2024-10-14T21:22:08.690Z,1728940928.690 [DATMMP](INFO): DAT read: user:1>
2024-10-14T21:22:08.691Z,1728940928.691 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-10-14T21:22:08.691Z,1728940928.691 [DATMMP](INFO): entering MMP mode
2024-10-14T21:22:08.941Z,1728940928.941 [DATMMP](INFO): Starting
2024-10-14T21:22:08.942Z,1728940928.942 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-14T21:22:08.944Z,1728940928.944 [DATMMP](INFO): status rx: x1
2024-10-14T21:22:08.944Z,1728940928.944 [DATMMP](INFO): MMP status: 1: started
2024-10-14T21:22:08.944Z,1728940928.944 [DATMMP](INFO): Received message type: status
2024-10-14T21:22:08.944Z,1728940928.944 [DATMMP](INFO): Handled
2024-10-14T21:22:09.193Z,1728940929.193 [DATMMP](INFO): Starting
2024-10-14T21:22:09.195Z,1728940929.195 [DATMMP](INFO): Sent Tx Power Config 8
2024-10-14T21:22:09.195Z,1728940929.195 [DATMMP](INFO): Setting local address: 1
2024-10-14T21:22:09.197Z,1728940929.197 [DATMMP](INFO): Setting time to: 21:22:9 And date to:10/14/2024
2024-10-14T21:22:09.445Z,1728940929.445 [DATMMP](INFO): Starting
2024-10-14T21:22:09.446Z,1728940929.446 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-14T21:22:09.447Z,1728940929.447 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-10-14T21:22:09.447Z,1728940929.447 [DATMMP](INFO): Received message type: mdmpower
2024-10-14T21:22:09.447Z,1728940929.447 [DATMMP](INFO): Handled
2024-10-14T21:22:09.697Z,1728940929.697 [DATMMP](INFO): Starting
2024-10-14T21:22:09.698Z,1728940929.698 [DATMMP](INFO): GSXN notify for xid: 10
2024-10-14T21:22:09.698Z,1728940929.698 [DATMMP](INFO): sreg_txpower rx: x8
2024-10-14T21:22:09.699Z,1728940929.699 [DATMMP](INFO): Received message type: sreg_txpower
2024-10-14T21:22:09.699Z,1728940929.699 [DATMMP](INFO): Match for : sreg_txpower
2024-10-14T21:22:09.699Z,1728940929.699 [DATMMP](INFO): ACK
2024-10-14T21:22:09.950Z,1728940929.950 [DATMMP](INFO): Starting
2024-10-14T21:22:09.950Z,1728940929.950 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-14T21:22:09.951Z,1728940929.951 [DATMMP](INFO): mdmlocal rx: x1 x0 x0 x0
2024-10-14T21:22:09.951Z,1728940929.951 [DATMMP](INFO): Received message type: mdmlocal
2024-10-14T21:22:09.951Z,1728940929.951 [DATMMP](INFO): Handled
2024-10-14T21:22:10.201Z,1728940930.201 [DATMMP](INFO): Starting
2024-10-14T21:22:10.202Z,1728940930.202 [DATMMP](INFO): GSXN notify for xid: 11
2024-10-14T21:22:10.202Z,1728940930.202 [DATMMP](INFO): sreg_locaddr rx: x1
2024-10-14T21:22:10.203Z,1728940930.203 [DATMMP](INFO): Received message type: sreg_locaddr
2024-10-14T21:22:10.203Z,1728940930.203 [DATMMP](INFO): Match for : sreg_locaddr
2024-10-14T21:22:10.203Z,1728940930.203 [DATMMP](INFO): ACK
2024-10-14T21:22:10.453Z,1728940930.453 [DATMMP](INFO): Starting
2024-10-14T21:22:10.454Z,1728940930.454 [DATMMP](INFO): GSXN notify for xid: 12
2024-10-14T21:22:10.455Z,1728940930.455 [DATMMP](INFO): timedate rx: x0 x15 x16 x9 x9 xE x7C x0
2024-10-14T21:22:10.455Z,1728940930.455 [DATMMP](INFO): Received message type: timedate
2024-10-14T21:22:10.455Z,1728940930.455 [DATMMP](INFO): Match for : timedate
2024-10-14T21:22:10.455Z,1728940930.455 [DATMMP](INFO): ACK
2024-10-14T21:22:10.706Z,1728940930.706 [DATMMP](INFO): Starting
2024-10-14T21:22:10.706Z,1728940930.706 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-14T21:22:10.707Z,1728940930.707 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-10-14T21:22:10.707Z,1728940930.707 [DATMMP](INFO): Received message type: sys_verb
2024-10-14T21:22:10.707Z,1728940930.707 [DATMMP](INFO): Handled
2024-10-14T21:22:10.957Z,1728940930.957 [DATMMP](INFO): Starting
2024-10-14T21:22:10.958Z,1728940930.958 [DATMMP](INFO): GSXN notify for xid: 13
2024-10-14T21:22:10.958Z,1728940930.958 [DATMMP](INFO): sreg_verbosity rx: x0
2024-10-14T21:22:10.959Z,1728940930.959 [DATMMP](INFO): Received message type: sreg_verbosity
2024-10-14T21:22:10.959Z,1728940930.959 [DATMMP](INFO): Match for : sreg_verbosity
2024-10-14T21:22:10.959Z,1728940930.959 [DATMMP](INFO): ACK
2024-10-14T21:22:11.209Z,1728940931.209 [DATMMP](INFO): Starting
2024-10-14T21:22:11.210Z,1728940931.210 [DATMMP](INFO): GSXN notify for xid: 14
2024-10-14T21:22:11.211Z,1728940931.211 [DATMMP](INFO): version rx: x2 x5
2024-10-14T21:22:11.211Z,1728940931.211 [DATMMP](INFO): DatMMPVersion: 2.5
2024-10-14T21:22:11.211Z,1728940931.211 [DATMMP](INFO): Received message type: version
2024-10-14T21:22:11.211Z,1728940931.211 [DATMMP](INFO): Match for : version
2024-10-14T21:22:11.211Z,1728940931.211 [DATMMP](INFO): ACK
2024-10-14T21:22:11.211Z,1728940931.211 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-10-14T21:22:18.479Z,1728940938.479 [NAL9602](INFO): Powering up NAL9602
2024-10-14T21:22:22.132Z,1728940942.132 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019555067, 0.0, +0. +0, +0, +0,V
2024-10-14T21:22:24.585Z,1728940944.585 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-14T21:22:24.588Z,1728940944.588 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-10-14T21:22:24.588Z,1728940944.588 [DATMMP](INFO): Received message type: timesync
2024-10-14T21:22:24.588Z,1728940944.588 [DATMMP](INFO): Handled
2024-10-14T21:22:29.431Z,1728940949.431 [NAL9602](INFO): NAL9602 initialized
2024-10-14T21:22:34.430Z,1728940954.430 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-14T21:22:34.439Z,1728940954.439 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-10-14T21:22:34.439Z,1728940954.439 [DATMMP](INFO): Received message type: timesync
2024-10-14T21:22:34.439Z,1728940954.439 [DATMMP](INFO): Handled
2024-10-14T21:22:36.051Z,1728940956.051 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,220320195.0, +0.0, 0.0,1475.0, 0
2024-10-14T21:22:40.948Z,1728940960.948 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, 7, +0, +0,A
2024-10-14T21:22:43.180Z,1728940963.180 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.0 +0.00, +0.00, 0.10, 0.00
2024-10-14T21:22:47.297Z,1728940967.297 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019561582, 0.0, +0.0, 0.0,14
2024-10-14T21:23:04.019Z,1728940984.019 [SBIT](IMPORTANT): Beginning Startup BIT
2024-10-14T21:23:04.035Z,1728940984.035 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-14T21:23:15.071Z,1728940995.071 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.027552
CHAN A1 (24V): 0.001945
CHAN A2 (12V): 0.000727
CHAN A3 (5V): -0.001856
CHAN B0 (3.3V): 0.000703
CHAN B1 (3.15aV): 0.000413
CHAN B2 (3.15bV): -0.000116
CHAN B3 (GND): -0.000113
OPEN: 0.006698
Full Scale: +/- 1 mA
2024-10-14T21:23:18.827Z,1728940998.827 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:23:24.898Z,1728941004.898 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:23:39.917Z,1728941019.917 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:23:54.838Z,1728941034.838 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:23:58.278Z,1728941038.278 [SBIT](IMPORTANT): SBIT PASSED
2024-10-14T21:23:58.278Z,1728941038.278 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-10-14T21:23:58.279Z,1728941038.279 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2024-10-14T21:23:58.279Z,1728941038.279 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-10-14T21:23:58.279Z,1728941038.279 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-10-14T21:23:58.279Z,1728941038.279 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-10-14T21:23:58.279Z,1728941038.279 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-10-14T21:23:58.279Z,1728941038.279 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=30 minute;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.600000 count_per_second;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.600000 count_per_second;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_diatoms 30.000000 count_per_milliliter;
2024-10-14T21:23:58.280Z,1728941038.280 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_dinoflagellates 30.000000 count_per_milliliter;
2024-10-14T21:23:58.281Z,1728941038.281 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;
2024-10-14T21:23:58.281Z,1728941038.281 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-10-14T21:23:58.281Z,1728941038.281 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;
2024-10-14T21:23:58.281Z,1728941038.281 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-10-14T21:23:58.285Z,1728941038.285 [SBIT](IMPORTANT): Normal all _.predict_plankton_data_pct_dinos_sqerr;
2024-10-14T21:23:58.286Z,1728941038.286 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=292.185592 cubic_centimeter;
2024-10-14T21:23:58.287Z,1728941038.287 [SBIT](IMPORTANT): VerticalControl.massDefault=13.415397 millimeter;
2024-10-14T21:23:58.287Z,1728941038.287 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=1 bool;
2024-10-14T21:23:59.004Z,1728941039.004 [MissionManager](IMPORTANT): Started mission Startup
2024-10-14T21:23:59.010Z,1728941039.010 [Startup] Running Loop=1
2024-10-14T21:23:59.011Z,1728941039.011 [Startup](DEBUG): Aggregate::initialize Startup
2024-10-14T21:23:59.011Z,1728941039.011 [Startup:A.GoToSurface] Running Loop=1
2024-10-14T21:23:59.011Z,1728941039.011 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-14T21:23:59.018Z,1728941039.018 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-14T21:23:59.020Z,1728941039.020 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-14T21:23:59.026Z,1728941039.026 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-14T21:23:59.026Z,1728941039.026 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-14T21:23:59.027Z,1728941039.027 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-14T21:23:59.027Z,1728941039.027 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-14T21:23:59.029Z,1728941039.029 [Startup:StartupSatComms] Running Loop=1
2024-10-14T21:23:59.029Z,1728941039.029 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-10-14T21:23:59.029Z,1728941039.029 [Startup:StartupSatComms:A] Running Loop=1
2024-10-14T21:23:59.608Z,1728941039.608 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-10-14T21:24:00.942Z,1728941040.942 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019572951, 0. 0.0,1475:WI, +, +0, S, +0, +0,V
2024-10-14T21:24:10.146Z,1728941050.146 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:24:11.091Z,1728941051.091 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0. +0.00, +0.00, 0.09, 0.00
2024-10-14T21:24:25.134Z,1728941065.134 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:24:40.034Z,1728941080.034 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:24:51.091Z,1728941091.091 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-14T21:24:51.091Z,1728941091.091 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-14T21:24:51.092Z,1728941091.092 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-10-14T21:24:51.092Z,1728941091.092 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-10-14T21:24:51.094Z,1728941091.094 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-10-14T21:24:51.094Z,1728941091.094 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:24:51.140Z,1728941091.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-14T21:24:51.140Z,1728941091.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-10-14T21:24:51.140Z,1728941091.140 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:24:51.492Z,1728941091.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-14T21:24:51.492Z,1728941091.492 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-14T21:24:51.492Z,1728941091.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-10-14T21:24:51.493Z,1728941091.493 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-10-14T21:24:51.493Z,1728941091.493 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:24:51.494Z,1728941091.494 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-10-14T21:24:51.870Z,1728941091.870 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:24:55.006Z,1728941095.006 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:24:59.155Z,1728941099.155 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-14T21:23:59.0Z
2024-10-14T21:24:59.156Z,1728941099.156 [Startup:StartupSatComms:A] Stopped
2024-10-14T21:24:59.156Z,1728941099.156 [Startup:StartupSatComms:B] Running Loop=1
2024-10-14T21:24:59.495Z,1728941099.495 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-14T21:25:02.459Z,1728941102.459 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240927T195108/Courier0004.lzma
2024-10-14T21:25:03.403Z,1728941103.403 [DataOverHttps](INFO): Moved sent file to Logs/20240927T195108/Courier0004.lzma.bak
2024-10-14T21:25:03.403Z,1728941103.403 [DataOverHttps](INFO): SBD MOMSN=23295953
2024-10-14T21:25:10.158Z,1728941110.158 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:25:17.538Z,1728941117.538 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +SA, +0.00, +0.00, 0.00
2024-10-14T21:25:20.832Z,1728941120.832 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240927T195544/Courier0000.lzma
2024-10-14T21:25:21.831Z,1728941121.831 [DataOverHttps](INFO): Moved sent file to Logs/20240927T195544/Courier0000.lzma.bak
2024-10-14T21:25:21.831Z,1728941121.831 [DataOverHttps](INFO): SBD MOMSN=23295955
2024-10-14T21:25:25.106Z,1728941125.106 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:25:37.414Z,1728941137.414 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20240927T195759/Courier0000.lzma
2024-10-14T21:25:38.408Z,1728941138.408 [DataOverHttps](INFO): Moved sent file to Logs/20240927T195759/Courier0000.lzma.bak
2024-10-14T21:25:38.408Z,1728941138.408 [DataOverHttps](INFO): SBD MOMSN=23295959
2024-10-14T21:25:39.650Z,1728941139.650 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-10-14T21:25:42.994Z,1728941142.994 [Waterlinked](INFO): Powering down
2024-10-14T21:25:54.089Z,1728941154.089 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241014T212138/Courier0000.lzma
2024-10-14T21:25:55.091Z,1728941155.091 [DataOverHttps](INFO): Moved sent file to Logs/20241014T212138/Courier0000.lzma.bak
2024-10-14T21:25:55.091Z,1728941155.091 [DataOverHttps](INFO): SBD MOMSN=23295964
2024-10-14T21:25:59.490Z,1728941159.490 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-14T21:24:59.2Z
2024-10-14T21:25:59.490Z,1728941159.490 [Startup:StartupSatComms:B] Stopped
2024-10-14T21:25:59.490Z,1728941159.490 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-10-14T21:25:59.491Z,1728941159.491 [Startup:StartupSatComms] Stopped
2024-10-14T21:25:59.491Z,1728941159.491 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-10-14T21:25:59.492Z,1728941159.492 [Startup](INFO): Completed Startup
2024-10-14T21:25:59.492Z,1728941159.492 [MissionManager](INFO): Startup is completed.
2024-10-14T21:25:59.492Z,1728941159.492 [MissionManager](INFO): Uninitializing Mission Startup
2024-10-14T21:25:59.492Z,1728941159.492 [Startup] Stopped
2024-10-14T21:25:59.492Z,1728941159.492 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-10-14T21:25:59.492Z,1728941159.492 [Startup:A.GoToSurface] Stopped
2024-10-14T21:25:59.492Z,1728941159.492 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-14T21:25:59.896Z,1728941159.896 [MissionManager](IMPORTANT): Started mission Default
2024-10-14T21:25:59.896Z,1728941159.896 [Default] Running Loop=1
2024-10-14T21:25:59.896Z,1728941159.896 [Default](DEBUG): Aggregate::initialize Default
2024-10-14T21:25:59.896Z,1728941159.896 [Default:B.GoToSurface] Running Loop=1
2024-10-14T21:25:59.896Z,1728941159.896 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-14T21:25:59.896Z,1728941159.896 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-14T21:25:59.897Z,1728941159.897 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-14T21:25:59.901Z,1728941159.901 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-14T21:25:59.902Z,1728941159.902 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-14T21:25:59.902Z,1728941159.902 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-14T21:25:59.902Z,1728941159.902 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-14T21:25:59.902Z,1728941159.902 [Default:A.Wait] Running Loop=1
2024-10-14T21:25:59.903Z,1728941159.903 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-10-14T21:26:10.994Z,1728941170.994 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240927T195108/Express0005.lzma
2024-10-14T21:26:11.995Z,1728941171.995 [DataOverHttps](INFO): Moved sent file to Logs/20240927T195108/Express0005.lzma.bak
2024-10-14T21:26:11.995Z,1728941171.995 [DataOverHttps](INFO): SBD MOMSN=23295982
2024-10-14T21:26:13.212Z,1728941173.212 [Default:A.Wait](INFO): Done Waiting.
2024-10-14T21:26:13.212Z,1728941173.212 [Default:A.Wait] Stopped
2024-10-14T21:26:13.212Z,1728941173.212 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-10-14T21:26:13.606Z,1728941173.606 [Default:CheckIn] Running Loop=1
2024-10-14T21:26:13.606Z,1728941173.606 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-14T21:26:13.606Z,1728941173.606 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-14T21:26:14.068Z,1728941174.069 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-10-14T21:26:43.778Z,1728941203.778 [CommandExec](IMPORTANT): got command failComponent
2024-10-14T21:26:43.778Z,1728941203.778 [CommandExec](IMPORTANT): Failed components:
2024-10-14T21:26:43.779Z,1728941203.779 [CommandExec](IMPORTANT): No failed Components.
2024-10-14T21:27:33.183Z,1728941253.183 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-14T21:27:52.195Z,1728941272.195 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-14T21:27:52.196Z,1728941272.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-14T21:27:52.197Z,1728941272.197 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-10-14T21:27:52.197Z,1728941272.197 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-10-14T21:27:52.198Z,1728941272.198 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-10-14T21:27:52.198Z,1728941272.198 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:27:52.234Z,1728941272.234 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-14T21:27:52.234Z,1728941272.234 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-10-14T21:27:52.234Z,1728941272.234 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:27:52.610Z,1728941272.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-14T21:27:52.610Z,1728941272.610 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-14T21:27:52.610Z,1728941272.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-10-14T21:27:52.611Z,1728941272.611 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-10-14T21:27:52.611Z,1728941272.611 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:27:52.611Z,1728941272.611 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-10-14T21:27:53.011Z,1728941273.011 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:28:01.454Z,1728941281.454 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-10-14T21:28:07.515Z,1728941287.515 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:28:21.260Z,1728941301.260 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:28:35.395Z,1728941315.395 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:28:49.530Z,1728941329.530 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:29:03.670Z,1728941343.670 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:29:17.006Z,1728941357.006 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:29:30.334Z,1728941370.334 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:29:43.667Z,1728941383.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:29:57.807Z,1728941397.807 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-14T21:29:59.026Z,1728941399.026 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2024-10-14T21:29:59.027Z,1728941399.027 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2024-10-14T21:29:59.062Z,1728941399.062 [BPC1](INFO): Received data from all battery sticks.
2024-10-14T21:30:53.179Z,1728941453.179 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-10-14T21:30:53.179Z,1728941453.179 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-14T21:30:53.180Z,1728941453.180 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2024-10-14T21:30:53.180Z,1728941453.180 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-10-14T21:30:53.182Z,1728941453.182 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2024-10-14T21:30:53.182Z,1728941453.182 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:30:53.216Z,1728941453.216 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-14T21:30:53.217Z,1728941453.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-10-14T21:30:53.217Z,1728941453.217 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:30:53.595Z,1728941453.595 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-14T21:30:53.595Z,1728941453.595 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-10-14T21:30:53.595Z,1728941453.595 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-10-14T21:30:53.595Z,1728941453.595 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2024-10-14T21:30:53.596Z,1728941453.596 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:30:53.596Z,1728941453.596 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2024-10-14T21:30:53.974Z,1728941453.974 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:31:13.805Z,1728941473.805 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-14T21:26:13.6Z
2024-10-14T21:31:13.805Z,1728941473.805 [Default:CheckIn:Read_GPS] Stopped
2024-10-14T21:31:13.805Z,1728941473.805 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-14T21:31:14.200Z,1728941474.200 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-14T21:31:15.068Z,1728941475.068 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20241014T212138/Courier0004.lzma
2024-10-14T21:31:15.959Z,1728941475.959 [DataOverHttps](INFO): Moved sent file to Logs/20241014T212138/Courier0004.lzma.bak
2024-10-14T21:31:15.959Z,1728941475.959 [DataOverHttps](INFO): SBD MOMSN=23296026
2024-10-14T21:31:31.457Z,1728941491.457 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240927T195544/Express0001.lzma
2024-10-14T21:31:32.459Z,1728941492.459 [DataOverHttps](INFO): Moved sent file to Logs/20240927T195544/Express0001.lzma.bak
2024-10-14T21:31:32.459Z,1728941492.459 [DataOverHttps](INFO): SBD MOMSN=23296028
2024-10-14T21:32:19.303Z,1728941539.303 [DataOverHttps](INFO): Sending 429 bytes from file Logs/20240927T195759/Express0001.lzma
2024-10-14T21:32:20.303Z,1728941540.303 [DataOverHttps](INFO): Moved sent file to Logs/20240927T195759/Express0001.lzma.bak
2024-10-14T21:32:20.303Z,1728941540.303 [DataOverHttps](INFO): SBD MOMSN=23296030
2024-10-14T21:32:31.335Z,1728941551.335 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-14T21:32:31.335Z,1728941551.335 [NAL9602] Data Fault, FailCount= 1
2024-10-14T21:32:31.335Z,1728941551.335 [NAL9602](ERROR): Data Fault
2024-10-14T21:32:31.373Z,1728941551.373 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-14T21:32:31.735Z,1728941551.735 [NAL9602](INFO): Powering down
2024-10-14T21:32:32.583Z,1728941552.583 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-14T21:32:32.583Z,1728941552.583 [NAL9602] No Fault, FailCount= 1
2024-10-14T21:32:35.807Z,1728941555.807 [DataOverHttps](INFO): Sending 1183 bytes from file Logs/20241014T212138/Express0001.lzma
2024-10-14T21:32:36.807Z,1728941556.807 [DataOverHttps](INFO): Moved sent file to Logs/20241014T212138/Express0001.lzma.bak
2024-10-14T21:32:36.808Z,1728941556.808 [DataOverHttps](INFO): SBD MOMSN=23296039
2024-10-14T21:32:54.641Z,1728941574.641 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20241014T212138/Express0005.lzma
2024-10-14T21:32:55.643Z,1728941575.643 [DataOverHttps](INFO): Moved sent file to Logs/20241014T212138/Express0005.lzma.bak
2024-10-14T21:32:55.643Z,1728941575.643 [DataOverHttps](INFO): SBD MOMSN=23296074
2024-10-14T21:32:56.858Z,1728941576.858 [Default:CheckIn:Read_Iridium] Stopped
2024-10-14T21:32:56.858Z,1728941576.858 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-14T21:32:56.859Z,1728941576.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-14T21:33:02.099Z,1728941582.099 [NAL9602](INFO): Powering up NAL9602
2024-10-14T21:33:13.007Z,1728941593.007 [NAL9602](INFO): NAL9602 initialized
2024-10-14T21:33:54.235Z,1728941634.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-10-14T21:33:54.236Z,1728941634.236 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-14T21:33:54.237Z,1728941634.237 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2024-10-14T21:33:54.237Z,1728941634.237 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-10-14T21:33:54.238Z,1728941634.238 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2024-10-14T21:33:54.238Z,1728941634.238 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:33:54.250Z,1728941634.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-14T21:33:54.250Z,1728941634.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-10-14T21:33:54.250Z,1728941634.250 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:33:54.656Z,1728941634.656 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-14T21:33:54.656Z,1728941634.656 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-10-14T21:33:54.657Z,1728941634.657 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-10-14T21:33:54.658Z,1728941634.658 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2024-10-14T21:33:54.659Z,1728941634.659 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:33:54.659Z,1728941634.659 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2024-10-14T21:33:55.062Z,1728941635.062 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:36:55.238Z,1728941815.238 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-10-14T21:36:55.238Z,1728941815.238 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-14T21:36:55.240Z,1728941815.240 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2024-10-14T21:36:55.240Z,1728941815.240 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-10-14T21:36:55.241Z,1728941815.241 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2024-10-14T21:36:55.241Z,1728941815.241 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:36:55.252Z,1728941815.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-14T21:36:55.252Z,1728941815.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-10-14T21:36:55.253Z,1728941815.253 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:36:55.672Z,1728941815.672 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-14T21:36:55.673Z,1728941815.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-10-14T21:36:55.673Z,1728941815.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-10-14T21:36:55.673Z,1728941815.673 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2024-10-14T21:36:55.674Z,1728941815.674 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:36:55.674Z,1728941815.674 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2024-10-14T21:36:56.066Z,1728941816.066 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:37:57.488Z,1728941877.488 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-14T21:37:57.488Z,1728941877.488 [Default:CheckIn:C.Wait] Stopped
2024-10-14T21:37:57.488Z,1728941877.488 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-14T21:37:57.489Z,1728941877.489 [Default:CheckIn:D] Running Loop=1
2024-10-14T21:37:57.892Z,1728941877.892 [Default:CheckIn:D] Stopped
2024-10-14T21:37:57.893Z,1728941877.893 [Default:CheckIn:E] Running Loop=1
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.966418 min
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn:E] Stopped
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn] Stopped
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn](INFO): Running loop #2
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn] Running Loop=2
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-14T21:37:58.290Z,1728941878.290 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-14T21:39:56.252Z,1728941996.252 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-10-14T21:39:56.252Z,1728941996.252 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-14T21:39:56.254Z,1728941996.254 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2024-10-14T21:39:56.254Z,1728941996.254 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-10-14T21:39:56.258Z,1728941996.258 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2024-10-14T21:39:56.258Z,1728941996.258 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:39:56.277Z,1728941996.277 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-14T21:39:56.278Z,1728941996.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-10-14T21:39:56.278Z,1728941996.278 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:39:56.682Z,1728941996.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-14T21:39:56.682Z,1728941996.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-10-14T21:39:56.682Z,1728941996.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-10-14T21:39:56.682Z,1728941996.682 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2024-10-14T21:39:56.683Z,1728941996.683 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:39:56.683Z,1728941996.683 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2024-10-14T21:39:57.054Z,1728941997.054 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-10-14T21:40:06.741Z,1728942006.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214005.00,A,3648.16756,N,12147.28667,W,0.253,324.69,141024,,,A*74
2024-10-14T21:40:06.758Z,1728942006.758 [NAL9602](INFO): GPS fix at 20241014T214005: (36.802793, -121.788111)
2024-10-14T21:40:06.837Z,1728942006.837 [Default:CheckIn:Read_GPS] Stopped
2024-10-14T21:40:06.838Z,1728942006.838 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-14T21:40:07.905Z,1728942007.905 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20241014T212138/Courier0007.lzma
2024-10-14T21:40:08.843Z,1728942008.843 [DataOverHttps](INFO): Moved sent file to Logs/20241014T212138/Courier0007.lzma.bak
2024-10-14T21:40:08.843Z,1728942008.843 [DataOverHttps](INFO): SBD MOMSN=23296112
2024-10-14T21:40:24.585Z,1728942024.585 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20241014T212138/Express0008.lzma
2024-10-14T21:40:25.587Z,1728942025.587 [DataOverHttps](INFO): Moved sent file to Logs/20241014T212138/Express0008.lzma.bak
2024-10-14T21:40:25.587Z,1728942025.587 [DataOverHttps](INFO): SBD MOMSN=23296115
2024-10-14T21:40:26.967Z,1728942026.967 [Default:CheckIn:Read_Iridium] Stopped
2024-10-14T21:40:26.967Z,1728942026.967 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-14T21:40:26.967Z,1728942026.967 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-14T21:40:39.465Z,1728942039.465 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-14T21:42:57.247Z,1728942177.247 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-10-14T21:42:57.247Z,1728942177.247 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-10-14T21:42:57.258Z,1728942177.258 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-10-14T21:42:57.657Z,1728942177.657 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-10-14T21:42:57.657Z,1728942177.657 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-10-14T21:45:09.139Z,1728942309.139 [DataOverHttps](IMPORTANT): SBD MTMSN=20241014T214508
2024-10-14T21:45:16.550Z,1728942316.550 [DataOverHttps](INFO): Received command: restart sys
2024-10-14T21:45:16.579Z,1728942316.579 [CommandExec](IMPORTANT): got command restart system
2024-10-14T21:45:18.637Z,1728942318.637 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-10-14T21:45:18.638Z,1728942318.638 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:18.638Z,1728942318.638 [CommandExec](INFO): Uninitializing the command executive.
2024-10-14T21:45:18.638Z,1728942318.638 [CommandExec](INFO): Uninitializing the command scheduler.
2024-10-14T21:45:18.638Z,1728942318.638 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:18.810Z,1728942318.810 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-10-14T21:45:18.810Z,1728942318.810 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-10-14T21:45:18.810Z,1728942318.810 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:18.811Z,1728942318.811 [NavChartDb](INFO): Join timeout helper Thread ID is 1464
2024-10-14T21:45:18.973Z,1728942318.973 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:18.974Z,1728942318.974 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:18.989Z,1728942318.989 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-10-14T21:45:18.989Z,1728942318.989 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:18.990Z,1728942318.990 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1465
2024-10-14T21:45:19.453Z,1728942319.453 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:19.454Z,1728942319.454 [WetLabsBB2FL](INFO): Powering down
2024-10-14T21:45:19.455Z,1728942319.455 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:19.465Z,1728942319.465 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-10-14T21:45:19.466Z,1728942319.466 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:19.466Z,1728942319.466 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1466
2024-10-14T21:45:19.634Z,1728942319.634 [CTD_Seabird](INFO): Powering down
2024-10-14T21:45:19.645Z,1728942319.645 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:19.646Z,1728942319.646 [CTD_Seabird](INFO): Powering down
2024-10-14T21:45:19.657Z,1728942319.657 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:19.669Z,1728942319.669 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-10-14T21:45:19.669Z,1728942319.669 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:19.670Z,1728942319.670 [Radio_Surface](INFO): Join timeout helper Thread ID is 1467
2024-10-14T21:45:19.993Z,1728942319.993 [Radio_Surface](INFO): Powering down
2024-10-14T21:45:19.994Z,1728942319.994 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:19.995Z,1728942319.995 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:20.009Z,1728942320.009 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-10-14T21:45:20.010Z,1728942320.010 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:20.010Z,1728942320.010 [Onboard](INFO): Join timeout helper Thread ID is 1468
2024-10-14T21:45:20.562Z,1728942320.562 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-10-14T21:45:21.722Z,1728942321.722 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:21.722Z,1728942321.722 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:21.745Z,1728942321.745 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-10-14T21:45:21.751Z,1728942321.751 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:21.751Z,1728942321.751 [DataOverHttps](INFO): Join timeout helper Thread ID is 1469
2024-10-14T21:45:22.569Z,1728942322.569 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:22.570Z,1728942322.570 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.590Z,1728942322.590 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-10-14T21:45:22.590Z,1728942322.590 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.591Z,1728942322.591 [DATMMP](INFO): Join timeout helper Thread ID is 1470
2024-10-14T21:45:22.597Z,1728942322.597 [DATMMP](INFO): Stop
2024-10-14T21:45:22.597Z,1728942322.597 [DATMMP](INFO): uninitialize
2024-10-14T21:45:22.598Z,1728942322.598 [DATMMP](INFO): Powering down
2024-10-14T21:45:22.666Z,1728942322.666 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:22.666Z,1728942322.666 [DATMMP](INFO): uninitialize
2024-10-14T21:45:22.666Z,1728942322.666 [DATMMP](INFO): Powering down
2024-10-14T21:45:22.667Z,1728942322.667 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.674Z,1728942322.674 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-10-14T21:45:22.674Z,1728942322.674 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.675Z,1728942322.675 [BackseatComponent](INFO): Join timeout helper Thread ID is 1471
2024-10-14T21:45:22.701Z,1728942322.701 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:22.702Z,1728942322.702 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.714Z,1728942322.714 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-10-14T21:45:22.714Z,1728942322.714 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.715Z,1728942322.715 [logger](INFO): Join timeout helper Thread ID is 1472
2024-10-14T21:45:22.733Z,1728942322.733 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:22.734Z,1728942322.734 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.746Z,1728942322.746 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-10-14T21:45:22.746Z,1728942322.746 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.747Z,1728942322.747 [CommandLine](INFO): Join timeout helper Thread ID is 1473
2024-10-14T21:45:22.790Z,1728942322.790 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-14T21:45:22.790Z,1728942322.790 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.798Z,1728942322.798 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-10-14T21:45:22.798Z,1728942322.798 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.799Z,1728942322.799 [CommandExec](INFO): Join timeout helper Thread ID is 1474
2024-10-14T21:45:22.800Z,1728942322.800 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-10-14T21:45:22.800Z,1728942322.800 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-14T21:45:22.800Z,1728942322.800 [controlThread](INFO): Join timeout helper Thread ID is 1475
2024-10-14T21:45:23.094Z,1728942323.094 [controlThread ThreadHandler](INFO): Uninitializing