2021-08-16T03:05:03.309Z,1629083103.309 [Supervisor](DEBUG): Initializing supervisor.
2021-08-16T03:05:03.314Z,1629083103.314 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-08-16T03:05:03.314Z,1629083103.314 [SyncHandler](INFO): Protected caller Thread ID is 836
2021-08-16T03:05:03.315Z,1629083103.315 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-08-16T03:05:03.315Z,1629083103.315 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-08-16T03:05:03.316Z,1629083103.316 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2021-08-16T03:05:03.320Z,1629083103.320 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-08-16T03:05:03.340Z,1629083103.340 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-08-16T03:05:03.341Z,1629083103.341 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-08-16T03:05:03.341Z,1629083103.341 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2021-08-16T03:05:03.344Z,1629083103.344 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-08-16T03:05:03.345Z,1629083103.345 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-08-16T03:05:03.345Z,1629083103.345 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2021-08-16T03:05:03.349Z,1629083103.349 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-08-16T03:05:03.349Z,1629083103.349 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-08-16T03:05:03.354Z,1629083103.354 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-08-16T03:05:03.796Z,1629083103.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-08-16T03:05:03.796Z,1629083103.796 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-08-16T03:05:03.877Z,1629083103.877 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-08-16T03:05:04.214Z,1629083104.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-08-16T03:05:04.215Z,1629083104.215 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-08-16T03:05:04.325Z,1629083104.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-08-16T03:05:04.327Z,1629083104.327 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-08-16T03:05:04.530Z,1629083104.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-08-16T03:05:04.532Z,1629083104.532 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-08-16T03:05:05.070Z,1629083105.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-08-16T03:05:05.072Z,1629083105.072 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-08-16T03:05:05.318Z,1629083105.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-08-16T03:05:05.320Z,1629083105.320 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-08-16T03:05:05.479Z,1629083105.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-08-16T03:05:05.479Z,1629083105.479 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-08-16T03:05:05.675Z,1629083105.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-08-16T03:05:05.676Z,1629083105.676 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-08-16T03:05:05.774Z,1629083105.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-08-16T03:05:05.774Z,1629083105.774 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-08-16T03:05:06.003Z,1629083106.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-08-16T03:05:06.004Z,1629083106.004 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-08-16T03:05:06.394Z,1629083106.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-08-16T03:05:06.394Z,1629083106.394 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-08-16T03:05:06.476Z,1629083106.476 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-08-16T03:05:06.608Z,1629083106.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-08-16T03:05:07.139Z,1629083107.139 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-08-16T03:05:08.114Z,1629083108.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-08-16T03:05:08.116Z,1629083108.116 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-08-16T03:05:08.499Z,1629083108.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-08-16T03:05:08.501Z,1629083108.501 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-polaris/
2021-08-16T03:05:08.506Z,1629083108.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/vehicle.cfg
2021-08-16T03:05:08.712Z,1629083108.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Control.cfg
2021-08-16T03:05:08.815Z,1629083108.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Estimation.cfg
2021-08-16T03:05:08.916Z,1629083108.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/BIT.cfg
2021-08-16T03:05:09.016Z,1629083109.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Battery.cfg
2021-08-16T03:05:09.285Z,1629083109.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-08-16T03:05:09.286Z,1629083109.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Simulator.cfg
2021-08-16T03:05:09.373Z,1629083109.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Navigation.cfg
2021-08-16T03:05:09.469Z,1629083109.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Derivation.cfg
2021-08-16T03:05:09.610Z,1629083109.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/logger.cfg
2021-08-16T03:05:09.723Z,1629083109.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/secure.cfg
2021-08-16T03:05:09.810Z,1629083109.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Dock.cfg
2021-08-16T03:05:09.897Z,1629083109.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Servo.cfg
2021-08-16T03:05:10.007Z,1629083110.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/workSite.cfg
2021-08-16T03:05:10.109Z,1629083110.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Sensor.cfg
2021-08-16T03:05:10.309Z,1629083110.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Science.cfg
2021-08-16T03:05:10.475Z,1629083110.475 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-08-16T03:05:10.479Z,1629083110.479 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-08-16T03:05:11.205Z,1629083111.205 [AHRS_M2] Loaded
2021-08-16T03:05:11.205Z,1629083111.205 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-08-16T03:05:11.451Z,1629083111.451 [BackseatComponent] Loaded
2021-08-16T03:05:11.452Z,1629083111.452 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-08-16T03:05:11.453Z,1629083111.453 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4079A4E0
2021-08-16T03:05:11.453Z,1629083111.453 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 933
2021-08-16T03:05:11.456Z,1629083111.456 [LcmUniversalReporter] Loaded
2021-08-16T03:05:11.456Z,1629083111.456 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-08-16T03:05:12.264Z,1629083112.264 [BPC1] Loaded
2021-08-16T03:05:12.264Z,1629083112.264 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-08-16T03:05:12.403Z,1629083112.403 [DataOverHttps] Loaded
2021-08-16T03:05:12.404Z,1629083112.404 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-08-16T03:05:12.405Z,1629083112.405 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407CA4E0
2021-08-16T03:05:12.405Z,1629083112.405 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 934
2021-08-16T03:05:12.428Z,1629083112.428 [DDM] Loaded
2021-08-16T03:05:12.428Z,1629083112.428 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2021-08-16T03:05:12.448Z,1629083112.448 [Depth_Keller] Loaded
2021-08-16T03:05:12.448Z,1629083112.448 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-08-16T03:05:12.453Z,1629083112.453 [DropWeight] Loaded
2021-08-16T03:05:12.453Z,1629083112.453 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-08-16T03:05:12.499Z,1629083112.499 [DUSBL_Hydroid] Loaded
2021-08-16T03:05:12.499Z,1629083112.499 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2021-08-16T03:05:12.537Z,1629083112.537 [Micromodem] Loaded
2021-08-16T03:05:12.537Z,1629083112.537 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2021-08-16T03:05:12.598Z,1629083112.598 [NAL9602] Loaded
2021-08-16T03:05:12.598Z,1629083112.598 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-08-16T03:05:12.628Z,1629083112.628 [Onboard] Loaded
2021-08-16T03:05:12.628Z,1629083112.628 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-08-16T03:05:12.629Z,1629083112.629 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 407FA4E0
2021-08-16T03:05:12.629Z,1629083112.629 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 935
2021-08-16T03:05:12.637Z,1629083112.637 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2021-08-16T03:05:12.648Z,1629083112.648 [PowerOnly] Loaded
2021-08-16T03:05:12.648Z,1629083112.648 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2021-08-16T03:05:12.660Z,1629083112.660 [Power24vConverter] Loaded
2021-08-16T03:05:12.660Z,1629083112.660 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-08-16T03:05:12.674Z,1629083112.674 [Radio_Surface] Loaded
2021-08-16T03:05:12.675Z,1629083112.675 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-08-16T03:05:12.676Z,1629083112.676 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4082A4E0
2021-08-16T03:05:12.676Z,1629083112.676 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 936
2021-08-16T03:05:12.721Z,1629083112.721 [RDI_Pathfinder] Loaded
2021-08-16T03:05:12.721Z,1629083112.721 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-08-16T03:05:12.748Z,1629083112.748 [RDI_PathfinderUp] Loaded
2021-08-16T03:05:12.748Z,1629083112.748 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2021-08-16T03:05:12.749Z,1629083112.749 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-08-16T03:05:12.751Z,1629083112.751 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-08-16T03:05:12.777Z,1629083112.777 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-08-16T03:05:12.778Z,1629083112.778 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-08-16T03:05:13.344Z,1629083113.344 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-08-16T03:05:13.344Z,1629083113.344 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-08-16T03:05:13.358Z,1629083113.358 [NavChart] Loaded
2021-08-16T03:05:13.358Z,1629083113.358 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-08-16T03:05:13.364Z,1629083113.364 [UniversalFixResidualReporter] Loaded
2021-08-16T03:05:13.365Z,1629083113.365 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-08-16T03:05:13.376Z,1629083113.376 [WorkSite] Loaded
2021-08-16T03:05:13.377Z,1629083113.377 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2021-08-16T03:05:13.377Z,1629083113.377 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-08-16T03:05:13.378Z,1629083113.378 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-08-16T03:05:13.507Z,1629083113.507 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-08-16T03:05:13.508Z,1629083113.508 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-08-16T03:05:13.680Z,1629083113.680 [BuoyancyServo] Loaded
2021-08-16T03:05:13.680Z,1629083113.680 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-08-16T03:05:13.702Z,1629083113.702 [ElevatorServo] Loaded
2021-08-16T03:05:13.703Z,1629083113.703 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-08-16T03:05:13.724Z,1629083113.724 [MassServo] Loaded
2021-08-16T03:05:13.725Z,1629083113.725 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-08-16T03:05:13.745Z,1629083113.745 [RudderServo] Loaded
2021-08-16T03:05:13.746Z,1629083113.746 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-08-16T03:05:13.765Z,1629083113.765 [ThrusterServo] Loaded
2021-08-16T03:05:13.765Z,1629083113.765 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-08-16T03:05:13.766Z,1629083113.766 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-08-16T03:05:13.766Z,1629083113.766 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-08-16T03:05:14.004Z,1629083114.004 [CTD_Seabird] Loaded
2021-08-16T03:05:14.005Z,1629083114.005 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-08-16T03:05:14.006Z,1629083114.006 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409544E0
2021-08-16T03:05:14.006Z,1629083114.006 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 937
2021-08-16T03:05:14.040Z,1629083114.040 [WetLabsSeaOWL_UV_A] Loaded
2021-08-16T03:05:14.041Z,1629083114.041 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2021-08-16T03:05:14.042Z,1629083114.042 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409844E0
2021-08-16T03:05:14.042Z,1629083114.042 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 938
2021-08-16T03:05:14.043Z,1629083114.043 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-08-16T03:05:14.043Z,1629083114.043 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-08-16T03:05:14.459Z,1629083114.459 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-08-16T03:05:14.460Z,1629083114.460 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-08-16T03:05:14.526Z,1629083114.526 [DepthRateCalculator] Loaded
2021-08-16T03:05:14.526Z,1629083114.526 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-08-16T03:05:14.532Z,1629083114.532 [PitchRateCalculator] Loaded
2021-08-16T03:05:14.532Z,1629083114.532 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-08-16T03:05:14.542Z,1629083114.542 [SpeedCalculator] Loaded
2021-08-16T03:05:14.542Z,1629083114.542 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-08-16T03:05:14.556Z,1629083114.556 [TempGradientCalculator] Loaded
2021-08-16T03:05:14.556Z,1629083114.556 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-08-16T03:05:14.572Z,1629083114.572 [VerticalHomogeneityIndexCalculator] Loaded
2021-08-16T03:05:14.572Z,1629083114.572 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2021-08-16T03:05:14.577Z,1629083114.577 [YawRateCalculator] Loaded
2021-08-16T03:05:14.577Z,1629083114.577 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-08-16T03:05:14.600Z,1629083114.600 [ElevatorOffsetCalculator] Loaded
2021-08-16T03:05:14.600Z,1629083114.600 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-08-16T03:05:14.601Z,1629083114.601 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-08-16T03:05:14.601Z,1629083114.601 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-08-16T03:05:14.794Z,1629083114.794 [SBIT](DEBUG): Construct Startup Built In Test.
2021-08-16T03:05:14.807Z,1629083114.807 [SBIT] Loaded
2021-08-16T03:05:14.807Z,1629083114.807 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-08-16T03:05:14.810Z,1629083114.810 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-08-16T03:05:14.824Z,1629083114.824 [IBIT] Loaded
2021-08-16T03:05:14.824Z,1629083114.824 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-08-16T03:05:14.830Z,1629083114.830 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-08-16T03:05:14.937Z,1629083114.937 [CBIT] Loaded
2021-08-16T03:05:14.937Z,1629083114.937 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-08-16T03:05:14.937Z,1629083114.937 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-08-16T03:05:14.938Z,1629083114.938 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-08-16T03:05:15.040Z,1629083115.040 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.399988 degC
2021-08-16T03:05:15.040Z,1629083115.040 [StratificationFrontDetector](INFO): (re)initializing
2021-08-16T03:05:15.041Z,1629083115.041 [StratificationFrontDetector] Loaded
2021-08-16T03:05:15.041Z,1629083115.041 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2021-08-16T03:05:15.041Z,1629083115.041 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-08-16T03:05:15.042Z,1629083115.042 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-08-16T03:05:15.189Z,1629083115.189 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-08-16T03:05:15.189Z,1629083115.189 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-08-16T03:05:15.267Z,1629083115.267 [VerticalControl](DEBUG): Construct VerticalControl.
2021-08-16T03:05:15.320Z,1629083115.320 [VerticalControl] Loaded
2021-08-16T03:05:15.321Z,1629083115.321 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-08-16T03:05:15.324Z,1629083115.324 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-08-16T03:05:15.362Z,1629083115.362 [HorizontalControl] Loaded
2021-08-16T03:05:15.362Z,1629083115.362 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-08-16T03:05:15.365Z,1629083115.365 [SpeedControl](DEBUG): Construct SpeedControl.
2021-08-16T03:05:15.366Z,1629083115.366 [SpeedControl] Loaded
2021-08-16T03:05:15.367Z,1629083115.367 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-08-16T03:05:15.370Z,1629083115.370 [LoopControl](DEBUG): Construct LoopControl.
2021-08-16T03:05:15.371Z,1629083115.371 [LoopControl] Loaded
2021-08-16T03:05:15.371Z,1629083115.371 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-08-16T03:05:15.371Z,1629083115.371 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-08-16T03:05:15.372Z,1629083115.372 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-08-16T03:05:15.418Z,1629083115.418 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-08-16T03:05:15.424Z,1629083115.424 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-08-16T03:05:15.427Z,1629083115.427 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-08-16T03:05:15.438Z,1629083115.438 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-08-16T03:05:15.439Z,1629083115.439 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B324E0
2021-08-16T03:05:15.439Z,1629083115.439 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 939
2021-08-16T03:05:15.444Z,1629083115.444 [Supervisor](INFO): Main Thread ID is 830
2021-08-16T03:05:15.444Z,1629083115.444 [Supervisor](DEBUG): Running supervisor.
2021-08-16T03:05:15.445Z,1629083115.445 [CommandLine ThreadHandler](INFO): Handler Thread ID is 940
2021-08-16T03:05:15.447Z,1629083115.447 [controlThread ThreadHandler](INFO): Handler Thread ID is 941
2021-08-16T03:05:15.448Z,1629083115.448 [controlThread](DEBUG): Initializing ControlThread
2021-08-16T03:05:15.456Z,1629083115.456 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-08-16T03:05:15.456Z,1629083115.456 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-08-16T03:05:15.457Z,1629083115.457 [WorkSite](DEBUG): Initializing WorkSite component.
2021-08-16T03:05:15.458Z,1629083115.458 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-08-16T03:05:15.458Z,1629083115.458 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-08-16T03:05:15.459Z,1629083115.459 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-08-16T03:05:15.459Z,1629083115.459 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-08-16T03:05:15.460Z,1629083115.460 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2021-08-16T03:05:15.461Z,1629083115.461 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-08-16T03:05:15.461Z,1629083115.461 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-08-16T03:05:15.462Z,1629083115.462 [SBIT](INFO): Initialize SBIT Component.
2021-08-16T03:05:15.462Z,1629083115.462 [SBIT](IMPORTANT): git: 2021-08-12
2021-08-16T03:05:15.462Z,1629083115.462 [SBIT](INFO): git hash: 4ac1c7994e02762939ba6e7c4596a90b7f61f056
2021-08-16T03:05:15.463Z,1629083115.463 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-08-16T03:05:15.464Z,1629083115.464 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2021-08-16T03:05:15.465Z,1629083115.465 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2021-08-16T03:05:15.466Z,1629083115.466 [IBIT](INFO): Initialize IBIT Component.
2021-08-16T03:05:15.467Z,1629083115.467 [CBIT](DEBUG): Initialize CBIT Component.
2021-08-16T03:05:15.468Z,1629083115.468 [logger ThreadHandler](INFO): Handler Thread ID is 942
2021-08-16T03:05:15.481Z,1629083115.481 [CBIT](DEBUG): Initialized mux pins.
2021-08-16T03:05:15.481Z,1629083115.481 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2021-08-16T03:05:15.481Z,1629083115.481 [CBIT](DEBUG): Initializing the watchdog timer.
2021-08-16T03:05:15.485Z,1629083115.485 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 943
2021-08-16T03:05:15.493Z,1629083115.493 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 944
2021-08-16T03:05:15.494Z,1629083115.494 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2021-08-16T03:05:15.501Z,1629083115.501 [Onboard ThreadHandler](INFO): Handler Thread ID is 945
2021-08-16T03:05:15.505Z,1629083115.505 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2021-08-16T03:05:15.510Z,1629083115.510 [CBIT](FAULT): Found backtrace file:
1629082741.316971 [CommandLine]: SIGSEGV: address not mapped to object
1629082741.316971 [CommandLine]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1a11d0]
./bin/LRAUV(_ZN11CommandLine15commandScheduleEP13ParsedCommandNS_12ScheduleTypeE+0x618) [0x1ac15c]
./bin/LRAUV(_ZN13ParsedCommand7executeER6Logger+0x38) [0x1a5aac]
./bin/LRAUV(_ZN11CommandLine8getLinesEv+0x43c) [0x1b0118]
./bin/LRAUV(_ZN11CommandLine3runEv+0x1c) [0x1b047c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1a0c1c]

