2020-01-16T00:50:02.369Z,1579135802.369 [Supervisor](DEBUG): Initializing supervisor.
2020-01-16T00:50:02.372Z,1579135802.372 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-16T00:50:02.373Z,1579135802.373 [SyncHandler](INFO): Protected caller Thread ID is 6602
2020-01-16T00:50:02.373Z,1579135802.373 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-16T00:50:02.374Z,1579135802.374 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-16T00:50:02.374Z,1579135802.374 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6603
2020-01-16T00:50:02.377Z,1579135802.377 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-16T00:50:02.388Z,1579135802.388 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-16T00:50:02.389Z,1579135802.389 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-16T00:50:02.389Z,1579135802.389 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6604
2020-01-16T00:50:02.390Z,1579135802.390 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-16T00:50:02.391Z,1579135802.391 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-16T00:50:02.391Z,1579135802.391 [logger ThreadHandler](INFO): Protected caller Thread ID is 6605
2020-01-16T00:50:02.393Z,1579135802.393 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-16T00:50:02.394Z,1579135802.394 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-16T00:50:02.395Z,1579135802.395 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-16T00:50:02.822Z,1579135802.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-16T00:50:02.822Z,1579135802.822 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-16T00:50:02.917Z,1579135802.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-16T00:50:02.918Z,1579135802.918 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-16T00:50:03.220Z,1579135803.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-16T00:50:03.221Z,1579135803.221 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-16T00:50:03.355Z,1579135803.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-16T00:50:03.356Z,1579135803.356 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-16T00:50:03.538Z,1579135803.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-16T00:50:03.538Z,1579135803.538 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-16T00:50:04.282Z,1579135804.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-16T00:50:04.283Z,1579135804.283 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-16T00:50:04.487Z,1579135804.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-16T00:50:04.487Z,1579135804.487 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-16T00:50:04.628Z,1579135804.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-16T00:50:04.628Z,1579135804.628 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-16T00:50:04.810Z,1579135804.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-16T00:50:04.810Z,1579135804.810 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-16T00:50:04.904Z,1579135804.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-16T00:50:04.905Z,1579135804.905 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-16T00:50:05.194Z,1579135805.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-16T00:50:05.195Z,1579135805.195 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-16T00:50:05.274Z,1579135805.274 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-16T00:50:05.375Z,1579135805.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-16T00:50:05.376Z,1579135805.376 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-16T00:50:05.992Z,1579135805.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-16T00:50:05.993Z,1579135805.993 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-16T00:50:06.406Z,1579135806.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-16T00:50:06.408Z,1579135806.408 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-16T00:50:06.409Z,1579135806.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-16T00:50:06.606Z,1579135806.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-16T00:50:06.702Z,1579135806.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-16T00:50:06.798Z,1579135806.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-16T00:50:07.020Z,1579135807.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-16T00:50:07.020Z,1579135807.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-16T00:50:07.103Z,1579135807.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-16T00:50:07.195Z,1579135807.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-16T00:50:07.291Z,1579135807.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-16T00:50:07.373Z,1579135807.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-16T00:50:07.478Z,1579135807.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-16T00:50:07.654Z,1579135807.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-16T00:50:07.781Z,1579135807.781 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-16T00:50:07.781Z,1579135807.781 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-16T00:50:07.795Z,1579135807.795 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-16T00:50:08.112Z,1579135808.112 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-16T00:50:08.117Z,1579135808.117 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-16T00:50:08.119Z,1579135808.119 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-16T00:50:08.123Z,1579135808.123 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-16T00:50:08.124Z,1579135808.124 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-16T00:50:08.129Z,1579135808.129 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-16T00:50:08.130Z,1579135808.130 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-16T00:50:08.134Z,1579135808.134 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-16T00:50:08.203Z,1579135808.203 [AHRS_M2] Loaded
2020-01-16T00:50:08.203Z,1579135808.203 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-16T00:50:08.281Z,1579135808.281 [DataOverHttps] Loaded
2020-01-16T00:50:08.282Z,1579135808.282 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-16T00:50:08.283Z,1579135808.283 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-16T00:50:08.283Z,1579135808.283 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6684
2020-01-16T00:50:08.306Z,1579135808.306 [DDM] Loaded
2020-01-16T00:50:08.306Z,1579135808.306 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-16T00:50:08.319Z,1579135808.319 [Depth_Keller] Loaded
2020-01-16T00:50:08.319Z,1579135808.319 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-16T00:50:08.324Z,1579135808.324 [DropWeight] Loaded
2020-01-16T00:50:08.324Z,1579135808.324 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-16T00:50:08.384Z,1579135808.384 [DUSBL_Hydroid] Loaded
2020-01-16T00:50:08.384Z,1579135808.384 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-16T00:50:08.429Z,1579135808.429 [Micromodem] Loaded
2020-01-16T00:50:08.430Z,1579135808.430 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-16T00:50:08.522Z,1579135808.522 [NAL9602] Loaded
2020-01-16T00:50:08.523Z,1579135808.523 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-16T00:50:08.559Z,1579135808.559 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-16T00:50:08.560Z,1579135808.560 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-16T00:50:08.560Z,1579135808.560 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-16T00:50:08.561Z,1579135808.561 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-16T00:50:08.561Z,1579135808.561 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-16T00:50:08.562Z,1579135808.562 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-16T00:50:08.562Z,1579135808.562 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-16T00:50:08.563Z,1579135808.563 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-16T00:50:08.563Z,1579135808.563 [Onboard] Loaded
2020-01-16T00:50:08.563Z,1579135808.563 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-16T00:50:08.569Z,1579135808.569 [PowerOnly] Loaded
2020-01-16T00:50:08.569Z,1579135808.569 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-16T00:50:08.576Z,1579135808.576 [Radio_Surface] Loaded
2020-01-16T00:50:08.576Z,1579135808.576 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-16T00:50:08.577Z,1579135808.577 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-16T00:50:08.577Z,1579135808.577 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6685
2020-01-16T00:50:08.619Z,1579135808.619 [RDI_Pathfinder] Loaded
2020-01-16T00:50:08.619Z,1579135808.619 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-16T00:50:10.733Z,1579135810.733 [BPC1] Loaded
2020-01-16T00:50:10.733Z,1579135810.733 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-16T00:50:10.734Z,1579135810.734 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-16T00:50:10.734Z,1579135810.734 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-16T00:50:10.835Z,1579135810.835 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-16T00:50:10.836Z,1579135810.836 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-16T00:50:10.936Z,1579135810.936 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-16T00:50:10.937Z,1579135810.937 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-16T00:50:10.956Z,1579135810.956 [NavChart] Loaded
2020-01-16T00:50:10.957Z,1579135810.957 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-16T00:50:10.961Z,1579135810.961 [UniversalFixResidualReporter] Loaded
2020-01-16T00:50:10.961Z,1579135810.961 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-16T00:50:10.961Z,1579135810.961 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-16T00:50:10.962Z,1579135810.962 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-16T00:50:11.066Z,1579135811.066 [BuoyancyServo] Loaded
2020-01-16T00:50:11.066Z,1579135811.066 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-16T00:50:11.081Z,1579135811.081 [ElevatorServo] Loaded
2020-01-16T00:50:11.081Z,1579135811.081 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-16T00:50:11.096Z,1579135811.096 [MassServo] Loaded
2020-01-16T00:50:11.096Z,1579135811.096 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-16T00:50:11.111Z,1579135811.111 [RudderServo] Loaded
2020-01-16T00:50:11.111Z,1579135811.111 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-16T00:50:11.125Z,1579135811.125 [ThrusterServo] Loaded
2020-01-16T00:50:11.125Z,1579135811.125 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-16T00:50:11.126Z,1579135811.126 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-16T00:50:11.126Z,1579135811.126 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-16T00:50:11.376Z,1579135811.376 [CTD_NeilBrown] Loaded
2020-01-16T00:50:11.377Z,1579135811.377 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-16T00:50:11.378Z,1579135811.378 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-16T00:50:11.378Z,1579135811.378 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6686
2020-01-16T00:50:11.392Z,1579135811.392 [PAR_Licor] Loaded
2020-01-16T00:50:11.393Z,1579135811.393 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-16T00:50:11.435Z,1579135811.435 [WetLabsSeaOWL_UV_A] Loaded
2020-01-16T00:50:11.435Z,1579135811.435 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-16T00:50:11.436Z,1579135811.436 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-16T00:50:11.437Z,1579135811.437 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6687
2020-01-16T00:50:11.437Z,1579135811.437 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-16T00:50:11.438Z,1579135811.438 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-16T00:50:11.759Z,1579135811.759 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-16T00:50:11.760Z,1579135811.760 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-16T00:50:11.799Z,1579135811.799 [DepthRateCalculator] Loaded
2020-01-16T00:50:11.800Z,1579135811.800 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-16T00:50:11.805Z,1579135811.805 [PitchRateCalculator] Loaded
2020-01-16T00:50:11.805Z,1579135811.805 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-16T00:50:11.817Z,1579135811.817 [SpeedCalculator] Loaded
2020-01-16T00:50:11.817Z,1579135811.817 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-16T00:50:11.838Z,1579135811.838 [TempGradientCalculator] Loaded
2020-01-16T00:50:11.838Z,1579135811.838 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-16T00:50:11.844Z,1579135811.844 [YawRateCalculator] Loaded
2020-01-16T00:50:11.844Z,1579135811.844 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-16T00:50:11.884Z,1579135811.884 [ElevatorOffsetCalculator] Loaded
2020-01-16T00:50:11.884Z,1579135811.884 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-16T00:50:11.884Z,1579135811.884 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-16T00:50:11.885Z,1579135811.885 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-16T00:50:12.016Z,1579135812.016 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-16T00:50:12.037Z,1579135812.037 [SBIT] Loaded
2020-01-16T00:50:12.038Z,1579135812.038 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-16T00:50:12.038Z,1579135812.038 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-16T00:50:12.051Z,1579135812.051 [IBIT] Loaded
2020-01-16T00:50:12.051Z,1579135812.051 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-16T00:50:12.054Z,1579135812.054 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-16T00:50:12.189Z,1579135812.189 [CBIT] Loaded
2020-01-16T00:50:12.189Z,1579135812.189 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-16T00:50:12.190Z,1579135812.190 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-16T00:50:12.190Z,1579135812.190 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-16T00:50:12.272Z,1579135812.272 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-16T00:50:12.272Z,1579135812.272 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-16T00:50:12.367Z,1579135812.367 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-16T00:50:12.367Z,1579135812.367 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-16T00:50:12.413Z,1579135812.413 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-16T00:50:12.495Z,1579135812.495 [VerticalControl] Loaded
2020-01-16T00:50:12.495Z,1579135812.495 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-16T00:50:12.496Z,1579135812.496 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-16T00:50:12.564Z,1579135812.564 [HorizontalControl] Loaded
2020-01-16T00:50:12.564Z,1579135812.564 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-16T00:50:12.565Z,1579135812.565 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-16T00:50:12.566Z,1579135812.566 [SpeedControl] Loaded
2020-01-16T00:50:12.567Z,1579135812.567 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-16T00:50:12.567Z,1579135812.567 [LoopControl](DEBUG): Construct LoopControl.
2020-01-16T00:50:12.568Z,1579135812.568 [LoopControl] Loaded
2020-01-16T00:50:12.568Z,1579135812.568 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-16T00:50:12.568Z,1579135812.568 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-16T00:50:12.569Z,1579135812.569 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-16T00:50:12.600Z,1579135812.600 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-16T00:50:12.603Z,1579135812.603 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-16T00:50:12.604Z,1579135812.604 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-16T00:50:12.611Z,1579135812.611 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-16T00:50:12.612Z,1579135812.612 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B094E0
2020-01-16T00:50:12.612Z,1579135812.612 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6688
2020-01-16T00:50:12.617Z,1579135812.617 [Supervisor](INFO): Main Thread ID is 5419
2020-01-16T00:50:12.617Z,1579135812.617 [Supervisor](DEBUG): Running supervisor.
2020-01-16T00:50:12.617Z,1579135812.617 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6689
2020-01-16T00:50:12.620Z,1579135812.620 [controlThread ThreadHandler](INFO): Handler Thread ID is 6690
2020-01-16T00:50:12.621Z,1579135812.621 [controlThread](DEBUG): Initializing ControlThread
2020-01-16T00:50:12.629Z,1579135812.629 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-16T00:50:12.629Z,1579135812.629 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-16T00:50:12.633Z,1579135812.633 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-16T00:50:12.633Z,1579135812.633 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-16T00:50:12.634Z,1579135812.634 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-16T00:50:12.634Z,1579135812.634 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-16T00:50:12.634Z,1579135812.634 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-16T00:50:12.635Z,1579135812.635 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-16T00:50:12.635Z,1579135812.635 [SBIT](INFO): Initialize SBIT Component.
2020-01-16T00:50:12.636Z,1579135812.636 [SBIT](IMPORTANT): git: 2019-12-03-34-g1420f60
2020-01-16T00:50:12.636Z,1579135812.636 [SBIT](INFO): git hash: 1420f6075bfd9e2317cf67bc08ebc33a04e592a5
2020-01-16T00:50:12.636Z,1579135812.636 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-16T00:50:12.638Z,1579135812.638 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2020-01-16T00:50:12.639Z,1579135812.639 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-16T00:50:12.639Z,1579135812.639 [IBIT](INFO): Initialize IBIT Component.
2020-01-16T00:50:12.640Z,1579135812.640 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-16T00:50:12.641Z,1579135812.641 [logger ThreadHandler](INFO): Handler Thread ID is 6691
2020-01-16T00:50:12.653Z,1579135812.653 [CBIT](DEBUG): Initialized mux pins.
2020-01-16T00:50:12.653Z,1579135812.653 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-16T00:50:12.661Z,1579135812.661 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6692
2020-01-16T00:50:12.662Z,1579135812.662 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-16T00:50:12.673Z,1579135812.673 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6693
2020-01-16T00:50:12.677Z,1579135812.677 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-16T00:50:12.677Z,1579135812.677 [CBIT](DEBUG): Initializing heartbeat.
2020-01-16T00:50:12.685Z,1579135812.685 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6694
2020-01-16T00:50:12.686Z,1579135812.686 [CTD_NeilBrown](INFO): Powering down
2020-01-16T00:50:12.705Z,1579135812.705 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6695
2020-01-16T00:50:12.706Z,1579135812.706 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T00:50:12.741Z,1579135812.741 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6696
2020-01-16T00:50:12.744Z,1579135812.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-16T00:50:12.744Z,1579135812.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-16T00:50:12.745Z,1579135812.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-16T00:50:12.745Z,1579135812.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-16T00:50:12.745Z,1579135812.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-16T00:50:12.745Z,1579135812.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-16T00:50:12.745Z,1579135812.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-16T00:50:12.745Z,1579135812.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-16T00:50:12.746Z,1579135812.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-16T00:50:12.746Z,1579135812.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-16T00:50:12.746Z,1579135812.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-16T00:50:12.746Z,1579135812.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-16T00:50:12.746Z,1579135812.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-16T00:50:12.746Z,1579135812.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-16T00:50:12.747Z,1579135812.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-16T00:50:12.747Z,1579135812.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-16T00:50:12.749Z,1579135812.749 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-16T00:50:12.749Z,1579135812.749 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-16T00:50:12.785Z,1579135812.785 [CBIT](DEBUG): Backplane powered.
2020-01-16T00:50:12.785Z,1579135812.785 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-16T00:50:12.787Z,1579135812.787 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-16T00:50:12.788Z,1579135812.788 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-16T00:50:12.788Z,1579135812.788 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-16T00:50:12.789Z,1579135812.789 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-16T00:50:12.799Z,1579135812.799 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T00:50:12.846Z,1579135812.846 [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>


2020-01-16T00:50:12.847Z,1579135812.847 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-16T00:50:12.918Z,1579135812.918 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-16T00:50:12.919Z,1579135812.919 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-16T00:50:12.920Z,1579135812.920 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T00:50:12.944Z,1579135812.944 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-16T00:50:12.986Z,1579135812.986 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-16T00:50:12.991Z,1579135812.991 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-16T00:50:13.011Z,1579135813.011 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</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>13</Value></Setting>
    </Guidance:Wait>

    <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>

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

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

</Mission>


2020-01-16T00:50:13.015Z,1579135813.015 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-16T00:50:13.021Z,1579135813.021 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T00:50:13.057Z,1579135813.057 [DDM](INFO): Powering up
2020-01-16T00:50:13.057Z,1579135813.057 [DDM](DEBUG): Initializing DDM.
2020-01-16T00:50:13.077Z,1579135813.077 [Radio_Surface](INFO): Powering up
2020-01-16T00:50:13.083Z,1579135813.083 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T00:50:13.083Z,1579135813.083 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T00:50:13.289Z,1579135813.289 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:50:13.297Z,1579135813.297 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-16T00:50:13.299Z,1579135813.299 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:50:13.305Z,1579135813.305 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-16T00:50:13.306Z,1579135813.306 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:50:13.313Z,1579135813.313 [MassServo](DEBUG): Initializing MassServo.
2020-01-16T00:50:13.314Z,1579135813.314 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:50:13.321Z,1579135813.321 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T00:50:13.322Z,1579135813.322 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:50:13.329Z,1579135813.329 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-16T00:50:13.442Z,1579135813.442 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-16T00:50:13.442Z,1579135813.442 [DropWeight] Hardware Fault, FailCount= 1
2020-01-16T00:50:13.442Z,1579135813.442 [DropWeight](ERROR): Hardware Fault
2020-01-16T00:50:13.444Z,1579135813.444 [Micromodem](INFO): Powering up
2020-01-16T00:50:13.444Z,1579135813.444 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:50:13.491Z,1579135813.491 [CommandLine](FAULT): Scheduling is paused
2020-01-16T00:50:13.491Z,1579135813.491 [CBIT](INFO): Critical error at 20200116T005013
2020-01-16T00:50:13.491Z,1579135813.491 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-16T00:50:13.494Z,1579135813.494 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-16T00:50:13.494Z,1579135813.494 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-16T00:50:14.229Z,1579135814.229 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-16T00:50:14.229Z,1579135814.229 [RudderServo](FAULT): Rudder failed to initialize
2020-01-16T00:50:14.229Z,1579135814.229 [RudderServo] Communications Fault, FailCount= 1
2020-01-16T00:50:14.229Z,1579135814.229 [RudderServo](ERROR): Communications Fault
2020-01-16T00:50:14.338Z,1579135814.338 [CBIT](INFO): Critical error at 20200116T005013
2020-01-16T00:50:14.341Z,1579135814.341 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-16T00:50:14.546Z,1579135814.546 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T00:50:14.546Z,1579135814.546 [RudderServo](INFO): Powering down
2020-01-16T00:50:15.223Z,1579135815.223 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:50:15.338Z,1579135815.338 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T00:50:15.342Z,1579135815.342 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-16T00:50:15.342Z,1579135815.342 [RudderServo] No Fault, FailCount= 1
2020-01-16T00:50:18.427Z,1579135818.427 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T00:50:22.653Z,1579135822.653 [CBIT](CRITICAL): Environmental Failure. Press:14.712050 PSI. Humidity:28%. Temp:22 C. ABORTING MISSION
2020-01-16T00:50:23.007Z,1579135823.007 [CBIT](INFO): Critical error at 20200116T005022
2020-01-16T00:50:23.657Z,1579135823.657 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-16T00:50:26.500Z,1579135826.500 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-16T00:50:29.715Z,1579135829.715 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:50:30.930Z,1579135830.930 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-16T00:50:35.803Z,1579135835.803 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.00,        +0.00,        +0.00,   0.00,  8.00
2020-01-16T00:50:38.591Z,1579135838.591 [NAL9602](INFO): Powering up NAL9602
2020-01-16T00:50:38.622Z,1579135838.622 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-16T00:50:41.018Z,1579135841.018 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:50:49.503Z,1579135849.503 [NAL9602](INFO): NAL9602 initialized
2020-01-16T00:50:52.010Z,1579135852.010 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-16T00:50:52.015Z,1579135852.015 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-16T00:50:52.330Z,1579135852.330 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:50:55.650Z,1579135855.650 [CommandLine](IMPORTANT): got command show variable surfaceThreshold
2020-01-16T00:50:55.674Z,1579135855.674 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2020-01-16T00:50:55.884Z,1579135855.884 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter)
2020-01-16T00:50:55.891Z,1579135855.891 [CommandLine](IMPORTANT): DAT.surfaceThreshold (meter)
2020-01-16T00:50:55.931Z,1579135855.931 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter)
2020-01-16T00:51:03.172Z,1579135863.172 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.016846
CHAN A1 (24V): -0.027667
CHAN A2 (12V): -0.006429
CHAN A3 (5V): -0.002837
CHAN B0 (3.3V): 0.000185
CHAN B1 (3.15aV): 0.000592
CHAN B2 (3.15bV): 0.000888
CHAN B3 (GND): 0.001739
OPEN: 0.005185
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-16T00:51:03.407Z,1579135863.407 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:51:03.999Z,1579135863.999 [CommandLine](IMPORTANT): got command get Micromodem.surfaceThreshold
2020-01-16T00:51:03.000Z,1579135864.000 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold 0.000000 m
2020-01-16T00:51:05.143Z,1579135865.143 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.989025
2020-01-16T00:51:08.922Z,1579135868.922 [CommandLine](IMPORTANT): got command get depth
2020-01-16T00:51:08.922Z,1579135868.922 [CommandLine](IMPORTANT): depth 0.138507 m
2020-01-16T00:51:14.454Z,1579135874.454 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:51:15.382Z,1579135875.382 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.000000 
2020-01-16T00:51:15.382Z,1579135875.382 [CommandLine](FAULT): Incomplete syntax. Try: help maintain
2020-01-16T00:51:18.020Z,1579135878.020 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.000000 meter
2020-01-16T00:51:18.022Z,1579135878.022 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2020-01-16T00:51:18.283Z,1579135878.283 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-16T00:51:23.786Z,1579135883.786 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T00:51:23.786Z,1579135883.786 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518033030,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-16T00:51:25.766Z,1579135885.766 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:51:29.379Z,1579135889.379 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 35.500000 degree
2020-01-16T00:51:29.380Z,1579135889.380 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2020-01-16T00:51:29.380Z,1579135889.380 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.989998 degree
2020-01-16T00:51:29.382Z,1579135889.382 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2020-01-16T00:51:29.492Z,1579135889.492 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-16T00:51:29.851Z,1579135889.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:29.851Z,1579135889.851 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-16T00:51:29.851Z,1579135889.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:29.861Z,1579135889.861 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:30.286Z,1579135890.286 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:30.286Z,1579135890.286 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-16T00:51:31.055Z,1579135891.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:31.055Z,1579135891.055 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-16T00:51:31.055Z,1579135891.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:31.065Z,1579135891.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:31.504Z,1579135891.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:31.504Z,1579135891.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-16T00:51:32.302Z,1579135892.302 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:32.302Z,1579135892.302 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-16T00:51:32.302Z,1579135892.302 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:32.328Z,1579135892.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:32.691Z,1579135892.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:32.691Z,1579135892.691 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-16T00:51:33.618Z,1579135893.618 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:33.618Z,1579135893.618 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-16T00:51:33.618Z,1579135893.618 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:33.628Z,1579135893.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:34.078Z,1579135894.078 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:34.078Z,1579135894.078 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-16T00:51:34.868Z,1579135894.868 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:34.868Z,1579135894.868 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-16T00:51:34.868Z,1579135894.868 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:34.974Z,1579135894.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:35.337Z,1579135895.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:35.337Z,1579135895.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-16T00:51:36.025Z,1579135896.025 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:36.026Z,1579135896.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-16T00:51:36.026Z,1579135896.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:36.129Z,1579135896.129 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:36.541Z,1579135896.541 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:36.541Z,1579135896.541 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-16T00:51:36.806Z,1579135896.806 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:51:37.274Z,1579135897.274 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:37.274Z,1579135897.274 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-01-16T00:51:37.274Z,1579135897.274 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:37.497Z,1579135897.497 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:37.766Z,1579135897.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:37.766Z,1579135897.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-01-16T00:51:38.465Z,1579135898.465 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:38.466Z,1579135898.466 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-01-16T00:51:38.466Z,1579135898.466 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:38.514Z,1579135898.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:38.937Z,1579135898.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:38.937Z,1579135898.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-01-16T00:51:39.702Z,1579135899.702 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:39.702Z,1579135899.702 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-01-16T00:51:39.702Z,1579135899.702 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:39.768Z,1579135899.768 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:40.150Z,1579135900.150 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:40.150Z,1579135900.150 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-01-16T00:51:40.879Z,1579135900.879 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-16T00:51:40.879Z,1579135900.879 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-01-16T00:51:40.879Z,1579135900.879 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:51:40.943Z,1579135900.943 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1579135872.000000 second
2020-01-16T00:51:41.006Z,1579135901.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:41.303Z,1579135901.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:51:41.303Z,1579135901.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-01-16T00:51:45.758Z,1579135905.758 [SBIT](CRITICAL): SBIT FAILED
2020-01-16T00:51:45.797Z,1579135905.797 [CBIT](INFO): Critical error at 20200116T005145
2020-01-16T00:51:45.849Z,1579135905.849 [CommandLine](IMPORTANT): got command configSet list
2020-01-16T00:51:45.849Z,1579135905.849 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-16T00:51:45.850Z,1579135905.850 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-16T00:51:45.850Z,1579135905.850 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-16T00:51:45.850Z,1579135905.850 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=25 count;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-16T00:51:45.851Z,1579135905.851 [CommandLine](IMPORTANT): Micromodem.sendExpress=1 bool;

