2020-01-16T00:40:43.810Z,1579135243.810 [Supervisor](DEBUG): Initializing supervisor.
2020-01-16T00:40:43.813Z,1579135243.813 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-16T00:40:43.814Z,1579135243.814 [SyncHandler](INFO): Protected caller Thread ID is 6443
2020-01-16T00:40:43.814Z,1579135243.814 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-16T00:40:43.815Z,1579135243.815 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-16T00:40:43.816Z,1579135243.816 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6444
2020-01-16T00:40:43.818Z,1579135243.818 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-16T00:40:43.830Z,1579135243.830 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-16T00:40:43.830Z,1579135243.830 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-16T00:40:43.831Z,1579135243.831 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6445
2020-01-16T00:40:43.832Z,1579135243.832 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-16T00:40:43.832Z,1579135243.832 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-16T00:40:43.833Z,1579135243.833 [logger ThreadHandler](INFO): Protected caller Thread ID is 6446
2020-01-16T00:40:43.835Z,1579135243.835 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-16T00:40:43.835Z,1579135243.835 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-16T00:40:43.837Z,1579135243.837 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-16T00:40:44.271Z,1579135244.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-16T00:40:44.272Z,1579135244.272 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-16T00:40:44.368Z,1579135244.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-16T00:40:44.369Z,1579135244.369 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-16T00:40:44.678Z,1579135244.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-16T00:40:44.678Z,1579135244.678 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-16T00:40:44.815Z,1579135244.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-16T00:40:44.816Z,1579135244.816 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-16T00:40:45.002Z,1579135245.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-16T00:40:45.003Z,1579135245.003 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-16T00:40:45.457Z,1579135245.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-16T00:40:45.457Z,1579135245.457 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-16T00:40:45.941Z,1579135245.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-16T00:40:45.941Z,1579135245.941 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-16T00:40:46.084Z,1579135246.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-16T00:40:46.085Z,1579135246.085 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-16T00:40:46.271Z,1579135246.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-16T00:40:46.271Z,1579135246.271 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-16T00:40:46.366Z,1579135246.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-16T00:40:46.366Z,1579135246.366 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-16T00:40:46.662Z,1579135246.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-16T00:40:46.662Z,1579135246.662 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-16T00:40:46.742Z,1579135246.742 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-16T00:40:46.845Z,1579135246.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-16T00:40:46.846Z,1579135246.846 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-16T00:40:47.476Z,1579135247.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-16T00:40:47.476Z,1579135247.476 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-16T00:40:47.883Z,1579135247.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-16T00:40:47.884Z,1579135247.884 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-16T00:40:47.885Z,1579135247.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-16T00:40:48.088Z,1579135248.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-16T00:40:48.185Z,1579135248.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-16T00:40:48.282Z,1579135248.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-16T00:40:48.504Z,1579135248.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-16T00:40:48.505Z,1579135248.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-16T00:40:48.589Z,1579135248.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-16T00:40:48.681Z,1579135248.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-16T00:40:48.779Z,1579135248.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-16T00:40:48.861Z,1579135248.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-16T00:40:48.967Z,1579135248.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-16T00:40:49.147Z,1579135249.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-16T00:40:49.276Z,1579135249.276 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-16T00:40:49.277Z,1579135249.277 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-16T00:40:49.291Z,1579135249.291 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-16T00:40:49.608Z,1579135249.608 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-16T00:40:49.613Z,1579135249.613 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-16T00:40:49.615Z,1579135249.615 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-16T00:40:49.620Z,1579135249.620 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-16T00:40:49.620Z,1579135249.620 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-16T00:40:49.625Z,1579135249.625 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-16T00:40:49.626Z,1579135249.626 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-16T00:40:49.631Z,1579135249.631 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-16T00:40:49.699Z,1579135249.699 [AHRS_M2] Loaded
2020-01-16T00:40:49.699Z,1579135249.699 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-16T00:40:49.780Z,1579135249.780 [DataOverHttps] Loaded
2020-01-16T00:40:49.780Z,1579135249.780 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-16T00:40:49.781Z,1579135249.781 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-16T00:40:49.782Z,1579135249.782 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6525
2020-01-16T00:40:49.805Z,1579135249.805 [DDM] Loaded
2020-01-16T00:40:49.805Z,1579135249.805 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-16T00:40:49.818Z,1579135249.818 [Depth_Keller] Loaded
2020-01-16T00:40:49.818Z,1579135249.818 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-16T00:40:49.823Z,1579135249.823 [DropWeight] Loaded
2020-01-16T00:40:49.823Z,1579135249.823 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-16T00:40:49.883Z,1579135249.883 [DUSBL_Hydroid] Loaded
2020-01-16T00:40:49.883Z,1579135249.883 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-16T00:40:49.929Z,1579135249.929 [Micromodem] Loaded
2020-01-16T00:40:49.929Z,1579135249.929 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-16T00:40:50.023Z,1579135250.023 [NAL9602] Loaded
2020-01-16T00:40:50.023Z,1579135250.023 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-16T00:40:50.059Z,1579135250.059 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-16T00:40:50.060Z,1579135250.060 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-16T00:40:50.060Z,1579135250.060 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-16T00:40:50.061Z,1579135250.061 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-16T00:40:50.061Z,1579135250.061 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-16T00:40:50.062Z,1579135250.062 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-16T00:40:50.062Z,1579135250.062 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-16T00:40:50.063Z,1579135250.063 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-16T00:40:50.063Z,1579135250.063 [Onboard] Loaded
2020-01-16T00:40:50.063Z,1579135250.063 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-16T00:40:50.069Z,1579135250.069 [PowerOnly] Loaded
2020-01-16T00:40:50.069Z,1579135250.069 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-16T00:40:50.076Z,1579135250.076 [Radio_Surface] Loaded
2020-01-16T00:40:50.076Z,1579135250.076 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-16T00:40:50.077Z,1579135250.077 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-16T00:40:50.077Z,1579135250.077 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6526
2020-01-16T00:40:50.119Z,1579135250.119 [RDI_Pathfinder] Loaded
2020-01-16T00:40:50.120Z,1579135250.120 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-16T00:40:52.325Z,1579135252.325 [BPC1] Loaded
2020-01-16T00:40:52.325Z,1579135252.325 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-16T00:40:52.325Z,1579135252.325 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-16T00:40:52.326Z,1579135252.326 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-16T00:40:52.339Z,1579135252.339 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-16T00:40:52.339Z,1579135252.339 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-16T00:40:52.441Z,1579135252.441 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-16T00:40:52.441Z,1579135252.441 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-16T00:40:52.461Z,1579135252.461 [NavChart] Loaded
2020-01-16T00:40:52.461Z,1579135252.461 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-16T00:40:52.465Z,1579135252.465 [UniversalFixResidualReporter] Loaded
2020-01-16T00:40:52.466Z,1579135252.466 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-16T00:40:52.466Z,1579135252.466 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-16T00:40:52.467Z,1579135252.467 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-16T00:40:52.570Z,1579135252.570 [BuoyancyServo] Loaded
2020-01-16T00:40:52.571Z,1579135252.571 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-16T00:40:52.586Z,1579135252.586 [ElevatorServo] Loaded
2020-01-16T00:40:52.586Z,1579135252.586 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-16T00:40:52.600Z,1579135252.600 [MassServo] Loaded
2020-01-16T00:40:52.600Z,1579135252.600 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-16T00:40:52.615Z,1579135252.615 [RudderServo] Loaded
2020-01-16T00:40:52.615Z,1579135252.615 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-16T00:40:52.629Z,1579135252.629 [ThrusterServo] Loaded
2020-01-16T00:40:52.630Z,1579135252.630 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-16T00:40:52.630Z,1579135252.630 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-16T00:40:52.631Z,1579135252.631 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-16T00:40:52.881Z,1579135252.881 [CTD_NeilBrown] Loaded
2020-01-16T00:40:52.881Z,1579135252.881 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-16T00:40:52.882Z,1579135252.882 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-16T00:40:52.883Z,1579135252.883 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6527
2020-01-16T00:40:52.897Z,1579135252.897 [PAR_Licor] Loaded
2020-01-16T00:40:52.897Z,1579135252.897 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-16T00:40:52.940Z,1579135252.940 [WetLabsSeaOWL_UV_A] Loaded
2020-01-16T00:40:52.940Z,1579135252.940 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-16T00:40:52.941Z,1579135252.941 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-16T00:40:52.942Z,1579135252.942 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6528
2020-01-16T00:40:52.942Z,1579135252.942 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-16T00:40:52.943Z,1579135252.943 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-16T00:40:53.264Z,1579135253.264 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-16T00:40:53.264Z,1579135253.264 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-16T00:40:53.304Z,1579135253.304 [DepthRateCalculator] Loaded
2020-01-16T00:40:53.304Z,1579135253.304 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-16T00:40:53.310Z,1579135253.310 [PitchRateCalculator] Loaded
2020-01-16T00:40:53.310Z,1579135253.310 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-16T00:40:53.322Z,1579135253.322 [SpeedCalculator] Loaded
2020-01-16T00:40:53.322Z,1579135253.322 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-16T00:40:53.343Z,1579135253.343 [TempGradientCalculator] Loaded
2020-01-16T00:40:53.343Z,1579135253.343 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-16T00:40:53.349Z,1579135253.349 [YawRateCalculator] Loaded
2020-01-16T00:40:53.349Z,1579135253.349 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-16T00:40:53.389Z,1579135253.389 [ElevatorOffsetCalculator] Loaded
2020-01-16T00:40:53.389Z,1579135253.389 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-16T00:40:53.390Z,1579135253.390 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-16T00:40:53.390Z,1579135253.390 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-16T00:40:53.521Z,1579135253.521 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-16T00:40:53.543Z,1579135253.543 [SBIT] Loaded
2020-01-16T00:40:53.543Z,1579135253.543 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-16T00:40:53.544Z,1579135253.544 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-16T00:40:53.556Z,1579135253.556 [IBIT] Loaded
2020-01-16T00:40:53.556Z,1579135253.556 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-16T00:40:53.560Z,1579135253.560 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-16T00:40:53.696Z,1579135253.696 [CBIT] Loaded
2020-01-16T00:40:53.696Z,1579135253.696 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-16T00:40:53.696Z,1579135253.696 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-16T00:40:53.697Z,1579135253.697 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-16T00:40:53.777Z,1579135253.777 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-16T00:40:53.778Z,1579135253.778 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-16T00:40:53.874Z,1579135253.874 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-16T00:40:53.874Z,1579135253.874 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-16T00:40:53.920Z,1579135253.920 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-16T00:40:54.003Z,1579135254.003 [VerticalControl] Loaded
2020-01-16T00:40:54.003Z,1579135254.003 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-16T00:40:54.004Z,1579135254.004 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-16T00:40:54.072Z,1579135254.072 [HorizontalControl] Loaded
2020-01-16T00:40:54.072Z,1579135254.072 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-16T00:40:54.073Z,1579135254.073 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-16T00:40:54.074Z,1579135254.074 [SpeedControl] Loaded
2020-01-16T00:40:54.075Z,1579135254.075 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-16T00:40:54.075Z,1579135254.075 [LoopControl](DEBUG): Construct LoopControl.
2020-01-16T00:40:54.076Z,1579135254.076 [LoopControl] Loaded
2020-01-16T00:40:54.076Z,1579135254.076 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-16T00:40:54.076Z,1579135254.076 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-16T00:40:54.077Z,1579135254.077 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-16T00:40:54.108Z,1579135254.108 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-16T00:40:54.111Z,1579135254.111 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-16T00:40:54.112Z,1579135254.112 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-16T00:40:54.119Z,1579135254.119 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-16T00:40:54.120Z,1579135254.120 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B094E0
2020-01-16T00:40:54.120Z,1579135254.120 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6529
2020-01-16T00:40:54.125Z,1579135254.125 [Supervisor](INFO): Main Thread ID is 5419
2020-01-16T00:40:54.125Z,1579135254.125 [Supervisor](DEBUG): Running supervisor.
2020-01-16T00:40:54.126Z,1579135254.126 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6530
2020-01-16T00:40:54.128Z,1579135254.128 [controlThread ThreadHandler](INFO): Handler Thread ID is 6531
2020-01-16T00:40:54.129Z,1579135254.129 [controlThread](DEBUG): Initializing ControlThread
2020-01-16T00:40:54.136Z,1579135254.136 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-16T00:40:54.136Z,1579135254.136 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-16T00:40:54.141Z,1579135254.141 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-16T00:40:54.141Z,1579135254.141 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-16T00:40:54.142Z,1579135254.142 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-16T00:40:54.142Z,1579135254.142 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-16T00:40:54.142Z,1579135254.142 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-16T00:40:54.143Z,1579135254.143 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-16T00:40:54.143Z,1579135254.143 [SBIT](INFO): Initialize SBIT Component.
2020-01-16T00:40:54.144Z,1579135254.144 [SBIT](IMPORTANT): git: 2019-12-03-34-g1420f60
2020-01-16T00:40:54.144Z,1579135254.144 [SBIT](INFO): git hash: 1420f6075bfd9e2317cf67bc08ebc33a04e592a5
2020-01-16T00:40:54.144Z,1579135254.144 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-16T00:40:54.145Z,1579135254.145 [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:40:54.146Z,1579135254.146 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-16T00:40:54.147Z,1579135254.147 [IBIT](INFO): Initialize IBIT Component.
2020-01-16T00:40:54.148Z,1579135254.148 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-16T00:40:54.149Z,1579135254.149 [logger ThreadHandler](INFO): Handler Thread ID is 6532
2020-01-16T00:40:54.161Z,1579135254.161 [CBIT](DEBUG): Initialized mux pins.
2020-01-16T00:40:54.161Z,1579135254.161 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-16T00:40:54.169Z,1579135254.169 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6533
2020-01-16T00:40:54.170Z,1579135254.170 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-16T00:40:54.181Z,1579135254.181 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6534
2020-01-16T00:40:54.185Z,1579135254.185 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-16T00:40:54.185Z,1579135254.185 [CBIT](DEBUG): Initializing heartbeat.
2020-01-16T00:40:54.193Z,1579135254.193 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6535
2020-01-16T00:40:54.194Z,1579135254.194 [CTD_NeilBrown](INFO): Powering down
2020-01-16T00:40:54.221Z,1579135254.221 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6536
2020-01-16T00:40:54.222Z,1579135254.222 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T00:40:54.249Z,1579135254.249 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6537
2020-01-16T00:40:54.252Z,1579135254.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-16T00:40:54.252Z,1579135254.252 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-16T00:40:54.253Z,1579135254.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-16T00:40:54.253Z,1579135254.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-16T00:40:54.253Z,1579135254.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-16T00:40:54.253Z,1579135254.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-16T00:40:54.253Z,1579135254.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-16T00:40:54.253Z,1579135254.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-16T00:40:54.254Z,1579135254.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-16T00:40:54.254Z,1579135254.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-16T00:40:54.254Z,1579135254.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-16T00:40:54.254Z,1579135254.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-16T00:40:54.254Z,1579135254.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-16T00:40:54.254Z,1579135254.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-16T00:40:54.255Z,1579135254.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-16T00:40:54.255Z,1579135254.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-16T00:40:54.257Z,1579135254.257 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-16T00:40:54.257Z,1579135254.257 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-16T00:40:54.293Z,1579135254.293 [CBIT](DEBUG): Backplane powered.
2020-01-16T00:40:54.293Z,1579135254.293 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-16T00:40:54.295Z,1579135254.295 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-16T00:40:54.296Z,1579135254.296 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-16T00:40:54.296Z,1579135254.296 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-16T00:40:54.297Z,1579135254.297 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-16T00:40:54.307Z,1579135254.307 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T00:40:54.350Z,1579135254.350 [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:40:54.351Z,1579135254.351 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-16T00:40:54.421Z,1579135254.421 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-16T00:40:54.422Z,1579135254.422 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-16T00:40:54.424Z,1579135254.424 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T00:40:54.475Z,1579135254.475 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-16T00:40:54.477Z,1579135254.477 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-16T00:40:54.510Z,1579135254.510 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-16T00:40:54.514Z,1579135254.514 [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:40:54.525Z,1579135254.525 [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:40:54.537Z,1579135254.537 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T00:40:54.574Z,1579135254.574 [DDM](INFO): Powering up
2020-01-16T00:40:54.574Z,1579135254.574 [DDM](DEBUG): Initializing DDM.
2020-01-16T00:40:54.585Z,1579135254.585 [Radio_Surface](INFO): Powering up
2020-01-16T00:40:54.604Z,1579135254.604 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T00:40:54.604Z,1579135254.604 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T00:40:54.839Z,1579135254.839 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:40:54.845Z,1579135254.845 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-16T00:40:54.846Z,1579135254.846 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:40:54.853Z,1579135254.853 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-16T00:40:54.854Z,1579135254.854 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:40:54.861Z,1579135254.861 [MassServo](DEBUG): Initializing MassServo.
2020-01-16T00:40:54.862Z,1579135254.862 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:40:54.869Z,1579135254.869 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T00:40:54.870Z,1579135254.870 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:40:54.877Z,1579135254.877 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-16T00:40:54.954Z,1579135254.954 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-16T00:40:54.954Z,1579135254.954 [DropWeight] Hardware Fault, FailCount= 1
2020-01-16T00:40:54.954Z,1579135254.954 [DropWeight](ERROR): Hardware Fault
2020-01-16T00:40:54.956Z,1579135254.956 [Micromodem](INFO): Powering up
2020-01-16T00:40:54.956Z,1579135254.956 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:40:55.040Z,1579135255.040 [CommandLine](FAULT): Scheduling is paused
2020-01-16T00:40:55.041Z,1579135255.041 [CBIT](INFO): Critical error at 20200116T004054
2020-01-16T00:40:55.041Z,1579135255.041 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-16T00:40:55.043Z,1579135255.043 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-16T00:40:55.044Z,1579135255.044 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-16T00:40:55.721Z,1579135255.721 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-16T00:40:55.721Z,1579135255.721 [RudderServo](FAULT): Rudder failed to initialize
2020-01-16T00:40:55.721Z,1579135255.721 [RudderServo] Communications Fault, FailCount= 1
2020-01-16T00:40:55.721Z,1579135255.721 [RudderServo](ERROR): Communications Fault
2020-01-16T00:40:55.830Z,1579135255.830 [CBIT](INFO): Critical error at 20200116T004055
2020-01-16T00:40:55.833Z,1579135255.833 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-16T00:40:56.006Z,1579135256.006 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T00:40:56.006Z,1579135256.006 [RudderServo](INFO): Powering down
2020-01-16T00:40:56.689Z,1579135256.689 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T00:40:56.810Z,1579135256.810 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T00:40:56.814Z,1579135256.814 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-16T00:40:56.814Z,1579135256.814 [RudderServo] No Fault, FailCount= 1
2020-01-16T00:40:59.919Z,1579135259.919 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T00:41:04.529Z,1579135264.529 [CBIT](CRITICAL): Environmental Failure. Press:14.827577 PSI. Humidity:28%. Temp:22 C. ABORTING MISSION
2020-01-16T00:41:04.894Z,1579135264.894 [CBIT](INFO): Critical error at 20200116T004104
2020-01-16T00:41:05.144Z,1579135265.144 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-16T00:41:05.253Z,1579135265.253 [DDM](INFO): Cable State:34
2020-01-16T00:41:07.992Z,1579135267.992 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-16T00:41:11.198Z,1579135271.198 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:41:12.814Z,1579135272.814 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-16T00:41:20.083Z,1579135280.083 [NAL9602](INFO): Powering up NAL9602
2020-01-16T00:41:22.510Z,1579135282.510 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:41:30.995Z,1579135290.995 [NAL9602](INFO): NAL9602 initialized
2020-01-16T00:41:33.851Z,1579135293.851 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:41:33.999Z,1579135293.999 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-16T00:41:34.012Z,1579135294.012 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-16T00:41:44.690Z,1579135304.690 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013153
CHAN A1 (24V): -0.029178
CHAN A2 (12V): -0.006789
CHAN A3 (5V): -0.001530
CHAN B0 (3.3V): -0.000277
CHAN B1 (3.15aV): 0.000398
CHAN B2 (3.15bV): 0.000449
CHAN B3 (GND): 0.002072
OPEN: 0.004923
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-16T00:41:44.970Z,1579135304.970 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:41:55.332Z,1579135315.332 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-16T00:41:56.119Z,1579135316.119 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:42:07.410Z,1579135327.410 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:42:13.575Z,1579135333.575 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.989025
2020-01-16T00:42:13.575Z,1579135333.575 [SBIT](FAULT): Control surface position failure.
2020-01-16T00:42:18.454Z,1579135338.454 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:42:27.828Z,1579135347.828 [SBIT](CRITICAL): SBIT FAILED
2020-01-16T00:42:27.881Z,1579135347.881 [CommandLine](IMPORTANT): got command configSet list
2020-01-16T00:42:27.881Z,1579135347.881 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-16T00:42:27.882Z,1579135347.882 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-16T00:42:27.882Z,1579135347.882 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-16T00:42:27.882Z,1579135347.882 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=25 count;

2020-01-16T00:42:27.882Z,1579135347.882 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-16T00:42:27.882Z,1579135347.882 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): Micromodem.sendExpress=1 bool;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-16T00:42:27.883Z,1579135347.883 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-16T00:42:27.884Z,1579135347.884 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-16T00:42:27.884Z,1579135347.884 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-16T00:42:27.905Z,1579135347.905 [CBIT](INFO): Critical error at 20200116T004227
2020-01-16T00:42:28.226Z,1579135348.226 [MissionManager](IMPORTANT): Started mission Startup
2020-01-16T00:42:28.226Z,1579135348.226 [Startup] Running Loop=1
2020-01-16T00:42:28.226Z,1579135348.226 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-16T00:42:28.226Z,1579135348.226 [Startup:A.GoToSurface] Running Loop=1
2020-01-16T00:42:28.227Z,1579135348.227 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T00:42:28.227Z,1579135348.227 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T00:42:28.228Z,1579135348.228 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T00:42:28.228Z,1579135348.228 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T00:42:28.228Z,1579135348.228 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T00:42:28.228Z,1579135348.228 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T00:42:28.230Z,1579135348.230 [Startup:StartupSatComms] Running Loop=1
2020-01-16T00:42:28.230Z,1579135348.230 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-16T00:42:28.230Z,1579135348.230 [Startup:StartupSatComms:A] Running Loop=1
2020-01-16T00:42:28.708Z,1579135348.708 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-16T00:42:29.769Z,1579135349.769 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:42:41.086Z,1579135361.086 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:42:52.403Z,1579135372.403 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:42:54.830Z,1579135374.830 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:42:54.830Z,1579135374.830 [Micromodem] Communications Fault, FailCount= 1
2020-01-16T00:42:54.831Z,1579135374.831 [Micromodem](ERROR): Communications Fault
2020-01-16T00:42:54.926Z,1579135374.926 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:42:55.239Z,1579135375.239 [Micromodem](INFO): Powering down
2020-01-16T00:42:56.146Z,1579135376.146 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:42:56.146Z,1579135376.146 [Micromodem] No Fault, FailCount= 1
2020-01-16T00:42:58.866Z,1579135378.866 [Micromodem](INFO): Powering up
2020-01-16T00:42:58.867Z,1579135378.867 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:43:03.728Z,1579135383.728 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:43:15.030Z,1579135395.030 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:43:24.839Z,1579135404.839 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004981
2020-01-16T00:43:26.358Z,1579135406.358 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:43:28.415Z,1579135408.415 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-16T00:42:28.2Z
2020-01-16T00:43:28.415Z,1579135408.415 [Startup:StartupSatComms:A] Stopped
2020-01-16T00:43:28.415Z,1579135408.415 [Startup:StartupSatComms:B] Running Loop=1
2020-01-16T00:43:28.845Z,1579135408.845 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-16T00:43:35.342Z,1579135415.342 [DataOverHttps](INFO): Sending 602 bytes from file Logs/20200116T000807/Courier0004.lzma
2020-01-16T00:43:37.346Z,1579135417.346 [DataOverHttps](INFO): Moved sent file to Logs/20200116T000807/Courier0004.lzma.bak
2020-01-16T00:43:37.347Z,1579135417.347 [DataOverHttps](INFO): SBD MOMSN=12194244
2020-01-16T00:43:37.674Z,1579135417.674 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:43:48.986Z,1579135428.986 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:43:49.417Z,1579135429.417 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-01-16T00:43:54.276Z,1579135434.276 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-16T00:43:54.276Z,1579135434.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:43:54.286Z,1579135434.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:43:54.337Z,1579135434.337 [DataOverHttps](INFO): Sending 347 bytes from file Logs/20200116T001620/Courier0000.lzma
2020-01-16T00:43:54.669Z,1579135434.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:43:54.669Z,1579135434.669 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-16T00:43:56.358Z,1579135436.358 [DataOverHttps](INFO): Moved sent file to Logs/20200116T001620/Courier0000.lzma.bak
2020-01-16T00:43:56.358Z,1579135436.358 [DataOverHttps](INFO): SBD MOMSN=12194248
2020-01-16T00:44:00.308Z,1579135440.308 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:44:10.420Z,1579135450.420 [CommandLine](IMPORTANT): got command show stack
2020-01-16T00:44:10.420Z,1579135450.420 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T00:44:10.421Z,1579135450.421 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2020-01-16T00:44:10.421Z,1579135450.421 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2020-01-16T00:44:11.614Z,1579135451.614 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:44:13.962Z,1579135453.962 [DataOverHttps](INFO): Sending 712 bytes from file Logs/20200116T001620/Courier0004.lzma
2020-01-16T00:44:15.966Z,1579135455.966 [DataOverHttps](INFO): Moved sent file to Logs/20200116T001620/Courier0004.lzma.bak
2020-01-16T00:44:15.966Z,1579135455.966 [DataOverHttps](INFO): SBD MOMSN=12194254
2020-01-16T00:44:19.847Z,1579135459.847 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 35.500000 degree
2020-01-16T00:44:19.848Z,1579135459.848 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2020-01-16T00:44:19.868Z,1579135459.868 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.989998 degree
2020-01-16T00:44:19.869Z,1579135459.869 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2020-01-16T00:44:20.159Z,1579135460.159 [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_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:44:20.523Z,1579135460.523 [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:44:20.523Z,1579135460.523 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-16T00:44:20.523Z,1579135460.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:20.590Z,1579135460.590 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:21.022Z,1579135461.022 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:21.022Z,1579135461.022 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-16T00:44:21.778Z,1579135461.778 [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:44:21.778Z,1579135461.778 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-16T00:44:21.778Z,1579135461.778 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:21.821Z,1579135461.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:22.162Z,1579135462.162 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T00:44:22.162Z,1579135462.162 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011517562883,35.0, -0.1,   .9,  0
2020-01-16T00:44:22.195Z,1579135462.195 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:22.195Z,1579135462.195 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-16T00:44:22.918Z,1579135462.918 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:44:23.046Z,1579135463.046 [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:44:23.046Z,1579135463.046 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-16T00:44:23.046Z,1579135463.046 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:23.105Z,1579135463.105 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:23.414Z,1579135463.414 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:23.414Z,1579135463.414 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-16T00:44:24.191Z,1579135464.191 [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:44:24.191Z,1579135464.191 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-16T00:44:24.191Z,1579135464.191 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:24.233Z,1579135464.233 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:24.591Z,1579135464.591 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:24.591Z,1579135464.591 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-16T00:44:25.371Z,1579135465.371 [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:44:25.371Z,1579135465.371 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-16T00:44:25.371Z,1579135465.371 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:25.381Z,1579135465.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:25.792Z,1579135465.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:25.792Z,1579135465.792 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-16T00:44:26.587Z,1579135466.587 [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:44:26.587Z,1579135466.587 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-01-16T00:44:26.587Z,1579135466.587 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:26.597Z,1579135466.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:27.000Z,1579135467.000 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:27.000Z,1579135467.000 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-01-16T00:44:27.831Z,1579135467.831 [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:44:27.831Z,1579135467.831 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-01-16T00:44:27.831Z,1579135467.831 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:27.841Z,1579135467.841 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:28.219Z,1579135468.219 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:28.219Z,1579135468.219 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-01-16T00:44:28.619Z,1579135468.619 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-16T00:43:28.4Z
2020-01-16T00:44:28.619Z,1579135468.619 [Startup:StartupSatComms:B] Stopped
2020-01-16T00:44:28.620Z,1579135468.620 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-16T00:44:28.620Z,1579135468.620 [Startup:StartupSatComms] Stopped
2020-01-16T00:44:28.620Z,1579135468.620 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-16T00:44:28.620Z,1579135468.620 [Startup](INFO): Completed Startup
2020-01-16T00:44:28.621Z,1579135468.621 [MissionManager](INFO): Startup is completed.
2020-01-16T00:44:28.621Z,1579135468.621 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-16T00:44:28.621Z,1579135468.621 [Startup] Stopped
2020-01-16T00:44:28.621Z,1579135468.621 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-16T00:44:28.621Z,1579135468.621 [Startup:A.GoToSurface] Stopped
2020-01-16T00:44:28.621Z,1579135468.621 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T00:44:29.012Z,1579135469.012 [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:44:29.012Z,1579135469.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-01-16T00:44:29.012Z,1579135469.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:29.013Z,1579135469.013 [MissionManager](IMPORTANT): Started mission Default
2020-01-16T00:44:29.013Z,1579135469.013 [Default] Running Loop=1
2020-01-16T00:44:29.013Z,1579135469.013 [Default](DEBUG): Aggregate::initialize Default
2020-01-16T00:44:29.013Z,1579135469.013 [Default:B.GoToSurface] Running Loop=1
2020-01-16T00:44:29.013Z,1579135469.013 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T00:44:29.014Z,1579135469.014 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T00:44:29.014Z,1579135469.014 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T00:44:29.014Z,1579135469.014 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T00:44:29.014Z,1579135469.014 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T00:44:29.015Z,1579135469.015 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T00:44:29.015Z,1579135469.015 [Default:A.Wait] Running Loop=1
2020-01-16T00:44:29.015Z,1579135469.015 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-16T00:44:29.024Z,1579135469.024 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:29.534Z,1579135469.534 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:29.534Z,1579135469.534 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-01-16T00:44:30.247Z,1579135470.247 [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:44:30.247Z,1579135470.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-01-16T00:44:30.247Z,1579135470.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:30.257Z,1579135470.257 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:30.682Z,1579135470.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:30.682Z,1579135470.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-01-16T00:44:31.434Z,1579135471.434 [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:44:31.434Z,1579135471.434 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-01-16T00:44:31.434Z,1579135471.434 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:31.452Z,1579135471.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:31.918Z,1579135471.918 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:31.918Z,1579135471.918 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-01-16T00:44:32.670Z,1579135472.670 [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:44:32.670Z,1579135472.670 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-01-16T00:44:32.670Z,1579135472.670 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-16T00:44:32.692Z,1579135472.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:33.150Z,1579135473.150 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-16T00:44:33.150Z,1579135473.150 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-01-16T00:44:33.433Z,1579135473.433 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1579135488.000000 second
2020-01-16T00:44:34.238Z,1579135474.238 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:44:37.525Z,1579135477.525 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20200116T002406/Courier0000.lzma
2020-01-16T00:44:37.850Z,1579135477.850 [CommandLine](IMPORTANT): got command get latitude
2020-01-16T00:44:37.851Z,1579135477.851 [CommandLine](IMPORTANT): latitude 35.500000 arcdeg
2020-01-16T00:44:39.530Z,1579135479.530 [DataOverHttps](INFO): Moved sent file to Logs/20200116T002406/Courier0000.lzma.bak
2020-01-16T00:44:39.530Z,1579135479.530 [DataOverHttps](INFO): SBD MOMSN=12194260
2020-01-16T00:44:41.371Z,1579135481.371 [CommandLine](IMPORTANT): got command show stack
2020-01-16T00:44:41.371Z,1579135481.371 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-16T00:44:41.372Z,1579135481.372 [Default](IMPORTANT): Priority 0: Default:A.Wait
2020-01-16T00:44:41.372Z,1579135481.372 [Default](IMPORTANT): Priority 1: Default:B.GoToSurface
2020-01-16T00:44:42.364Z,1579135482.364 [Default:A.Wait](INFO): Done Waiting.
2020-01-16T00:44:42.365Z,1579135482.365 [Default:A.Wait] Stopped
2020-01-16T00:44:42.365Z,1579135482.365 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-16T00:44:42.907Z,1579135482.907 [Default:CheckIn] Running Loop=1
2020-01-16T00:44:42.907Z,1579135482.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-16T00:44:42.907Z,1579135482.907 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-16T00:44:43.211Z,1579135483.211 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-16T00:44:44.860Z,1579135484.860 [CommandLine](IMPORTANT): got command get depth
2020-01-16T00:44:44.860Z,1579135484.860 [CommandLine](IMPORTANT): depth 0.130834 m
2020-01-16T00:44:45.562Z,1579135485.562 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:44:49.792Z,1579135489.792 [CommandLine](IMPORTANT): got command show variable cable
2020-01-16T00:44:49.876Z,1579135489.876 [CommandLine](IMPORTANT): DDM.cablePresent (bool)
2020-01-16T00:44:56.372Z,1579135496.372 [CommandLine](IMPORTANT): got command report mod DDM.cablePresent
2020-01-16T00:44:56.582Z,1579135496.582 [Reporter](INFO): DDM.cablePresent no_value
2020-01-16T00:44:56.866Z,1579135496.866 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:44:58.500Z,1579135498.500 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:44:58.500Z,1579135498.500 [Micromodem] Communications Fault, FailCount= 2
2020-01-16T00:44:58.500Z,1579135498.500 [Micromodem](ERROR): Communications Fault
2020-01-16T00:44:58.533Z,1579135498.533 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:44:58.899Z,1579135498.899 [Micromodem](INFO): Powering down
2020-01-16T00:44:59.611Z,1579135499.611 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Docked.xml
2020-01-16T00:44:59.612Z,1579135499.612 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Docked.xml
2020-01-16T00:44:59.749Z,1579135499.749 [MissionManager](INFO): DefineArg Docked.MaxDockRange = 8.000000 m
2020-01-16T00:44:59.773Z,1579135499.773 [MissionManager](INFO): DefineArg Docked.DetachedTimeout = 5.000000 min
2020-01-16T00:44:59.777Z,1579135499.777 [MissionManager](INFO): DefineArg Docked.Depth = 5.000000 m
2020-01-16T00:44:59.780Z,1579135499.780 [MissionManager](INFO): DefineArg Docked.TransponderCode = 2.000000 count
2020-01-16T00:44:59.829Z,1579135499.829 [MissionManager](INFO): DefineArg Docked.TrackingUpdatePeriod = 60.000000 s
2020-01-16T00:44:59.872Z,1579135499.872 [MissionManager](INFO): DefineArg Docked.NumberOfPings = 1.000000 count
2020-01-16T00:44:59.875Z,1579135499.875 [MissionManager](INFO): DefineArg Docked.Speed = 0.000000 m/s
2020-01-16T00:44:59.899Z,1579135499.899 [MissionManager](INFO): DefineArg Docked.DepthDeadband = 1.500000 m
2020-01-16T00:44:59.902Z,1579135499.902 [MissionManager](INFO): DefineArg Docked.PitchLimit = 20.000000 arcdeg
2020-01-16T00:44:59.935Z,1579135499.935 [MissionManager](INFO): DefineArg Docked.MinDepth = 1.000000 m
2020-01-16T00:44:59.946Z,1579135499.946 [MissionManager](INFO): DefineArg Docked.MaxDepth = 215.000000 m
2020-01-16T00:44:59.947Z,1579135499.947 [Docked:A.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-01-16T00:44:59.955Z,1579135499.955 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:44:59.955Z,1579135499.955 [Micromodem] No Fault, FailCount= 2
2020-01-16T00:45:00.226Z,1579135500.226 [Docked:E.Pitch](DEBUG): Construct.
2020-01-16T00:45:00.240Z,1579135500.240 [Docked:F.SetSpeed](DEBUG): Construct.
2020-01-16T00:45:00.254Z,1579135500.254 [Docked:G.Docked](DEBUG): Construct.
2020-01-16T00:45:00.279Z,1579135500.279 [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>
        <Sequence/>
        <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:45:00.285Z,1579135500.285 [CommandLine](IMPORTANT): Running ./Missions/Insert/Docked.xml
2020-01-16T00:45:00.552Z,1579135500.552 [Default] Stopped
2020-01-16T00:45:00.552Z,1579135500.552 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-16T00:45:00.552Z,1579135500.552 [Default:B.GoToSurface] Stopped
2020-01-16T00:45:00.552Z,1579135500.552 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T00:45:00.552Z,1579135500.552 [Default:CheckIn] Stopped
2020-01-16T00:45:00.552Z,1579135500.552 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-16T00:45:00.552Z,1579135500.552 [Default:CheckIn:Read_GPS] Stopped
2020-01-16T00:45:00.552Z,1579135500.552 [MissionManager](IMPORTANT): Started mission Docked
2020-01-16T00:45:00.557Z,1579135500.557 [Docked] Running Loop=1
2020-01-16T00:45:00.557Z,1579135500.557 [Docked](DEBUG): Aggregate::initialize Docked
2020-01-16T00:45:00.557Z,1579135500.557 [Docked:A.DepthEnvelope] Running Loop=1
2020-01-16T00:45:00.557Z,1579135500.557 [Docked:A.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-01-16T00:45:00.557Z,1579135500.557 [Docked:B.] Running Loop=1
2020-01-16T00:45:00.557Z,1579135500.557 [Docked:B.](INFO): Initializing TrackAcousticContact.
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:C] Running Loop=1
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:D] Running Loop=1
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:E.Pitch] Running Loop=1
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:E.Pitch](DEBUG): Initialize.
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:F.SetSpeed] Running Loop=1
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:F.SetSpeed](DEBUG): Initialize.
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:G.Docked] Running Loop=1
2020-01-16T00:45:00.558Z,1579135500.558 [Docked:G.Docked](DEBUG): Initialize.
2020-01-16T00:45:00.559Z,1579135500.559 [Docked:F.SetSpeed] Running Loop=1
2020-01-16T00:45:00.559Z,1579135500.559 [Docked:E.Pitch] Running Loop=1
2020-01-16T00:45:00.560Z,1579135500.560 [Docked:D] Running Loop=1
2020-01-16T00:45:00.560Z,1579135500.560 [Docked:C] Running Loop=1
2020-01-16T00:45:00.560Z,1579135500.560 [Docked:B.] Running Loop=1
2020-01-16T00:45:00.565Z,1579135500.565 [Docked:A.DepthEnvelope] Running Loop=1
2020-01-16T00:45:01.737Z,1579135501.737 [DDM](FAULT): failed to activate IR emitter.
2020-01-16T00:45:01.737Z,1579135501.737 [DDM] Hardware Fault, FailCount= 1
2020-01-16T00:45:01.737Z,1579135501.737 [DDM](ERROR): Hardware Fault
2020-01-16T00:45:01.919Z,1579135501.919 [CBIT](ERROR): Hardware Fault in component: DDM
2020-01-16T00:45:02.115Z,1579135502.115 [DDM](INFO): Powering down
2020-01-16T00:45:02.520Z,1579135502.520 [Micromodem](INFO): Powering up
2020-01-16T00:45:02.520Z,1579135502.520 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:45:03.081Z,1579135503.081 [CBIT](INFO): Clearing failed state for component DDM
2020-01-16T00:45:03.081Z,1579135503.081 [DDM] No Fault, FailCount= 1
2020-01-16T00:45:05.346Z,1579135505.346 [DDM](INFO): Powering up
2020-01-16T00:45:05.346Z,1579135505.346 [DDM](DEBUG): Initializing DDM.
2020-01-16T00:45:08.183Z,1579135508.183 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:45:15.855Z,1579135515.855 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-16T00:45:15.989Z,1579135515.989 [DDM](INFO): Cable State:21
2020-01-16T00:45:16.112Z,1579135516.112 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:16.289Z,1579135516.289 [DDM](INFO): Cable State:23
2020-01-16T00:45:16.685Z,1579135516.685 [DDM](INFO): Cable State:27
2020-01-16T00:45:17.121Z,1579135517.121 [DDM](INFO): Cable State:25
2020-01-16T00:45:17.505Z,1579135517.505 [DDM](INFO): Cable State:13
2020-01-16T00:45:18.062Z,1579135518.062 [DDM](INFO): Cable State:19
2020-01-16T00:45:18.461Z,1579135518.461 [DDM](INFO): Cable State:23
2020-01-16T00:45:18.869Z,1579135518.869 [DDM](INFO): Cable State:28
2020-01-16T00:45:19.277Z,1579135519.277 [DDM](INFO): Cable State:25
2020-01-16T00:45:19.286Z,1579135519.286 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:45:19.678Z,1579135519.678 [DDM](INFO): Cable State:23
2020-01-16T00:45:20.077Z,1579135520.077 [DDM](INFO): Cable State:15
2020-01-16T00:45:20.485Z,1579135520.485 [DDM](INFO): Cable State:29
2020-01-16T00:45:20.885Z,1579135520.885 [DDM](INFO): Cable State:19
2020-01-16T00:45:21.289Z,1579135521.289 [DDM](INFO): Cable State:16
2020-01-16T00:45:21.697Z,1579135521.697 [DDM](INFO): Cable State:19
2020-01-16T00:45:22.101Z,1579135522.101 [DDM](INFO): Cable State:17
2020-01-16T00:45:22.501Z,1579135522.501 [DDM](INFO): Cable State:16
2020-01-16T00:45:22.905Z,1579135522.905 [DDM](INFO): Cable State:12
2020-01-16T00:45:23.309Z,1579135523.309 [DDM](INFO): Cable State:22
2020-01-16T00:45:23.713Z,1579135523.713 [DDM](INFO): Cable State:20
2020-01-16T00:45:24.117Z,1579135524.117 [DDM](INFO): Cable State:17
2020-01-16T00:45:24.162Z,1579135524.162 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-01-16T00:45:24.162Z,1579135524.162 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0,V
2020-01-16T00:45:24.525Z,1579135524.525 [DDM](INFO): Cable State:16
2020-01-16T00:45:24.981Z,1579135524.981 [DDM](INFO): Cable State:21
2020-01-16T00:45:25.333Z,1579135525.333 [DDM](INFO): Cable State:16
2020-01-16T00:45:25.733Z,1579135525.733 [DDM](INFO): Cable State:20
2020-01-16T00:45:26.154Z,1579135526.154 [DDM](INFO): Cable State:16
2020-01-16T00:45:26.545Z,1579135526.545 [DDM](INFO): Cable State:19
2020-01-16T00:45:26.946Z,1579135526.946 [DDM](INFO): Cable State:19
2020-01-16T00:45:27.349Z,1579135527.349 [DDM](INFO): Cable State:18
2020-01-16T00:45:27.761Z,1579135527.761 [DDM](INFO): Cable State:16
2020-01-16T00:45:28.159Z,1579135528.159 [DDM](INFO): Cable State:19
2020-01-16T00:45:28.565Z,1579135528.565 [DDM](INFO): Cable State:15
2020-01-16T00:45:28.965Z,1579135528.965 [DDM](INFO): Cable State:17
2020-01-16T00:45:29.373Z,1579135529.373 [DDM](INFO): Cable State:16
2020-01-16T00:45:29.793Z,1579135529.793 [DDM](INFO): Cable State:21
2020-01-16T00:45:30.181Z,1579135530.181 [DDM](INFO): Cable State:19
2020-01-16T00:45:30.582Z,1579135530.582 [DDM](INFO): Cable State:19
2020-01-16T00:45:30.590Z,1579135530.590 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:45:30.985Z,1579135530.985 [DDM](INFO): Cable State:19
2020-01-16T00:45:31.393Z,1579135531.393 [DDM](INFO): Cable State:15
2020-01-16T00:45:31.797Z,1579135531.797 [DDM](INFO): Cable State:18
2020-01-16T00:45:32.197Z,1579135532.197 [DDM](INFO): Cable State:14
2020-01-16T00:45:32.605Z,1579135532.605 [DDM](INFO): Cable State:22
2020-01-16T00:45:33.009Z,1579135533.009 [DDM](INFO): Cable State:18
2020-01-16T00:45:33.413Z,1579135533.413 [DDM](INFO): Cable State:20
2020-01-16T00:45:33.833Z,1579135533.833 [DDM](INFO): Cable State:27
2020-01-16T00:45:34.217Z,1579135534.217 [DDM](INFO): Cable State:12
2020-01-16T00:45:34.621Z,1579135534.621 [DDM](INFO): Cable State:27
2020-01-16T00:45:35.029Z,1579135535.029 [DDM](INFO): Cable State:13
2020-01-16T00:45:35.429Z,1579135535.429 [DDM](INFO): Cable State:19
2020-01-16T00:45:35.833Z,1579135535.833 [DDM](INFO): Cable State:26
2020-01-16T00:45:36.241Z,1579135536.241 [DDM](INFO): Cable State:19
2020-01-16T00:45:36.641Z,1579135536.641 [DDM](INFO): Cable State:19
2020-01-16T00:45:37.045Z,1579135537.045 [DDM](INFO): Cable State:24
2020-01-16T00:45:37.455Z,1579135537.455 [DDM](INFO): Cable State:6
2020-01-16T00:45:37.853Z,1579135537.853 [DDM](INFO): Cable State:26
2020-01-16T00:45:38.257Z,1579135538.257 [DDM](INFO): Cable State:17
2020-01-16T00:45:38.663Z,1579135538.663 [DDM](INFO): Cable State:24
2020-01-16T00:45:39.065Z,1579135539.065 [DDM](INFO): Cable State:16
2020-01-16T00:45:39.469Z,1579135539.469 [DDM](INFO): Cable State:17
2020-01-16T00:45:39.881Z,1579135539.881 [DDM](INFO): Cable State:15
2020-01-16T00:45:40.277Z,1579135540.277 [DDM](INFO): Cable State:16
2020-01-16T00:45:40.849Z,1579135540.849 [DDM](INFO): Cable State:17
2020-01-16T00:45:41.245Z,1579135541.245 [DDM](INFO): Cable State:17
2020-01-16T00:45:41.649Z,1579135541.649 [DDM](INFO): Cable State:19
2020-01-16T00:45:41.678Z,1579135541.678 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:45:42.057Z,1579135542.057 [DDM](INFO): Cable State:18
2020-01-16T00:45:42.457Z,1579135542.457 [DDM](INFO): Cable State:16
2020-01-16T00:45:42.862Z,1579135542.862 [DDM](INFO): Cable State:18
2020-01-16T00:45:43.269Z,1579135543.269 [DDM](INFO): Cable State:20
2020-01-16T00:45:43.673Z,1579135543.673 [DDM](INFO): Cable State:20
2020-01-16T00:45:44.073Z,1579135544.073 [DDM](INFO): Cable State:16
2020-01-16T00:45:44.483Z,1579135544.483 [DDM](INFO): Cable State:16
2020-01-16T00:45:44.885Z,1579135544.885 [DDM](INFO): Cable State:16
2020-01-16T00:45:45.285Z,1579135545.285 [DDM](INFO): Cable State:21
2020-01-16T00:45:45.693Z,1579135545.693 [DDM](INFO): Cable State:17
2020-01-16T00:45:46.107Z,1579135546.107 [DDM](INFO): Cable State:18
2020-01-16T00:45:46.497Z,1579135546.497 [DDM](INFO): Cable State:12
2020-01-16T00:45:46.905Z,1579135546.905 [DDM](INFO): Cable State:17
2020-01-16T00:45:47.309Z,1579135547.309 [DDM](INFO): Cable State:16
2020-01-16T00:45:47.713Z,1579135547.713 [DDM](INFO): Cable State:12
2020-01-16T00:45:48.117Z,1579135548.117 [DDM](INFO): Cable State:19
2020-01-16T00:45:48.521Z,1579135548.521 [DDM](INFO): Cable State:17
2020-01-16T00:45:48.929Z,1579135548.929 [DDM](INFO): Cable State:17
2020-01-16T00:45:49.332Z,1579135549.332 [DDM](INFO): Cable State:14
2020-01-16T00:45:49.738Z,1579135549.738 [DDM](INFO): Cable State:12
2020-01-16T00:45:50.138Z,1579135550.138 [DDM](INFO): Cable State:164
2020-01-16T00:45:50.303Z,1579135550.303 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:45:50.541Z,1579135550.541 [DDM](INFO): Cable State:151
2020-01-16T00:45:50.945Z,1579135550.945 [DDM](INFO): Cable State:166
2020-01-16T00:45:51.349Z,1579135551.349 [DDM](INFO): Cable State:135
2020-01-16T00:45:51.777Z,1579135551.777 [DDM](INFO): Cable State:36
2020-01-16T00:45:51.958Z,1579135551.958 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:52.157Z,1579135552.157 [DDM](INFO): Cable State:43
2020-01-16T00:45:52.561Z,1579135552.561 [DDM](INFO): Cable State:57
2020-01-16T00:45:52.749Z,1579135552.749 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:45:52.969Z,1579135552.969 [DDM](INFO): Cable State:43
2020-01-16T00:45:52.978Z,1579135552.978 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:45:53.170Z,1579135553.170 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:53.369Z,1579135553.369 [DDM](INFO): Cable State:44
2020-01-16T00:45:53.774Z,1579135553.774 [DDM](INFO): Cable State:43
2020-01-16T00:45:53.836Z,1579135553.836 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T00:45:53.836Z,1579135553.836 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011517580083,35.0  0.0,1448.9,  0
2020-01-16T00:45:54.177Z,1579135554.177 [DDM](INFO): Cable State:45
2020-01-16T00:45:54.577Z,1579135554.577 [DDM](INFO): Cable State:51
2020-01-16T00:45:54.712Z,1579135554.712 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:45:54.981Z,1579135554.981 [DDM](INFO): Cable State:39
2020-01-16T00:45:55.151Z,1579135555.151 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:55.391Z,1579135555.391 [DDM](INFO): Cable State:47
2020-01-16T00:45:55.793Z,1579135555.793 [DDM](INFO): Cable State:50
2020-01-16T00:45:55.922Z,1579135555.922 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:45:56.193Z,1579135556.193 [DDM](INFO): Cable State:45
2020-01-16T00:45:56.330Z,1579135556.330 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:56.597Z,1579135556.597 [DDM](INFO): Cable State:46
2020-01-16T00:45:57.001Z,1579135557.001 [DDM](INFO): Cable State:43
2020-01-16T00:45:57.409Z,1579135557.409 [DDM](INFO): Cable State:53
2020-01-16T00:45:57.583Z,1579135557.583 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:45:57.817Z,1579135557.817 [DDM](INFO): Cable State:44
2020-01-16T00:45:57.963Z,1579135557.963 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:58.217Z,1579135558.217 [DDM](INFO): Cable State:44
2020-01-16T00:45:58.617Z,1579135558.617 [DDM](INFO): Cable State:45
2020-01-16T00:45:59.025Z,1579135559.025 [DDM](INFO): Cable State:53
2020-01-16T00:45:59.206Z,1579135559.206 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:45:59.425Z,1579135559.425 [DDM](INFO): Cable State:43
2020-01-16T00:45:59.564Z,1579135559.564 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:45:59.829Z,1579135559.829 [DDM](INFO): Cable State:51
2020-01-16T00:45:59.974Z,1579135559.974 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:46:00.243Z,1579135560.243 [DDM](INFO): Cable State:45
2020-01-16T00:46:00.346Z,1579135560.346 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:46:00.637Z,1579135560.637 [DDM](INFO): Cable State:45
2020-01-16T00:46:00.698Z,1579135560.698 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:46:01.049Z,1579135561.049 [DDM](INFO): Cable State:51
2020-01-16T00:46:01.215Z,1579135561.215 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:46:01.449Z,1579135561.449 [DDM](INFO): Cable State:44
2020-01-16T00:46:01.599Z,1579135561.599 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:46:01.853Z,1579135561.853 [DDM](INFO): Cable State:44
2020-01-16T00:46:02.258Z,1579135562.258 [DDM](INFO): Cable State:47
2020-01-16T00:46:02.661Z,1579135562.661 [DDM](INFO): Cable State:44
2020-01-16T00:46:03.317Z,1579135563.317 [DDM](INFO): Cable State:44
2020-01-16T00:46:03.721Z,1579135563.721 [DDM](INFO): Cable State:45
2020-01-16T00:46:04.129Z,1579135564.129 [DDM](INFO): Cable State:43
2020-01-16T00:46:04.138Z,1579135564.138 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:46:04.529Z,1579135564.529 [DDM](INFO): Cable State:38
2020-01-16T00:46:04.965Z,1579135564.965 [DDM](INFO): Cable State:41
2020-01-16T00:46:05.337Z,1579135565.337 [DDM](INFO): Cable State:44
2020-01-16T00:46:05.741Z,1579135565.741 [DDM](INFO): Cable State:44
2020-01-16T00:46:06.157Z,1579135566.157 [DDM](INFO): Cable State:39
2020-01-16T00:46:06.549Z,1579135566.549 [DDM](INFO): Cable State:43
2020-01-16T00:46:06.953Z,1579135566.953 [DDM](INFO): Cable State:38
2020-01-16T00:46:07.361Z,1579135567.361 [DDM](INFO): Cable State:59
2020-01-16T00:46:07.535Z,1579135567.535 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:46:07.778Z,1579135567.778 [DDM](INFO): Cable State:47
2020-01-16T00:46:07.934Z,1579135567.934 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:46:08.165Z,1579135568.165 [DDM](INFO): Cable State:44
2020-01-16T00:46:08.569Z,1579135568.569 [DDM](INFO): Cable State:48
2020-01-16T00:46:08.973Z,1579135568.973 [DDM](INFO): Cable State:44
2020-01-16T00:46:09.377Z,1579135569.377 [DDM](INFO): Cable State:42
2020-01-16T00:46:09.797Z,1579135569.797 [DDM](INFO): Cable State:55
2020-01-16T00:46:09.906Z,1579135569.906 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:46:10.185Z,1579135570.185 [DDM](INFO): Cable State:42
2020-01-16T00:46:10.310Z,1579135570.310 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:46:10.589Z,1579135570.589 [DDM](INFO): Cable State:47
2020-01-16T00:46:10.993Z,1579135570.993 [DDM](INFO): Cable State:40
2020-01-16T00:46:11.404Z,1579135571.404 [DDM](INFO): Cable State:43
2020-01-16T00:46:11.801Z,1579135571.801 [DDM](INFO): Cable State:42
2020-01-16T00:46:12.205Z,1579135572.205 [DDM](INFO): Cable State:49
2020-01-16T00:46:12.609Z,1579135572.609 [DDM](INFO): Cable State:46
2020-01-16T00:46:13.017Z,1579135573.017 [DDM](INFO): Cable State:43
2020-01-16T00:46:13.417Z,1579135573.417 [DDM](INFO): Cable State:47
2020-01-16T00:46:13.841Z,1579135573.841 [DDM](INFO): Cable State:58
2020-01-16T00:46:13.970Z,1579135573.970 [Reporter](INFO): DDM.cablePresent 0 bool
2020-01-16T00:46:14.225Z,1579135574.225 [DDM](INFO): Cable State:165
2020-01-16T00:46:14.630Z,1579135574.630 [DDM](INFO): Cable State:175
2020-01-16T00:46:15.033Z,1579135575.033 [DDM](INFO): Cable State:163
2020-01-16T00:46:15.441Z,1579135575.441 [DDM](INFO): Cable State:164
2020-01-16T00:46:15.450Z,1579135575.450 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:46:15.841Z,1579135575.841 [DDM](INFO): Cable State:166
2020-01-16T00:46:16.245Z,1579135576.245 [DDM](INFO): Cable State:165
2020-01-16T00:46:16.649Z,1579135576.649 [DDM](INFO): Cable State:171
2020-01-16T00:46:17.057Z,1579135577.057 [DDM](INFO): Cable State:160
2020-01-16T00:46:17.453Z,1579135577.453 [DDM](INFO): Cable State:161
2020-01-16T00:46:17.857Z,1579135577.857 [DDM](INFO): Cable State:164
2020-01-16T00:46:18.265Z,1579135578.265 [DDM](INFO): Cable State:164
2020-01-16T00:46:18.665Z,1579135578.665 [DDM](INFO): Cable State:161
2020-01-16T00:46:19.077Z,1579135579.077 [DDM](INFO): Cable State:162
2020-01-16T00:46:19.481Z,1579135579.481 [DDM](INFO): Cable State:163
2020-01-16T00:46:19.877Z,1579135579.877 [DDM](INFO): Cable State:162
2020-01-16T00:46:20.281Z,1579135580.281 [DDM](INFO): Cable State:167
2020-01-16T00:46:20.685Z,1579135580.685 [DDM](INFO): Cable State:163
2020-01-16T00:46:21.101Z,1579135581.101 [DDM](INFO): Cable State:171
2020-01-16T00:46:21.494Z,1579135581.494 [DDM](INFO): Cable State:168
2020-01-16T00:46:21.907Z,1579135581.907 [DDM](INFO): Cable State:172
2020-01-16T00:46:22.306Z,1579135582.306 [DDM](INFO): Cable State:165
2020-01-16T00:46:22.706Z,1579135582.706 [DDM](INFO): Cable State:162
2020-01-16T00:46:23.109Z,1579135583.109 [DDM](INFO): Cable State:163
2020-01-16T00:46:23.513Z,1579135583.513 [DDM](INFO): Cable State:165
2020-01-16T00:46:23.917Z,1579135583.917 [DDM](INFO): Cable State:164
2020-01-16T00:46:24.322Z,1579135584.322 [DDM](INFO): Cable State:164
2020-01-16T00:46:24.725Z,1579135584.725 [DDM](INFO): Cable State:160
2020-01-16T00:46:25.133Z,1579135585.133 [DDM](INFO): Cable State:164
2020-01-16T00:46:25.541Z,1579135585.541 [DDM](INFO): Cable State:173
2020-01-16T00:46:26.098Z,1579135586.098 [DDM](INFO): Cable State:162
2020-01-16T00:46:26.497Z,1579135586.497 [DDM](INFO): Cable State:165
2020-01-16T00:46:26.502Z,1579135586.502 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:46:26.901Z,1579135586.901 [DDM](INFO): Cable State:164
2020-01-16T00:46:27.301Z,1579135587.301 [DDM](INFO): Cable State:164
2020-01-16T00:46:27.706Z,1579135587.706 [DDM](INFO): Cable State:162
2020-01-16T00:46:28.109Z,1579135588.109 [DDM](INFO): Cable State:164
2020-01-16T00:46:28.517Z,1579135588.517 [DDM](INFO): Cable State:162
2020-01-16T00:46:28.921Z,1579135588.921 [DDM](INFO): Cable State:160
2020-01-16T00:46:29.325Z,1579135589.325 [DDM](INFO): Cable State:166
2020-01-16T00:46:29.730Z,1579135589.730 [DDM](INFO): Cable State:165
2020-01-16T00:46:30.129Z,1579135590.129 [DDM](INFO): Cable State:164
2020-01-16T00:46:30.533Z,1579135590.533 [DDM](INFO): Cable State:160
2020-01-16T00:46:30.943Z,1579135590.943 [DDM](INFO): Cable State:163
2020-01-16T00:46:31.345Z,1579135591.345 [DDM](INFO): Cable State:171
2020-01-16T00:46:31.761Z,1579135591.761 [DDM](INFO): Cable State:168
2020-01-16T00:46:32.153Z,1579135592.153 [DDM](INFO): Cable State:165
2020-01-16T00:46:32.557Z,1579135592.557 [DDM](INFO): Cable State:166
2020-01-16T00:46:32.961Z,1579135592.961 [DDM](INFO): Cable State:164
2020-01-16T00:46:33.367Z,1579135593.367 [DDM](INFO): Cable State:161
2020-01-16T00:46:33.769Z,1579135593.769 [DDM](INFO): Cable State:166
2020-01-16T00:46:34.173Z,1579135594.173 [DDM](INFO): Cable State:162
2020-01-16T00:46:34.581Z,1579135594.581 [DDM](INFO): Cable State:167
2020-01-16T00:46:34.591Z,1579135594.591 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-16T00:46:34.977Z,1579135594.977 [DDM](INFO): Cable State:165
2020-01-16T00:46:35.381Z,1579135595.381 [DDM](INFO): Cable State:161
2020-01-16T00:46:35.789Z,1579135595.789 [DDM](INFO): Cable State:171
2020-01-16T00:46:36.193Z,1579135596.193 [DDM](INFO): Cable State:175
2020-01-16T00:46:36.593Z,1579135596.593 [DDM](INFO): Cable State:163
2020-01-16T00:46:37.001Z,1579135597.001 [DDM](INFO): Cable State:163
2020-01-16T00:46:37.405Z,1579135597.405 [DDM](INFO): Cable State:171
2020-01-16T00:46:37.813Z,1579135597.813 [DDM](INFO): Cable State:164
2020-01-16T00:46:37.836Z,1579135597.836 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:46:38.213Z,1579135598.213 [DDM](INFO): Cable State:163
2020-01-16T00:46:38.613Z,1579135598.613 [DDM](INFO): Cable State:162
2020-01-16T00:46:39.017Z,1579135599.017 [DDM](INFO): Cable State:168
2020-01-16T00:46:39.421Z,1579135599.421 [DDM](INFO): Cable State:165
2020-01-16T00:46:39.829Z,1579135599.829 [DDM](INFO): Cable State:158
2020-01-16T00:46:40.233Z,1579135600.233 [DDM](INFO): Cable State:179
2020-01-16T00:46:40.633Z,1579135600.633 [DDM](INFO): Cable State:167
2020-01-16T00:46:41.037Z,1579135601.037 [DDM](INFO): Cable State:169
2020-01-16T00:46:41.445Z,1579135601.445 [DDM](INFO): Cable State:162
2020-01-16T00:46:41.857Z,1579135601.857 [DDM](INFO): Cable State:171
2020-01-16T00:46:42.253Z,1579135602.253 [DDM](INFO): Cable State:160
2020-01-16T00:46:42.658Z,1579135602.658 [DDM](INFO): Cable State:162
2020-01-16T00:46:43.071Z,1579135603.071 [DDM](INFO): Cable State:164
2020-01-16T00:46:43.465Z,1579135603.465 [DDM](INFO): Cable State:171
2020-01-16T00:46:43.878Z,1579135603.878 [DDM](INFO): Cable State:166
2020-01-16T00:46:44.276Z,1579135604.276 [DDM](INFO): Cable State:165
2020-01-16T00:46:44.673Z,1579135604.673 [DDM](INFO): Cable State:171
2020-01-16T00:46:45.081Z,1579135605.081 [DDM](INFO): Cable State:167
2020-01-16T00:46:45.489Z,1579135605.489 [DDM](INFO): Cable State:159
2020-01-16T00:46:45.885Z,1579135605.885 [DDM](INFO): Cable State:163
2020-01-16T00:46:46.293Z,1579135606.293 [DDM](INFO): Cable State:163
2020-01-16T00:46:46.693Z,1579135606.693 [DDM](INFO): Cable State:175
2020-01-16T00:46:47.125Z,1579135607.125 [DDM](INFO): Cable State:166
2020-01-16T00:46:47.509Z,1579135607.509 [DDM](INFO): Cable State:162
2020-01-16T00:46:48.057Z,1579135608.057 [DDM](INFO): Cable State:163
2020-01-16T00:46:48.453Z,1579135608.453 [DDM](INFO): Cable State:163
2020-01-16T00:46:48.861Z,1579135608.861 [DDM](INFO): Cable State:164
2020-01-16T00:46:48.867Z,1579135608.867 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:46:49.265Z,1579135609.265 [DDM](INFO): Cable State:163
2020-01-16T00:46:49.661Z,1579135609.661 [DDM](INFO): Cable State:162
2020-01-16T00:46:50.069Z,1579135610.069 [DDM](INFO): Cable State:175
2020-01-16T00:46:50.469Z,1579135610.469 [DDM](INFO): Cable State:170
2020-01-16T00:46:50.881Z,1579135610.881 [DDM](INFO): Cable State:166
2020-01-16T00:46:51.277Z,1579135611.277 [DDM](INFO): Cable State:171
2020-01-16T00:46:51.689Z,1579135611.689 [DDM](INFO): Cable State:160
2020-01-16T00:46:52.081Z,1579135612.081 [DDM](INFO): Cable State:162
2020-01-16T00:46:52.493Z,1579135612.493 [DDM](INFO): Cable State:167
2020-01-16T00:46:52.893Z,1579135612.893 [DDM](INFO): Cable State:161
2020-01-16T00:46:53.297Z,1579135613.297 [DDM](INFO): Cable State:167
2020-01-16T00:46:53.697Z,1579135613.697 [DDM](INFO): Cable State:178
2020-01-16T00:46:54.105Z,1579135614.105 [DDM](INFO): Cable State:161
2020-01-16T00:46:54.505Z,1579135614.505 [DDM](INFO): Cable State:164
2020-01-16T00:46:54.918Z,1579135614.918 [DDM](INFO): Cable State:115
2020-01-16T00:46:55.319Z,1579135615.319 [DDM](INFO): Cable State:18
2020-01-16T00:46:55.447Z,1579135615.447 [Reporter](INFO): DDM.cablePresent 1 bool
2020-01-16T00:46:55.717Z,1579135615.717 [DDM](INFO): Cable State:23
2020-01-16T00:46:56.161Z,1579135616.161 [DDM](INFO): Cable State:13
2020-01-16T00:46:56.525Z,1579135616.525 [DDM](INFO): Cable State:9
2020-01-16T00:46:56.929Z,1579135616.929 [DDM](INFO): Cable State:10
2020-01-16T00:46:57.345Z,1579135617.345 [DDM](INFO): Cable State:16
2020-01-16T00:46:57.742Z,1579135617.742 [DDM](INFO): Cable State:24
2020-01-16T00:46:58.149Z,1579135618.149 [DDM](INFO): Cable State:17
2020-01-16T00:46:58.549Z,1579135618.549 [DDM](INFO): Cable State:14
2020-01-16T00:46:58.969Z,1579135618.969 [DDM](INFO): Cable State:16
2020-01-16T00:46:59.357Z,1579135619.357 [DDM](INFO): Cable State:8
2020-01-16T00:46:59.797Z,1579135619.797 [DDM](INFO): Cable State:18
2020-01-16T00:47:00.173Z,1579135620.173 [DDM](INFO): Cable State:15
2020-01-16T00:47:00.176Z,1579135620.176 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:47:00.565Z,1579135620.565 [DDM](INFO): Cable State:14
2020-01-16T00:47:00.981Z,1579135620.981 [DDM](INFO): Cable State:12
2020-01-16T00:47:01.050Z,1579135621.050 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:47:01.377Z,1579135621.377 [DDM](INFO): Cable State:22
2020-01-16T00:47:01.809Z,1579135621.809 [DDM](INFO): Cable State:23
2020-01-16T00:47:02.193Z,1579135622.193 [DDM](INFO): Cable State:19
2020-01-16T00:47:02.601Z,1579135622.601 [DDM](INFO): Cable State:14
2020-01-16T00:47:02.607Z,1579135622.607 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:47:02.607Z,1579135622.607 [Micromodem] Communications Fault, FailCount= 3
2020-01-16T00:47:02.607Z,1579135622.607 [Micromodem](ERROR): Communications Fault
2020-01-16T00:47:02.777Z,1579135622.777 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:47:02.993Z,1579135622.993 [DDM](INFO): Cable State:14
2020-01-16T00:47:03.002Z,1579135623.002 [Micromodem](INFO): Powering down
2020-01-16T00:47:03.405Z,1579135623.405 [DDM](INFO): Cable State:14
2020-01-16T00:47:03.822Z,1579135623.822 [DDM](INFO): Cable State:16
2020-01-16T00:47:03.953Z,1579135623.953 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:47:03.953Z,1579135623.953 [Micromodem] No Fault, FailCount= 3
2020-01-16T00:47:05.030Z,1579135625.030 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-16T00:47:06.642Z,1579135626.642 [Micromodem](INFO): Powering up
2020-01-16T00:47:06.642Z,1579135626.642 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:47:09.465Z,1579135629.465 [DDM](INFO): Cable State:0
2020-01-16T00:47:09.861Z,1579135629.861 [DDM](INFO): Cable State:1
2020-01-16T00:47:10.441Z,1579135630.441 [DDM](INFO): Cable State:2
2020-01-16T00:47:10.838Z,1579135630.838 [DDM](INFO): Cable State:1
2020-01-16T00:47:11.241Z,1579135631.241 [DDM](INFO): Cable State:0
2020-01-16T00:47:11.270Z,1579135631.270 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:47:11.649Z,1579135631.649 [DDM](INFO): Cable State:0
2020-01-16T00:47:12.049Z,1579135632.049 [DDM](INFO): Cable State:0
2020-01-16T00:47:12.453Z,1579135632.453 [DDM](INFO): Cable State:0
2020-01-16T00:47:12.857Z,1579135632.857 [DDM](INFO): Cable State:1
2020-01-16T00:47:13.261Z,1579135633.261 [DDM](INFO): Cable State:0
2020-01-16T00:47:13.665Z,1579135633.665 [DDM](INFO): Cable State:1
2020-01-16T00:47:14.069Z,1579135634.069 [DDM](INFO): Cable State:2
2020-01-16T00:47:14.477Z,1579135634.477 [DDM](INFO): Cable State:1
2020-01-16T00:47:14.902Z,1579135634.902 [DDM](INFO): Cable State:0
2020-01-16T00:47:15.281Z,1579135635.281 [DDM](INFO): Cable State:0
2020-01-16T00:47:15.697Z,1579135635.697 [DDM](INFO): Cable State:0
2020-01-16T00:47:16.098Z,1579135636.098 [DDM](INFO): Cable State:0
2020-01-16T00:47:16.501Z,1579135636.501 [DDM](INFO): Cable State:2
2020-01-16T00:47:16.901Z,1579135636.901 [DDM](INFO): Cable State:1
2020-01-16T00:47:17.196Z,1579135637.196 [CommandLine](IMPORTANT): got command get DDM.cablePresent
2020-01-16T00:47:17.196Z,1579135637.196 [CommandLine](IMPORTANT): DDM.cablePresent 1 bool
2020-01-16T00:47:17.309Z,1579135637.309 [DDM](INFO): Cable State:2
2020-01-16T00:47:17.709Z,1579135637.709 [DDM](INFO): Cable State:1
2020-01-16T00:47:18.113Z,1579135638.113 [DDM](INFO): Cable State:1
2020-01-16T00:47:18.160Z,1579135638.160 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-16T00:47:18.160Z,1579135638.160 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011517592483,35.0, -0.1,   0.0,14
2020-01-16T00:47:18.517Z,1579135638.517 [DDM](INFO): Cable State:1
2020-01-16T00:47:18.921Z,1579135638.921 [DDM](INFO): Cable State:0
2020-01-16T00:47:19.321Z,1579135639.321 [DDM](INFO): Cable State:0
2020-01-16T00:47:19.733Z,1579135639.733 [DDM](INFO): Cable State:2
2020-01-16T00:47:20.129Z,1579135640.129 [DDM](INFO): Cable State:0
2020-01-16T00:47:20.537Z,1579135640.537 [DDM](INFO): Cable State:0
2020-01-16T00:47:20.937Z,1579135640.937 [DDM](INFO): Cable State:0
2020-01-16T00:47:21.341Z,1579135641.341 [DDM](INFO): Cable State:0
2020-01-16T00:47:21.769Z,1579135641.769 [DDM](INFO): Cable State:1
2020-01-16T00:47:22.153Z,1579135642.153 [DDM](INFO): Cable State:0
2020-01-16T00:47:22.553Z,1579135642.553 [DDM](INFO): Cable State:0
2020-01-16T00:47:22.562Z,1579135642.562 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:47:22.961Z,1579135642.961 [DDM](INFO): Cable State:1
2020-01-16T00:47:23.367Z,1579135643.367 [DDM](INFO): Cable State:1
2020-01-16T00:47:23.765Z,1579135643.765 [DDM](INFO): Cable State:0
2020-01-16T00:47:23.843Z,1579135643.843 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-16T00:47:23.843Z,1579135643.843 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,375.99
2020-01-16T00:47:24.181Z,1579135644.181 [DDM](INFO): Cable State:2
2020-01-16T00:47:24.577Z,1579135644.577 [DDM](INFO): Cable State:0
2020-01-16T00:47:24.977Z,1579135644.977 [DDM](INFO): Cable State:2
2020-01-16T00:47:25.385Z,1579135645.385 [DDM](INFO): Cable State:2
2020-01-16T00:47:25.789Z,1579135645.789 [DDM](INFO): Cable State:0
2020-01-16T00:47:26.190Z,1579135646.190 [DDM](INFO): Cable State:2
2020-01-16T00:47:26.593Z,1579135646.593 [DDM](INFO): Cable State:0
2020-01-16T00:47:27.001Z,1579135647.001 [DDM](INFO): Cable State:1
2020-01-16T00:47:27.413Z,1579135647.413 [DDM](INFO): Cable State:1
2020-01-16T00:47:27.813Z,1579135647.813 [DDM](INFO): Cable State:2
2020-01-16T00:47:28.209Z,1579135648.209 [DDM](INFO): Cable State:0
2020-01-16T00:47:28.618Z,1579135648.618 [DDM](INFO): Cable State:0
2020-01-16T00:47:29.019Z,1579135649.019 [DDM](INFO): Cable State:1
2020-01-16T00:47:29.425Z,1579135649.425 [DDM](INFO): Cable State:0
2020-01-16T00:47:29.825Z,1579135649.825 [DDM](INFO): Cable State:0
2020-01-16T00:47:30.233Z,1579135650.233 [DDM](INFO): Cable State:0
2020-01-16T00:47:30.633Z,1579135650.633 [DDM](INFO): Cable State:0
2020-01-16T00:47:31.045Z,1579135651.045 [DDM](INFO): Cable State:0
2020-01-16T00:47:31.450Z,1579135651.450 [DDM](INFO): Cable State:0
2020-01-16T00:47:31.853Z,1579135651.853 [DDM](INFO): Cable State:2
2020-01-16T00:47:32.257Z,1579135652.257 [DDM](INFO): Cable State:0
2020-01-16T00:47:32.657Z,1579135652.657 [DDM](INFO): Cable State:1
2020-01-16T00:47:33.173Z,1579135653.173 [DDM](INFO): Cable State:1
2020-01-16T00:47:33.573Z,1579135653.573 [DDM](INFO): Cable State:1
2020-01-16T00:47:33.601Z,1579135653.601 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:47:33.981Z,1579135653.981 [DDM](INFO): Cable State:0
2020-01-16T00:47:34.385Z,1579135654.385 [DDM](INFO): Cable State:1
2020-01-16T00:47:34.786Z,1579135654.786 [DDM](INFO): Cable State:0
2020-01-16T00:47:35.189Z,1579135655.189 [DDM](INFO): Cable State:1
2020-01-16T00:47:35.597Z,1579135655.597 [DDM](INFO): Cable State:0
2020-01-16T00:47:35.997Z,1579135655.997 [DDM](INFO): Cable State:3
2020-01-16T00:47:36.405Z,1579135656.405 [DDM](INFO): Cable State:0
2020-01-16T00:47:36.809Z,1579135656.809 [DDM](INFO): Cable State:0
2020-01-16T00:47:37.213Z,1579135657.213 [DDM](INFO): Cable State:0
2020-01-16T00:47:37.617Z,1579135657.617 [DDM](INFO): Cable State:0
2020-01-16T00:47:38.021Z,1579135658.021 [DDM](INFO): Cable State:0
2020-01-16T00:47:38.421Z,1579135658.421 [DDM](INFO): Cable State:0
2020-01-16T00:47:38.829Z,1579135658.829 [DDM](INFO): Cable State:0
2020-01-16T00:47:39.233Z,1579135659.233 [DDM](INFO): Cable State:2
2020-01-16T00:47:39.633Z,1579135659.633 [DDM](INFO): Cable State:0
2020-01-16T00:47:40.037Z,1579135660.037 [DDM](INFO): Cable State:1
2020-01-16T00:47:40.445Z,1579135660.445 [DDM](INFO): Cable State:0
2020-01-16T00:47:40.845Z,1579135660.845 [DDM](INFO): Cable State:0
2020-01-16T00:47:41.249Z,1579135661.249 [DDM](INFO): Cable State:0
2020-01-16T00:47:41.657Z,1579135661.657 [DDM](INFO): Cable State:2
2020-01-16T00:47:42.057Z,1579135662.057 [DDM](INFO): Cable State:1
2020-01-16T00:47:42.469Z,1579135662.469 [DDM](INFO): Cable State:0
2020-01-16T00:47:42.869Z,1579135662.869 [DDM](INFO): Cable State:0
2020-01-16T00:47:43.273Z,1579135663.273 [DDM](INFO): Cable State:0
2020-01-16T00:47:43.681Z,1579135663.681 [DDM](INFO): Cable State:0
2020-01-16T00:47:44.083Z,1579135664.083 [DDM](INFO): Cable State:1
2020-01-16T00:47:44.481Z,1579135664.481 [DDM](INFO): Cable State:2
2020-01-16T00:47:44.886Z,1579135664.886 [DDM](INFO): Cable State:0
2020-01-16T00:47:44.912Z,1579135664.912 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:47:45.293Z,1579135665.293 [DDM](INFO): Cable State:0
2020-01-16T00:47:45.697Z,1579135665.697 [DDM](INFO): Cable State:1
2020-01-16T00:47:46.106Z,1579135666.106 [DDM](INFO): Cable State:0
2020-01-16T00:47:46.505Z,1579135666.505 [DDM](INFO): Cable State:1
2020-01-16T00:47:46.909Z,1579135666.909 [DDM](INFO): Cable State:0
2020-01-16T00:47:47.309Z,1579135667.309 [DDM](INFO): Cable State:0
2020-01-16T00:47:47.717Z,1579135667.717 [DDM](INFO): Cable State:1
2020-01-16T00:47:48.121Z,1579135668.121 [DDM](INFO): Cable State:0
2020-01-16T00:47:48.521Z,1579135668.521 [DDM](INFO): Cable State:0
2020-01-16T00:47:48.929Z,1579135668.929 [DDM](INFO): Cable State:0
2020-01-16T00:47:49.329Z,1579135669.329 [DDM](INFO): Cable State:0
2020-01-16T00:47:49.741Z,1579135669.741 [DDM](INFO): Cable State:0
2020-01-16T00:47:50.149Z,1579135670.149 [DDM](INFO): Cable State:0
2020-01-16T00:47:50.541Z,1579135670.541 [DDM](INFO): Cable State:1
2020-01-16T00:47:50.949Z,1579135670.949 [DDM](INFO): Cable State:0
2020-01-16T00:47:51.353Z,1579135671.353 [DDM](INFO): Cable State:0
2020-01-16T00:47:51.785Z,1579135671.785 [DDM](INFO): Cable State:0
2020-01-16T00:47:52.157Z,1579135672.157 [DDM](INFO): Cable State:2
2020-01-16T00:47:52.573Z,1579135672.573 [DDM](INFO): Cable State:0
2020-01-16T00:47:52.977Z,1579135672.977 [DDM](INFO): Cable State:1
2020-01-16T00:47:53.369Z,1579135673.369 [DDM](INFO): Cable State:0
2020-01-16T00:47:53.777Z,1579135673.777 [DDM](INFO): Cable State:0
2020-01-16T00:47:54.181Z,1579135674.181 [DDM](INFO): Cable State:0
2020-01-16T00:47:54.581Z,1579135674.581 [DDM](INFO): Cable State:1
2020-01-16T00:47:54.986Z,1579135674.986 [DDM](INFO): Cable State:0
2020-01-16T00:47:55.557Z,1579135675.557 [DDM](INFO): Cable State:2
2020-01-16T00:47:55.949Z,1579135675.949 [DDM](INFO): Cable State:1
2020-01-16T00:47:55.958Z,1579135675.958 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:47:56.355Z,1579135676.355 [DDM](INFO): Cable State:1
2020-01-16T00:47:56.753Z,1579135676.753 [DDM](INFO): Cable State:0
2020-01-16T00:47:57.157Z,1579135677.157 [DDM](INFO): Cable State:0
2020-01-16T00:47:57.561Z,1579135677.561 [DDM](INFO): Cable State:0
2020-01-16T00:47:57.973Z,1579135677.973 [DDM](INFO): Cable State:0
2020-01-16T00:47:58.365Z,1579135678.365 [DDM](INFO): Cable State:0
2020-01-16T00:47:58.809Z,1579135678.809 [DDM](INFO): Cable State:1
2020-01-16T00:47:59.181Z,1579135679.181 [DDM](INFO): Cable State:0
2020-01-16T00:47:59.585Z,1579135679.585 [DDM](INFO): Cable State:0
2020-01-16T00:47:59.985Z,1579135679.985 [DDM](INFO): Cable State:2
2020-01-16T00:48:00.385Z,1579135680.385 [DDM](INFO): Cable State:2
2020-01-16T00:48:00.805Z,1579135680.805 [DDM](INFO): Cable State:0
2020-01-16T00:48:01.201Z,1579135681.201 [DDM](INFO): Cable State:2
2020-01-16T00:48:01.258Z,1579135681.258 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:48:01.605Z,1579135681.605 [DDM](INFO): Cable State:2
2020-01-16T00:48:02.005Z,1579135682.005 [DDM](INFO): Cable State:1
2020-01-16T00:48:02.417Z,1579135682.417 [DDM](INFO): Cable State:1
2020-01-16T00:48:02.821Z,1579135682.821 [DDM](INFO): Cable State:1
2020-01-16T00:48:03.222Z,1579135683.222 [DDM](INFO): Cable State:0
2020-01-16T00:48:03.629Z,1579135683.629 [DDM](INFO): Cable State:0
2020-01-16T00:48:04.033Z,1579135684.033 [DDM](INFO): Cable State:0
2020-01-16T00:48:04.437Z,1579135684.437 [DDM](INFO): Cable State:0
2020-01-16T00:48:04.841Z,1579135684.841 [DDM](INFO): Cable State:1
2020-01-16T00:48:05.257Z,1579135685.257 [DDM](INFO): Cable State:2
2020-01-16T00:48:05.649Z,1579135685.649 [DDM](INFO): Cable State:1
2020-01-16T00:48:06.049Z,1579135686.049 [DDM](INFO): Cable State:0
2020-01-16T00:48:06.457Z,1579135686.457 [DDM](INFO): Cable State:0
2020-01-16T00:48:06.861Z,1579135686.861 [DDM](INFO): Cable State:2
2020-01-16T00:48:07.265Z,1579135687.265 [DDM](INFO): Cable State:0
2020-01-16T00:48:07.270Z,1579135687.270 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:48:07.669Z,1579135687.669 [DDM](INFO): Cable State:0
2020-01-16T00:48:08.070Z,1579135688.070 [DDM](INFO): Cable State:1
2020-01-16T00:48:08.473Z,1579135688.473 [DDM](INFO): Cable State:1
2020-01-16T00:48:08.881Z,1579135688.881 [DDM](INFO): Cable State:1
2020-01-16T00:48:09.281Z,1579135689.281 [DDM](INFO): Cable State:0
2020-01-16T00:48:09.687Z,1579135689.687 [DDM](INFO): Cable State:0
2020-01-16T00:48:10.089Z,1579135690.089 [DDM](INFO): Cable State:1
2020-01-16T00:48:10.493Z,1579135690.493 [DDM](INFO): Cable State:2
2020-01-16T00:48:10.897Z,1579135690.897 [DDM](INFO): Cable State:0
2020-01-16T00:48:11.305Z,1579135691.305 [DDM](INFO): Cable State:0
2020-01-16T00:48:11.705Z,1579135691.705 [DDM](INFO): Cable State:0
2020-01-16T00:48:12.111Z,1579135692.111 [DDM](INFO): Cable State:1
2020-01-16T00:48:12.521Z,1579135692.521 [DDM](INFO): Cable State:2
2020-01-16T00:48:12.921Z,1579135692.921 [DDM](INFO): Cable State:1
2020-01-16T00:48:13.325Z,1579135693.325 [DDM](INFO): Cable State:1
2020-01-16T00:48:13.730Z,1579135693.730 [DDM](INFO): Cable State:1
2020-01-16T00:48:14.129Z,1579135694.129 [DDM](INFO): Cable State:2
2020-01-16T00:48:14.537Z,1579135694.537 [DDM](INFO): Cable State:0
2020-01-16T00:48:14.973Z,1579135694.973 [DDM](INFO): Cable State:1
2020-01-16T00:48:15.345Z,1579135695.345 [DDM](INFO): Cable State:1
2020-01-16T00:48:15.758Z,1579135695.758 [DDM](INFO): Cable State:0
2020-01-16T00:48:16.163Z,1579135696.163 [DDM](INFO): Cable State:1
2020-01-16T00:48:16.558Z,1579135696.558 [DDM](INFO): Cable State:0
2020-01-16T00:48:16.961Z,1579135696.961 [DDM](INFO): Cable State:0
2020-01-16T00:48:17.361Z,1579135697.361 [DDM](INFO): Cable State:1
2020-01-16T00:48:17.778Z,1579135697.778 [DDM](INFO): Cable State:0
2020-01-16T00:48:18.345Z,1579135698.345 [DDM](INFO): Cable State:0
2020-01-16T00:48:18.375Z,1579135698.375 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:48:18.432Z,1579135698.432 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-16T00:48:18.435Z,1579135698.435 [BPC1](INFO): Received data from all battery sticks.
2020-01-16T00:48:18.754Z,1579135698.754 [DDM](INFO): Cable State:0
2020-01-16T00:48:19.149Z,1579135699.149 [DDM](INFO): Cable State:1
2020-01-16T00:48:19.553Z,1579135699.553 [DDM](INFO): Cable State:1
2020-01-16T00:48:19.965Z,1579135699.965 [DDM](INFO): Cable State:0
2020-01-16T00:48:20.357Z,1579135700.357 [DDM](INFO): Cable State:0
2020-01-16T00:48:20.789Z,1579135700.789 [DDM](INFO): Cable State:0
2020-01-16T00:48:21.165Z,1579135701.165 [DDM](INFO): Cable State:1
2020-01-16T00:48:21.569Z,1579135701.569 [DDM](INFO): Cable State:1
2020-01-16T00:48:21.989Z,1579135701.989 [DDM](INFO): Cable State:1
2020-01-16T00:48:22.377Z,1579135702.377 [DDM](INFO): Cable State:0
2020-01-16T00:48:22.809Z,1579135702.809 [DDM](INFO): Cable State:0
2020-01-16T00:48:23.189Z,1579135703.189 [DDM](INFO): Cable State:0
2020-01-16T00:48:23.597Z,1579135703.597 [DDM](INFO): Cable State:1
2020-01-16T00:48:23.993Z,1579135703.993 [DDM](INFO): Cable State:0
2020-01-16T00:48:24.397Z,1579135704.397 [DDM](INFO): Cable State:1
2020-01-16T00:48:24.801Z,1579135704.801 [DDM](INFO): Cable State:0
2020-01-16T00:48:25.205Z,1579135705.205 [DDM](INFO): Cable State:1
2020-01-16T00:48:25.615Z,1579135705.615 [DDM](INFO): Cable State:0
2020-01-16T00:48:26.013Z,1579135706.013 [DDM](INFO): Cable State:0
2020-01-16T00:48:26.421Z,1579135706.421 [DDM](INFO): Cable State:1
2020-01-16T00:48:26.829Z,1579135706.829 [DDM](INFO): Cable State:1
2020-01-16T00:48:27.225Z,1579135707.225 [DDM](INFO): Cable State:0
2020-01-16T00:48:27.629Z,1579135707.629 [DDM](INFO): Cable State:0
2020-01-16T00:48:28.037Z,1579135708.037 [DDM](INFO): Cable State:1
2020-01-16T00:48:28.441Z,1579135708.441 [DDM](INFO): Cable State:1
2020-01-16T00:48:28.842Z,1579135708.842 [DDM](INFO): Cable State:0
2020-01-16T00:48:29.245Z,1579135709.245 [DDM](INFO): Cable State:0
2020-01-16T00:48:29.649Z,1579135709.649 [DDM](INFO): Cable State:2
2020-01-16T00:48:29.658Z,1579135709.658 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:48:30.057Z,1579135710.057 [DDM](INFO): Cable State:2
2020-01-16T00:48:30.457Z,1579135710.457 [DDM](INFO): Cable State:0
2020-01-16T00:48:30.865Z,1579135710.865 [DDM](INFO): Cable State:1
2020-01-16T00:48:31.265Z,1579135711.265 [DDM](INFO): Cable State:1
2020-01-16T00:48:31.669Z,1579135711.669 [DDM](INFO): Cable State:1
2020-01-16T00:48:32.073Z,1579135712.073 [DDM](INFO): Cable State:1
2020-01-16T00:48:32.481Z,1579135712.481 [DDM](INFO): Cable State:0
2020-01-16T00:48:32.885Z,1579135712.885 [DDM](INFO): Cable State:1
2020-01-16T00:48:33.287Z,1579135713.287 [DDM](INFO): Cable State:0
2020-01-16T00:48:33.689Z,1579135713.689 [DDM](INFO): Cable State:0
2020-01-16T00:48:34.097Z,1579135714.097 [DDM](INFO): Cable State:0
2020-01-16T00:48:34.501Z,1579135714.501 [DDM](INFO): Cable State:0
2020-01-16T00:48:34.918Z,1579135714.918 [DDM](INFO): Cable State:2
2020-01-16T00:48:35.309Z,1579135715.309 [DDM](INFO): Cable State:0
2020-01-16T00:48:35.713Z,1579135715.713 [DDM](INFO): Cable State:0
2020-01-16T00:48:36.141Z,1579135716.141 [DDM](INFO): Cable State:1
2020-01-16T00:48:36.517Z,1579135716.517 [DDM](INFO): Cable State:0
2020-01-16T00:48:36.921Z,1579135716.921 [DDM](INFO): Cable State:0
2020-01-16T00:48:37.337Z,1579135717.337 [DDM](INFO): Cable State:1
2020-01-16T00:48:37.741Z,1579135717.741 [DDM](INFO): Cable State:1
2020-01-16T00:48:38.133Z,1579135718.133 [DDM](INFO): Cable State:0
2020-01-16T00:48:38.541Z,1579135718.541 [DDM](INFO): Cable State:1
2020-01-16T00:48:38.949Z,1579135718.949 [DDM](INFO): Cable State:1
2020-01-16T00:48:39.345Z,1579135719.345 [DDM](INFO): Cable State:0
2020-01-16T00:48:39.805Z,1579135719.805 [DDM](INFO): Cable State:0
2020-01-16T00:48:40.173Z,1579135720.173 [DDM](INFO): Cable State:0
2020-01-16T00:48:40.706Z,1579135720.706 [DDM](INFO): Cable State:0
2020-01-16T00:48:40.710Z,1579135720.710 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:48:41.097Z,1579135721.097 [DDM](INFO): Cable State:1
2020-01-16T00:48:41.505Z,1579135721.505 [DDM](INFO): Cable State:0
2020-01-16T00:48:41.913Z,1579135721.913 [DDM](INFO): Cable State:0
2020-01-16T00:48:42.310Z,1579135722.310 [DDM](INFO): Cable State:1
2020-01-16T00:48:42.713Z,1579135722.713 [DDM](INFO): Cable State:0
2020-01-16T00:48:43.117Z,1579135723.117 [DDM](INFO): Cable State:1
2020-01-16T00:48:43.521Z,1579135723.521 [DDM](INFO): Cable State:0
2020-01-16T00:48:43.925Z,1579135723.925 [DDM](INFO): Cable State:0
2020-01-16T00:48:44.329Z,1579135724.329 [DDM](INFO): Cable State:0
2020-01-16T00:48:44.737Z,1579135724.737 [DDM](INFO): Cable State:0
2020-01-16T00:48:45.145Z,1579135725.145 [DDM](INFO): Cable State:2
2020-01-16T00:48:45.545Z,1579135725.545 [DDM](INFO): Cable State:2
2020-01-16T00:48:45.949Z,1579135725.949 [DDM](INFO): Cable State:1
2020-01-16T00:48:46.349Z,1579135726.349 [DDM](INFO): Cable State:1
2020-01-16T00:48:46.757Z,1579135726.757 [DDM](INFO): Cable State:0
2020-01-16T00:48:47.161Z,1579135727.161 [DDM](INFO): Cable State:0
2020-01-16T00:48:47.565Z,1579135727.565 [DDM](INFO): Cable State:0
2020-01-16T00:48:47.969Z,1579135727.969 [DDM](INFO): Cable State:0
2020-01-16T00:48:48.369Z,1579135728.369 [DDM](INFO): Cable State:2
2020-01-16T00:48:48.793Z,1579135728.793 [DDM](INFO): Cable State:0
2020-01-16T00:48:49.177Z,1579135729.177 [DDM](INFO): Cable State:0
2020-01-16T00:48:49.585Z,1579135729.585 [DDM](INFO): Cable State:0
2020-01-16T00:48:49.991Z,1579135729.991 [DDM](INFO): Cable State:1
2020-01-16T00:48:50.389Z,1579135730.389 [DDM](INFO): Cable State:0
2020-01-16T00:48:50.801Z,1579135730.801 [DDM](INFO): Cable State:1
2020-01-16T00:48:51.201Z,1579135731.201 [DDM](INFO): Cable State:2
2020-01-16T00:48:51.601Z,1579135731.601 [DDM](INFO): Cable State:0
2020-01-16T00:48:52.005Z,1579135732.005 [DDM](INFO): Cable State:0
2020-01-16T00:48:52.010Z,1579135732.010 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:48:52.415Z,1579135732.415 [DDM](INFO): Cable State:1
2020-01-16T00:48:52.817Z,1579135732.817 [DDM](INFO): Cable State:0
2020-01-16T00:48:53.221Z,1579135733.221 [DDM](INFO): Cable State:0
2020-01-16T00:48:53.625Z,1579135733.625 [DDM](INFO): Cable State:0
2020-01-16T00:48:54.030Z,1579135734.030 [DDM](INFO): Cable State:0
2020-01-16T00:48:54.429Z,1579135734.429 [DDM](INFO): Cable State:1
2020-01-16T00:48:54.837Z,1579135734.837 [DDM](INFO): Cable State:0
2020-01-16T00:48:55.242Z,1579135735.242 [DDM](INFO): Cable State:2
2020-01-16T00:48:55.641Z,1579135735.641 [DDM](INFO): Cable State:1
2020-01-16T00:48:56.049Z,1579135736.049 [DDM](INFO): Cable State:1
2020-01-16T00:48:56.453Z,1579135736.453 [DDM](INFO): Cable State:1
2020-01-16T00:48:56.853Z,1579135736.853 [DDM](INFO): Cable State:2
2020-01-16T00:48:57.261Z,1579135737.261 [DDM](INFO): Cable State:1
2020-01-16T00:48:57.665Z,1579135737.665 [DDM](INFO): Cable State:0
2020-01-16T00:48:58.065Z,1579135738.065 [DDM](INFO): Cable State:1
2020-01-16T00:48:58.481Z,1579135738.481 [DDM](INFO): Cable State:2
2020-01-16T00:48:58.877Z,1579135738.877 [DDM](INFO): Cable State:0
2020-01-16T00:48:59.277Z,1579135739.277 [DDM](INFO): Cable State:0
2020-01-16T00:48:59.685Z,1579135739.685 [DDM](INFO): Cable State:1
2020-01-16T00:49:00.102Z,1579135740.102 [DDM](INFO): Cable State:1
2020-01-16T00:49:00.493Z,1579135740.493 [DDM](INFO): Cable State:0
2020-01-16T00:49:00.893Z,1579135740.893 [DDM](INFO): Cable State:1
2020-01-16T00:49:01.298Z,1579135741.298 [DDM](INFO): Cable State:0
2020-01-16T00:49:01.357Z,1579135741.357 [Docked:B.](INFO): *** querying acoustic contact ***
2020-01-16T00:49:01.705Z,1579135741.705 [DDM](INFO): Cable State:0
2020-01-16T00:49:02.105Z,1579135742.105 [DDM](INFO): Cable State:0
2020-01-16T00:49:02.513Z,1579135742.513 [DDM](INFO): Cable State:0
2020-01-16T00:49:02.917Z,1579135742.917 [DDM](INFO): Cable State:1
2020-01-16T00:49:03.461Z,1579135743.461 [DDM](INFO): Cable State:0
2020-01-16T00:49:03.470Z,1579135743.470 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:49:03.877Z,1579135743.877 [DDM](INFO): Cable State:1
2020-01-16T00:49:04.266Z,1579135744.266 [DDM](INFO): Cable State:0
2020-01-16T00:49:04.673Z,1579135744.673 [DDM](INFO): Cable State:1
2020-01-16T00:49:05.077Z,1579135745.077 [DDM](INFO): Cable State:0
2020-01-16T00:49:05.481Z,1579135745.481 [DDM](INFO): Cable State:0
2020-01-16T00:49:05.881Z,1579135745.881 [DDM](INFO): Cable State:2
2020-01-16T00:49:06.289Z,1579135746.289 [DDM](INFO): Cable State:0
2020-01-16T00:49:06.318Z,1579135746.318 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2020-01-16T00:49:06.318Z,1579135746.318 [Micromodem] Communications Fault, FailCount= 4
2020-01-16T00:49:06.318Z,1579135746.318 [Micromodem](ERROR): Communications Fault
2020-01-16T00:49:06.401Z,1579135746.401 [CBIT](ERROR): Communications Fault in component: Micromodem
2020-01-16T00:49:06.689Z,1579135746.689 [DDM](INFO): Cable State:0
2020-01-16T00:49:06.698Z,1579135746.698 [Micromodem](INFO): Powering down
2020-01-16T00:49:07.106Z,1579135747.106 [DDM](INFO): Cable State:0
2020-01-16T00:49:07.501Z,1579135747.501 [DDM](INFO): Cable State:0
2020-01-16T00:49:07.564Z,1579135747.564 [CBIT](INFO): Clearing failed state for component Micromodem
2020-01-16T00:49:07.564Z,1579135747.564 [Micromodem] No Fault, FailCount= 4
2020-01-16T00:49:07.901Z,1579135747.901 [DDM](INFO): Cable State:0
2020-01-16T00:49:08.309Z,1579135748.309 [DDM](INFO): Cable State:0
2020-01-16T00:49:08.713Z,1579135748.713 [DDM](INFO): Cable State:0
2020-01-16T00:49:09.118Z,1579135749.118 [DDM](INFO): Cable State:0
2020-01-16T00:49:10.330Z,1579135750.330 [Micromodem](INFO): Powering up
2020-01-16T00:49:10.330Z,1579135750.330 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T00:49:13.153Z,1579135753.153 [DDM](INFO): Cable State:1
2020-01-16T00:49:13.557Z,1579135753.557 [DDM](INFO): Cable State:1
2020-01-16T00:49:13.965Z,1579135753.965 [DDM](INFO): Cable State:2
2020-01-16T00:49:14.369Z,1579135754.369 [DDM](INFO): Cable State:1
2020-01-16T00:49:14.770Z,1579135754.770 [DDM](INFO): Cable State:2
2020-01-16T00:49:14.778Z,1579135754.778 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:49:15.177Z,1579135755.177 [DDM](INFO): Cable State:0
2020-01-16T00:49:15.581Z,1579135755.581 [DDM](INFO): Cable State:0
2020-01-16T00:49:15.985Z,1579135755.985 [DDM](INFO): Cable State:0
2020-01-16T00:49:16.385Z,1579135756.385 [DDM](INFO): Cable State:1
2020-01-16T00:49:16.789Z,1579135756.789 [DDM](INFO): Cable State:1
2020-01-16T00:49:17.197Z,1579135757.197 [DDM](INFO): Cable State:1
2020-01-16T00:49:17.605Z,1579135757.605 [DDM](INFO): Cable State:2
2020-01-16T00:49:18.001Z,1579135758.001 [DDM](INFO): Cable State:0
2020-01-16T00:49:18.405Z,1579135758.405 [DDM](INFO): Cable State:1
2020-01-16T00:49:18.813Z,1579135758.813 [DDM](INFO): Cable State:2
2020-01-16T00:49:19.217Z,1579135759.217 [DDM](INFO): Cable State:1
2020-01-16T00:49:19.617Z,1579135759.617 [DDM](INFO): Cable State:0
2020-01-16T00:49:20.021Z,1579135760.021 [DDM](INFO): Cable State:0
2020-01-16T00:49:20.429Z,1579135760.429 [DDM](INFO): Cable State:0
2020-01-16T00:49:20.841Z,1579135760.841 [DDM](INFO): Cable State:0
2020-01-16T00:49:21.238Z,1579135761.238 [DDM](INFO): Cable State:0
2020-01-16T00:49:21.641Z,1579135761.641 [DDM](INFO): Cable State:1
2020-01-16T00:49:22.041Z,1579135762.041 [DDM](INFO): Cable State:0
2020-01-16T00:49:22.445Z,1579135762.445 [DDM](INFO): Cable State:0
2020-01-16T00:49:22.853Z,1579135762.853 [DDM](INFO): Cable State:0
2020-01-16T00:49:23.265Z,1579135763.265 [DDM](INFO): Cable State:0
2020-01-16T00:49:23.661Z,1579135763.661 [DDM](INFO): Cable State:1
2020-01-16T00:49:24.065Z,1579135764.065 [DDM](INFO): Cable State:0
2020-01-16T00:49:24.470Z,1579135764.470 [DDM](INFO): Cable State:0
2020-01-16T00:49:24.870Z,1579135764.870 [DDM](INFO): Cable State:1
2020-01-16T00:49:25.273Z,1579135765.273 [DDM](INFO): Cable State:0
2020-01-16T00:49:25.793Z,1579135765.793 [DDM](INFO): Cable State:0
2020-01-16T00:49:25.820Z,1579135765.820 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:49:26.197Z,1579135766.197 [DDM](INFO): Cable State:0
2020-01-16T00:49:26.597Z,1579135766.597 [DDM](INFO): Cable State:0
2020-01-16T00:49:27.001Z,1579135767.001 [DDM](INFO): Cable State:0
2020-01-16T00:49:27.405Z,1579135767.405 [DDM](INFO): Cable State:0
2020-01-16T00:49:27.817Z,1579135767.817 [DDM](INFO): Cable State:1
2020-01-16T00:49:28.214Z,1579135768.214 [DDM](INFO): Cable State:0
2020-01-16T00:49:28.617Z,1579135768.617 [DDM](INFO): Cable State:0
2020-01-16T00:49:29.021Z,1579135769.021 [DDM](INFO): Cable State:1
2020-01-16T00:49:29.426Z,1579135769.426 [DDM](INFO): Cable State:2
2020-01-16T00:49:29.829Z,1579135769.829 [DDM](INFO): Cable State:0
2020-01-16T00:49:30.233Z,1579135770.233 [DDM](INFO): Cable State:1
2020-01-16T00:49:30.638Z,1579135770.638 [DDM](INFO): Cable State:0
2020-01-16T00:49:31.041Z,1579135771.041 [DDM](INFO): Cable State:0
2020-01-16T00:49:31.449Z,1579135771.449 [DDM](INFO): Cable State:0
2020-01-16T00:49:31.849Z,1579135771.849 [DDM](INFO): Cable State:1
2020-01-16T00:49:32.253Z,1579135772.253 [DDM](INFO): Cable State:2
2020-01-16T00:49:32.661Z,1579135772.661 [DDM](INFO): Cable State:2
2020-01-16T00:49:33.062Z,1579135773.062 [DDM](INFO): Cable State:1
2020-01-16T00:49:33.465Z,1579135773.465 [DDM](INFO): Cable State:0
2020-01-16T00:49:33.869Z,1579135773.869 [DDM](INFO): Cable State:0
2020-01-16T00:49:34.277Z,1579135774.277 [DDM](INFO): Cable State:0
2020-01-16T00:49:34.678Z,1579135774.678 [DDM](INFO): Cable State:0
2020-01-16T00:49:35.081Z,1579135775.081 [DDM](INFO): Cable State:0
2020-01-16T00:49:35.485Z,1579135775.485 [DDM](INFO): Cable State:1
2020-01-16T00:49:35.893Z,1579135775.893 [DDM](INFO): Cable State:0
2020-01-16T00:49:36.293Z,1579135776.293 [DDM](INFO): Cable State:1
2020-01-16T00:49:36.701Z,1579135776.701 [DDM](INFO): Cable State:1
2020-01-16T00:49:37.125Z,1579135777.125 [DDM](INFO): Cable State:0
2020-01-16T00:49:37.134Z,1579135777.134 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:49:37.505Z,1579135777.505 [DDM](INFO): Cable State:0
2020-01-16T00:49:37.909Z,1579135777.909 [DDM](INFO): Cable State:1
2020-01-16T00:49:38.317Z,1579135778.317 [DDM](INFO): Cable State:0
2020-01-16T00:49:38.725Z,1579135778.725 [DDM](INFO): Cable State:2
2020-01-16T00:49:39.125Z,1579135779.125 [DDM](INFO): Cable State:2
2020-01-16T00:49:39.529Z,1579135779.529 [DDM](INFO): Cable State:0
2020-01-16T00:49:39.933Z,1579135779.933 [DDM](INFO): Cable State:1
2020-01-16T00:49:40.334Z,1579135780.334 [DDM](INFO): Cable State:0
2020-01-16T00:49:40.750Z,1579135780.750 [DDM](INFO): Cable State:0
2020-01-16T00:49:41.141Z,1579135781.141 [DDM](INFO): Cable State:2
2020-01-16T00:49:41.549Z,1579135781.549 [DDM](INFO): Cable State:0
2020-01-16T00:49:41.950Z,1579135781.950 [DDM](INFO): Cable State:0
2020-01-16T00:49:42.361Z,1579135782.361 [DDM](INFO): Cable State:2
2020-01-16T00:49:42.770Z,1579135782.770 [DDM](INFO): Cable State:0
2020-01-16T00:49:43.177Z,1579135783.177 [DDM](INFO): Cable State:1
2020-01-16T00:49:43.569Z,1579135783.569 [DDM](INFO): Cable State:0
2020-01-16T00:49:43.976Z,1579135783.976 [DDM](INFO): Cable State:0
2020-01-16T00:49:44.373Z,1579135784.373 [DDM](INFO): Cable State:0
2020-01-16T00:49:44.781Z,1579135784.781 [DDM](INFO): Cable State:0
2020-01-16T00:49:45.181Z,1579135785.181 [DDM](INFO): Cable State:0
2020-01-16T00:49:45.585Z,1579135785.585 [DDM](INFO): Cable State:0
2020-01-16T00:49:45.993Z,1579135785.993 [DDM](INFO): Cable State:0
2020-01-16T00:49:46.397Z,1579135786.397 [DDM](INFO): Cable State:0
2020-01-16T00:49:46.804Z,1579135786.804 [DDM](INFO): Cable State:0
2020-01-16T00:49:47.201Z,1579135787.201 [DDM](INFO): Cable State:1
2020-01-16T00:49:47.609Z,1579135787.609 [DDM](INFO): Cable State:1
2020-01-16T00:49:48.141Z,1579135788.141 [DDM](INFO): Cable State:0
2020-01-16T00:49:48.150Z,1579135788.150 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:49:48.537Z,1579135788.537 [DDM](INFO): Cable State:0
2020-01-16T00:49:48.941Z,1579135788.941 [DDM](INFO): Cable State:2
2020-01-16T00:49:49.345Z,1579135789.345 [DDM](INFO): Cable State:1
2020-01-16T00:49:49.777Z,1579135789.777 [DDM](INFO): Cable State:0
2020-01-16T00:49:50.153Z,1579135790.153 [DDM](INFO): Cable State:0
2020-01-16T00:49:50.569Z,1579135790.569 [DDM](INFO): Cable State:1
2020-01-16T00:49:50.961Z,1579135790.961 [DDM](INFO): Cable State:1
2020-01-16T00:49:51.369Z,1579135791.369 [DDM](INFO): Cable State:2
2020-01-16T00:49:51.789Z,1579135791.789 [DDM](INFO): Cable State:2
2020-01-16T00:49:52.173Z,1579135792.173 [DDM](INFO): Cable State:1
2020-01-16T00:49:52.577Z,1579135792.577 [DDM](INFO): Cable State:1
2020-01-16T00:49:52.985Z,1579135792.985 [DDM](INFO): Cable State:1
2020-01-16T00:49:53.389Z,1579135793.389 [DDM](INFO): Cable State:0
2020-01-16T00:49:53.790Z,1579135793.790 [DDM](INFO): Cable State:0
2020-01-16T00:49:54.193Z,1579135794.193 [DDM](INFO): Cable State:2
2020-01-16T00:49:54.597Z,1579135794.597 [DDM](INFO): Cable State:0
2020-01-16T00:49:55.005Z,1579135795.005 [DDM](INFO): Cable State:1
2020-01-16T00:49:55.413Z,1579135795.413 [DDM](INFO): Cable State:0
2020-01-16T00:49:55.809Z,1579135795.809 [DDM](INFO): Cable State:0
2020-01-16T00:49:56.217Z,1579135796.217 [DDM](INFO): Cable State:0
2020-01-16T00:49:56.621Z,1579135796.621 [DDM](INFO): Cable State:0
2020-01-16T00:49:57.033Z,1579135797.033 [DDM](INFO): Cable State:0
2020-01-16T00:49:57.211Z,1579135797.211 [CommandLine](IMPORTANT): got command restart application
2020-01-16T00:49:57.425Z,1579135797.425 [DDM](INFO): Cable State:1
2020-01-16T00:49:57.833Z,1579135797.833 [DDM](INFO): Cable State:1
2020-01-16T00:49:58.217Z,1579135798.217 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-01-16T00:49:58.217Z,1579135798.217 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:49:58.217Z,1579135798.217 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:58.237Z,1579135798.237 [DDM](INFO): Cable State:0
2020-01-16T00:49:58.291Z,1579135798.291 [MissionManager](INFO): MissionManager is completed.
2020-01-16T00:49:58.291Z,1579135798.291 [MissionManager](INFO): Uninitializing Mission Docked
2020-01-16T00:49:58.291Z,1579135798.291 [Docked] Stopped
2020-01-16T00:49:58.291Z,1579135798.291 [Docked](DEBUG): Aggregate::uninitialize Docked
2020-01-16T00:49:58.291Z,1579135798.291 [Docked:A.DepthEnvelope] Stopped
2020-01-16T00:49:58.291Z,1579135798.291 [Docked:A.DepthEnvelope](DEBUG): Uninitialize.
2020-01-16T00:49:58.291Z,1579135798.291 [Docked:B.] Stopped
2020-01-16T00:49:58.291Z,1579135798.291 [Docked:C] Stopped
2020-01-16T00:49:58.292Z,1579135798.292 [Docked:D] Stopped
2020-01-16T00:49:58.292Z,1579135798.292 [Docked:E.Pitch] Stopped
2020-01-16T00:49:58.292Z,1579135798.292 [Docked:F.SetSpeed] Stopped
2020-01-16T00:49:58.292Z,1579135798.292 [Docked:F.SetSpeed](DEBUG): Uninitialize.
2020-01-16T00:49:58.292Z,1579135798.292 [Docked:G.Docked] Stopped
2020-01-16T00:49:58.393Z,1579135798.393 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-16T00:49:58.393Z,1579135798.393 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:58.394Z,1579135798.394 [CommandLine](INFO): Join timeout helper Thread ID is 6592
2020-01-16T00:49:58.394Z,1579135798.394 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-16T00:49:58.394Z,1579135798.394 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:58.395Z,1579135798.395 [NavChartDb](INFO): Join timeout helper Thread ID is 6593
2020-01-16T00:49:58.655Z,1579135798.655 [MissionManager](IMPORTANT): Started mission Default
2020-01-16T00:49:58.656Z,1579135798.656 [Default] Running Loop=1
2020-01-16T00:49:58.656Z,1579135798.656 [Default](DEBUG): Aggregate::initialize Default
2020-01-16T00:49:58.656Z,1579135798.656 [Default:B.GoToSurface] Running Loop=1
2020-01-16T00:49:58.656Z,1579135798.656 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-16T00:49:58.656Z,1579135798.656 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-16T00:49:58.656Z,1579135798.656 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-16T00:49:58.657Z,1579135798.657 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-16T00:49:58.657Z,1579135798.657 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-16T00:49:58.657Z,1579135798.657 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-16T00:49:58.658Z,1579135798.658 [Default:A.Wait] Running Loop=1
2020-01-16T00:49:58.659Z,1579135798.659 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-16T00:49:58.672Z,1579135798.672 [Reporter](INFO): DDM.cablePresent no_value
2020-01-16T00:49:58.738Z,1579135798.738 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:49:58.739Z,1579135798.739 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:58.746Z,1579135798.746 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-16T00:49:58.746Z,1579135798.746 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:58.747Z,1579135798.747 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6594
2020-01-16T00:49:59.093Z,1579135799.093 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:49:59.093Z,1579135799.093 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T00:49:59.094Z,1579135799.094 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:59.113Z,1579135799.113 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-16T00:49:59.113Z,1579135799.113 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:59.113Z,1579135799.113 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6597
2020-01-16T00:49:59.177Z,1579135799.177 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:49:59.177Z,1579135799.177 [CTD_NeilBrown](INFO): Powering down
2020-01-16T00:49:59.189Z,1579135799.189 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:59.201Z,1579135799.201 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-16T00:49:59.201Z,1579135799.201 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:59.201Z,1579135799.201 [Radio_Surface](INFO): Join timeout helper Thread ID is 6598
2020-01-16T00:49:59.301Z,1579135799.301 [Radio_Surface](INFO): Powering down
2020-01-16T00:49:59.302Z,1579135799.302 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:49:59.302Z,1579135799.302 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:59.322Z,1579135799.322 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-16T00:49:59.322Z,1579135799.322 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:49:59.322Z,1579135799.322 [DataOverHttps](INFO): Join timeout helper Thread ID is 6599
2020-01-16T00:49:59.426Z,1579135799.426 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-01-16T00:50:00.001Z,1579135800.001 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:50:00.001Z,1579135800.001 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.018Z,1579135800.018 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-16T00:50:00.018Z,1579135800.018 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.018Z,1579135800.018 [logger](INFO): Join timeout helper Thread ID is 6600
2020-01-16T00:50:00.057Z,1579135800.057 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:50:00.057Z,1579135800.057 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.077Z,1579135800.077 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-16T00:50:00.078Z,1579135800.078 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.078Z,1579135800.078 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-16T00:50:00.078Z,1579135800.078 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.078Z,1579135800.078 [controlThread](INFO): Join timeout helper Thread ID is 6601
2020-01-16T00:50:00.217Z,1579135800.217 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T00:50:00.217Z,1579135800.217 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-16T00:50:00.218Z,1579135800.218 [AHRS_M2](INFO): Powering down
2020-01-16T00:50:00.289Z,1579135800.289 [DDM](INFO): Powering down
2020-01-16T00:50:00.361Z,1579135800.361 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T00:50:00.433Z,1579135800.433 [Micromodem](INFO): Powering down
2020-01-16T00:50:00.529Z,1579135800.529 [NAL9602](INFO): Powering down
2020-01-16T00:50:00.601Z,1579135800.601 [RDI_Pathfinder](INFO): Powering down
2020-01-16T00:50:00.603Z,1579135800.603 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-16T00:50:00.604Z,1579135800.604 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-16T00:50:00.604Z,1579135800.604 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-16T00:50:00.604Z,1579135800.604 [MissionManager](INFO): Uninitializing Mission Default
2020-01-16T00:50:00.605Z,1579135800.605 [Default] Stopped
2020-01-16T00:50:00.605Z,1579135800.605 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-16T00:50:00.605Z,1579135800.605 [Default:A.Wait] Stopped
2020-01-16T00:50:00.605Z,1579135800.605 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-16T00:50:00.605Z,1579135800.605 [Default:B.GoToSurface] Stopped
2020-01-16T00:50:00.605Z,1579135800.605 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-16T00:50:00.607Z,1579135800.607 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-16T00:50:00.607Z,1579135800.607 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-16T00:50:00.608Z,1579135800.608 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-16T00:50:00.608Z,1579135800.608 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-16T00:50:00.608Z,1579135800.608 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-16T00:50:00.608Z,1579135800.608 [BuoyancyServo](INFO): Powering down
2020-01-16T00:50:00.741Z,1579135800.741 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-16T00:50:00.741Z,1579135800.741 [ElevatorServo](INFO): Powering down
2020-01-16T00:50:00.742Z,1579135800.742 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-16T00:50:00.742Z,1579135800.742 [MassServo](INFO): Powering down
2020-01-16T00:50:00.743Z,1579135800.743 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T00:50:00.743Z,1579135800.743 [RudderServo](INFO): Powering down
2020-01-16T00:50:00.744Z,1579135800.744 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-16T00:50:00.744Z,1579135800.744 [ThrusterServo](INFO): Powering down
2020-01-16T00:50:00.744Z,1579135800.744 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-16T00:50:00.745Z,1579135800.745 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-16T00:50:00.745Z,1579135800.745 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-16T00:50:00.745Z,1579135800.745 [CBIT](DEBUG): Powering off loads.
2020-01-16T00:50:00.757Z,1579135800.757 [CBIT](DEBUG): Disabling WDT.
2020-01-16T00:50:00.769Z,1579135800.769 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-16T00:50:00.770Z,1579135800.770 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.815Z,1579135800.815 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.826Z,1579135800.826 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.991Z,1579135800.991 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:00.994Z,1579135800.994 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:01.052Z,1579135801.052 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T00:50:01.111Z,1579135801.111 [logger ThreadHandler](INFO): Thread cancelled.