1629082741.567384 [CommandLine]: SIGABRT: execution aborted (failed assertion, corrupted heap, or other problem?)
1629082741.567384 [CommandLine]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1a11d0]
./bin/LRAUV(_ZN11CommandLine3runEv+0x1c) [0x1b047c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1a0c1c]

1629082741.813088 [CommandLine]: SIGABRT: execution aborted (failed assertion, corrupted heap, or other problem?)
1629082741.813088 [CommandLine]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1a11d0]
/lib/libstdc++.so.6 [0x4020bb88]
/lib/libstdc++.so.6(_ZSt9terminatev+0x18) [0x4020bbc4]

2021-08-16T03:05:15.527Z,1629083115.527 [CBIT](FAULT): Renaming backtrace file to Data/backtrace14
2021-08-16T03:05:15.527Z,1629083115.527 [CBIT](DEBUG): Initializing heartbeat.
2021-08-16T03:05:15.529Z,1629083115.529 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 946
2021-08-16T03:05:15.539Z,1629083115.539 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 947
2021-08-16T03:05:15.540Z,1629083115.540 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-08-16T03:05:15.546Z,1629083115.546 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 949
2021-08-16T03:05:15.547Z,1629083115.547 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-08-16T03:05:15.557Z,1629083115.557 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 950
2021-08-16T03:05:15.566Z,1629083115.566 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-08-16T03:05:15.566Z,1629083115.566 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-08-16T03:05:15.566Z,1629083115.566 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-08-16T03:05:15.567Z,1629083115.567 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-08-16T03:05:15.567Z,1629083115.567 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-08-16T03:05:15.567Z,1629083115.567 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-08-16T03:05:15.567Z,1629083115.567 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-08-16T03:05:15.567Z,1629083115.567 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-08-16T03:05:15.567Z,1629083115.567 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-08-16T03:05:15.568Z,1629083115.568 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-08-16T03:05:15.568Z,1629083115.568 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-08-16T03:05:15.568Z,1629083115.568 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-08-16T03:05:15.568Z,1629083115.568 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-08-16T03:05:15.568Z,1629083115.568 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-08-16T03:05:15.569Z,1629083115.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-08-16T03:05:15.569Z,1629083115.569 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-08-16T03:05:15.596Z,1629083115.596 [CBIT](DEBUG): Deactivating GF circuits.
2021-08-16T03:05:15.597Z,1629083115.597 [CBIT](DEBUG): Deactivating emergency mode.
2021-08-16T03:05:15.633Z,1629083115.633 [CBIT](DEBUG): Backplane powered.
2021-08-16T03:05:15.633Z,1629083115.633 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-08-16T03:05:15.635Z,1629083115.635 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-08-16T03:05:15.636Z,1629083115.636 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-08-16T03:05:15.637Z,1629083115.637 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-08-16T03:05:15.638Z,1629083115.638 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-08-16T03:05:15.671Z,1629083115.671 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-08-16T03:05:15.698Z,1629083115.698 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-08-16T03:05:15.699Z,1629083115.699 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2021-08-16T03:05:15.837Z,1629083115.837 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2021-08-16T03:05:15.861Z,1629083115.861 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2021-08-16T03:05:15.866Z,1629083115.866 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 2.000000 count
2021-08-16T03:05:15.886Z,1629083115.886 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2021-08-16T03:05:15.890Z,1629083115.890 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2021-08-16T03:05:15.902Z,1629083115.902 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachModeDDM = 2.000000 count
2021-08-16T03:05:15.903Z,1629083115.903 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2021-08-16T03:05:15.933Z,1629083115.933 [Radio_Surface](INFO): Powering up
2021-08-16T03:05:16.038Z,1629083116.038 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2021-08-16T03:05:16.039Z,1629083116.039 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-08-16T03:05:16.103Z,1629083116.103 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-08-16T03:05:16.156Z,1629083116.156 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-08-16T03:05:16.158Z,1629083116.158 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-08-16T03:05:16.184Z,1629083116.184 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2021-08-16T03:05:16.206Z,1629083116.206 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Control="Tethys/Control"
         xmlns:Dock="Tethys/Dock"
         xmlns:Estimation="Tethys/Estimation"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Sensor="Tethys/Sensor"
         xmlns:Units="Tethys/Units"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Dock http://okeanids.mbari.org/tethys/Xml/Dock.xsd
                             Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="DefaultWithUndock">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to detach form the dock.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Dock transponder address.
    </Description><Dock:Dock.transponderCode/></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        Time duration between acoustic range queries while the mission is
        detaching from the dock.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="DetachModeDDM"><Description>
        DDM DETACH mode enumeration (whiskers retracted, latch open).
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>30</Value></Setting>
    </Guidance:Wait>

    <Aggregate Id="LeaveDock">

        <Sequence/>

        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>