2020-01-16T00:51:45.852Z,1579135905.852 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-16T00:51:45.852Z,1579135905.852 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-16T00:51:45.852Z,1579135905.852 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-16T00:51:45.852Z,1579135905.852 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-16T00:51:45.852Z,1579135905.852 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-16T00:51:46.158Z,1579135906.158 [MissionManager](IMPORTANT): Started mission Startup
2020-01-16T00:51:46.158Z,1579135906.158 [Startup] Running Loop=1
2020-01-16T00:51:46.158Z,1579135906.158 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-16T00:51:46.158Z,1579135906.158 [Startup:A.GoToSurface] Running Loop=1
2020-01-16T00:51:46.158Z,1579135906.158 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T00:51:46.159Z,1579135906.159 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T00:51:46.159Z,1579135906.159 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T00:51:46.160Z,1579135906.160 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T00:51:46.160Z,1579135906.160 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T00:51:46.160Z,1579135906.160 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T00:51:46.170Z,1579135906.170 [Startup:StartupSatComms] Running Loop=1
2020-01-16T00:51:46.170Z,1579135906.170 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-16T00:51:46.170Z,1579135906.170 [Startup:StartupSatComms:A] Running Loop=1
2020-01-16T00:51:46.614Z,1579135906.614 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-16T00:51:46.614Z,1579135906.614 [Startup:StartupSatComms:A] Stopped
2020-01-16T00:51:46.614Z,1579135906.614 [Startup:StartupSatComms:B] Running Loop=1
2020-01-16T00:51:47.067Z,1579135907.067 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-16T00:51:48.179Z,1579135908.179 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:51:49.476Z,1579135909.476 [CommandLine](IMPORTANT): got command show stack
2020-01-16T00:51:49.476Z,1579135909.476 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T00:51:49.476Z,1579135909.476 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2020-01-16T00:51:49.476Z,1579135909.476 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2020-01-16T00:51:57.677Z,1579135917.677 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2020-01-16T00:51:57.915Z,1579135917.915 [Startup:StartupSatComms:B] Stopped
2020-01-16T00:51:57.916Z,1579135917.916 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-16T00:51:57.916Z,1579135917.916 [Startup:StartupSatComms] Stopped
2020-01-16T00:51:57.916Z,1579135917.916 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-16T00:51:57.916Z,1579135917.916 [Startup](INFO): Completed Startup
2020-01-16T00:51:57.916Z,1579135917.916 [MissionManager](INFO): Startup is completed.
2020-01-16T00:51:57.917Z,1579135917.917 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-16T00:51:57.917Z,1579135917.917 [Startup] Stopped
2020-01-16T00:51:57.917Z,1579135917.917 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-16T00:51:57.917Z,1579135917.917 [Startup:A.GoToSurface] Stopped
2020-01-16T00:51:57.917Z,1579135917.917 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T00:51:58.326Z,1579135918.326 [MissionManager](IMPORTANT): Started mission Default
2020-01-16T00:51:58.326Z,1579135918.326 [Default] Running Loop=1
2020-01-16T00:51:58.326Z,1579135918.326 [Default](DEBUG): Aggregate::initialize Default
2020-01-16T00:51:58.326Z,1579135918.326 [Default:B.GoToSurface] Running Loop=1
2020-01-16T00:51:58.326Z,1579135918.326 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T00:51:58.327Z,1579135918.327 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T00:51:58.327Z,1579135918.327 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T00:51:58.327Z,1579135918.327 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T00:51:58.327Z,1579135918.327 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T00:51:58.328Z,1579135918.328 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T00:51:58.328Z,1579135918.328 [Default:A.Wait] Running Loop=1
2020-01-16T00:51:58.328Z,1579135918.328 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-16T00:51:59.494Z,1579135919.494 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:52:10.809Z,1579135930.809 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:52:11.661Z,1579135931.661 [Default:A.Wait](INFO): Done Waiting.
2020-01-16T00:52:11.661Z,1579135931.661 [Default:A.Wait] Stopped
2020-01-16T00:52:11.662Z,1579135931.662 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-16T00:52:12.048Z,1579135932.048 [Default:CheckIn] Running Loop=1
2020-01-16T00:52:12.067Z,1579135932.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-16T00:52:12.067Z,1579135932.067 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-16T00:52:12.453Z,1579135932.453 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-16T00:52:13.230Z,1579135933.230 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:52:13.230Z,1579135933.230 [Micromodem] Communications Fault, FailCount= 1
2020-01-16T00:52:13.230Z,1579135933.230 [Micromodem](ERROR): Communications Fault
2020-01-16T00:52:13.322Z,1579135933.322 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:52:13.635Z,1579135933.635 [Micromodem](INFO): Powering down
2020-01-16T00:52:14.509Z,1579135934.509 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:52:14.509Z,1579135934.509 [Micromodem] No Fault, FailCount= 1
2020-01-16T00:52:15.812Z,1579135935.812 [CommandLine](IMPORTANT): got command report
2020-01-16T00:52:17.265Z,1579135937.265 [Micromodem](INFO): Powering up
2020-01-16T00:52:17.266Z,1579135937.266 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:52:22.112Z,1579135942.112 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:52:22.412Z,1579135942.412 [CommandLine](IMPORTANT): got command show variable cable
2020-01-16T00:52:22.496Z,1579135942.496 [CommandLine](IMPORTANT): DDM.cablePresent (bool)
2020-01-16T00:52:28.597Z,1579135948.597 [CommandLine](IMPORTANT): got command report mod DDM.cablePresent
2020-01-16T00:52:28.675Z,1579135948.675 [Reporter](INFO): DDM.cablePresent no_value
2020-01-16T00:52:33.170Z,1579135953.170 [CommandLine](IMPORTANT): got command show variable range
2020-01-16T00:52:33.171Z,1579135953.171 [CommandLine](IMPORTANT): acoustic_contact_range (unknown)
2020-01-16T00:52:33.303Z,1579135953.303 [CommandLine](IMPORTANT): BR_Ping1D.minRange (meter)
2020-01-16T00:52:33.303Z,1579135953.303 [CommandLine](IMPORTANT): BR_Ping1D.maxRange (meter)
2020-01-16T00:52:33.347Z,1579135953.347 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_range (meter)
2020-01-16T00:52:33.350Z,1579135953.350 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2020-01-16T00:52:33.351Z,1579135953.351 [CommandLine](IMPORTANT): Micromodem.range (meter)
2020-01-16T00:52:33.354Z,1579135953.354 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2020-01-16T00:52:33.355Z,1579135953.355 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2020-01-16T00:52:33.355Z,1579135953.355 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2020-01-16T00:52:33.355Z,1579135953.355 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2020-01-16T00:52:33.438Z,1579135953.438 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:52:41.375Z,1579135961.375 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.acoustic_contact_range
2020-01-16T00:52:42.356Z,1579135962.356 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004953
2020-01-16T00:52:44.754Z,1579135964.754 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:52:56.060Z,1579135976.060 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:53:07.434Z,1579135987.434 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:53:18.730Z,1579135998.730 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:53:30.047Z,1579136010.047 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:53:41.370Z,1579136021.370 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:53:52.666Z,1579136032.666 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:54:03.978Z,1579136043.978 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:54:15.290Z,1579136055.290 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:54:16.922Z,1579136056.922 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:54:16.922Z,1579136056.922 [Micromodem] Communications Fault, FailCount= 2
2020-01-16T00:54:16.922Z,1579136056.922 [Micromodem](ERROR): Communications Fault
2020-01-16T00:54:16.997Z,1579136056.997 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:54:17.308Z,1579136057.308 [Micromodem](INFO): Powering down
2020-01-16T00:54:18.164Z,1579136058.164 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:54:18.164Z,1579136058.164 [Micromodem] No Fault, FailCount= 2
2020-01-16T00:54:20.946Z,1579136060.946 [Micromodem](INFO): Powering up
2020-01-16T00:54:20.946Z,1579136060.946 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:54:26.608Z,1579136066.608 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:54:30.124Z,1579136070.124 [CommandLine](IMPORTANT): got command load ./Missions/Insert/Docked.xml
2020-01-16T00:54:30.124Z,1579136070.124 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Docked.xml
2020-01-16T00:54:30.219Z,1579136070.219 [MissionManager](INFO): DefineArg Docked.MaxDockRange = 8.000000 m
2020-01-16T00:54:30.277Z,1579136070.277 [MissionManager](INFO): DefineArg Docked.DetachedTimeout = 5.000000 min
2020-01-16T00:54:30.318Z,1579136070.318 [MissionManager](INFO): DefineArg Docked.Depth = 5.000000 m
2020-01-16T00:54:30.345Z,1579136070.345 [MissionManager](INFO): DefineArg Docked.TransponderCode = 2.000000 count
2020-01-16T00:54:30.349Z,1579136070.349 [MissionManager](INFO): DefineArg Docked.TrackingUpdatePeriod = 60.000000 s
2020-01-16T00:54:30.352Z,1579136070.352 [MissionManager](INFO): DefineArg Docked.NumberOfPings = 1.000000 count
2020-01-16T00:54:30.373Z,1579136070.373 [MissionManager](INFO): DefineArg Docked.Speed = 0.000000 m/s
2020-01-16T00:54:30.376Z,1579136070.376 [MissionManager](INFO): DefineArg Docked.DepthDeadband = 1.500000 m
2020-01-16T00:54:30.379Z,1579136070.379 [MissionManager](INFO): DefineArg Docked.PitchLimit = 20.000000 arcdeg
2020-01-16T00:54:30.400Z,1579136070.400 [MissionManager](INFO): DefineArg Docked.MinDepth = 1.000000 m
2020-01-16T00:54:30.404Z,1579136070.404 [MissionManager](INFO): DefineArg Docked.MaxDepth = 215.000000 m
2020-01-16T00:54:30.405Z,1579136070.405 [Docked:A.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-16T00:54:30.715Z,1579136070.715 [Docked:E.Pitch](DEBUG): Construct.
2020-01-16T00:54:30.746Z,1579136070.746 [Docked:F.SetSpeed](DEBUG): Construct.
2020-01-16T00:54:30.759Z,1579136070.759 [Docked:G.Docked](DEBUG): Construct.
2020-01-16T00:54:30.772Z,1579136070.772 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Universal="Tethys/Universal"
           xmlns:BIT="Tethys/BIT"
           xmlns:Estimation="Tethys/Estimation"
           xmlns:Guidance="Tethys/Guidance"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/BIT http://okeanids.mbari.org/tethys/Xml/BIT.xsd
                               Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                               Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="Docked">

   <DefineArg Name="MaxDockRange"><Description>
       Maximum range allowed from the dock.
   </Description><Units:meter/><Value>8</Value></DefineArg>

   <DefineArg Name="DetachedTimeout"><Description>
       Time duration limit for determining if the vehicle is detached from the dock.
   </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth to maintain while docked.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries.
    </Description><Units:second/><Value>60</Value></DefineArg>

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

    <DefineArg Name="Speed"><Description>
        Thruster speed while docked.
    </Description><Units:meter_per_second/><Value>0.0</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed while docked.
    </Description><Units:meter/><Value>1.5</Value></DefineArg>

    <DefineArg Name="PitchLimit"><Description>
        Max vehicle pitch (+/-) while on dock.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="MinDepth"><Description>
        Minimum depth while docked.
    </Description><Control:VerticalControl.surfaceThreshold/></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth while docked.
    </Description><BIT:CBIT.stopDepth/></DefineArg>


    <Sequence/>

    <Guidance:DepthEnvelope>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="MinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <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>

    <Assign><Parallel/><Control:VerticalControl.pitchLimit/><Arg Name="PitchLimit"/></Assign>
    <Assign><Parallel/><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><<Units:meter_per_second/><Value>0.0</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:Docked>
        <Progression/>
        <Setting><Guidance:Docked.StopDepth/><Arg Name="MaxDepth"/></Setting>
        <Setting><Guidance:Docked.DockRange/><Arg Name="MaxDockRange"/></Setting>
        <Setting><Guidance:Docked.DetachedTimeout/><Arg Name="DetachedTimeout"/></Setting>
    </Guidance:Docked>

</Aggregate>


2020-01-16T00:54:30.777Z,1579136070.777 [CommandLine](IMPORTANT): Loaded ./Missions/Insert/Docked.xml
2020-01-16T00:54:37.918Z,1579136077.918 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:54:41.076Z,1579136081.076 [CommandLine](IMPORTANT): got command set Docked.DetachedTimeout 10.000000 second
2020-01-16T00:54:43.573Z,1579136083.573 [CommandLine](IMPORTANT): got command run
2020-01-16T00:54:43.587Z,1579136083.587 [CommandLine](IMPORTANT): Running 
2020-01-16T00:54:43.656Z,1579136083.656 [Default] Stopped
2020-01-16T00:54:43.656Z,1579136083.656 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-16T00:54:43.656Z,1579136083.656 [Default:B.GoToSurface] Stopped
2020-01-16T00:54:43.656Z,1579136083.656 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T00:54:43.656Z,1579136083.656 [Default:CheckIn] Stopped
2020-01-16T00:54:43.656Z,1579136083.656 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-16T00:54:43.656Z,1579136083.656 [Default:CheckIn:Read_GPS] Stopped
2020-01-16T00:54:43.656Z,1579136083.656 [MissionManager](IMPORTANT): Started mission Docked
2020-01-16T00:54:43.657Z,1579136083.657 [Docked] Running Loop=1
2020-01-16T00:54:43.657Z,1579136083.657 [Docked](DEBUG): Aggregate::initialize Docked
2020-01-16T00:54:43.657Z,1579136083.657 [Docked:A.DepthEnvelope] Running Loop=1
2020-01-16T00:54:43.657Z,1579136083.657 [Docked:A.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-16T00:54:43.657Z,1579136083.657 [Docked:B.] Running Loop=1
2020-01-16T00:54:43.657Z,1579136083.657 [Docked:B.](INFO): Initializing TrackAcousticContact.
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:C] Running Loop=1
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:D] Running Loop=1
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:E.Pitch] Running Loop=1
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:E.Pitch](DEBUG): Initialize.
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:F.SetSpeed] Running Loop=1
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:F.SetSpeed](DEBUG): Initialize.
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:G.Docked] Running Loop=1
2020-01-16T00:54:43.658Z,1579136083.658 [Docked:G.Docked](DEBUG): Initialize.
2020-01-16T00:54:43.659Z,1579136083.659 [Docked:F.SetSpeed] Running Loop=1
2020-01-16T00:54:43.659Z,1579136083.659 [Docked:E.Pitch] Running Loop=1
2020-01-16T00:54:43.660Z,1579136083.660 [Docked:D] Running Loop=1
2020-01-16T00:54:43.660Z,1579136083.660 [Docked:C] Running Loop=1
2020-01-16T00:54:43.660Z,1579136083.660 [Docked:B.] Running Loop=1
2020-01-16T00:54:43.661Z,1579136083.661 [Docked:A.DepthEnvelope] Running Loop=1
2020-01-16T00:54:44.963Z,1579136084.963 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:54:49.270Z,1579136089.270 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:54:49.434Z,1579136089.434 [CommandLine](IMPORTANT): got command show stack
2020-01-16T00:54:49.434Z,1579136089.434 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T00:54:49.434Z,1579136089.434 [Docked](IMPORTANT): Priority 0: Docked:A.DepthEnvelope
2020-01-16T00:54:49.435Z,1579136089.435 [Docked](IMPORTANT): Priority 1: Docked:B.
2020-01-16T00:54:49.435Z,1579136089.435 [Docked](IMPORTANT): Priority 2: Docked:C
2020-01-16T00:54:49.435Z,1579136089.435 [Docked](IMPORTANT): Priority 3: Docked:D
2020-01-16T00:54:49.435Z,1579136089.435 [Docked](IMPORTANT): Priority 4: Docked:E.Pitch
2020-01-16T00:54:49.436Z,1579136089.436 [Docked](IMPORTANT): Priority 5: Docked:F.SetSpeed
2020-01-16T00:54:49.436Z,1579136089.436 [Docked](IMPORTANT): Priority 6: Docked:G.Docked
2020-01-16T00:55:00.566Z,1579136100.566 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:55:11.724Z,1579136111.724 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:55:14.600Z,1579136114.600 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-16T00:55:14.600Z,1579136114.600 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.   +0.00,   0.00,288.00
2020-01-16T00:55:17.772Z,1579136117.772 [CommandLine](IMPORTANT): got command show stack
2020-01-16T00:55:17.772Z,1579136117.772 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T00:55:17.772Z,1579136117.772 [Docked](IMPORTANT): Priority 0: Docked:A.DepthEnvelope
2020-01-16T00:55:17.772Z,1579136117.772 [Docked](IMPORTANT): Priority 1: Docked:B.
2020-01-16T00:55:17.773Z,1579136117.773 [Docked](IMPORTANT): Priority 2: Docked:C
2020-01-16T00:55:17.773Z,1579136117.773 [Docked](IMPORTANT): Priority 3: Docked:D
2020-01-16T00:55:17.773Z,1579136117.773 [Docked](IMPORTANT): Priority 4: Docked:E.Pitch
2020-01-16T00:55:17.773Z,1579136117.773 [Docked](IMPORTANT): Priority 5: Docked:F.SetSpeed
2020-01-16T00:55:17.774Z,1579136117.774 [Docked](IMPORTANT): Priority 6: Docked:G.Docked
2020-01-16T00:55:23.034Z,1579136123.034 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:55:25.376Z,1579136125.376 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:55:34.066Z,1579136134.066 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:55:35.348Z,1579136135.348 [Docked:G.Docked](CRITICAL): VEHICLE DETACHED.
2020-01-16T00:55:35.378Z,1579136135.378 [Docked](INFO): Completed Docked
2020-01-16T00:55:35.378Z,1579136135.378 [MissionManager](INFO): Docked is completed.
2020-01-16T00:55:35.379Z,1579136135.379 [MissionManager](INFO): Uninitializing Mission Docked
2020-01-16T00:55:35.379Z,1579136135.379 [Docked] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked](DEBUG): Aggregate::uninitialize Docked
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:A.DepthEnvelope] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:A.DepthEnvelope](DEBUG): Uninitialize.
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:B.] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:C] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:D] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:E.Pitch] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:F.SetSpeed] Stopped
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:F.SetSpeed](DEBUG): Uninitialize.
2020-01-16T00:55:35.379Z,1579136135.379 [Docked:G.Docked] Stopped
2020-01-16T00:55:35.508Z,1579136135.508 [CBIT](INFO): Critical error at 20200116T005535
2020-01-16T00:55:35.508Z,1579136135.508 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-16T00:55:35.745Z,1579136135.745 [MissionManager](IMPORTANT): Started mission Default
2020-01-16T00:55:35.745Z,1579136135.745 [Default] Running Loop=1
2020-01-16T00:55:35.745Z,1579136135.745 [Default](DEBUG): Aggregate::initialize Default
2020-01-16T00:55:35.745Z,1579136135.745 [Default:B.GoToSurface] Running Loop=1
2020-01-16T00:55:35.745Z,1579136135.745 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T00:55:35.746Z,1579136135.746 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T00:55:35.746Z,1579136135.746 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T00:55:35.746Z,1579136135.746 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T00:55:35.746Z,1579136135.746 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T00:55:35.747Z,1579136135.747 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T00:55:35.747Z,1579136135.747 [Default:A.Wait] Running Loop=1
2020-01-16T00:55:35.747Z,1579136135.747 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-16T00:55:35.834Z,1579136135.834 [Reporter](INFO): DDM.cablePresent no_value
2020-01-16T00:55:40.595Z,1579136140.595 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-16T00:55:45.406Z,1579136145.406 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:55:49.089Z,1579136149.089 [Default:A.Wait](INFO): Done Waiting.
2020-01-16T00:55:49.089Z,1579136149.089 [Default:A.Wait] Stopped
2020-01-16T00:55:49.089Z,1579136149.089 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-16T00:55:49.497Z,1579136149.497 [Default:CheckIn] Running Loop=1
2020-01-16T00:55:49.497Z,1579136149.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-16T00:55:49.497Z,1579136149.497 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-16T00:55:53.091Z,1579136153.091 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-16T00:55:56.722Z,1579136156.722 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:56:08.030Z,1579136168.030 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:56:10.974Z,1579136170.974 [CommandLine](IMPORTANT): got command load ./Missions/Insert/Docked.xml
2020-01-16T00:56:10.974Z,1579136170.974 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Docked.xml
2020-01-16T00:56:11.083Z,1579136171.083 [MissionManager](INFO): DefineArg Docked.MaxDockRange = 8.000000 m
2020-01-16T00:56:11.112Z,1579136171.112 [MissionManager](INFO): DefineArg Docked.DetachedTimeout = 5.000000 min
2020-01-16T00:56:11.115Z,1579136171.115 [MissionManager](INFO): DefineArg Docked.Depth = 5.000000 m
2020-01-16T00:56:11.117Z,1579136171.117 [MissionManager](INFO): DefineArg Docked.TransponderCode = 2.000000 count
2020-01-16T00:56:11.119Z,1579136171.119 [MissionManager](INFO): DefineArg Docked.TrackingUpdatePeriod = 60.000000 s
2020-01-16T00:56:11.133Z,1579136171.133 [MissionManager](INFO): DefineArg Docked.NumberOfPings = 1.000000 count
2020-01-16T00:56:11.135Z,1579136171.135 [MissionManager](INFO): DefineArg Docked.Speed = 0.000000 m/s
2020-01-16T00:56:11.137Z,1579136171.137 [MissionManager](INFO): DefineArg Docked.DepthDeadband = 1.500000 m
2020-01-16T00:56:11.139Z,1579136171.139 [MissionManager](INFO): DefineArg Docked.PitchLimit = 20.000000 arcdeg
2020-01-16T00:56:11.145Z,1579136171.145 [MissionManager](INFO): DefineArg Docked.MinDepth = 1.000000 m
2020-01-16T00:56:11.148Z,1579136171.148 [MissionManager](INFO): DefineArg Docked.MaxDepth = 215.000000 m
2020-01-16T00:56:11.148Z,1579136171.148 [Docked:A.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-16T00:56:11.400Z,1579136171.400 [Docked:E.Pitch](DEBUG): Construct.
2020-01-16T00:56:11.404Z,1579136171.404 [Docked:F.SetSpeed](DEBUG): Construct.
2020-01-16T00:56:11.475Z,1579136171.475 [Docked:G.Docked](DEBUG): Construct.
2020-01-16T00:56:11.521Z,1579136171.521 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Universal="Tethys/Universal"
           xmlns:BIT="Tethys/BIT"
           xmlns:Estimation="Tethys/Estimation"
           xmlns:Guidance="Tethys/Guidance"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/BIT http://okeanids.mbari.org/tethys/Xml/BIT.xsd
                               Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                               Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="Docked">

   <DefineArg Name="MaxDockRange"><Description>
       Maximum range allowed from the dock.
   </Description><Units:meter/><Value>8</Value></DefineArg>

   <DefineArg Name="DetachedTimeout"><Description>
       Time duration limit for determining if the vehicle is detached from the dock.
   </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth to maintain while docked.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries.
    </Description><Units:second/><Value>60</Value></DefineArg>

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

    <DefineArg Name="Speed"><Description>
        Thruster speed while docked.
    </Description><Units:meter_per_second/><Value>0.0</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed while docked.
    </Description><Units:meter/><Value>1.5</Value></DefineArg>

    <DefineArg Name="PitchLimit"><Description>
        Max vehicle pitch (+/-) while on dock.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="MinDepth"><Description>
        Minimum depth while docked.
    </Description><Control:VerticalControl.surfaceThreshold/></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth while docked.
    </Description><BIT:CBIT.stopDepth/></DefineArg>


    <Sequence/>

    <Guidance:DepthEnvelope>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="MinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <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>

    <Assign><Parallel/><Control:VerticalControl.pitchLimit/><Arg Name="PitchLimit"/></Assign>
    <Assign><Parallel/><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><<Units:meter_per_second/><Value>0.0</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:Docked>
        <Progression/>
        <Setting><Guidance:Docked.StopDepth/><Arg Name="MaxDepth"/></Setting>
        <Setting><Guidance:Docked.DockRange/><Arg Name="MaxDockRange"/></Setting>
        <Setting><Guidance:Docked.DetachedTimeout/><Arg Name="DetachedTimeout"/></Setting>
    </Guidance:Docked>

</Aggregate>


2020-01-16T00:56:11.521Z,1579136171.521 [CommandLine](IMPORTANT): Loaded ./Missions/Insert/Docked.xml
2020-01-16T00:56:19.358Z,1579136179.358 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:56:20.570Z,1579136180.570 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:56:20.570Z,1579136180.570 [Micromodem] Communications Fault, FailCount= 3
2020-01-16T00:56:20.570Z,1579136180.570 [Micromodem](ERROR): Communications Fault
2020-01-16T00:56:20.683Z,1579136180.683 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:56:20.981Z,1579136180.981 [Micromodem](INFO): Powering down
2020-01-16T00:56:21.376Z,1579136181.376 [Micromodem](FAULT): LCB 2 fault:  Software Overcurrent.
2020-01-16T00:56:21.376Z,1579136181.376 [Micromodem] Hardware Fault, FailCount= 3
2020-01-16T00:56:21.376Z,1579136181.376 [Micromodem](ERROR): Hardware Fault
2020-01-16T00:56:21.828Z,1579136181.828 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:56:21.828Z,1579136181.828 [Micromodem] No Fault, FailCount= 3
2020-01-16T00:56:24.614Z,1579136184.614 [Micromodem](INFO): Powering up
2020-01-16T00:56:24.614Z,1579136184.614 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:56:29.340Z,1579136189.340 [CommandLine](IMPORTANT): got command run
2020-01-16T00:56:29.341Z,1579136189.341 [CommandLine](IMPORTANT): Running 
2020-01-16T00:56:29.535Z,1579136189.535 [Default] Stopped
2020-01-16T00:56:29.535Z,1579136189.535 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-16T00:56:29.535Z,1579136189.535 [Default:B.GoToSurface] Stopped
2020-01-16T00:56:29.535Z,1579136189.535 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T00:56:29.535Z,1579136189.535 [Default:CheckIn] Stopped
2020-01-16T00:56:29.535Z,1579136189.535 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-16T00:56:29.535Z,1579136189.535 [Default:CheckIn:Read_GPS] Stopped
2020-01-16T00:56:29.535Z,1579136189.535 [MissionManager](IMPORTANT): Started mission Docked
2020-01-16T00:56:29.535Z,1579136189.535 [Docked] Running Loop=1
2020-01-16T00:56:29.535Z,1579136189.536 [Docked](DEBUG): Aggregate::initialize Docked
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:A.DepthEnvelope] Running Loop=1
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:A.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:B.] Running Loop=1
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:B.](INFO): Initializing TrackAcousticContact.
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:C] Running Loop=1
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:D] Running Loop=1
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:E.Pitch] Running Loop=1
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:E.Pitch](DEBUG): Initialize.
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:F.SetSpeed] Running Loop=1
2020-01-16T00:56:29.536Z,1579136189.536 [Docked:F.SetSpeed](DEBUG): Initialize.
2020-01-16T00:56:29.537Z,1579136189.537 [Docked:G.Docked] Running Loop=1
2020-01-16T00:56:29.537Z,1579136189.537 [Docked:G.Docked](DEBUG): Initialize.
2020-01-16T00:56:29.537Z,1579136189.537 [Docked:F.SetSpeed] Running Loop=1
2020-01-16T00:56:29.537Z,1579136189.537 [Docked:E.Pitch] Running Loop=1
2020-01-16T00:56:29.538Z,1579136189.538 [Docked:D] Running Loop=1
2020-01-16T00:56:29.538Z,1579136189.538 [Docked:C] Running Loop=1
2020-01-16T00:56:29.538Z,1579136189.538 [Docked:B.] Running Loop=1
2020-01-16T00:56:29.538Z,1579136189.538 [Docked:A.DepthEnvelope] Running Loop=1
2020-01-16T00:56:30.767Z,1579136190.767 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:56:30.946Z,1579136190.946 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:56:41.330Z,1579136201.330 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:56:42.018Z,1579136202.018 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:56:53.092Z,1579136213.092 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:57:00.784Z,1579136220.784 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-16T00:57:04.398Z,1579136224.398 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:57:15.458Z,1579136235.458 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:57:26.778Z,1579136246.778 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:57:29.625Z,1579136249.625 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:57:37.930Z,1579136257.930 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:57:49.230Z,1579136269.230 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:57:57.913Z,1579136277.913 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-16T00:57:57.916Z,1579136277.916 [BPC1](INFO): Received data from all battery sticks.
2020-01-16T00:58:00.322Z,1579136280.322 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:58:07.610Z,1579136287.610 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T00:58:07.610Z,1579136287.610 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518101430,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-16T00:58:11.626Z,1579136291.626 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:58:22.630Z,1579136302.630 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:58:24.250Z,1579136304.250 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:58:24.250Z,1579136304.250 [Micromodem] Communications Fault, FailCount= 4
2020-01-16T00:58:24.251Z,1579136304.251 [Micromodem](ERROR): Communications Fault
2020-01-16T00:58:24.393Z,1579136304.393 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:58:24.654Z,1579136304.654 [Micromodem](INFO): Powering down
2020-01-16T00:58:25.583Z,1579136305.583 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:58:25.583Z,1579136305.583 [Micromodem] No Fault, FailCount= 4
2020-01-16T00:58:28.290Z,1579136308.290 [Micromodem](INFO): Powering up
2020-01-16T00:58:28.291Z,1579136308.291 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:58:29.931Z,1579136309.931 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:58:33.934Z,1579136313.934 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:58:45.018Z,1579136325.018 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:58:56.298Z,1579136336.298 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:59:07.366Z,1579136347.366 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:59:10.242Z,1579136350.242 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-16T00:59:10.242Z,1579136350.242 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,523.99
2020-01-16T00:59:18.682Z,1579136358.682 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:59:29.694Z,1579136369.694 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:59:30.131Z,1579136370.131 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:59:34.589Z,1579136374.589 [RDI_Pathfinder](ERROR): Failed to parse:
:8,-32768,-32768,V
2020-01-16T00:59:41.002Z,1579136381.002 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:59:41.628Z,1579136381.628 [CommandLine](IMPORTANT): got command failComponent hardware Micromodem
2020-01-16T00:59:41.628Z,1579136381.628 [Micromodem] Hardware Fault, FailCount= 5
2020-01-16T00:59:41.628Z,1579136381.628 [Micromodem](ERROR): Hardware Fault
2020-01-16T00:59:41.628Z,1579136381.628 [CommandLine](IMPORTANT): Micromodem failureMode is Hardware Fault
2020-01-16T00:59:41.814Z,1579136381.814 [Micromodem](INFO): Powering down
2020-01-16T00:59:42.021Z,1579136382.021 [CBIT](ERROR): Hardware Fault in component: Micromodem
2020-01-16T00:59:42.726Z,1579136382.726 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:59:42.726Z,1579136382.726 [Micromodem] No Fault, FailCount= 5
2020-01-16T00:59:45.454Z,1579136385.454 [Micromodem](INFO): Powering up
2020-01-16T00:59:45.454Z,1579136385.454 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:59:52.014Z,1579136392.014 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:00:03.330Z,1579136403.330 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:00:14.350Z,1579136414.350 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:00:25.654Z,1579136425.654 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:00:29.052Z,1579136429.052 [CommandLine](IMPORTANT): got command failComponent
2020-01-16T01:00:29.052Z,1579136429.052 [CommandLine](IMPORTANT): Failed components:
2020-01-16T01:00:29.052Z,1579136429.052 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-01-16T01:00:30.135Z,1579136430.135 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:00:36.678Z,1579136436.678 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:00:47.974Z,1579136447.974 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:00:59.362Z,1579136459.362 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:01:10.663Z,1579136470.663 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:01:21.694Z,1579136481.694 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:01:30.322Z,1579136490.322 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:01:33.006Z,1579136493.006 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:01:44.026Z,1579136504.026 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:01:45.238Z,1579136505.238 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T01:01:45.238Z,1579136505.238 [Micromodem] Communications Fault, FailCount= 6
2020-01-16T01:01:45.239Z,1579136505.239 [Micromodem](ERROR): Communications Fault
2020-01-16T01:01:45.371Z,1579136505.371 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T01:01:45.645Z,1579136505.645 [Micromodem](INFO): Powering down
2020-01-16T01:01:46.046Z,1579136506.046 [Micromodem](FAULT): LCB 2 fault:  Software Overcurrent.
2020-01-16T01:01:46.046Z,1579136506.046 [Micromodem] Hardware Fault, FailCount= 6
2020-01-16T01:01:46.046Z,1579136506.046 [Micromodem](ERROR): Hardware Fault
2020-01-16T01:01:46.541Z,1579136506.541 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T01:01:46.541Z,1579136506.541 [Micromodem] No Fault, FailCount= 6
2020-01-16T01:01:49.282Z,1579136509.282 [Micromodem](INFO): Powering up
2020-01-16T01:01:49.282Z,1579136509.282 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T01:01:55.342Z,1579136515.342 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:02:06.710Z,1579136526.710 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:02:18.014Z,1579136538.014 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:02:26.249Z,1579136546.249 [RDI_Pathfinder](ERROR): Failed to parse:
68,-32768,-32768,V
2020-01-16T01:02:29.026Z,1579136549.026 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:02:30.329Z,1579136550.329 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:02:32.305Z,1579136552.305 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-16T01:02:32.305Z,1579136552.305 [RDI_Pathfinder](ERROR): Failed to parse:
:BD +0.00,        +0.00,        +0.00,   0.00,725.98
2020-01-16T01:02:40.335Z,1579136560.335 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:02:51.654Z,1579136571.654 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:03:02.970Z,1579136582.970 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:03:14.274Z,1579136594.274 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:03:19.527Z,1579136599.527 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T01:03:03.175832Z,RTC,RTC*5D

