2025-12-09T02:07:48.410Z,1765246068.410 [Supervisor](DEBUG): Initializing supervisor.
2025-12-09T02:07:48.414Z,1765246068.414 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-12-09T02:07:48.415Z,1765246068.415 [SyncHandler](INFO): Protected caller Thread ID is 839
2025-12-09T02:07:48.415Z,1765246068.415 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-12-09T02:07:48.416Z,1765246068.416 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-12-09T02:07:48.417Z,1765246068.417 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840
2025-12-09T02:07:48.421Z,1765246068.421 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-12-09T02:07:48.440Z,1765246068.440 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-12-09T02:07:48.441Z,1765246068.441 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-12-09T02:07:48.441Z,1765246068.441 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841
2025-12-09T02:07:48.445Z,1765246068.445 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-12-09T02:07:48.446Z,1765246068.446 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-12-09T02:07:48.446Z,1765246068.446 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842
2025-12-09T02:07:48.448Z,1765246068.448 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-12-09T02:07:48.449Z,1765246068.449 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-12-09T02:07:48.450Z,1765246068.450 [logger ThreadHandler](INFO): Protected caller Thread ID is 843
2025-12-09T02:07:48.453Z,1765246068.453 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-12-09T02:07:48.454Z,1765246068.454 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-12-09T02:07:48.458Z,1765246068.458 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-12-09T02:07:48.562Z,1765246068.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-12-09T02:07:48.564Z,1765246068.564 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-12-09T02:07:49.277Z,1765246069.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-12-09T02:07:49.278Z,1765246069.278 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-12-09T02:07:49.520Z,1765246069.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-12-09T02:07:49.522Z,1765246069.522 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-12-09T02:07:49.625Z,1765246069.625 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-12-09T02:07:49.808Z,1765246069.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-12-09T02:07:49.809Z,1765246069.809 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-12-09T02:07:49.908Z,1765246069.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-12-09T02:07:49.910Z,1765246069.910 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-12-09T02:07:50.025Z,1765246070.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-12-09T02:07:50.025Z,1765246070.025 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-12-09T02:07:50.416Z,1765246070.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-12-09T02:07:50.417Z,1765246070.417 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-12-09T02:07:50.951Z,1765246070.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-12-09T02:07:50.953Z,1765246070.953 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-12-09T02:07:51.174Z,1765246071.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-12-09T02:07:51.174Z,1765246071.174 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-12-09T02:07:51.366Z,1765246071.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-12-09T02:07:51.367Z,1765246071.367 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-12-09T02:07:51.928Z,1765246071.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-12-09T02:07:52.527Z,1765246072.527 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-12-09T02:07:52.854Z,1765246072.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-12-09T02:07:52.856Z,1765246072.856 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-12-09T02:07:53.096Z,1765246073.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-12-09T02:07:53.097Z,1765246073.097 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-12-09T02:07:53.483Z,1765246073.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-12-09T02:07:53.485Z,1765246073.485 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-12-09T02:07:53.838Z,1765246073.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-12-09T02:07:53.840Z,1765246073.840 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-12-09T02:07:53.842Z,1765246073.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-12-09T02:07:53.935Z,1765246073.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-12-09T02:07:54.106Z,1765246074.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-12-09T02:07:54.219Z,1765246074.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-12-09T02:07:54.322Z,1765246074.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-12-09T02:07:54.437Z,1765246074.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-12-09T02:07:54.530Z,1765246074.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-12-09T02:07:54.633Z,1765246074.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-12-09T02:07:54.751Z,1765246074.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-12-09T02:07:55.021Z,1765246075.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-12-09T02:07:55.022Z,1765246075.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-12-09T02:07:55.114Z,1765246075.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-12-09T02:07:55.215Z,1765246075.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-12-09T02:07:55.361Z,1765246075.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-12-09T02:07:55.464Z,1765246075.464 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-12-09T02:07:55.470Z,1765246075.470 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-12-09T02:07:55.638Z,1765246075.638 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-12-09T02:07:55.639Z,1765246075.639 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-12-09T02:07:55.710Z,1765246075.710 [VerticalControl](DEBUG): Construct VerticalControl.
2025-12-09T02:07:55.770Z,1765246075.770 [VerticalControl] Loaded
2025-12-09T02:07:55.770Z,1765246075.770 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-12-09T02:07:55.773Z,1765246075.773 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-12-09T02:07:55.820Z,1765246075.820 [HorizontalControl] Loaded
2025-12-09T02:07:55.820Z,1765246075.820 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-12-09T02:07:55.822Z,1765246075.822 [SpeedControl](DEBUG): Construct SpeedControl.
2025-12-09T02:07:55.825Z,1765246075.825 [SpeedControl] Loaded
2025-12-09T02:07:55.826Z,1765246075.826 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-12-09T02:07:55.828Z,1765246075.828 [LoopControl](DEBUG): Construct LoopControl.
2025-12-09T02:07:55.829Z,1765246075.829 [LoopControl] Loaded
2025-12-09T02:07:55.829Z,1765246075.829 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-12-09T02:07:55.829Z,1765246075.829 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-12-09T02:07:55.830Z,1765246075.830 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-12-09T02:07:55.900Z,1765246075.900 [DepthRateCalculator] Loaded
2025-12-09T02:07:55.900Z,1765246075.900 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-12-09T02:07:55.905Z,1765246075.905 [PitchRateCalculator] Loaded
2025-12-09T02:07:55.905Z,1765246075.905 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-12-09T02:07:55.915Z,1765246075.915 [SpeedCalculator] Loaded
2025-12-09T02:07:55.915Z,1765246075.915 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-12-09T02:07:55.919Z,1765246075.919 [YawRateCalculator] Loaded
2025-12-09T02:07:55.919Z,1765246075.919 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-12-09T02:07:55.938Z,1765246075.938 [ElevatorOffsetCalculator] Loaded
2025-12-09T02:07:55.938Z,1765246075.938 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-12-09T02:07:55.938Z,1765246075.938 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-12-09T02:07:55.939Z,1765246075.939 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-12-09T02:07:55.988Z,1765246075.988 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-12-09T02:07:55.989Z,1765246075.989 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-12-09T02:07:56.075Z,1765246076.075 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-12-09T02:07:56.075Z,1765246076.075 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-12-09T02:07:56.472Z,1765246076.472 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-12-09T02:07:56.473Z,1765246076.473 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-12-09T02:07:56.683Z,1765246076.683 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-12-09T02:07:56.684Z,1765246076.684 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-12-09T02:07:57.697Z,1765246077.697 [AHRS_M2] Loaded
2025-12-09T02:07:57.698Z,1765246077.698 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-12-09T02:07:58.007Z,1765246078.007 [BackseatComponent] Loaded
2025-12-09T02:07:58.007Z,1765246078.007 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-12-09T02:07:58.008Z,1765246078.008 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2025-12-09T02:07:58.009Z,1765246078.009 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 932
2025-12-09T02:07:58.012Z,1765246078.012 [LcmPublisher] Loaded
2025-12-09T02:07:58.012Z,1765246078.012 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-12-09T02:07:58.829Z,1765246078.829 [BPC1] Loaded
2025-12-09T02:07:58.829Z,1765246078.829 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-12-09T02:07:58.925Z,1765246078.925 [DAT] Loaded
2025-12-09T02:07:58.926Z,1765246078.926 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-12-09T02:07:58.927Z,1765246078.927 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2025-12-09T02:07:58.927Z,1765246078.927 [DAT ThreadHandler](INFO): Protected caller Thread ID is 933
2025-12-09T02:07:59.066Z,1765246079.066 [DataOverHttps] Loaded
2025-12-09T02:07:59.066Z,1765246079.066 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-12-09T02:07:59.067Z,1765246079.067 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0
2025-12-09T02:07:59.068Z,1765246079.068 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 934
2025-12-09T02:07:59.091Z,1765246079.091 [Depth_Keller] Loaded
2025-12-09T02:07:59.091Z,1765246079.091 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-12-09T02:07:59.096Z,1765246079.096 [DropWeight] Loaded
2025-12-09T02:07:59.096Z,1765246079.096 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-12-09T02:07:59.160Z,1765246079.160 [NAL9602] Loaded
2025-12-09T02:07:59.160Z,1765246079.160 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-12-09T02:07:59.189Z,1765246079.189 [Onboard] Loaded
2025-12-09T02:07:59.190Z,1765246079.190 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-12-09T02:07:59.191Z,1765246079.191 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0
2025-12-09T02:07:59.191Z,1765246079.191 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 935
2025-12-09T02:07:59.205Z,1765246079.205 [Power24vConverter] Loaded
2025-12-09T02:07:59.205Z,1765246079.205 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-12-09T02:07:59.220Z,1765246079.220 [Radio_Surface] Loaded
2025-12-09T02:07:59.221Z,1765246079.221 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-12-09T02:07:59.222Z,1765246079.222 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2025-12-09T02:07:59.222Z,1765246079.222 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 936
2025-12-09T02:07:59.269Z,1765246079.269 [RDI_Pathfinder] Loaded
2025-12-09T02:07:59.269Z,1765246079.269 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-12-09T02:07:59.284Z,1765246079.284 [Sonardyne_Nano] Loaded
2025-12-09T02:07:59.284Z,1765246079.284 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-12-09T02:07:59.285Z,1765246079.285 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-12-09T02:07:59.285Z,1765246079.285 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-12-09T02:07:59.422Z,1765246079.422 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-12-09T02:07:59.423Z,1765246079.423 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-12-09T02:07:59.436Z,1765246079.436 [NavChart] Loaded
2025-12-09T02:07:59.436Z,1765246079.436 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-12-09T02:07:59.442Z,1765246079.442 [UniversalFixResidualReporter] Loaded
2025-12-09T02:07:59.442Z,1765246079.442 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-12-09T02:07:59.442Z,1765246079.442 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-12-09T02:07:59.444Z,1765246079.444 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-12-09T02:07:59.637Z,1765246079.637 [SBIT](DEBUG): Construct Startup Built In Test.
2025-12-09T02:07:59.646Z,1765246079.646 [SBIT] Loaded
2025-12-09T02:07:59.646Z,1765246079.646 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-12-09T02:07:59.649Z,1765246079.649 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-12-09T02:07:59.661Z,1765246079.661 [IBIT] Loaded
2025-12-09T02:07:59.662Z,1765246079.662 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-12-09T02:07:59.667Z,1765246079.667 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-12-09T02:07:59.747Z,1765246079.747 [CBIT] Loaded
2025-12-09T02:07:59.747Z,1765246079.747 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-12-09T02:07:59.779Z,1765246079.779 [GFScanner] Loaded
2025-12-09T02:07:59.780Z,1765246079.780 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-12-09T02:07:59.780Z,1765246079.780 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-12-09T02:07:59.781Z,1765246079.781 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-12-09T02:07:59.944Z,1765246079.944 [BuoyancyServo] Loaded
2025-12-09T02:07:59.944Z,1765246079.944 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-12-09T02:07:59.968Z,1765246079.968 [ElevatorServo] Loaded
2025-12-09T02:07:59.968Z,1765246079.968 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-12-09T02:07:59.996Z,1765246079.996 [DockingStepper] Loaded
2025-12-09T02:07:59.997Z,1765246079.997 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-12-09T02:08:00.018Z,1765246080.018 [MassServo] Loaded
2025-12-09T02:08:00.018Z,1765246080.018 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-12-09T02:08:00.039Z,1765246080.039 [RudderServo] Loaded
2025-12-09T02:08:00.039Z,1765246080.039 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-12-09T02:08:00.058Z,1765246080.058 [ThrusterServo] Loaded
2025-12-09T02:08:00.058Z,1765246080.058 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-12-09T02:08:00.059Z,1765246080.059 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-12-09T02:08:00.059Z,1765246080.059 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-12-09T02:08:00.075Z,1765246080.075 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-12-09T02:08:00.076Z,1765246080.076 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-12-09T02:08:00.297Z,1765246080.297 [CTD_Seabird] Loaded
2025-12-09T02:08:00.297Z,1765246080.297 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-12-09T02:08:00.298Z,1765246080.298 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0
2025-12-09T02:08:00.298Z,1765246080.298 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 937
2025-12-09T02:08:00.321Z,1765246080.321 [PAR_Licor] Loaded
2025-12-09T02:08:00.322Z,1765246080.322 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-12-09T02:08:00.366Z,1765246080.366 [WetLabsBB2FL] Loaded
2025-12-09T02:08:00.367Z,1765246080.367 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-12-09T02:08:00.368Z,1765246080.368 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-12-09T02:08:00.368Z,1765246080.368 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 938
2025-12-09T02:08:00.389Z,1765246080.389 [WetLabsUBAT] Loaded
2025-12-09T02:08:00.389Z,1765246080.389 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2025-12-09T02:08:00.390Z,1765246080.390 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-12-09T02:08:00.390Z,1765246080.390 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 939
2025-12-09T02:08:00.391Z,1765246080.391 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-12-09T02:08:00.397Z,1765246080.397 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-12-09T02:08:00.400Z,1765246080.400 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-12-09T02:08:00.411Z,1765246080.411 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-12-09T02:08:00.412Z,1765246080.412 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9D4E0
2025-12-09T02:08:00.413Z,1765246080.413 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 940
2025-12-09T02:08:00.417Z,1765246080.417 [Supervisor](INFO): Main Thread ID is 830
2025-12-09T02:08:00.417Z,1765246080.417 [Supervisor](DEBUG): Running supervisor.
2025-12-09T02:08:00.418Z,1765246080.418 [CommandExec ThreadHandler](INFO): Handler Thread ID is 941
2025-12-09T02:08:00.418Z,1765246080.418 [CommandExec](INFO): Initializing the command executive.
2025-12-09T02:08:00.420Z,1765246080.420 [CommandLine ThreadHandler](INFO): Handler Thread ID is 942
2025-12-09T02:08:00.422Z,1765246080.422 [controlThread ThreadHandler](INFO): Handler Thread ID is 943
2025-12-09T02:08:00.422Z,1765246080.422 [controlThread](DEBUG): Initializing ControlThread
2025-12-09T02:08:00.423Z,1765246080.423 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-12-09T02:08:00.425Z,1765246080.425 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-12-09T02:08:00.426Z,1765246080.426 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-12-09T02:08:00.426Z,1765246080.426 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-12-09T02:08:00.426Z,1765246080.426 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-12-09T02:08:00.427Z,1765246080.427 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-12-09T02:08:00.427Z,1765246080.427 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-12-09T02:08:00.428Z,1765246080.428 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-12-09T02:08:00.428Z,1765246080.428 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-12-09T02:08:00.431Z,1765246080.431 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-12-09T02:08:00.432Z,1765246080.432 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-12-09T02:08:00.432Z,1765246080.432 [SBIT](INFO): Initialize SBIT Component.
2025-12-09T02:08:00.433Z,1765246080.433 [SBIT](IMPORTANT): git: 2025-12-08
2025-12-09T02:08:00.433Z,1765246080.433 [SBIT](INFO): git hash: eeac4c2f4f4dbc8506b8351228d523ccb0de86b1
2025-12-09T02:08:00.433Z,1765246080.433 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-12-09T02:08:00.434Z,1765246080.434 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-12-09T02:08:00.435Z,1765246080.435 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2025-12-09T02:08:00.436Z,1765246080.436 [IBIT](INFO): Initialize IBIT Component.
2025-12-09T02:08:00.437Z,1765246080.437 [CBIT](DEBUG): Initialize CBIT Component.
2025-12-09T02:08:00.438Z,1765246080.438 [logger ThreadHandler](INFO): Handler Thread ID is 944
2025-12-09T02:08:00.448Z,1765246080.448 [CBIT](DEBUG): Initialized mux pins.
2025-12-09T02:08:00.448Z,1765246080.448 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-12-09T02:08:00.448Z,1765246080.448 [CBIT](DEBUG): Initializing the watchdog timer.
2025-12-09T02:08:00.456Z,1765246080.456 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 945
2025-12-09T02:08:00.472Z,1765246080.472 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2025-12-09T02:08:00.472Z,1765246080.472 [CBIT](DEBUG): Initializing heartbeat.
2025-12-09T02:08:00.473Z,1765246080.473 [DAT ThreadHandler](INFO): Handler Thread ID is 946
2025-12-09T02:08:00.474Z,1765246080.474 [DAT](INFO): Powering up
2025-12-09T02:08:00.474Z,1765246080.474 [DAT](DEBUG): Initializing DAT.
2025-12-09T02:08:00.477Z,1765246080.477 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 948
2025-12-09T02:08:00.479Z,1765246080.479 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-12-09T02:08:00.500Z,1765246080.500 [Onboard ThreadHandler](INFO): Handler Thread ID is 949
2025-12-09T02:08:00.517Z,1765246080.517 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 950
2025-12-09T02:08:00.536Z,1765246080.536 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 951
2025-12-09T02:08:00.537Z,1765246080.537 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-12-09T02:08:00.541Z,1765246080.541 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 953
2025-12-09T02:08:00.544Z,1765246080.544 [CBIT](DEBUG): Deactivating emergency mode.
2025-12-09T02:08:00.545Z,1765246080.545 [WetLabsBB2FL](INFO): Powering up
2025-12-09T02:08:00.546Z,1765246080.546 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 955
2025-12-09T02:08:00.548Z,1765246080.548 [WetLabsUBAT](INFO): Powering up
2025-12-09T02:08:00.549Z,1765246080.549 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 957
2025-12-09T02:08:00.558Z,1765246080.558 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-12-09T02:08:00.558Z,1765246080.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-12-09T02:08:00.559Z,1765246080.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-12-09T02:08:00.559Z,1765246080.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-12-09T02:08:00.559Z,1765246080.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-12-09T02:08:00.559Z,1765246080.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-12-09T02:08:00.559Z,1765246080.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-12-09T02:08:00.559Z,1765246080.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-12-09T02:08:00.584Z,1765246080.584 [CBIT](DEBUG): Backplane powered.
2025-12-09T02:08:00.584Z,1765246080.584 [GFScanner](DEBUG): Initializing GFScanner
2025-12-09T02:08:00.584Z,1765246080.584 [GFScanner](DEBUG): Deactivating GF circuits.
2025-12-09T02:08:00.596Z,1765246080.596 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-12-09T02:08:00.597Z,1765246080.597 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-12-09T02:08:00.597Z,1765246080.597 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-12-09T02:08:00.616Z,1765246080.616 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-12-09T02:08:00.631Z,1765246080.631 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
2025-12-09T02:08:00.631Z,1765246080.631 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-12-09T02:08:00.631Z,1765246080.631 [MissionManager](ERROR): Could not load startup mission.
2025-12-09T02:08:00.632Z,1765246080.632 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-12-09T02:08:00.632Z,1765246080.632 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-12-09T02:08:00.641Z,1765246080.641 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-12-09T02:08:00.924Z,1765246080.924 [Radio_Surface](INFO): Powering up
2025-12-09T02:08:01.158Z,1765246081.158 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
0
6
10
1
3
30
Timed out while trying to detach from dock at current depth of
.
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-12-09T02:08:01.209Z,1765246081.209 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-12-09T02:08:01.213Z,1765246081.213 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-12-09T02:08:01.217Z,1765246081.217 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-12-09T02:08:01.221Z,1765246081.221 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-12-09T02:08:01.244Z,1765246081.244 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-12-09T02:08:01.256Z,1765246081.256 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-12-09T02:08:01.257Z,1765246081.257 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-12-09T02:08:01.411Z,1765246081.411 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-12-09T02:08:01.411Z,1765246081.411 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-12-09T02:08:01.477Z,1765246081.477 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-09T02:08:01.519Z,1765246081.519 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-12-09T02:08:01.522Z,1765246081.522 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-12-09T02:08:01.544Z,1765246081.544 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-12-09T02:08:01.561Z,1765246081.561 [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 DefaultWithUndock {
arguments {
ElapsedSinceDefaultStarted = 0 minute
DetachTimeout = 6 hour
"""
Maximum time duration for the vehicle to detach form the dock.
"""
TransponderCode = Dock:Dock.transponderCode
"""
Dock transponder address.
"""
TrackingUpdatePeriod = 10 second
"""
Time duration between acoustic range queries while the mission is
detaching from the dock.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
DriftTimeout = 3 minute
"""
How long to allow vehicle to drift away from dock before retrying reverse
"""
}
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 = 30 second
}
aggregate LeaveDock {
run in sequence
timeout duration=DetachTimeout {
syslog important "Timed out while trying to detach from dock at current depth of "
+ Universal:depth~meter + "."
}
# Acoustic tracking directives
behavior Estimation:TrackAcousticContact {
run while (
Universal:depth > Control:VerticalControl.surfaceThreshold
)
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
# Leave the dock
behavior Dock:Undock {
run in sequence
set driftTimeout = DriftTimeout
}
}
aggregate SurfaceDefault {
run in sequence
# This mission assumes open water so from here on it's the same old default that's always used
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=P2H {
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-12-09T02:08:01.566Z,1765246081.566 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2025-12-09T02:08:01.594Z,1765246081.594 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-12-09T02:08:01.728Z,1765246081.728 [Depth_Keller](INFO): Initializing.
2025-12-09T02:08:01.730Z,1765246081.730 [Power24vConverter](INFO): Powering up.
2025-12-09T02:08:01.731Z,1765246081.731 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-12-09T02:08:01.734Z,1765246081.734 [Sonardyne_Nano](INFO): Initializing.
2025-12-09T02:08:01.769Z,1765246081.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-09T02:08:01.804Z,1765246081.804 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-12-09T02:08:01.812Z,1765246081.812 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-12-09T02:08:01.813Z,1765246081.813 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-12-09T02:08:01.820Z,1765246081.820 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-12-09T02:08:01.821Z,1765246081.821 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-12-09T02:08:01.828Z,1765246081.828 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-12-09T02:08:01.829Z,1765246081.829 [MassServo](DEBUG): Initializing EZServoServo.
2025-12-09T02:08:01.836Z,1765246081.836 [MassServo](DEBUG): Initializing MassServo.
2025-12-09T02:08:01.837Z,1765246081.837 [RudderServo](DEBUG): Initializing EZServoServo.
2025-12-09T02:08:01.845Z,1765246081.845 [RudderServo](DEBUG): Initializing RudderServo.
2025-12-09T02:08:01.846Z,1765246081.846 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-12-09T02:08:01.852Z,1765246081.852 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-12-09T02:08:01.854Z,1765246081.854 [CommandExec](FAULT): Scheduling is paused
2025-12-09T02:08:01.854Z,1765246081.854 [CBIT](INFO): Critical error at 20251209T020800
2025-12-09T02:08:01.855Z,1765246081.855 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-12-09T02:08:02.013Z,1765246082.013 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m
2025-12-09T02:08:02.017Z,1765246082.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-09T02:08:03.156Z,1765246083.156 [WetLabsBB2FL](INFO): Powering down
2025-12-09T02:08:03.998Z,1765246083.998 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-12-09T02:08:05.249Z,1765246085.249 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2025-12-09T02:08:06.176Z,1765246086.176 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-12-09T02:08:06.177Z,1765246086.177 [Radio_Surface](INFO): No local IP found
2025-12-09T02:08:06.984Z,1765246086.984 [Radio_Surface](INFO): Powering down
2025-12-09T02:08:09.872Z,1765246089.872 [WetLabsUBAT](INFO): Powering down
2025-12-09T02:08:14.338Z,1765246094.338 [DAT](INFO): DAT read:
2025-12-09T02:08:14.342Z,1765246094.342 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-12-09T02:08:19.881Z,1765246099.881 [DAT](INFO): DAT read: MF Frequency Band
2025-12-09T02:08:19.882Z,1765246099.882 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2025-12-09T02:08:19.883Z,1765246099.883 [DAT](INFO): DAT read: Dec 9 2025 02:07:03
2025-12-09T02:08:20.889Z,1765246100.889 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-12-09T02:08:21.141Z,1765246101.141 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-12-09T02:08:21.142Z,1765246101.142 [DAT](INFO): commRate: 600
2025-12-09T02:08:23.208Z,1765246103.208 [DAT](INFO): entering command mode
2025-12-09T02:08:23.408Z,1765246103.408 [DAT](INFO): DAT read:
2025-12-09T02:08:23.409Z,1765246103.409 [DAT](INFO): DAT read: user:1>
2025-12-09T02:08:23.409Z,1765246103.409 [DAT](INFO): setting verbose to 3
2025-12-09T02:08:23.661Z,1765246103.661 [DAT](INFO): DAT read: user:1>
2025-12-09T02:08:23.662Z,1765246103.662 [DAT](INFO): DAT read: Verbose | 3
2025-12-09T02:08:23.662Z,1765246103.662 [DAT](INFO): set verbose to 3
2025-12-09T02:08:23.662Z,1765246103.662 [DAT](INFO): setting DatVerbose to 27440
2025-12-09T02:08:23.913Z,1765246103.913 [DAT](INFO): DAT read: user:2>
2025-12-09T02:08:23.914Z,1765246103.914 [DAT](INFO): DAT read: DatVerbose | 27440
2025-12-09T02:08:23.914Z,1765246103.914 [DAT](INFO): set DatVerbose to 27440
2025-12-09T02:08:23.914Z,1765246103.914 [DAT](INFO): setting transmit power to 8
2025-12-09T02:08:24.165Z,1765246104.165 [DAT](INFO): DAT read: user:3>
2025-12-09T02:08:24.165Z,1765246104.165 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-12-09T02:08:24.166Z,1765246104.166 [DAT](INFO): set transmit power to 8
2025-12-09T02:08:24.166Z,1765246104.166 [DAT](INFO): setting local address to 10
2025-12-09T02:08:24.417Z,1765246104.417 [DAT](INFO): DAT read: user:4>
2025-12-09T02:08:24.418Z,1765246104.418 [DAT](INFO): DAT read: LocalAddr | 10
2025-12-09T02:08:24.418Z,1765246104.418 [DAT](INFO): set local address to 10
2025-12-09T02:08:24.419Z,1765246104.419 [DAT](INFO): Setting time to: 2:8:24 And date to:12/9/2025
2025-12-09T02:08:24.669Z,1765246104.669 [DAT](INFO): DAT read: user:5>
2025-12-09T02:08:24.669Z,1765246104.669 [DAT](INFO): DAT read: Tue Dec 9, 2025 02:08:24
2025-12-09T02:08:24.670Z,1765246104.670 [DAT](INFO): Local DAT time set to Tue Dec 9, 2025 02:08:24
2025-12-09T02:08:29.486Z,1765246109.486 [NAL9602](INFO): Powering up NAL9602
2025-12-09T02:08:30.721Z,1765246110.721 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +1.15, -1.59, S, -245, -97, -121,A
2025-12-09T02:08:40.402Z,1765246120.402 [NAL9602](INFO): NAL9602 initialized
2025-12-09T02:08:46.922Z,1765246126.922 [SBIT](IMPORTANT): Beginning Startup BIT
2025-12-09T02:08:46.927Z,1765246126.927 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-09T02:08:51.500Z,1765246131.500 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003520
CHAN A1 (24V): -0.008977
CHAN A2 (12V): 0.001937
CHAN A3 (5V): 0.000647
CHAN B0 (3.3V): 0.001235
CHAN B1 (3.15aV): 0.000667
CHAN B2 (3.15bV): 0.001188
CHAN B3 (GND): 0.000526
OPEN: -0.002902
Full Scale: +/- 1 mA
2025-12-09T02:09:40.766Z,1765246180.766 [SBIT](IMPORTANT): SBIT PASSED
2025-12-09T02:09:40.766Z,1765246180.766 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-12-09T02:09:41.106Z,1765246181.106 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-12-09T02:09:41.107Z,1765246181.107 [DefaultWithUndock] Running Loop=1
2025-12-09T02:09:41.107Z,1765246181.107 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-12-09T02:09:41.107Z,1765246181.107 [DefaultWithUndock:A.Wait] Running Loop=1
2025-12-09T02:09:41.107Z,1765246181.107 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-12-09T02:10:11.386Z,1765246211.386 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-12-09T02:10:11.386Z,1765246211.386 [DefaultWithUndock:A.Wait] Stopped
2025-12-09T02:10:11.386Z,1765246211.386 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-12-09T02:10:11.386Z,1765246211.386 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-12-09T02:10:11.387Z,1765246211.387 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-12-09T02:10:11.387Z,1765246211.387 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-12-09T02:10:11.387Z,1765246211.387 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-12-09T02:10:11.387Z,1765246211.387 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-12-09T02:10:11.387Z,1765246211.387 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-12-09T02:10:11.387Z,1765246211.387 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-12-09T02:10:11.811Z,1765246211.811 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-12-09T02:10:11.811Z,1765246211.811 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-12-09T02:10:11.812Z,1765246211.812 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 5.904479 m.
2025-12-09T02:10:11.813Z,1765246211.813 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-12-09T02:10:12.642Z,1765246212.642 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-12-09T02:10:12.757Z,1765246212.757 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-12-09T02:10:15.074Z,1765246215.074 [CTD_Seabird](ERROR): Failed to parse device response:
2025-12-09T02:10:16.297Z,1765246216.297 [DockingStepper](INFO): Changing to mode: 3
2025-12-09T02:10:21.487Z,1765246221.487 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-12-09T02:10:21.625Z,1765246221.625 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:21.625Z,1765246221.625 [DAT](INFO): setting remote address to 0
2025-12-09T02:10:21.877Z,1765246221.877 [DAT](INFO): DAT read: user:6>
2025-12-09T02:10:21.878Z,1765246221.878 [DAT](INFO): DAT read: RemoteAddr | 0
2025-12-09T02:10:21.878Z,1765246221.878 [DAT](INFO): set remote address to 0
2025-12-09T02:10:21.878Z,1765246221.878 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:21.879Z,1765246221.879 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-12-09T02:10:22.129Z,1765246222.129 [DAT](INFO): DAT read: user:7>
2025-12-09T02:10:22.130Z,1765246222.130 [DAT](INFO): DAT read: Tx time:02:10:21.6080
2025-12-09T02:10:22.130Z,1765246222.130 [DAT](INFO): Ping request sent.
2025-12-09T02:10:22.130Z,1765246222.130 [DAT](INFO): transmitted an acoustic signal
2025-12-09T02:10:22.131Z,1765246222.131 [DAT](INFO): DAT read: TxSync time:02:10:21.6072
2025-12-09T02:10:22.131Z,1765246222.131 [DAT](INFO): publishing transmit ping time
2025-12-09T02:10:22.132Z,1765246222.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001875
2025-12-09T02:10:22.385Z,1765246222.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.254377
2025-12-09T02:10:22.633Z,1765246222.633 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502578
2025-12-09T02:10:22.885Z,1765246222.885 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754623
2025-12-09T02:10:23.137Z,1765246223.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006708
2025-12-09T02:10:23.389Z,1765246223.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258649
2025-12-09T02:10:23.641Z,1765246223.641 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510872
2025-12-09T02:10:23.893Z,1765246223.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762638
2025-12-09T02:10:24.145Z,1765246224.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014606
2025-12-09T02:10:24.399Z,1765246224.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.269315
2025-12-09T02:10:24.649Z,1765246224.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518658
2025-12-09T02:10:24.901Z,1765246224.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770777
2025-12-09T02:10:25.153Z,1765246225.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022987
2025-12-09T02:10:25.405Z,1765246225.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274655
2025-12-09T02:10:25.657Z,1765246225.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526616
2025-12-09T02:10:25.909Z,1765246225.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779003
2025-12-09T02:10:26.161Z,1765246226.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030686
2025-12-09T02:10:26.413Z,1765246226.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282618
2025-12-09T02:10:26.665Z,1765246226.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534600
2025-12-09T02:10:26.917Z,1765246226.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786659
2025-12-09T02:10:27.169Z,1765246227.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038741
2025-12-09T02:10:27.421Z,1765246227.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290881
2025-12-09T02:10:27.673Z,1765246227.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542664
2025-12-09T02:10:27.929Z,1765246227.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798876
2025-12-09T02:10:28.181Z,1765246228.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050481
2025-12-09T02:10:28.433Z,1765246228.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302808
2025-12-09T02:10:28.557Z,1765246228.557 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-12-09T02:10:28.685Z,1765246228.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554598
2025-12-09T02:10:28.756Z,1765246228.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:10:28.937Z,1765246228.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806631
2025-12-09T02:10:29.189Z,1765246229.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058777
2025-12-09T02:10:29.441Z,1765246229.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310604
2025-12-09T02:10:29.693Z,1765246229.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562885
2025-12-09T02:10:29.945Z,1765246229.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815142
2025-12-09T02:10:30.197Z,1765246230.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066588
2025-12-09T02:10:30.449Z,1765246230.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318626
2025-12-09T02:10:30.701Z,1765246230.701 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570658
2025-12-09T02:10:30.953Z,1765246230.953 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822869
2025-12-09T02:10:31.206Z,1765246231.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075390
2025-12-09T02:10:31.457Z,1765246231.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326626
2025-12-09T02:10:31.583Z,1765246231.583 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-12-09T02:10:31.709Z,1765246231.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578592
2025-12-09T02:10:31.961Z,1765246231.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830835
2025-12-09T02:10:32.212Z,1765246232.212 [DAT](INFO): Reached modem response timeout
2025-12-09T02:10:32.213Z,1765246232.213 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:32.213Z,1765246232.213 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-12-09T02:10:32.466Z,1765246232.466 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:32.717Z,1765246232.717 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:32.969Z,1765246232.969 [DAT](INFO): DAT read: Response Not Received
2025-12-09T02:10:32.970Z,1765246232.970 [DAT](INFO): response not received
2025-12-09T02:10:32.970Z,1765246232.970 [DAT](INFO): DAT read: user:8>
2025-12-09T02:10:32.971Z,1765246232.971 [DAT](INFO): DAT read: Tx time:02:10:32.5581
2025-12-09T02:10:32.971Z,1765246232.971 [DAT](INFO): Ping request sent.
2025-12-09T02:10:32.972Z,1765246232.972 [DAT](INFO): transmitted an acoustic signal
2025-12-09T02:10:32.972Z,1765246232.972 [DAT](INFO): publishing transmit ping time
2025-12-09T02:10:32.973Z,1765246232.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001109
2025-12-09T02:10:33.221Z,1765246233.221 [DAT](INFO): DAT read: TxSync time:02:10:32.5573
2025-12-09T02:10:33.222Z,1765246233.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249967
2025-12-09T02:10:33.473Z,1765246233.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501417
2025-12-09T02:10:33.725Z,1765246233.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753301
2025-12-09T02:10:33.977Z,1765246233.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005198
2025-12-09T02:10:34.229Z,1765246234.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257106
2025-12-09T02:10:34.481Z,1765246234.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509251
2025-12-09T02:10:34.733Z,1765246234.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761087
2025-12-09T02:10:34.985Z,1765246234.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013157
2025-12-09T02:10:35.237Z,1765246235.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265311
2025-12-09T02:10:35.489Z,1765246235.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517120
2025-12-09T02:10:35.609Z,1765246235.609 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:10:35.741Z,1765246235.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769668
2025-12-09T02:10:35.993Z,1765246235.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021218
2025-12-09T02:10:36.245Z,1765246236.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273090
2025-12-09T02:10:36.497Z,1765246236.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525426
2025-12-09T02:10:36.749Z,1765246236.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777222
2025-12-09T02:10:37.001Z,1765246237.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029123
2025-12-09T02:10:37.253Z,1765246237.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281228
2025-12-09T02:10:37.505Z,1765246237.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533235
2025-12-09T02:10:37.760Z,1765246237.760 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788871
2025-12-09T02:10:38.013Z,1765246238.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.041430
2025-12-09T02:10:38.265Z,1765246238.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.293201
2025-12-09T02:10:38.517Z,1765246238.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.545092
2025-12-09T02:10:38.769Z,1765246238.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.797152
2025-12-09T02:10:39.021Z,1765246239.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.049184
2025-12-09T02:10:39.273Z,1765246239.273 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.301179
2025-12-09T02:10:39.525Z,1765246239.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.553106
2025-12-09T02:10:39.777Z,1765246239.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.805199
2025-12-09T02:10:40.029Z,1765246240.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057152
2025-12-09T02:10:40.281Z,1765246240.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.309137
2025-12-09T02:10:40.533Z,1765246240.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.561752
2025-12-09T02:10:40.785Z,1765246240.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813137
2025-12-09T02:10:41.037Z,1765246241.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.065503
2025-12-09T02:10:41.291Z,1765246241.291 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.319043
2025-12-09T02:10:41.541Z,1765246241.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.569163
2025-12-09T02:10:41.750Z,1765246241.750 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-12-09T02:10:41.794Z,1765246241.794 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822197
2025-12-09T02:10:42.045Z,1765246242.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.073191
2025-12-09T02:10:42.297Z,1765246242.297 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.325132
2025-12-09T02:10:42.557Z,1765246242.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.585893
2025-12-09T02:10:42.809Z,1765246242.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.837165
2025-12-09T02:10:43.060Z,1765246243.060 [DAT](INFO): Reached modem response timeout
2025-12-09T02:10:43.061Z,1765246243.061 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:43.061Z,1765246243.061 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-12-09T02:10:43.312Z,1765246243.312 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:43.565Z,1765246243.565 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:43.817Z,1765246243.817 [DAT](INFO): DAT read: Response Not Received
2025-12-09T02:10:43.817Z,1765246243.817 [DAT](INFO): response not received
2025-12-09T02:10:43.817Z,1765246243.817 [DAT](INFO): DAT read: user:9>
2025-12-09T02:10:43.818Z,1765246243.818 [DAT](ERROR): No response from remote modem.
2025-12-09T02:10:43.818Z,1765246243.818 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:43.818Z,1765246243.818 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-12-09T02:10:44.069Z,1765246244.069 [DAT](INFO): DAT read: Txtim02:10:43.5082
2025-12-09T02:10:44.069Z,1765246244.069 [DAT](INFO): unknown deviceResponse_: Txtim02:10:43.5082
2025-12-09T02:10:44.070Z,1765246244.070 [DAT] Communications Fault, FailCount= 1
2025-12-09T02:10:44.070Z,1765246244.070 [DAT](ERROR): Communications Fault
2025-12-09T02:10:44.070Z,1765246244.070 [DAT](INFO): DAT read: TxSync time:02:10:43.5074
2025-12-09T02:10:44.071Z,1765246244.071 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:10:44.209Z,1765246244.209 [CBIT](ERROR): Communications Fault in component: DAT
2025-12-09T02:10:44.472Z,1765246244.472 [DAT](INFO): Powering down
2025-12-09T02:10:45.385Z,1765246245.385 [CBIT](INFO): Clearing failed state for component DAT
2025-12-09T02:10:45.385Z,1765246245.385 [DAT] No Fault, FailCount= 1
2025-12-09T02:10:47.520Z,1765246247.520 [DAT](INFO): Powering up
2025-12-09T02:10:47.520Z,1765246247.520 [DAT](DEBUG): Initializing DAT.
2025-12-09T02:10:48.600Z,1765246248.600 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-12-09T02:10:48.987Z,1765246248.987 [DockingStepper](INFO): Changing to mode: 1
2025-12-09T02:10:50.963Z,1765246250.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:10:51.803Z,1765246251.803 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-12-09T02:11:00.128Z,1765246260.128 [DAT](INFO): DAT read:
2025-12-09T02:11:00.129Z,1765246260.129 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-12-09T02:11:00.729Z,1765246260.729 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-12-09T02:11:00.729Z,1765246260.729 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-12-09T02:11:00.786Z,1765246260.786 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-12-09T02:11:01.083Z,1765246261.083 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-12-09T02:11:01.145Z,1765246261.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-12-09T02:11:01.145Z,1765246261.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock] Stopped
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-12-09T02:11:01.482Z,1765246261.482 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-12-09T02:11:01.483Z,1765246261.483 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-12-09T02:11:01.483Z,1765246261.483 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-12-09T02:11:01.483Z,1765246261.483 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-09T02:11:01.483Z,1765246261.483 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-09T02:11:01.484Z,1765246261.484 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-09T02:11:01.484Z,1765246261.484 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-09T02:11:01.485Z,1765246261.485 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-09T02:11:01.485Z,1765246261.485 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-09T02:11:01.485Z,1765246261.485 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-09T02:11:02.291Z,1765246262.291 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (1 active estimators).
2025-12-09T02:11:02.400Z,1765246262.400 [Radio_Surface](INFO): Powering up
2025-12-09T02:11:05.678Z,1765246265.678 [DAT](INFO): DAT read: MF Frequency Band
2025-12-09T02:11:05.679Z,1765246265.679 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2025-12-09T02:11:05.679Z,1765246265.679 [DAT](INFO): DAT read: Dec 9 2025 02:10:55
2025-12-09T02:11:06.317Z,1765246266.317 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:11:06.937Z,1765246266.937 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-12-09T02:11:06.939Z,1765246266.939 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-12-09T02:11:06.939Z,1765246266.939 [DAT](INFO): commRate: 600
2025-12-09T02:11:07.652Z,1765246267.652 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-12-09T02:11:07.652Z,1765246267.652 [Radio_Surface](INFO): No local IP found
2025-12-09T02:11:09.008Z,1765246269.008 [DAT](INFO): entering command mode
2025-12-09T02:11:09.209Z,1765246269.209 [DAT](INFO): DAT read:
2025-12-09T02:11:09.210Z,1765246269.210 [DAT](INFO): DAT read: user:1>
2025-12-09T02:11:09.210Z,1765246269.210 [DAT](INFO): setting verbose to 3
2025-12-09T02:11:09.461Z,1765246269.461 [DAT](INFO): DAT read: user:1>
2025-12-09T02:11:09.461Z,1765246269.461 [DAT](INFO): DAT read: Verbose | 3
2025-12-09T02:11:09.462Z,1765246269.462 [DAT](INFO): set verbose to 3
2025-12-09T02:11:09.462Z,1765246269.462 [DAT](INFO): setting DatVerbose to 27440
2025-12-09T02:11:09.713Z,1765246269.713 [DAT](INFO): DAT read: user:2>
2025-12-09T02:11:09.713Z,1765246269.713 [DAT](INFO): DAT read: DatVerbose | 27440
2025-12-09T02:11:09.714Z,1765246269.714 [DAT](INFO): set DatVerbose to 27440
2025-12-09T02:11:09.714Z,1765246269.714 [DAT](INFO): setting transmit power to 8
2025-12-09T02:11:09.965Z,1765246269.965 [DAT](INFO): DAT read: user:3>
2025-12-09T02:11:09.965Z,1765246269.965 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-12-09T02:11:09.966Z,1765246269.966 [DAT](INFO): set transmit power to 8
2025-12-09T02:11:09.966Z,1765246269.966 [DAT](INFO): setting local address to 10
2025-12-09T02:11:10.218Z,1765246270.218 [DAT](INFO): DAT read: user:4>
2025-12-09T02:11:10.219Z,1765246270.219 [DAT](INFO): DAT read: LocalAddr | 10
2025-12-09T02:11:10.219Z,1765246270.219 [DAT](INFO): set local address to 10
2025-12-09T02:11:10.220Z,1765246270.220 [DAT](INFO): Setting time to: 2:11:10 And date to:12/9/2025
2025-12-09T02:11:10.469Z,1765246270.469 [DAT](INFO): DAT read: user:5>
2025-12-09T02:11:10.470Z,1765246270.470 [DAT](INFO): DAT read: Tue Dec 9, 2025 02:11:10
2025-12-09T02:11:10.470Z,1765246270.470 [DAT](INFO): Local DAT time set to Tue Dec 9, 2025 02:11:10
2025-12-09T02:11:10.471Z,1765246270.471 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:11:10.471Z,1765246270.471 [DAT](INFO): setting remote address to 0
2025-12-09T02:11:10.721Z,1765246270.721 [DAT](INFO): DAT read: user:6>
2025-12-09T02:11:10.721Z,1765246270.721 [DAT](INFO): DAT read: RemoteAddr | 0
2025-12-09T02:11:10.722Z,1765246270.722 [DAT](INFO): set remote address to 0
2025-12-09T02:11:10.722Z,1765246270.722 [DAT](DEBUG): Acoustic response timeout
2025-12-09T02:11:10.722Z,1765246270.722 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-12-09T02:11:10.973Z,1765246270.973 [DAT](INFO): DAT read: user:7>
2025-12-09T02:11:10.973Z,1765246270.973 [DAT](INFO): DAT read: Tx time:02:11:10.6619
2025-12-09T02:11:10.974Z,1765246270.974 [DAT](INFO): Ping request sent.
2025-12-09T02:11:10.974Z,1765246270.974 [DAT](INFO): transmitted an acoustic signal
2025-12-09T02:11:10.974Z,1765246270.974 [DAT](INFO): publishing transmit ping time
2025-12-09T02:11:10.974Z,1765246270.974 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000730
2025-12-09T02:11:11.225Z,1765246271.225 [DAT](INFO): DAT read: TxSync time:02:11:10.6611
2025-12-09T02:11:11.226Z,1765246271.226 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252643
2025-12-09T02:11:11.477Z,1765246271.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502971
2025-12-09T02:11:11.729Z,1765246271.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755457
2025-12-09T02:11:11.981Z,1765246271.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007161
2025-12-09T02:11:12.233Z,1765246272.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258972
2025-12-09T02:11:12.487Z,1765246272.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512945
2025-12-09T02:11:12.737Z,1765246272.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763039
2025-12-09T02:11:12.989Z,1765246272.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015151
2025-12-09T02:11:13.241Z,1765246273.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267423
2025-12-09T02:11:13.493Z,1765246273.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519062
2025-12-09T02:11:13.749Z,1765246273.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774945
2025-12-09T02:11:13.997Z,1765246273.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023131
2025-12-09T02:11:14.249Z,1765246274.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275046
2025-12-09T02:11:14.501Z,1765246274.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527219
2025-12-09T02:11:14.753Z,1765246274.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779272
2025-12-09T02:11:15.005Z,1765246275.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031049
2025-12-09T02:11:15.257Z,1765246275.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283104
2025-12-09T02:11:15.509Z,1765246275.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535097
2025-12-09T02:11:15.761Z,1765246275.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787011
2025-12-09T02:11:16.013Z,1765246276.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038936
2025-12-09T02:11:16.265Z,1765246276.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291001
2025-12-09T02:11:16.517Z,1765246276.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543169
2025-12-09T02:11:16.769Z,1765246276.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794992
2025-12-09T02:11:17.021Z,1765246277.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047281
2025-12-09T02:11:17.273Z,1765246277.273 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299346
2025-12-09T02:11:17.525Z,1765246277.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550982
2025-12-09T02:11:17.777Z,1765246277.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803023
2025-12-09T02:11:18.029Z,1765246278.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055175
2025-12-09T02:11:18.281Z,1765246278.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307015
2025-12-09T02:11:18.533Z,1765246278.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559089
2025-12-09T02:11:18.786Z,1765246278.786 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811862
2025-12-09T02:11:19.037Z,1765246279.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062974
2025-12-09T02:11:19.290Z,1765246279.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315683
2025-12-09T02:11:19.541Z,1765246279.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567041
2025-12-09T02:11:19.793Z,1765246279.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818950
2025-12-09T02:11:20.045Z,1765246280.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071327
2025-12-09T02:11:20.297Z,1765246280.297 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323238
2025-12-09T02:11:20.550Z,1765246280.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.576111
2025-12-09T02:11:20.801Z,1765246280.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827284
2025-12-09T02:11:21.053Z,1765246281.053 [DAT](INFO): Reached modem response timeout
2025-12-09T02:11:21.261Z,1765246281.261 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:11:21.809Z,1765246281.809 [DAT](INFO): DAT read: Response Not Received
2025-12-09T02:11:21.809Z,1765246281.809 [DAT](INFO): response not received
2025-12-09T02:11:21.809Z,1765246281.809 [DAT](ERROR): No response from remote modem.
2025-12-09T02:11:30.166Z,1765246290.166 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-12-09T02:11:30.167Z,1765246290.167 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-12-09T02:11:30.167Z,1765246290.167 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-12-09T02:11:30.615Z,1765246290.615 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-09T02:11:36.312Z,1765246296.312 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:11:47.631Z,1765246307.631 [NAL9602](INFO): SBD MO Status=2, MOMSN=50811, MT Status=2, MTMSN=0
2025-12-09T02:11:47.631Z,1765246307.631 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-09T02:11:51.653Z,1765246311.653 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:12:07.005Z,1765246327.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:12:07.432Z,1765246327.432 [NAL9602](INFO): SBD MO Status=0, MOMSN=50811, MT Status=0, MTMSN=0
2025-12-09T02:12:07.432Z,1765246327.432 [NAL9602](INFO): No messages in MT queue
2025-12-09T02:12:22.382Z,1765246342.382 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:12:32.468Z,1765246352.468 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021232.00,A,3646.49562,N,12151.62839,W,1.050,251.77,091225,,,A*78
2025-12-09T02:12:32.471Z,1765246352.471 [NAL9602](INFO): GPS fix at 20251209T021232: (36.774927, -121.860473)
2025-12-09T02:12:32.501Z,1765246352.501 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-12-09T02:12:32.501Z,1765246352.501 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-12-09T02:12:32.905Z,1765246352.905 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-09T02:12:36.906Z,1765246356.906 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:12:52.018Z,1765246372.018 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-09T02:13:02.935Z,1765246382.935 [NAL9602](INFO): SBD MO Status=2, MOMSN=50812, MT Status=2, MTMSN=0
2025-12-09T02:13:02.935Z,1765246382.935 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-09T02:13:27.275Z,1765246407.275 [NAL9602](INFO): SBD MO Status=1, MOMSN=50812, MT Status=0, MTMSN=0
2025-12-09T02:13:27.324Z,1765246407.324 [NAL9602](INFO): Sent 18 bytes from file Logs/20251208T191201/Courier0103.lzma
2025-12-09T02:13:27.324Z,1765246407.324 [NAL9602](INFO): Packets left to send: 0
2025-12-09T02:13:43.177Z,1765246423.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=50813, MT Status=2, MTMSN=0
2025-12-09T02:13:43.177Z,1765246423.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-09T02:14:01.960Z,1765246441.960 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:14:04.714Z,1765246444.714 [NAL9602](INFO): SBD MO Status=1, MOMSN=50813, MT Status=0, MTMSN=0
2025-12-09T02:14:04.764Z,1765246444.764 [NAL9602](INFO): Sent 331 bytes from file Logs/20251209T020748/Courier0000.lzma
2025-12-09T02:14:04.764Z,1765246444.764 [NAL9602](INFO): Packets left to send: 0
2025-12-09T02:14:20.567Z,1765246460.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=50814, MT Status=2, MTMSN=0
2025-12-09T02:14:20.567Z,1765246460.567 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-09T02:14:33.112Z,1765246473.112 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:14:48.539Z,1765246488.539 [NAL9602](INFO): SBD MO Status=2, MOMSN=50814, MT Status=2, MTMSN=0
2025-12-09T02:14:48.539Z,1765246488.539 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-09T02:14:50.046Z,1765246490.046 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004194
2025-12-09T02:14:57.247Z,1765246497.247 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20251208T191201/Express0104.lzma
2025-12-09T02:14:58.250Z,1765246498.250 [DataOverHttps](INFO): Moved sent file to Logs/20251208T191201/Express0104.lzma.bak
2025-12-09T02:14:58.250Z,1765246498.250 [DataOverHttps](INFO): SBD MOMSN=26466994
2025-12-09T02:15:06.490Z,1765246506.490 [NAL9602](INFO): SBD MO Status=2, MOMSN=50814, MT Status=2, MTMSN=0
2025-12-09T02:15:06.491Z,1765246506.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-09T02:15:14.961Z,1765246514.961 [DataOverHttps](INFO): Sending 1147 bytes from file Logs/20251209T020748/Express0001.lzma
2025-12-09T02:15:15.962Z,1765246515.962 [DataOverHttps](INFO): Moved sent file to Logs/20251209T020748/Express0001.lzma.bak
2025-12-09T02:15:15.962Z,1765246515.962 [DataOverHttps](INFO): SBD MOMSN=26467000
2025-12-09T02:15:17.116Z,1765246517.116 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-12-09T02:15:17.116Z,1765246517.116 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-12-09T02:15:17.116Z,1765246517.116 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-09T02:15:25.600Z,1765246525.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:15:29.923Z,1765246529.923 [BPC1](ERROR): Battery stick #7 (s/n: 02BB) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0.
2025-12-09T02:15:29.924Z,1765246529.924 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5.
2025-12-09T02:15:29.927Z,1765246529.927 [BPC1](INFO): Received data from all battery sticks.
2025-12-09T02:15:34.512Z,1765246534.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:15:37.193Z,1765246537.193 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-09T02:15:42.980Z,1765246542.980 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:15:51.792Z,1765246551.792 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:00.512Z,1765246560.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:09.252Z,1765246569.252 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:17.845Z,1765246577.845 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:30.688Z,1765246590.688 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:39.232Z,1765246599.232 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:47.964Z,1765246607.964 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:16:58.588Z,1765246618.588 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:17:10.588Z,1765246630.588 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:17:24.389Z,1765246644.389 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:17:37.236Z,1765246657.236 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:17:45.861Z,1765246665.861 [DataOverHttps](IMPORTANT): SBD MTMSN=20251209T021746
2025-12-09T02:17:53.568Z,1765246673.568 [DataOverHttps](INFO): Received command: restart sys
2025-12-09T02:17:53.661Z,1765246673.661 [CommandExec](IMPORTANT): got command restart system
2025-12-09T02:17:55.572Z,1765246675.572 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-09T02:17:55.788Z,1765246675.788 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:17:55.788Z,1765246675.788 [CommandExec](INFO): Uninitializing the command executive.
2025-12-09T02:17:55.788Z,1765246675.788 [CommandExec](INFO): Uninitializing the command scheduler.
2025-12-09T02:17:55.789Z,1765246675.789 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:55.884Z,1765246675.884 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-12-09T02:17:55.884Z,1765246675.884 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-12-09T02:17:55.884Z,1765246675.884 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:55.885Z,1765246675.885 [NavChartDb](INFO): Join timeout helper Thread ID is 1028
2025-12-09T02:17:56.156Z,1765246676.156 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:17:56.156Z,1765246676.156 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:56.172Z,1765246676.172 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-12-09T02:17:56.172Z,1765246676.172 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:56.172Z,1765246676.172 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1029
2025-12-09T02:17:56.424Z,1765246676.424 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:17:56.424Z,1765246676.424 [WetLabsUBAT](INFO): Powering down
2025-12-09T02:17:56.425Z,1765246676.425 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:56.432Z,1765246676.432 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-12-09T02:17:56.432Z,1765246676.432 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:56.432Z,1765246676.432 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1030
2025-12-09T02:17:56.732Z,1765246676.732 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:17:56.732Z,1765246676.732 [WetLabsBB2FL](INFO): Powering down
2025-12-09T02:17:56.733Z,1765246676.733 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:56.740Z,1765246676.740 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-12-09T02:17:56.740Z,1765246676.740 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:56.741Z,1765246676.741 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1031
2025-12-09T02:17:57.108Z,1765246677.108 [CTD_Seabird](INFO): Powering down
2025-12-09T02:17:57.120Z,1765246677.120 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:17:57.120Z,1765246677.120 [CTD_Seabird](INFO): Powering down
2025-12-09T02:17:57.132Z,1765246677.132 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:57.140Z,1765246677.140 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-12-09T02:17:57.140Z,1765246677.140 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:57.140Z,1765246677.140 [Radio_Surface](INFO): Join timeout helper Thread ID is 1032
2025-12-09T02:17:57.252Z,1765246677.252 [Radio_Surface](INFO): Powering down
2025-12-09T02:17:57.253Z,1765246677.253 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:17:57.253Z,1765246677.253 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:57.261Z,1765246677.261 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-12-09T02:17:57.261Z,1765246677.261 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-12-09T02:17:57.261Z,1765246677.261 [Onboard](INFO): Join timeout helper Thread ID is 1033
2025-12-09T02:18:01.040Z,1765246681.040 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-09T02:18:01.040Z,1765246681.040 [Onboard ThreadHandler](INFO): Thread cancelled