<!-- Acoustic tracking directives -->

        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>

<!-- Leave the dock -->

        <Dock:Undock>
            <Sequence/>
        </Dock:Undock>

    </Aggregate>

    <Aggregate Id="SurfaceDefault">

        <Sequence/>

        <Aggregate Id="HoldLatchOpen">

            <While>
                <Universal:depth/>
                <Gt><Control:VerticalControl.surfaceThreshold/></Gt>
            </While>

<!-- Power the DDM -->

            <ReadData>
                <Dock:Dock.docking_state/>
            </ReadData>

<!-- Hold the DDM in DETACH mode (whiskers retracted, latch open) -->

            <Assign><Parallel/><Dock:Dock.docking_state_cmd/><Arg Name="DetachModeDDM"/></Assign>

        </Aggregate>

<!-- This mission assumes open water so from here on it's the same old default that's always used -->

        <Guidance:GoToSurface>
            <Progression/>
        </Guidance:GoToSurface>

        <Aggregate Id="CheckIn">

            <Sequence Repeat="288"/>

            <ReadDatum Id="Read_GPS">
                <Timeout Duration="P5M"/>
                <Universal:time_fix/>
            </ReadDatum>

            <ReadDatum Id="Read_Iridium">
                <Timeout Duration="P2H">
                    <Guidance:Execute>
                        <Sequence/>
                        <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                    </Guidance:Execute>
                    <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
                </Timeout>
                <Universal:platform_communications/>
            </ReadDatum>

            <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
            </Guidance:Wait>

            <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

            <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

        </Aggregate>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2021-08-16T03:05:16.212Z,1629083116.212 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,RDI_PathfinderUp,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-08-16T03:05:16.225Z,1629083116.225 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-08-16T03:05:16.373Z,1629083116.373 [DDM](INFO): Powering up