2020-01-16T01:03:19.527Z,1579136599.527 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-16T01:03:03.175832Z,RTC,RTC*5D

2020-01-16T01:03:25.614Z,1579136605.614 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T01:03:25.998Z,1579136605.998 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:25.998Z,1579136605.998 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:26.395Z,1579136606.395 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:26.395Z,1579136606.395 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:26.799Z,1579136606.799 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:26.799Z,1579136606.799 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:27.217Z,1579136607.217 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:27.218Z,1579136607.218 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:27.616Z,1579136607.616 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:03:27.616Z,1579136607.616 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:03:28.015Z,1579136608.015 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:03:28.015Z,1579136608.015 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:03:29.239Z,1579136609.239 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:03:29.239Z,1579136609.239 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:03:29.631Z,1579136609.631 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:03:29.631Z,1579136609.631 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:03:30.035Z,1579136610.035 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:30.035Z,1579136610.035 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:30.435Z,1579136610.435 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:30.435Z,1579136610.435 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:30.544Z,1579136610.544 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:03:30.859Z,1579136610.859 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:30.859Z,1579136610.859 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:31.268Z,1579136611.268 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:31.268Z,1579136611.268 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:31.646Z,1579136611.646 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:03:31.647Z,1579136611.647 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:03:32.054Z,1579136612.054 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:03:32.055Z,1579136612.055 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:03:32.459Z,1579136612.459 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:03:32.459Z,1579136612.459 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:03:32.858Z,1579136612.858 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:03:32.859Z,1579136612.859 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:03:33.262Z,1579136613.262 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:33.263Z,1579136613.263 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:33.671Z,1579136613.671 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:33.671Z,1579136613.671 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:34.071Z,1579136614.071 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:34.071Z,1579136614.071 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:34.475Z,1579136614.475 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:34.475Z,1579136614.475 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:34.884Z,1579136614.884 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:03:34.884Z,1579136614.884 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:03:35.283Z,1579136615.283 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:03:35.283Z,1579136615.283 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:03:35.686Z,1579136615.686 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:03:35.686Z,1579136615.686 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:03:36.084Z,1579136616.084 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-16T01:03:36.085Z,1579136616.085 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:03:36.085Z,1579136616.085 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:03:36.498Z,1579136616.498 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:36.499Z,1579136616.499 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:36.906Z,1579136616.906 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:36.906Z,1579136616.906 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:37.303Z,1579136617.303 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:37.303Z,1579136617.303 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:37.591Z,1579136617.591 [CommandLine](IMPORTANT): got command show stack
2020-01-16T01:03:37.591Z,1579136617.591 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T01:03:37.592Z,1579136617.592 [Docked](IMPORTANT): Priority 0: Docked:A.DepthEnvelope
2020-01-16T01:03:37.592Z,1579136617.592 [Docked](IMPORTANT): Priority 1: Docked:B.
2020-01-16T01:03:37.592Z,1579136617.592 [Docked](IMPORTANT): Priority 2: Docked:C
2020-01-16T01:03:37.593Z,1579136617.593 [Docked](IMPORTANT): Priority 3: Docked:D
2020-01-16T01:03:37.593Z,1579136617.593 [Docked](IMPORTANT): Priority 4: Docked:E.Pitch
2020-01-16T01:03:37.593Z,1579136617.593 [Docked](IMPORTANT): Priority 5: Docked:F.SetSpeed
2020-01-16T01:03:37.593Z,1579136617.593 [Docked](IMPORTANT): Priority 6: Docked:G.Docked
2020-01-16T01:03:37.723Z,1579136617.723 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:37.723Z,1579136617.723 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:38.922Z,1579136618.922 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:03:38.923Z,1579136618.923 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:03:39.326Z,1579136619.326 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:03:39.327Z,1579136619.327 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:03:39.724Z,1579136619.724 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:03:39.724Z,1579136619.724 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:03:40.130Z,1579136620.130 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:03:40.131Z,1579136620.131 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:03:40.535Z,1579136620.535 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:40.535Z,1579136620.535 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:40.947Z,1579136620.947 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:40.947Z,1579136620.947 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:41.339Z,1579136621.339 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:41.339Z,1579136621.339 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:41.754Z,1579136621.754 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:41.754Z,1579136621.754 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:42.148Z,1579136622.148 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:03:42.148Z,1579136622.148 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:03:42.548Z,1579136622.548 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:03:42.548Z,1579136622.548 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:03:42.962Z,1579136622.962 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:03:42.962Z,1579136622.962 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:03:43.357Z,1579136623.357 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:03:43.358Z,1579136623.358 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:03:43.770Z,1579136623.770 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:43.771Z,1579136623.771 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:44.170Z,1579136624.170 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:44.171Z,1579136624.171 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:44.593Z,1579136624.593 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:44.593Z,1579136624.593 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:44.979Z,1579136624.979 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:44.979Z,1579136624.979 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:45.394Z,1579136625.394 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:03:45.395Z,1579136625.395 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:03:45.783Z,1579136625.783 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:03:45.783Z,1579136625.783 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:03:46.192Z,1579136626.192 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:03:46.192Z,1579136626.192 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:03:46.602Z,1579136626.602 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:03:46.603Z,1579136626.603 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:03:46.999Z,1579136626.999 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:03:46.999Z,1579136626.999 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:03:47.404Z,1579136627.404 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:03:47.404Z,1579136627.404 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:03:47.807Z,1579136627.807 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:03:47.807Z,1579136627.807 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:03:49.019Z,1579136629.019 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:03:49.019Z,1579136629.019 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:03:49.019Z,1579136629.019 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CACFG,pwramp.txlevel,0*2D
, available: 
2020-01-16T01:03:49.019Z,1579136629.019 [Micromodem] Communications Fault, FailCount= 7
2020-01-16T01:03:49.019Z,1579136629.019 [Micromodem](ERROR): Communications Fault
2020-01-16T01:03:49.127Z,1579136629.127 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T01:03:49.422Z,1579136629.422 [Micromodem](INFO): Powering down
2020-01-16T01:03:50.374Z,1579136630.374 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T01:03:50.374Z,1579136630.374 [Micromodem] No Fault, FailCount= 7
2020-01-16T01:03:53.059Z,1579136633.059 [Micromodem](INFO): Powering up
2020-01-16T01:03:53.059Z,1579136633.059 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T01:03:57.904Z,1579136637.904 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T01:03:41.392558Z,RTC,RTC*51

2020-01-16T01:03:57.904Z,1579136637.904 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-16T01:03:41.392558Z,RTC,RTC*51

2020-01-16T01:04:00.330Z,1579136640.330 [Micromodem](ERROR): Nmea resend: $CCCFG,DTO,30*1E

2020-01-16T01:04:00.743Z,1579136640.743 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:04:00.743Z,1579136640.743 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:04:01.146Z,1579136641.146 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:04:01.146Z,1579136641.146 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:04:01.543Z,1579136641.543 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:04:01.543Z,1579136641.543 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:04:01.946Z,1579136641.946 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:04:01.947Z,1579136641.947 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,16,01,03,30*43

2020-01-16T01:04:02.355Z,1579136642.355 [Micromodem](INFO): Nmea in: $CACLK,2020,1,16,1,3,30*71

2020-01-16T01:04:02.355Z,1579136642.355 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T01:04:02.764Z,1579136642.764 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T01:04:02.764Z,1579136642.764 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T01:04:02.769Z,1579136642.769 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-16T01:03:31Z*70

2020-01-16T01:04:02.770Z,1579136642.770 [Micromodem](ERROR): CFG response from modem unexpected: $CATMS,0,2020-01-16T01:03:31Z*70

2020-01-16T01:04:03.159Z,1579136643.159 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T01:03:31.029115Z,USER_CMD,RTC*19