2021-08-16T03:05:16.374Z,1629083116.374 [DDM](DEBUG): Initializing DDM.
2021-08-16T03:05:16.399Z,1629083116.399 [DUSBL_Hydroid](INFO): Powering up
2021-08-16T03:05:16.399Z,1629083116.399 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2021-08-16T03:05:16.418Z,1629083116.418 [Power24vConverter](INFO): Powering up.
2021-08-16T03:05:16.469Z,1629083116.469 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-08-16T03:05:16.477Z,1629083116.477 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-08-16T03:05:16.478Z,1629083116.478 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-08-16T03:05:16.485Z,1629083116.485 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-08-16T03:05:16.486Z,1629083116.486 [MassServo](DEBUG): Initializing EZServoServo.
2021-08-16T03:05:16.493Z,1629083116.493 [MassServo](DEBUG): Initializing MassServo.
2021-08-16T03:05:16.494Z,1629083116.494 [RudderServo](DEBUG): Initializing EZServoServo.
2021-08-16T03:05:16.501Z,1629083116.501 [RudderServo](DEBUG): Initializing RudderServo.
2021-08-16T03:05:16.502Z,1629083116.502 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-08-16T03:05:16.509Z,1629083116.509 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-08-16T03:05:16.511Z,1629083116.511 [CommandLine](FAULT): Scheduling is paused
2021-08-16T03:05:16.511Z,1629083116.511 [CBIT](INFO): Critical error at 20210816T030515
2021-08-16T03:05:16.512Z,1629083116.512 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-08-16T03:05:16.644Z,1629083116.644 [Micromodem](INFO): Powering up
2021-08-16T03:05:16.644Z,1629083116.644 [Micromodem](DEBUG): Initializing Micromodem.
2021-08-16T03:05:17.353Z,1629083117.353 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-08-16T03:05:17.353Z,1629083117.353 [RudderServo](FAULT): Rudder failed to initialize
2021-08-16T03:05:17.353Z,1629083117.353 [RudderServo] Communications Fault, FailCount= 1
2021-08-16T03:05:17.353Z,1629083117.353 [RudderServo](ERROR): Communications Fault
2021-08-16T03:05:17.453Z,1629083117.453 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-08-16T03:05:17.634Z,1629083117.634 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-08-16T03:05:17.634Z,1629083117.634 [RudderServo](INFO): Powering down
2021-08-16T03:05:18.283Z,1629083118.283 [RudderServo](DEBUG): Initializing EZServoServo.
2021-08-16T03:05:18.402Z,1629083118.402 [RudderServo](DEBUG): Initializing RudderServo.
2021-08-16T03:05:18.406Z,1629083118.406 [CBIT](INFO): Clearing failed state for component RudderServo
2021-08-16T03:05:18.406Z,1629083118.406 [RudderServo] No Fault, FailCount= 1
2021-08-16T03:05:21.547Z,1629083121.547 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2021-08-16T03:05:22.569Z,1629083122.569 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-08-16T03:05:27.972Z,1629083127.972 [Micromodem](INFO): Nmea in: $CATMG,2021-08-16T03:05:26.040907Z,RTC,RTC*56