2020-01-16T01:04:03.159Z,1579136643.159 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-16T01:03:31.029115Z,USER_CMD,RTC*19

2020-01-16T01:04:03.160Z,1579136643.160 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T01:04:03.558Z,1579136643.558 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T01:04:03.972Z,1579136643.972 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T01:04:03.972Z,1579136643.972 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T01:04:04.371Z,1579136644.371 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T01:04:04.371Z,1579136644.371 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T01:04:04.782Z,1579136644.782 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T01:04:04.782Z,1579136644.782 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T01:04:05.179Z,1579136645.179 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T01:04:05.179Z,1579136645.179 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T01:04:05.619Z,1579136645.619 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T01:04:05.619Z,1579136645.619 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T01:04:05.994Z,1579136645.994 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T01:04:05.995Z,1579136645.995 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,16,01,04,02*45

2020-01-16T01:04:06.392Z,1579136646.392 [Micromodem](INFO): Nmea in: $CACLK,2020,1,16,1,4,2*47

2020-01-16T01:04:06.799Z,1579136646.799 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-16T01:04:03Z*76

2020-01-16T01:04:06.800Z,1579136646.800 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T01:04:03.027718Z,USER_CMD,RTC*1A

2020-01-16T01:04:23.456Z,1579136663.456 [CommandLine](IMPORTANT): got command show stack
2020-01-16T01:04:23.456Z,1579136663.456 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T01:04:23.456Z,1579136663.456 [Docked](IMPORTANT): Priority 0: Docked:A.DepthEnvelope
2020-01-16T01:04:23.456Z,1579136663.456 [Docked](IMPORTANT): Priority 1: Docked:B.
2020-01-16T01:04:23.457Z,1579136663.457 [Docked](IMPORTANT): Priority 2: Docked:C
2020-01-16T01:04:23.457Z,1579136663.457 [Docked](IMPORTANT): Priority 3: Docked:D
2020-01-16T01:04:23.457Z,1579136663.457 [Docked](IMPORTANT): Priority 4: Docked:E.Pitch
2020-01-16T01:04:23.458Z,1579136663.458 [Docked](IMPORTANT): Priority 5: Docked:F.SetSpeed
2020-01-16T01:04:23.458Z,1579136663.458 [Docked](IMPORTANT): Priority 6: Docked:G.Docked
2020-01-16T01:04:30.671Z,1579136670.671 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:04:31.455Z,1579136671.455 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:04:31.857Z,1579136671.857 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:04:32.671Z,1579136672.671 [Micromodem](INFO): Nmea in: $SNTTA,0.000196,,,,010428.04*45