2021-08-16T03:05:27.972Z,1629083127.972 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2021-08-16T03:05:26.040907Z,RTC,RTC*56

2021-08-16T03:05:32.007Z,1629083132.007 [DDM](INFO): Dynamic Docking Module:
2021-08-16T03:05:32.825Z,1629083132.825 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2021-08-16T03:05:33.252Z,1629083133.252 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2021-08-16T03:05:33.252Z,1629083133.252 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2021-08-16T03:05:33.653Z,1629083133.653 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2021-08-16T03:05:33.654Z,1629083133.654 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2021-08-16T03:05:34.061Z,1629083134.061 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2021-08-16T03:05:34.062Z,1629083134.062 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2021-08-16T03:05:34.459Z,1629083134.459 [DUSBL_Hydroid](INFO): DUSBL Version:O
2021-08-16T03:05:34.474Z,1629083134.474 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2021-08-16T03:05:34.474Z,1629083134.474 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2021-08-16T03:05:34.863Z,1629083134.863 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2021-08-16T03:05:34.863Z,1629083134.863 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2021-08-16T03:05:35.271Z,1629083135.271 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2021-08-16T03:05:35.271Z,1629083135.271 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2021-08-16T03:05:35.683Z,1629083135.683 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2021-08-16T03:05:35.683Z,1629083135.683 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2021-08-16T03:05:36.091Z,1629083136.091 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2021-08-16T03:05:36.092Z,1629083136.092 [Micromodem](INFO): Nmea out: $CCCLK,2021,08,16,03,05,37*48

2021-08-16T03:05:37.295Z,1629083137.295 [Micromodem](INFO): Nmea in: $CACLK,2021,8,16,3,5,37*7A

2021-08-16T03:05:37.296Z,1629083137.296 [Micromodem](INFO): Nmea in: $CATMS,0,2021-08-16T03:05:38Z*75

2021-08-16T03:05:37.298Z,1629083137.298 [Micromodem](INFO): Nmea in: $CATMG,2021-08-16T03:05:38.027396Z,USER_CMD,RTC*1B

2021-08-16T03:05:38.146Z,1629083138.146 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-08-16T03:05:42.975Z,1629083142.975 [NAL9602](INFO): Powering up NAL9602
2021-08-16T03:05:51.865Z,1629083151.865 [SBIT](IMPORTANT): Beginning Startup BIT
2021-08-16T03:05:51.896Z,1629083151.896 [CBIT](IMPORTANT): Beginning ground fault scan
2021-08-16T03:05:53.856Z,1629083153.856 [NAL9602](INFO): NAL9602 initialized
2021-08-16T03:06:02.838Z,1629083162.838 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006937
CHAN A1 (24V): -0.024184
CHAN A2 (12V): -0.005596
CHAN A3 (5V): -0.001754
CHAN B0 (3.3V): 0.000458
CHAN B1 (3.15aV): 0.000339
CHAN B2 (3.15bV): 0.000422
CHAN B3 (GND): 0.002150
OPEN: 0.008375
Full Scale Calc: 4.765 mA, -1.589 mA
2021-08-16T03:06:03.118Z,1629083163.118 [DDM](FAULT): Failed to open latch before timeout in pausing.
2021-08-16T03:06:03.118Z,1629083163.118 [DDM] Hardware Fault, FailCount= 1
2021-08-16T03:06:03.118Z,1629083163.118 [DDM](ERROR): Hardware Fault
2021-08-16T03:06:03.326Z,1629083163.326 [CBIT](ERROR): Hardware Fault in component: DDM
2021-08-16T03:06:03.519Z,1629083163.519 [DDM](INFO): Powering down
2021-08-16T03:06:04.373Z,1629083164.373 [CBIT](INFO): Clearing failed state for component DDM
2021-08-16T03:06:04.373Z,1629083164.373 [DDM] No Fault, FailCount= 1
2021-08-16T03:06:08.770Z,1629083168.770 [DDM](INFO): Powering up
2021-08-16T03:06:08.771Z,1629083168.771 [DDM](DEBUG): Initializing DDM.
2021-08-16T03:06:11.201Z,1629083171.201 [NAL9602](INFO): SBD MO Status=0, MOMSN=24235, MT Status=0, MTMSN=0
2021-08-16T03:06:11.201Z,1629083171.201 [NAL9602](INFO): No messages in MT queue
2021-08-16T03:06:24.527Z,1629083184.527 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2021-08-16T03:06:40.007Z,1629083200.007 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-08-16T03:06:42.021Z,1629083202.021 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-08-16T03:06:42.021Z,1629083202.021 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-08-16T03:06:42.021Z,1629083202.021 [RDI_Pathfinder](ERROR): Communications Fault
2021-08-16T03:06:42.066Z,1629083202.066 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-08-16T03:06:42.433Z,1629083202.433 [RDI_Pathfinder](INFO): Powering down
2021-08-16T03:06:43.308Z,1629083203.308 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-08-16T03:06:43.308Z,1629083203.308 [RDI_Pathfinder] No Fault, FailCount= 1
2021-08-16T03:06:46.013Z,1629083206.013 [SBIT](IMPORTANT): SBIT PASSED
2021-08-16T03:06:46.013Z,1629083206.013 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-08-16T03:06:46.391Z,1629083206.391 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2021-08-16T03:06:46.395Z,1629083206.395 [WorkSite](IMPORTANT): WorkSite fix at 20210816T030217: (41.567976, -70.723513)
2021-08-16T03:06:46.409Z,1629083206.409 [MissionManager](IMPORTANT): Started mission Startup
2021-08-16T03:06:46.409Z,1629083206.409 [Startup] Running Loop=1
2021-08-16T03:06:46.410Z,1629083206.410 [Startup](DEBUG): Aggregate::initialize Startup
2021-08-16T03:06:46.410Z,1629083206.410 [Startup:A.GoToSurface] Running Loop=1
2021-08-16T03:06:46.410Z,1629083206.410 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-08-16T03:06:46.410Z,1629083206.410 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-08-16T03:06:46.411Z,1629083206.411 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-08-16T03:06:46.411Z,1629083206.411 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-08-16T03:06:46.412Z,1629083206.412 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-08-16T03:06:46.412Z,1629083206.412 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-08-16T03:06:46.418Z,1629083206.418 [Startup:StartupSatComms] Running Loop=1
2021-08-16T03:06:46.418Z,1629083206.418 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-08-16T03:06:46.418Z,1629083206.418 [Startup:StartupSatComms:A] Running Loop=1
2021-08-16T03:06:46.802Z,1629083206.802 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-08-16T03:06:55.642Z,1629083215.642 [DDM](FAULT): Failed to open latch before timeout in pausing.
2021-08-16T03:06:55.642Z,1629083215.642 [DDM] Hardware Fault, FailCount= 2
2021-08-16T03:06:55.643Z,1629083215.643 [DDM](ERROR): Hardware Fault
2021-08-16T03:06:55.671Z,1629083215.671 [CBIT](ERROR): Hardware Fault in component: DDM
2021-08-16T03:06:56.056Z,1629083216.056 [DDM](INFO): Powering down
2021-08-16T03:06:56.923Z,1629083216.923 [CBIT](INFO): Clearing failed state for component DDM
2021-08-16T03:06:56.923Z,1629083216.923 [DDM] No Fault, FailCount= 2
2021-08-16T03:07:01.297Z,1629083221.297 [DDM](INFO): Powering up
2021-08-16T03:07:01.297Z,1629083221.297 [DDM](DEBUG): Initializing DDM.
2021-08-16T03:07:05.764Z,1629083225.764 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-08-16T03:07:17.054Z,1629083237.054 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2021-08-16T03:07:46.605Z,1629083266.605 [Startup:StartupSatComms:A](INFO): Timed out from 2021-08-16T03:06:46.4Z
2021-08-16T03:07:46.605Z,1629083266.605 [Startup:StartupSatComms:A] Stopped
2021-08-16T03:07:46.605Z,1629083266.605 [Startup:StartupSatComms:B] Running Loop=1
2021-08-16T03:07:47.035Z,1629083267.035 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-08-16T03:07:48.164Z,1629083268.164 [DDM](FAULT): Failed to open latch before timeout in pausing.
2021-08-16T03:07:48.164Z,1629083268.164 [DDM] Hardware Fault, FailCount= 3
2021-08-16T03:07:48.164Z,1629083268.164 [DDM](ERROR): Hardware Fault
2021-08-16T03:07:48.266Z,1629083268.266 [CBIT](ERROR): Hardware Fault in component: DDM
2021-08-16T03:07:48.267Z,1629083268.267 [CBIT](CRITICAL): Hardware Fault in component: DDM
2021-08-16T03:07:48.565Z,1629083268.565 [DDM](INFO): Powering down
2021-08-16T03:07:48.662Z,1629083268.662 [CBIT](INFO): Critical error at 20210816T030748
2021-08-16T03:08:01.866Z,1629083281.866 [NAL9602](INFO): SBD MO Status=1, MOMSN=24236, MT Status=0, MTMSN=0
2021-08-16T03:08:01.915Z,1629083281.915 [NAL9602](INFO): Sent 184 bytes from file Logs/20210816T030503/Courier0000.lzma
2021-08-16T03:08:01.915Z,1629083281.915 [NAL9602](INFO): Packets left to send: 1
2021-08-16T03:08:11.520Z,1629083291.520 [NAL9602](INFO): SBD MO Status=1, MOMSN=24237, MT Status=0, MTMSN=0
2021-08-16T03:08:11.577Z,1629083291.577 [NAL9602](INFO): Sent 141 bytes from file Logs/20210816T030503/Courier0000.lzma
2021-08-16T03:08:11.577Z,1629083291.577 [NAL9602](INFO): Packets left to send: 0
2021-08-16T03:08:20.369Z,1629083300.369 [NAL9602](INFO): SBD MO Status=1, MOMSN=24238, MT Status=0, MTMSN=0
2021-08-16T03:08:20.421Z,1629083300.421 [NAL9602](INFO): Sent 184 bytes from file Logs/20210816T030503/Express0001.lzma
2021-08-16T03:08:20.421Z,1629083300.421 [NAL9602](INFO): Packets left to send: 7
2021-08-16T03:08:31.269Z,1629083311.269 [NAL9602](INFO): SBD MO Status=1, MOMSN=24239, MT Status=0, MTMSN=0
2021-08-16T03:08:31.317Z,1629083311.317 [NAL9602](INFO): Sent 184 bytes from file Logs/20210816T030503/Express0001.lzma
2021-08-16T03:08:31.317Z,1629083311.317 [NAL9602](INFO): Packets left to send: 6
2021-08-16T03:08:46.595Z,1629083326.595 [NAL9602](INFO): SBD MO Status=1, MOMSN=24240, MT Status=0, MTMSN=0
2021-08-16T03:08:46.653Z,1629083326.653 [NAL9602](INFO): Sent 184 bytes from file Logs/20210816T030503/Express0001.lzma
2021-08-16T03:08:46.653Z,1629083326.653 [NAL9602](INFO): Packets left to send: 5
2021-08-16T03:08:46.687Z,1629083326.687 [Startup:StartupSatComms:B](INFO): Timed out from 2021-08-16T03:07:46.6Z
2021-08-16T03:08:46.687Z,1629083326.687 [Startup:StartupSatComms:B] Stopped
2021-08-16T03:08:46.687Z,1629083326.687 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-08-16T03:08:46.688Z,1629083326.688 [Startup:StartupSatComms] Stopped
2021-08-16T03:08:46.688Z,1629083326.688 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-08-16T03:08:46.690Z,1629083326.690 [Startup](INFO): Completed Startup
2021-08-16T03:08:46.691Z,1629083326.691 [MissionManager](INFO): Startup is completed.
2021-08-16T03:08:46.691Z,1629083326.691 [MissionManager](INFO): Uninitializing Mission Startup
2021-08-16T03:08:46.691Z,1629083326.691 [Startup] Stopped
2021-08-16T03:08:46.691Z,1629083326.691 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-08-16T03:08:46.691Z,1629083326.691 [Startup:A.GoToSurface] Stopped
2021-08-16T03:08:46.691Z,1629083326.691 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-08-16T03:08:47.743Z,1629083327.743 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2021-08-16T03:08:47.744Z,1629083327.744 [DefaultWithUndock] Running Loop=1
2021-08-16T03:08:47.744Z,1629083327.744 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2021-08-16T03:08:47.744Z,1629083327.744 [DefaultWithUndock:A.Wait] Running Loop=1
2021-08-16T03:08:47.744Z,1629083327.744 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2021-08-16T03:08:50.383Z,1629083330.383 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004311
2021-08-16T03:08:56.578Z,1629083336.578 [NAL9602](INFO): SBD MO Status=1, MOMSN=24241, MT Status=0, MTMSN=0
2021-08-16T03:08:56.630Z,1629083336.630 [NAL9602](INFO): Sent 184 bytes from file Logs/20210816T030503/Express0001.lzma
2021-08-16T03:08:56.630Z,1629083336.630 [NAL9602](INFO): Packets left to send: 4
2021-08-16T03:09:18.032Z,1629083358.032 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2021-08-16T03:09:18.032Z,1629083358.032 [DefaultWithUndock:A.Wait] Stopped
2021-08-16T03:09:18.032Z,1629083358.032 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2021-08-16T03:09:18.033Z,1629083358.033 [DefaultWithUndock:LeaveDock] Running Loop=1
2021-08-16T03:09:18.033Z,1629083358.033 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2021-08-16T03:09:18.033Z,1629083358.033 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-08-16T03:09:18.033Z,1629083358.033 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2021-08-16T03:09:18.033Z,1629083358.033 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2021-08-16T03:09:18.033Z,1629083358.033 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2021-08-16T03:09:18.034Z,1629083358.034 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-08-16T03:09:18.403Z,1629083358.403 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-08-16T03:09:18.791Z,1629083358.791 [PowerOnly](INFO): Powering up loadControl
2021-08-16T03:09:20.862Z,1629083360.862 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:21.684Z,1629083361.684 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:22.021Z,1629083362.021 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:23.231Z,1629083363.231 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030922.79*5A