2020-01-16T01:05:06.617Z,1579136706.617 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-16T01:05:06.618Z,1579136706.618 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-16T01:05:08.488Z,1579136708.488 [CommandLine](IMPORTANT): got command report
2020-01-16T01:05:08.488Z,1579136708.488 [Reporter](INFO): DDM.cablePresent reporting changes
2020-01-16T01:05:08.488Z,1579136708.488 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range reporting when touched
2020-01-16T01:05:13.523Z,1579136713.523 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-16T01:05:13.523Z,1579136713.523 [DropWeight] No Fault, FailCount= 1
2020-01-16T01:05:30.886Z,1579136730.886 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:05:31.651Z,1579136731.651 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:05:32.055Z,1579136732.055 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:05:32.529Z,1579136732.529 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 1.500000 m
2020-01-16T01:05:32.878Z,1579136732.878 [Micromodem](INFO): Nmea in: $SNTTA,0.000979,,,,010528.24*4F

2020-01-16T01:05:52.300Z,1579136752.300 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-16T01:05:52.300Z,1579136752.300 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-16T01:06:32.670Z,1579136792.670 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-16T01:06:32.670Z,1579136792.670 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-16T01:06:32.681Z,1579136792.681 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:06:33.464Z,1579136793.464 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:06:33.869Z,1579136793.869 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:06:34.683Z,1579136794.683 [Micromodem](INFO): Nmea in: $SNTTA,0.000208,,,,010630.05*4B