2021-08-16T03:09:23.751Z,1629083363.751 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:25.250Z,1629083365.250 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:09:26.535Z,1629083366.535 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:27.290Z,1629083367.290 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:27.292Z,1629083367.292 [NAL9602](INFO): Not Powering down - fast GPS
2021-08-16T03:09:27.694Z,1629083367.694 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:28.506Z,1629083368.506 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030928.40*5A

2021-08-16T03:09:29.370Z,1629083369.370 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:30.914Z,1629083370.914 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:09:32.188Z,1629083372.188 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:32.947Z,1629083372.947 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:33.354Z,1629083373.354 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:34.149Z,1629083374.149 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030934.06*55

2021-08-16T03:09:34.987Z,1629083374.987 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:36.566Z,1629083376.566 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:09:37.819Z,1629083377.819 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:38.643Z,1629083378.643 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:39.028Z,1629083379.028 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:39.818Z,1629083379.818 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030939.75*5C

2021-08-16T03:09:40.626Z,1629083380.626 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:42.223Z,1629083382.223 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:09:43.451Z,1629083383.451 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:44.247Z,1629083384.247 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:44.680Z,1629083384.680 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:45.455Z,1629083385.455 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030945.35*53

2021-08-16T03:09:46.274Z,1629083386.274 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:47.882Z,1629083387.882 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:09:49.126Z,1629083389.126 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:49.905Z,1629083389.905 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:50.498Z,1629083390.498 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:51.274Z,1629083391.274 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030951.07*57