2020-01-16T01:06:49.078Z,1579136809.078 [CommandLine](IMPORTANT): got command show variable detectionThresh
2020-01-16T01:06:49.286Z,1579136809.286 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2020-01-16T01:06:55.663Z,1579136815.663 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.detectionThreshold
2020-01-16T01:06:55.664Z,1579136815.664 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold 25 count
2020-01-16T01:07:02.148Z,1579136822.148 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 20.000000 count persist
2020-01-16T01:07:09.221Z,1579136829.221 [CommandLine](IMPORTANT): got command failComponent hardware DUSBL_Hydroid
2020-01-16T01:07:09.221Z,1579136829.221 [DUSBL_Hydroid] Hardware Fault, FailCount= 1
2020-01-16T01:07:09.221Z,1579136829.221 [DUSBL_Hydroid](ERROR): Hardware Fault
2020-01-16T01:07:09.221Z,1579136829.221 [CommandLine](IMPORTANT): DUSBL_Hydroid failureMode is Hardware Fault
2020-01-16T01:07:09.427Z,1579136829.427 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T01:07:09.555Z,1579136829.555 [CBIT](ERROR): Hardware Fault in component: DUSBL_Hydroid
2020-01-16T01:07:10.302Z,1579136830.302 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T01:07:10.302Z,1579136830.302 [DUSBL_Hydroid] No Fault, FailCount= 1
2020-01-16T01:07:12.639Z,1579136832.639 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T01:07:12.639Z,1579136832.639 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T01:07:30.826Z,1579136850.826 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-16T01:07:32.883Z,1579136852.883 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:07:33.656Z,1579136853.656 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:07:34.061Z,1579136854.061 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:07:34.879Z,1579136854.879 [Micromodem](INFO): Nmea in: $SNTTA,0.000227,,,,010730.24*44

2020-01-16T01:07:47.601Z,1579136867.601 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 15.000000 count persist
2020-01-16T01:07:52.464Z,1579136872.464 [CommandLine](IMPORTANT): got command failComponent hardware DUSBL_Hydroid
2020-01-16T01:07:52.464Z,1579136872.464 [DUSBL_Hydroid] Hardware Fault, FailCount= 2
2020-01-16T01:07:52.464Z,1579136872.464 [DUSBL_Hydroid](ERROR): Hardware Fault
2020-01-16T01:07:52.464Z,1579136872.464 [CommandLine](IMPORTANT): DUSBL_Hydroid failureMode is Hardware Fault
2020-01-16T01:07:52.643Z,1579136872.643 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T01:07:52.764Z,1579136872.764 [CBIT](ERROR): Hardware Fault in component: DUSBL_Hydroid
2020-01-16T01:07:53.543Z,1579136873.543 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T01:07:53.543Z,1579136873.543 [DUSBL_Hydroid] No Fault, FailCount= 2
2020-01-16T01:07:55.877Z,1579136875.877 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T01:07:55.877Z,1579136875.877 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T01:08:07.240Z,1579136887.240 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-16T01:08:07.240Z,1579136887.240 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-16T01:08:14.063Z,1579136894.063 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-16T01:08:33.105Z,1579136913.105 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:08:33.867Z,1579136913.867 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:08:34.265Z,1579136914.265 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:08:35.081Z,1579136915.081 [Micromodem](INFO): Nmea in: $SNTTA,0.000233,0.020920,,,010830.45*5E

2020-01-16T01:09:27.236Z,1579136967.236 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-16T01:09:27.236Z,1579136967.236 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,1140.94
2020-01-16T01:09:33.282Z,1579136973.282 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:09:34.063Z,1579136974.063 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:09:34.461Z,1579136974.461 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:09:35.279Z,1579136975.279 [Micromodem](INFO): Nmea in: $SNTTA,,,,,010930.65*56

2020-01-16T01:09:37.690Z,1579136977.690 [DUSBL_Hydroid](ERROR): No response from remote modem.
2020-01-16T01:09:46.607Z,1579136986.607 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:09:46.607Z,1579136986.607 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518215330,35.0, -0.1,   0.,  0
2020-01-16T01:10:32.661Z,1579137032.661 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:10:32.661Z,1579137032.661 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518223930,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-16T01:10:33.499Z,1579137033.499 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:10:34.255Z,1579137034.255 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:10:34.665Z,1579137034.665 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:10:35.479Z,1579137035.479 [Micromodem](INFO): Nmea in: $SNTTA,0.000230,,,,011030.84*4E

2020-01-16T01:10:58.829Z,1579137058.829 [CommandLine](IMPORTANT): got command show variable detectionThreshold
2020-01-16T01:10:59.007Z,1579137059.007 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2020-01-16T01:11:03.769Z,1579137063.769 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:11:03.769Z,1579137063.769 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518231030,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-16T01:11:04.502Z,1579137064.502 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.detectionThreshold
2020-01-16T01:11:04.503Z,1579137064.503 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold 15 count
2020-01-16T01:11:10.206Z,1579137070.206 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 30.000000 count persist
2020-01-16T01:11:15.696Z,1579137075.696 [CommandLine](IMPORTANT): got command failComponent hardware DUSBL_Hydroid
2020-01-16T01:11:15.696Z,1579137075.696 [DUSBL_Hydroid] Hardware Fault, FailCount= 1
2020-01-16T01:11:15.696Z,1579137075.696 [DUSBL_Hydroid](ERROR): Hardware Fault
2020-01-16T01:11:15.697Z,1579137075.697 [CommandLine](IMPORTANT): DUSBL_Hydroid failureMode is Hardware Fault
2020-01-16T01:11:15.865Z,1579137075.865 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T01:11:15.990Z,1579137075.990 [CBIT](ERROR): Hardware Fault in component: DUSBL_Hydroid
2020-01-16T01:11:16.716Z,1579137076.716 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T01:11:16.716Z,1579137076.716 [DUSBL_Hydroid] No Fault, FailCount= 1
2020-01-16T01:11:19.102Z,1579137079.102 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T01:11:19.102Z,1579137079.102 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T01:11:33.710Z,1579137093.710 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:11:34.118Z,1579137094.118 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.detectionThreshold
2020-01-16T01:11:34.119Z,1579137094.119 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold 30 count
2020-01-16T01:11:37.282Z,1579137097.282 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-16T01:12:33.862Z,1579137153.862 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:12:34.637Z,1579137154.637 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:12:35.062Z,1579137155.062 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:12:35.517Z,1579137155.517 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.299999 m
2020-01-16T01:12:35.878Z,1579137155.878 [Micromodem](INFO): Nmea in: $SNTTA,0.000229,,,,011231.22*49

2020-01-16T01:12:59.723Z,1579137179.723 [CommandLine](IMPORTANT): got command show variable sendExpress
2020-01-16T01:12:59.831Z,1579137179.831 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sendExpress (bool)
2020-01-16T01:12:59.834Z,1579137179.834 [CommandLine](IMPORTANT): DAT.sendExpress (bool)
2020-01-16T01:12:59.838Z,1579137179.838 [CommandLine](IMPORTANT): Micromodem.sendExpress (bool)
2020-01-16T01:13:05.103Z,1579137185.103 [CommandLine](IMPORTANT): got command get Micromodem.sendExpress
2020-01-16T01:13:05.104Z,1579137185.104 [CommandLine](IMPORTANT): Micromodem.sendExpress 1 bool
2020-01-16T01:13:12.659Z,1579137192.659 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:13:12.659Z,1579137192.659 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518251930,35.0, 0.0,1448.9,  0
2020-01-16T01:13:35.710Z,1579137215.710 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:13:36.468Z,1579137216.468 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:13:36.862Z,1579137216.862 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:13:37.352Z,1579137217.352 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.239999 m
2020-01-16T01:13:37.690Z,1579137217.690 [Micromodem](INFO): Nmea in: $SNTTA,0.000209,,,,011333.05*4D