2021-08-16T03:09:51.752Z,1629083391.752 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:53.313Z,1629083393.313 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:09:54.535Z,1629083394.535 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:55.311Z,1629083395.311 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:09:55.799Z,1629083395.799 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:09:56.543Z,1629083396.543 [Micromodem](INFO): Nmea in: $SNTTA,,,,,030956.42*51

2021-08-16T03:09:57.342Z,1629083397.342 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:09:58.942Z,1629083398.942 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:00.198Z,1629083400.198 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:00.991Z,1629083400.991 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:01.370Z,1629083401.370 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:02.197Z,1629083402.197 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031002.10*5F

2021-08-16T03:10:03.015Z,1629083403.015 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:04.619Z,1629083404.619 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:05.854Z,1629083405.854 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:06.627Z,1629083406.627 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:07.029Z,1629083407.029 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:07.844Z,1629083407.844 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031007.74*58

2021-08-16T03:10:08.659Z,1629083408.659 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:10.254Z,1629083410.254 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:11.510Z,1629083411.510 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:12.277Z,1629083412.277 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:12.681Z,1629083412.681 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:13.495Z,1629083413.495 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031013.39*54

2021-08-16T03:10:14.329Z,1629083414.329 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:15.570Z,1629083415.570 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:17.137Z,1629083417.137 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:17.935Z,1629083417.935 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:18.339Z,1629083418.339 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:19.151Z,1629083419.151 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031019.04*50

2021-08-16T03:10:20.023Z,1629083420.023 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:20.881Z,1629083420.881 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-08-16T03:10:21.586Z,1629083421.586 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:22.811Z,1629083422.811 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:23.594Z,1629083423.594 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:23.998Z,1629083423.998 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:24.809Z,1629083424.809 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031024.71*5C

2021-08-16T03:10:25.708Z,1629083425.708 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:27.223Z,1629083427.223 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:28.497Z,1629083428.497 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:29.244Z,1629083429.244 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:29.675Z,1629083429.675 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:30.460Z,1629083430.460 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031030.35*59

2021-08-16T03:10:31.316Z,1629083431.316 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:32.938Z,1629083432.938 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:34.134Z,1629083434.134 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:34.899Z,1629083434.899 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:35.308Z,1629083435.308 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:36.120Z,1629083436.120 [Micromodem](INFO): Nmea in: $SNTTA,,,,,031036.01*58

2021-08-16T03:10:36.932Z,1629083436.932 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:37.846Z,1629083437.846 [DataOverHttps](IMPORTANT): SBD MTMSN=20210816T031037
2021-08-16T03:10:38.536Z,1629083438.536 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:39.805Z,1629083439.805 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:40.581Z,1629083440.581 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:40.987Z,1629083440.987 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:41.769Z,1629083441.769 [Micromodem](INFO): Nmea in: $SNTTA,0.461000,0.460687,,,031041.69*5E

2021-08-16T03:10:42.611Z,1629083442.611 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:43.874Z,1629083443.874 [DataOverHttps](INFO): Received command:restart sys
2021-08-16T03:10:43.933Z,1629083443.933 [CommandLine](IMPORTANT): got command restart system
2021-08-16T03:10:44.198Z,1629083444.198 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-08-16T03:10:45.475Z,1629083445.475 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-08-16T03:10:46.029Z,1629083446.029 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-16T03:10:46.029Z,1629083446.029 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.076Z,1629083446.076 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-08-16T03:10:46.077Z,1629083446.077 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.077Z,1629083446.077 [CommandLine](INFO): Join timeout helper Thread ID is 993
2021-08-16T03:10:46.078Z,1629083446.078 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-08-16T03:10:46.078Z,1629083446.078 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.079Z,1629083446.079 [NavChartDb](INFO): Join timeout helper Thread ID is 994
2021-08-16T03:10:46.228Z,1629083446.228 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-08-16T03:10:46.317Z,1629083446.317 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-16T03:10:46.318Z,1629083446.318 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.329Z,1629083446.329 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2021-08-16T03:10:46.329Z,1629083446.329 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.329Z,1629083446.329 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 995
2021-08-16T03:10:46.577Z,1629083446.577 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-16T03:10:46.577Z,1629083446.577 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-08-16T03:10:46.578Z,1629083446.578 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.589Z,1629083446.589 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-08-16T03:10:46.589Z,1629083446.589 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.589Z,1629083446.589 [CTD_Seabird](INFO): Join timeout helper Thread ID is 996
2021-08-16T03:10:46.635Z,1629083446.635 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-08-16T03:10:46.893Z,1629083446.893 [CTD_Seabird](INFO): Powering down
2021-08-16T03:10:46.905Z,1629083446.905 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-16T03:10:46.905Z,1629083446.905 [CTD_Seabird](INFO): Powering down
2021-08-16T03:10:46.917Z,1629083446.917 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.933Z,1629083446.933 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-08-16T03:10:46.933Z,1629083446.933 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-08-16T03:10:46.933Z,1629083446.933 [Radio_Surface](INFO): Join timeout helper Thread ID is 997
2021-08-16T03:10:47.321Z,1629083447.321 [Radio_Surface](INFO): Powering down
2021-08-16T03:10:47.322Z,1629083447.322 [Radio_Surface ThreadHandler](INFO): Uni