2020-01-16T01:14:07.600Z,1579137247.600 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:14:07.600Z,1579137247.600 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518261430,35.0, -0.1,   0.0,144

2020-01-16T01:14:37.520Z,1579137277.520 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:14:38.283Z,1579137278.283 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:14:38.681Z,1579137278.681 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:14:39.118Z,1579137279.118 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.360000 m
2020-01-16T01:14:39.514Z,1579137279.514 [Micromodem](INFO): Nmea in: $SNTTA,0.000225,,,,011434.87*49

2020-01-16T01:15:39.326Z,1579137339.326 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:15:40.096Z,1579137340.096 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:15:40.497Z,1579137340.497 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:15:40.954Z,1579137340.954 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.360000 m
2020-01-16T01:15:41.334Z,1579137341.334 [Micromodem](INFO): Nmea in: $SNTTA,0.000244,,,,011536.68*4C

2020-01-16T01:16:06.387Z,1579137366.387 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +0.00,        +0.00,        +0.00,   0.00,1538.90
2020-01-16T01:16:11.251Z,1579137371.251 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-16T01:16:11.252Z,1579137371.252 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,1544.89
2020-01-16T01:16:41.129Z,1579137401.129 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:16:41.893Z,1579137401.893 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:16:42.327Z,1579137402.327 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:16:42.769Z,1579137402.769 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.299999 m
2020-01-16T01:16:43.130Z,1579137403.130 [Micromodem](INFO): Nmea in: $SNTTA,0.000235,,,,011638.48*45

2020-01-16T01:16:49.228Z,1579137409.228 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-16T01:16:49.228Z,1579137409.228 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3276,V
2020-01-16T01:17:04.952Z,1579137424.952 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:17:04.953Z,1579137424.953 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518291130,35.0, -0.1,448.9,  0
2020-01-16T01:17:42.932Z,1579137462.932 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:17:43.716Z,1579137463.716 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:17:44.110Z,1579137464.110 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:17:44.608Z,1579137464.608 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.299999 m
2020-01-16T01:17:44.942Z,1579137464.942 [Micromodem](INFO): Nmea in: $SNTTA,0.000230,,,,011740.30*41

2020-01-16T01:17:44.960Z,1579137464.960 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T01:17:44.960Z,1579137464.960 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011518290, -0.1,   0.0,1448.9,  0
2020-01-16T01:17:48.589Z,1579137468.589 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-16T01:17:48.589Z,1579137468.589 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-16T01:18:44.761Z,1579137524.761 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T01:18:45.538Z,1579137525.538 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2020-01-16T01:18:45.937Z,1579137525.937 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2020-01-16T01:18:46.450Z,1579137526.450 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 0.360000 m
2020-01-16T01:18:46.766Z,1579137526.766 [Micromodem](INFO): Nmea in: $SNTTA,0.000245,,,,011842.12*4E

2020-01-16T01:19:08.600Z,1579137548.600 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-16T01:19:08.600Z,1579137548.600 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-16T01:19:10.223Z,1579137550.223 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-16T01:19:10.224Z,1579137550.224 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-16T01:19:18.556Z,1579137558.556 [CommandLine](IMPORTANT): got command quit
2020-01-16T01:19:19.589Z,1579137559.589 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-01-16T01:19:19.590Z,1579137559.590 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:19.590Z,1579137559.590 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:19.697Z,1579137559.697 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-16T01:19:19.697Z,1579137559.697 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:19.698Z,1579137559.698 [CommandLine](INFO): Join timeout helper Thread ID is 6797
2020-01-16T01:19:19.698Z,1579137559.698 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-16T01:19:19.698Z,1579137559.698 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:19.699Z,1579137559.699 [NavChartDb](INFO): Join timeout helper Thread ID is 6798
2020-01-16T01:19:19.770Z,1579137559.770 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:19.771Z,1579137559.771 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:19.777Z,1579137559.777 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-16T01:19:19.777Z,1579137559.777 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:19.777Z,1579137559.777 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6799
2020-01-16T01:19:19.918Z,1579137559.918 [MissionManager](INFO): MissionManager is completed.
2020-01-16T01:19:19.918Z,1579137559.918 [MissionManager](INFO): Uninitializing Mission Docked
2020-01-16T01:19:19.918Z,1579137559.918 [Docked] Stopped
2020-01-16T01:19:19.918Z,1579137559.918 [Docked](DEBUG): Aggregate::uninitialize Docked
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:A.DepthEnvelope] Stopped
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:A.DepthEnvelope](DEBUG): Uninitialize.
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:B.] Stopped
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:C] Stopped
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:D] Stopped
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:E.Pitch] Stopped
2020-01-16T01:19:19.918Z,1579137559.918 [Docked:F.SetSpeed] Stopped
2020-01-16T01:19:19.919Z,1579137559.919 [Docked:F.SetSpeed](DEBUG): Uninitialize.
2020-01-16T01:19:19.919Z,1579137559.919 [Docked:G.Docked] Stopped
2020-01-16T01:19:20.001Z,1579137560.001 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:20.001Z,1579137560.001 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T01:19:20.002Z,1579137560.002 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:20.005Z,1579137560.005 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-16T01:19:20.005Z,1579137560.005 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:20.005Z,1579137560.005 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6800
2020-01-16T01:19:20.129Z,1579137560.129 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:20.129Z,1579137560.129 [CTD_NeilBrown](INFO): Powering down
2020-01-16T01:19:20.141Z,1579137560.141 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:20.149Z,1579137560.149 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-16T01:19:20.149Z,1579137560.149 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:20.149Z,1579137560.149 [Radio_Surface](INFO): Join timeout helper Thread ID is 6801
2020-01-16T01:19:20.301Z,1579137560.301 [MissionManager](IMPORTANT): Started mission Default
2020-01-16T01:19:20.301Z,1579137560.301 [Default] Running Loop=1
2020-01-16T01:19:20.301Z,1579137560.301 [Default](DEBUG): Aggregate::initialize Default
2020-01-16T01:19:20.301Z,1579137560.301 [Default:B.GoToSurface] Running Loop=1
2020-01-16T01:19:20.301Z,1579137560.301 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T01:19:20.302Z,1579137560.302 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T01:19:20.302Z,1579137560.302 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T01:19:20.302Z,1579137560.302 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T01:19:20.302Z,1579137560.302 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T01:19:20.303Z,1579137560.303 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T01:19:20.303Z,1579137560.303 [Default:A.Wait] Running Loop=1
2020-01-16T01:19:20.303Z,1579137560.303 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-16T01:19:20.325Z,1579137560.325 [Reporter](INFO): DDM.cablePresent no_value
2020-01-16T01:19:20.413Z,1579137560.413 [Radio_Surface](INFO): Powering down
2020-01-16T01:19:20.414Z,1579137560.414 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:20.414Z,1579137560.414 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:20.434Z,1579137560.434 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-16T01:19:20.434Z,1579137560.434 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:20.434Z,1579137560.434 [DataOverHttps](INFO): Join timeout helper Thread ID is 6802
2020-01-16T01:19:21.169Z,1579137561.169 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:21.169Z,1579137561.169 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:21.190Z,1579137561.190 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-16T01:19:21.190Z,1579137561.190 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:21.190Z,1579137561.190 [logger](INFO): Join timeout helper Thread ID is 6805
2020-01-16T01:19:21.206Z,1579137561.206 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:21.206Z,1579137561.206 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:21.212Z,1579137561.212 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-16T01:19:21.212Z,1579137561.212 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:21.213Z,1579137561.213 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-16T01:19:21.213Z,1579137561.213 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:21.214Z,1579137561.214 [controlThread](INFO): Join timeout helper Thread ID is 6806
2020-01-16T01:19:21.441Z,1579137561.441 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T01:19:21.441Z,1579137561.441 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-16T01:19:21.442Z,1579137561.442 [AHRS_M2](INFO): Powering down
2020-01-16T01:19:21.513Z,1579137561.513 [DDM](INFO): Powering down
2020-01-16T01:19:21.606Z,1579137561.606 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T01:19:21.677Z,1579137561.677 [Micromodem](INFO): Powering down
2020-01-16T01:19:21.773Z,1579137561.773 [NAL9602](INFO): Powering down
2020-01-16T01:19:21.845Z,1579137561.845 [RDI_Pathfinder](INFO): Powering down
2020-01-16T01:19:21.847Z,1579137561.847 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-16T01:19:21.848Z,1579137561.848 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-16T01:19:21.848Z,1579137561.848 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-16T01:19:21.849Z,1579137561.849 [MissionManager](INFO): Uninitializing Mission Default
2020-01-16T01:19:21.849Z,1579137561.849 [Default] Stopped
2020-01-16T01:19:21.849Z,1579137561.849 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-16T01:19:21.849Z,1579137561.849 [Default:A.Wait] Stopped
2020-01-16T01:19:21.849Z,1579137561.849 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-16T01:19:21.849Z,1579137561.849 [Default:B.GoToSurface] Stopped
2020-01-16T01:19:21.849Z,1579137561.849 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T01:19:21.851Z,1579137561.851 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-16T01:19:21.852Z,1579137561.852 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-16T01:19:21.852Z,1579137561.852 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-16T01:19:21.852Z,1579137561.852 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-16T01:19:21.852Z,1579137561.852 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-16T01:19:21.853Z,1579137561.852 [BuoyancyServo](INFO): Powering down
2020-01-16T01:19:21.985Z,1579137561.985 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-16T01:19:21.985Z,1579137561.985 [ElevatorServo](INFO): Powering down
2020-01-16T01:19:21.986Z,1579137561.986 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-16T01:19:21.986Z,1579137561.986 [MassServo](INFO): Powering down
2020-01-16T01:19:21.987Z,1579137561.987 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T01:19:21.987Z,1579137561.987 [RudderServo](INFO): Powering down
2020-01-16T01:19:21.988Z,1579137561.988 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-16T01:19:21.988Z,1579137561.988 [ThrusterServo](INFO): Powering down
2020-01-16T01:19:21.988Z,1579137561.988 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-16T01:19:21.989Z,1579137561.989 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-16T01:19:21.989Z,1579137561.989 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-16T01:19:21.989Z,1579137561.989 [CBIT](DEBUG): Powering off loads.
2020-01-16T01:19:22.001Z,1579137562.001 [CBIT](DEBUG): Disabling WDT.
2020-01-16T01:19:22.013Z,1579137562.013 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-16T01:19:22.014Z,1579137562.014 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:22.070Z,1579137562.070 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:22.081Z,1579137562.081 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:22.239Z,1579137562.239 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:22.242Z,1579137562.242 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:22.300Z,1579137562.300 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T01:19:22.364Z,1579137562.364 [logger ThreadHandler](INFO): Thread cancelled.