2020-01-17T02:35:40.106Z,1579228540.106 [Supervisor](DEBUG): Initializing supervisor. 2020-01-17T02:35:40.109Z,1579228540.109 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-01-17T02:35:40.109Z,1579228540.109 [SyncHandler](INFO): Protected caller Thread ID is 7690 2020-01-17T02:35:40.110Z,1579228540.110 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-01-17T02:35:40.111Z,1579228540.111 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-01-17T02:35:40.111Z,1579228540.111 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7691 2020-01-17T02:35:40.114Z,1579228540.114 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-01-17T02:35:40.126Z,1579228540.126 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-01-17T02:35:40.127Z,1579228540.127 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-01-17T02:35:40.127Z,1579228540.127 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7692 2020-01-17T02:35:40.128Z,1579228540.128 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-01-17T02:35:40.129Z,1579228540.129 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-01-17T02:35:40.129Z,1579228540.129 [logger ThreadHandler](INFO): Protected caller Thread ID is 7693 2020-01-17T02:35:40.131Z,1579228540.131 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-01-17T02:35:40.131Z,1579228540.131 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-01-17T02:35:40.133Z,1579228540.133 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-01-17T02:35:40.562Z,1579228540.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-01-17T02:35:40.563Z,1579228540.563 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-01-17T02:35:40.661Z,1579228540.661 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-01-17T02:35:40.661Z,1579228540.661 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-01-17T02:35:40.972Z,1579228540.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-01-17T02:35:40.972Z,1579228540.972 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-01-17T02:35:41.110Z,1579228541.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-01-17T02:35:41.111Z,1579228541.111 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-01-17T02:35:41.311Z,1579228541.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-01-17T02:35:41.312Z,1579228541.312 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-01-17T02:35:42.216Z,1579228542.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-01-17T02:35:42.216Z,1579228542.216 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-01-17T02:35:42.424Z,1579228542.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-01-17T02:35:42.424Z,1579228542.424 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-01-17T02:35:42.567Z,1579228542.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-01-17T02:35:42.568Z,1579228542.568 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-01-17T02:35:42.756Z,1579228542.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-01-17T02:35:42.757Z,1579228542.757 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-01-17T02:35:42.854Z,1579228542.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-01-17T02:35:42.854Z,1579228542.854 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-01-17T02:35:43.150Z,1579228543.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-01-17T02:35:43.150Z,1579228543.150 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-01-17T02:35:43.232Z,1579228543.232 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-01-17T02:35:43.334Z,1579228543.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-01-17T02:35:43.335Z,1579228543.335 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-01-17T02:35:43.962Z,1579228543.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-01-17T02:35:43.963Z,1579228543.963 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-01-17T02:35:44.364Z,1579228544.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-01-17T02:35:44.366Z,1579228544.366 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2020-01-17T02:35:44.367Z,1579228544.367 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2020-01-17T02:35:44.576Z,1579228544.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2020-01-17T02:35:44.676Z,1579228544.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2020-01-17T02:35:44.776Z,1579228544.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2020-01-17T02:35:45.002Z,1579228545.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-01-17T02:35:45.002Z,1579228545.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2020-01-17T02:35:45.088Z,1579228545.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2020-01-17T02:35:45.182Z,1579228545.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2020-01-17T02:35:45.281Z,1579228545.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2020-01-17T02:35:45.364Z,1579228545.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2020-01-17T02:35:45.473Z,1579228545.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg 2020-01-17T02:35:45.566Z,1579228545.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2020-01-17T02:35:45.749Z,1579228545.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2020-01-17T02:35:45.879Z,1579228545.879 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2020-01-17T02:35:45.880Z,1579228545.880 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-01-17T02:35:45.886Z,1579228545.886 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-01-17T02:35:46.184Z,1579228546.184 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-01-17T02:35:46.189Z,1579228546.189 [AHRS_M2](INFO): created writer for : platform_orientation 2020-01-17T02:35:46.191Z,1579228546.191 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-01-17T02:35:46.195Z,1579228546.195 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-01-17T02:35:46.196Z,1579228546.196 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-01-17T02:35:46.201Z,1579228546.201 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-01-17T02:35:46.202Z,1579228546.202 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-01-17T02:35:46.206Z,1579228546.206 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-01-17T02:35:46.274Z,1579228546.274 [AHRS_M2] Loaded 2020-01-17T02:35:46.274Z,1579228546.274 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-01-17T02:35:46.352Z,1579228546.352 [DataOverHttps] Loaded 2020-01-17T02:35:46.352Z,1579228546.352 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-01-17T02:35:46.353Z,1579228546.353 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0 2020-01-17T02:35:46.354Z,1579228546.354 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7775 2020-01-17T02:35:46.376Z,1579228546.376 [DDM] Loaded 2020-01-17T02:35:46.376Z,1579228546.376 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread. 2020-01-17T02:35:46.389Z,1579228546.389 [Depth_Keller] Loaded 2020-01-17T02:35:46.389Z,1579228546.389 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-01-17T02:35:46.393Z,1579228546.393 [DropWeight] Loaded 2020-01-17T02:35:46.394Z,1579228546.394 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-01-17T02:35:46.452Z,1579228546.452 [DUSBL_Hydroid] Loaded 2020-01-17T02:35:46.452Z,1579228546.452 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2020-01-17T02:35:46.496Z,1579228546.496 [Micromodem] Loaded 2020-01-17T02:35:46.497Z,1579228546.497 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2020-01-17T02:35:46.589Z,1579228546.589 [NAL9602] Loaded 2020-01-17T02:35:46.589Z,1579228546.589 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-01-17T02:35:46.624Z,1579228546.624 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-01-17T02:35:46.625Z,1579228546.625 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-01-17T02:35:46.626Z,1579228546.626 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-01-17T02:35:46.626Z,1579228546.626 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-01-17T02:35:46.627Z,1579228546.627 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-01-17T02:35:46.627Z,1579228546.627 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-01-17T02:35:46.627Z,1579228546.627 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-01-17T02:35:46.628Z,1579228546.628 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-01-17T02:35:46.628Z,1579228546.628 [Onboard] Loaded 2020-01-17T02:35:46.628Z,1579228546.628 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-01-17T02:35:46.634Z,1579228546.634 [PowerOnly] Loaded 2020-01-17T02:35:46.634Z,1579228546.634 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2020-01-17T02:35:46.640Z,1579228546.640 [Radio_Surface] Loaded 2020-01-17T02:35:46.640Z,1579228546.640 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-01-17T02:35:46.641Z,1579228546.641 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0 2020-01-17T02:35:46.642Z,1579228546.642 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7776 2020-01-17T02:35:46.683Z,1579228546.683 [RDI_Pathfinder] Loaded 2020-01-17T02:35:46.683Z,1579228546.683 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-01-17T02:35:48.799Z,1579228548.799 [BPC1] Loaded 2020-01-17T02:35:48.800Z,1579228548.800 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-01-17T02:35:48.800Z,1579228548.800 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-01-17T02:35:48.801Z,1579228548.801 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-01-17T02:35:48.813Z,1579228548.813 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-01-17T02:35:48.814Z,1579228548.814 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-01-17T02:35:48.913Z,1579228548.913 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-01-17T02:35:48.913Z,1579228548.913 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-01-17T02:35:48.933Z,1579228548.933 [NavChart] Loaded 2020-01-17T02:35:48.933Z,1579228548.933 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-01-17T02:35:48.936Z,1579228548.936 [UniversalFixResidualReporter] Loaded 2020-01-17T02:35:48.937Z,1579228548.937 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-01-17T02:35:48.937Z,1579228548.937 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-01-17T02:35:48.938Z,1579228548.938 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-01-17T02:35:49.040Z,1579228549.040 [BuoyancyServo] Loaded 2020-01-17T02:35:49.041Z,1579228549.041 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-01-17T02:35:49.055Z,1579228549.055 [ElevatorServo] Loaded 2020-01-17T02:35:49.056Z,1579228549.056 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-01-17T02:35:49.070Z,1579228549.070 [MassServo] Loaded 2020-01-17T02:35:49.070Z,1579228549.070 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-01-17T02:35:49.084Z,1579228549.084 [RudderServo] Loaded 2020-01-17T02:35:49.085Z,1579228549.085 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-01-17T02:35:49.098Z,1579228549.098 [ThrusterServo] Loaded 2020-01-17T02:35:49.099Z,1579228549.099 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-01-17T02:35:49.099Z,1579228549.099 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-01-17T02:35:49.100Z,1579228549.100 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-01-17T02:35:49.347Z,1579228549.347 [CTD_NeilBrown] Loaded 2020-01-17T02:35:49.347Z,1579228549.347 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2020-01-17T02:35:49.348Z,1579228549.348 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2020-01-17T02:35:49.348Z,1579228549.348 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7777 2020-01-17T02:35:49.362Z,1579228549.362 [PAR_Licor] Loaded 2020-01-17T02:35:49.363Z,1579228549.363 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-01-17T02:35:49.404Z,1579228549.404 [WetLabsSeaOWL_UV_A] Loaded 2020-01-17T02:35:49.404Z,1579228549.404 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2020-01-17T02:35:49.405Z,1579228549.405 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0 2020-01-17T02:35:49.405Z,1579228549.405 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7778 2020-01-17T02:35:49.406Z,1579228549.406 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-01-17T02:35:49.406Z,1579228549.406 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-01-17T02:35:49.729Z,1579228549.729 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-01-17T02:35:49.730Z,1579228549.730 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-01-17T02:35:49.769Z,1579228549.769 [DepthRateCalculator] Loaded 2020-01-17T02:35:49.769Z,1579228549.769 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-01-17T02:35:49.775Z,1579228549.775 [PitchRateCalculator] Loaded 2020-01-17T02:35:49.775Z,1579228549.775 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-01-17T02:35:49.787Z,1579228549.787 [SpeedCalculator] Loaded 2020-01-17T02:35:49.787Z,1579228549.787 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-01-17T02:35:49.807Z,1579228549.807 [TempGradientCalculator] Loaded 2020-01-17T02:35:49.807Z,1579228549.807 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-01-17T02:35:49.812Z,1579228549.812 [YawRateCalculator] Loaded 2020-01-17T02:35:49.812Z,1579228549.812 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-01-17T02:35:49.851Z,1579228549.851 [ElevatorOffsetCalculator] Loaded 2020-01-17T02:35:49.852Z,1579228549.852 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-01-17T02:35:49.852Z,1579228549.852 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-01-17T02:35:49.852Z,1579228549.852 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-01-17T02:35:49.981Z,1579228549.981 [SBIT](DEBUG): Construct Startup Built In Test. 2020-01-17T02:35:50.002Z,1579228550.002 [SBIT] Loaded 2020-01-17T02:35:50.002Z,1579228550.002 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-01-17T02:35:50.002Z,1579228550.002 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-01-17T02:35:50.014Z,1579228550.014 [IBIT] Loaded 2020-01-17T02:35:50.015Z,1579228550.015 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-01-17T02:35:50.018Z,1579228550.018 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-01-17T02:35:50.148Z,1579228550.148 [CBIT] Loaded 2020-01-17T02:35:50.148Z,1579228550.148 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-01-17T02:35:50.149Z,1579228550.149 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-01-17T02:35:50.149Z,1579228550.149 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-01-17T02:35:50.228Z,1579228550.228 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-01-17T02:35:50.229Z,1579228550.229 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-01-17T02:35:50.322Z,1579228550.322 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-01-17T02:35:50.323Z,1579228550.323 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-01-17T02:35:50.369Z,1579228550.369 [VerticalControl](DEBUG): Construct VerticalControl. 2020-01-17T02:35:50.449Z,1579228550.449 [VerticalControl] Loaded 2020-01-17T02:35:50.449Z,1579228550.449 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-01-17T02:35:50.450Z,1579228550.450 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-01-17T02:35:50.516Z,1579228550.516 [HorizontalControl] Loaded 2020-01-17T02:35:50.516Z,1579228550.516 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-01-17T02:35:50.517Z,1579228550.517 [SpeedControl](DEBUG): Construct SpeedControl. 2020-01-17T02:35:50.518Z,1579228550.518 [SpeedControl] Loaded 2020-01-17T02:35:50.519Z,1579228550.519 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-01-17T02:35:50.519Z,1579228550.519 [LoopControl](DEBUG): Construct LoopControl. 2020-01-17T02:35:50.520Z,1579228550.520 [LoopControl] Loaded 2020-01-17T02:35:50.520Z,1579228550.520 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-01-17T02:35:50.520Z,1579228550.520 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-01-17T02:35:50.521Z,1579228550.521 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-01-17T02:35:50.551Z,1579228550.551 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-01-17T02:35:50.555Z,1579228550.555 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-01-17T02:35:50.556Z,1579228550.556 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-01-17T02:35:50.562Z,1579228550.562 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-01-17T02:35:50.563Z,1579228550.563 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0 2020-01-17T02:35:50.563Z,1579228550.563 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7779 2020-01-17T02:35:50.568Z,1579228550.568 [Supervisor](INFO): Main Thread ID is 6780 2020-01-17T02:35:50.568Z,1579228550.568 [Supervisor](DEBUG): Running supervisor. 2020-01-17T02:35:50.569Z,1579228550.569 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7780 2020-01-17T02:35:50.571Z,1579228550.571 [controlThread ThreadHandler](INFO): Handler Thread ID is 7781 2020-01-17T02:35:50.571Z,1579228550.571 [controlThread](DEBUG): Initializing ControlThread 2020-01-17T02:35:50.580Z,1579228550.580 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-01-17T02:35:50.580Z,1579228550.580 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-01-17T02:35:50.585Z,1579228550.585 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-01-17T02:35:50.586Z,1579228550.586 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-01-17T02:35:50.586Z,1579228550.586 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-01-17T02:35:50.586Z,1579228550.586 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-01-17T02:35:50.587Z,1579228550.587 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-01-17T02:35:50.587Z,1579228550.587 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-01-17T02:35:50.588Z,1579228550.588 [SBIT](INFO): Initialize SBIT Component. 2020-01-17T02:35:50.588Z,1579228550.588 [SBIT](IMPORTANT): git: 2019-12-03-55-gabd6466 2020-01-17T02:35:50.588Z,1579228550.588 [SBIT](INFO): git hash: abd6466b03967f3b67cd1e0718a820876be3c92a 2020-01-17T02:35:50.589Z,1579228550.589 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-01-17T02:35:50.590Z,1579228550.590 [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-17T02:35:50.591Z,1579228550.591 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-01-17T02:35:50.591Z,1579228550.591 [IBIT](INFO): Initialize IBIT Component. 2020-01-17T02:35:50.592Z,1579228550.592 [CBIT](DEBUG): Initialize CBIT Component. 2020-01-17T02:35:50.593Z,1579228550.593 [logger ThreadHandler](INFO): Handler Thread ID is 7782 2020-01-17T02:35:50.609Z,1579228550.609 [CBIT](DEBUG): Initialized mux pins. 2020-01-17T02:35:50.609Z,1579228550.609 [CBIT](DEBUG): Initializing the watchdog timer. 2020-01-17T02:35:50.617Z,1579228550.617 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7783 2020-01-17T02:35:50.618Z,1579228550.618 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-01-17T02:35:50.629Z,1579228550.629 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7784 2020-01-17T02:35:50.633Z,1579228550.633 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-01-17T02:35:50.633Z,1579228550.633 [CBIT](DEBUG): Initializing heartbeat. 2020-01-17T02:35:50.641Z,1579228550.641 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7785 2020-01-17T02:35:50.642Z,1579228550.642 [CTD_NeilBrown](INFO): Powering down 2020-01-17T02:35:50.670Z,1579228550.670 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7786 2020-01-17T02:35:50.670Z,1579228550.670 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-17T02:35:50.693Z,1579228550.693 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7787 2020-01-17T02:35:50.696Z,1579228550.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-01-17T02:35:50.696Z,1579228550.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-01-17T02:35:50.696Z,1579228550.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-01-17T02:35:50.697Z,1579228550.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-01-17T02:35:50.697Z,1579228550.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-01-17T02:35:50.697Z,1579228550.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-01-17T02:35:50.697Z,1579228550.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-01-17T02:35:50.697Z,1579228550.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-01-17T02:35:50.698Z,1579228550.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-01-17T02:35:50.698Z,1579228550.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-01-17T02:35:50.698Z,1579228550.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-01-17T02:35:50.698Z,1579228550.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-01-17T02:35:50.698Z,1579228550.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-01-17T02:35:50.698Z,1579228550.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-01-17T02:35:50.699Z,1579228550.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-01-17T02:35:50.699Z,1579228550.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-01-17T02:35:50.705Z,1579228550.705 [CBIT](DEBUG): Deactivating GF circuits. 2020-01-17T02:35:50.705Z,1579228550.705 [CBIT](DEBUG): Deactivating emergency mode. 2020-01-17T02:35:50.741Z,1579228550.741 [CBIT](DEBUG): Backplane powered. 2020-01-17T02:35:50.741Z,1579228550.741 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-01-17T02:35:50.743Z,1579228550.743 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-01-17T02:35:50.744Z,1579228550.744 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-01-17T02:35:50.744Z,1579228550.744 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-01-17T02:35:50.745Z,1579228550.745 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-01-17T02:35:50.756Z,1579228550.756 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-17T02:35:50.775Z,1579228550.775 [MissionManager](DEBUG): 2020-01-17T02:35:50.775Z,1579228550.775 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-01-17T02:35:50.851Z,1579228550.851 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-01-17T02:35:50.852Z,1579228550.852 [Default:A.Wait](DEBUG): Construct Wait. 2020-01-17T02:35:50.870Z,1579228550.870 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-17T02:35:50.921Z,1579228550.921 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-01-17T02:35:50.924Z,1579228550.924 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-01-17T02:35:50.949Z,1579228550.949 [Default:E.Execute](DEBUG): Construct Execute. 2020-01-17T02:35:50.952Z,1579228550.952 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-01-17T02:35:50.965Z,1579228550.965 [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-17T02:35:50.979Z,1579228550.979 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-01-17T02:35:51.021Z,1579228551.021 [DDM](INFO): Powering up 2020-01-17T02:35:51.021Z,1579228551.021 [DDM](DEBUG): Initializing DDM. 2020-01-17T02:35:51.033Z,1579228551.033 [Radio_Surface](INFO): Powering up 2020-01-17T02:35:51.047Z,1579228551.047 [DUSBL_Hydroid](INFO): Powering up 2020-01-17T02:35:51.047Z,1579228551.047 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2020-01-17T02:35:51.287Z,1579228551.287 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-01-17T02:35:51.293Z,1579228551.293 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-01-17T02:35:51.294Z,1579228551.294 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-01-17T02:35:51.301Z,1579228551.301 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-01-17T02:35:51.302Z,1579228551.302 [MassServo](DEBUG): Initializing EZServoServo. 2020-01-17T02:35:51.309Z,1579228551.309 [MassServo](DEBUG): Initializing MassServo. 2020-01-17T02:35:51.310Z,1579228551.310 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-17T02:35:51.317Z,1579228551.317 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-17T02:35:51.318Z,1579228551.318 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-01-17T02:35:51.325Z,1579228551.325 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-01-17T02:35:51.403Z,1579228551.403 [Micromodem](INFO): Powering up 2020-01-17T02:35:51.404Z,1579228551.404 [Micromodem](DEBUG): Initializing Micromodem. 2020-01-17T02:35:52.177Z,1579228552.177 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-01-17T02:35:52.177Z,1579228552.177 [RudderServo](FAULT): Rudder failed to initialize 2020-01-17T02:35:52.177Z,1579228552.177 [RudderServo] Communications Fault, FailCount= 1 2020-01-17T02:35:52.177Z,1579228552.177 [RudderServo](ERROR): Communications Fault 2020-01-17T02:35:52.288Z,1579228552.288 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-01-17T02:35:52.470Z,1579228552.470 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-17T02:35:52.470Z,1579228552.470 [RudderServo](INFO): Powering down 2020-01-17T02:35:53.151Z,1579228553.151 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-17T02:35:53.270Z,1579228553.270 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-17T02:35:53.274Z,1579228553.274 [CBIT](INFO): Clearing failed state for component RudderServo 2020-01-17T02:35:53.274Z,1579228553.274 [RudderServo] No Fault, FailCount= 1 2020-01-17T02:35:56.379Z,1579228556.379 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-01-17T02:35:57.188Z,1579228557.188 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,02355,SLOT1,2.0.32731*6A expected 5D 2020-01-17T02:35:57.188Z,1579228557.188 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-01-17T02:35:58.396Z,1579228558.396 [Micromodem](INFO): Nmea in: $CATMG,2020-01-17T02:35:57.374843Z,RTC,RTC*5E 2020-01-17T02:35:58.396Z,1579228558.396 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-17T02:35:57.374843Z,RTC,RTC*5E 2020-01-17T02:35:58.803Z,1579228558.803 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-01-17T02:35:58.803Z,1579228558.803 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-01-17T02:35:59.191Z,1579228559.191 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-01-17T02:35:59.191Z,1579228559.191 [Micromodem](ERROR): CFG response from modem unexpected: $CACFG,ALL,0*31 2020-01-17T02:35:59.619Z,1579228559.619 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-01-17T02:35:59.619Z,1579228559.619 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-01-17T02:35:59.990Z,1579228559.990 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-01-17T02:35:59.991Z,1579228559.991 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-01-17T02:36:00.403Z,1579228560.403 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-01-17T02:36:00.403Z,1579228560.403 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-01-17T02:36:00.795Z,1579228560.795 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-01-17T02:36:00.795Z,1579228560.795 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-01-17T02:36:01.197Z,1579228561.197 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-01-17T02:36:01.197Z,1579228561.197 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-01-17T02:36:01.605Z,1579228561.605 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1 2020-01-17T02:36:01.719Z,1579228561.719 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-01-17T02:36:01.719Z,1579228561.719 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-01-17T02:36:01.999Z,1579228561.999 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-01-17T02:36:01.000Z,1579228562.000 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,17,02,36,02*46 2020-01-17T02:36:02.427Z,1579228562.427 [Micromodem](INFO): Nmea in: $CACLK,2020,1,17,2,36,2*74 2020-01-17T02:36:03.215Z,1579228563.215 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-17T02:36:03Z*75 2020-01-17T02:36:03.227Z,1579228563.227 [Micromodem](INFO): Nmea in: $CATMG,2020-01-17T02:36:03.027034Z,USER_CMD,RTC*10 2020-01-17T02:36:04.466Z,1579228564.466 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-01-17T02:36:08.874Z,1579228568.874 [DUSBL_Hydroid](INFO): DUSBL Version:O 2020-01-17T02:36:16.943Z,1579228576.943 [NAL9602](INFO): Powering up NAL9602 2020-01-17T02:36:24.654Z,1579228584.654 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-17T02:36:24.654Z,1579228584.654 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20011619482492,35, 0.0,1448.9, 0 2020-01-17T02:36:27.851Z,1579228587.851 [NAL9602](INFO): NAL9602 initialized 2020-01-17T02:36:30.355Z,1579228590.355 [SBIT](IMPORTANT): Beginning Startup BIT 2020-01-17T02:36:30.371Z,1579228590.371 [CBIT](IMPORTANT): Beginning ground fault scan 2020-01-17T02:36:41.109Z,1579228601.109 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.011796 CHAN A1 (24V): -0.029755 CHAN A2 (12V): -0.007159 CHAN A3 (5V): -0.001154 CHAN B0 (3.3V): 0.000122 CHAN B1 (3.15aV): 0.000055 CHAN B2 (3.15bV): 0.000069 CHAN B3 (GND): 0.002150 OPEN: 0.005315 Full Scale Calc: 4.765 mA, -1.589 mA 2020-01-17T02:36:44.353Z,1579228604.353 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2020-01-17T02:36:44.353Z,1579228604.353 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-01-17T02:36:44.353Z,1579228604.353 [RDI_Pathfinder](ERROR): Communications Fault 2020-01-17T02:36:44.353Z,1579228604.353 [RDI_Pathfinder](ERROR): Failed to parse: 2020-01-17T02:36:44.406Z,1579228604.406 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-01-17T02:36:44.733Z,1579228604.733 [RDI_Pathfinder](INFO): Powering down 2020-01-17T02:36:45.669Z,1579228605.669 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-01-17T02:36:45.669Z,1579228605.669 [RDI_Pathfinder] No Fault, FailCount= 1 2020-01-17T02:36:58.886Z,1579228618.886 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-01-17T02:37:15.997Z,1579228635.997 [RDI_Pathfinder](ERROR): Failed to parse: : +0.00, +0.00, +0.00, 0.00, 16.00 2020-01-17T02:37:24.112Z,1579228644.112 [SBIT](IMPORTANT): SBIT PASSED 2020-01-17T02:37:24.113Z,1579228644.113 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-17T02:37:24.114Z,1579228644.114 [SBIT](IMPORTANT): DDM.verbosity=3 count; 2020-01-17T02:37:24.114Z,1579228644.114 [SBIT](IMPORTANT): DUSBL_Hydroid.detectionThreshold=30 count; 2020-01-17T02:37:24.114Z,1579228644.114 [SBIT](IMPORTANT): Micromodem.sendExpress=1 bool; 2020-01-17T02:37:24.114Z,1579228644.114 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-17T02:37:24.114Z,1579228644.114 [SBIT](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-17T02:37:24.479Z,1579228644.479 [MissionManager](IMPORTANT): Started mission Startup 2020-01-17T02:37:24.479Z,1579228644.479 [Startup] Running Loop=1 2020-01-17T02:37:24.479Z,1579228644.479 [Startup](DEBUG): Aggregate::initialize Startup 2020-01-17T02:37:24.479Z,1579228644.479 [Startup:A.GoToSurface] Running Loop=1 2020-01-17T02:37:24.479Z,1579228644.479 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-17T02:37:24.480Z,1579228644.480 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-17T02:37:24.480Z,1579228644.480 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-17T02:37:24.481Z,1579228644.481 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-17T02:37:24.481Z,1579228644.481 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-17T02:37:24.482Z,1579228644.482 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-17T02:37:24.483Z,1579228644.483 [Startup:StartupSatComms] Running Loop=1 2020-01-17T02:37:24.483Z,1579228644.483 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-01-17T02:37:24.483Z,1579228644.483 [Startup:StartupSatComms:A] Running Loop=1 2020-01-17T02:37:25.028Z,1579228645.028 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-01-17T02:37:58.423Z,1579228678.423 [CommandLine](IMPORTANT): got command strobe off 2020-01-17T02:37:58.423Z,1579228678.423 [CommandLine](IMPORTANT): Deactivating strobe 2020-01-17T02:38:20.644Z,1579228700.644 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004895 2020-01-17T02:38:24.687Z,1579228704.687 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-17T02:37:24.5Z 2020-01-17T02:38:24.687Z,1579228704.687 [Startup:StartupSatComms:A] Stopped 2020-01-17T02:38:24.687Z,1579228704.687 [Startup:StartupSatComms:B] Running Loop=1 2020-01-17T02:38:25.109Z,1579228705.109 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-01-17T02:38:34.517Z,1579228714.517 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20200117T023540/Courier0000.lzma 2020-01-17T02:38:36.522Z,1579228716.522 [DataOverHttps](INFO): Moved sent file to Logs/20200117T023540/Courier0000.lzma.bak 2020-01-17T02:38:36.523Z,1579228716.523 [DataOverHttps](INFO): SBD MOMSN=12196470 2020-01-17T02:38:50.946Z,1579228730.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-01-17T02:38:50.946Z,1579228730.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:38:50.971Z,1579228730.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:38:51.349Z,1579228731.349 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:38:51.349Z,1579228731.349 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-01-17T02:39:03.976Z,1579228743.976 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20200117T022212/Express0008.lzma 2020-01-17T02:39:05.982Z,1579228745.982 [DataOverHttps](INFO): Moved sent file to Logs/20200117T022212/Express0008.lzma.bak 2020-01-17T02:39:05.982Z,1579228745.982 [DataOverHttps](INFO): SBD MOMSN=12196473 2020-01-17T02:39:09.125Z,1579228749.125 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2020-01-17T02:39:24.896Z,1579228764.896 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-17T02:38:24.7Z 2020-01-17T02:39:24.896Z,1579228764.896 [Startup:StartupSatComms:B] Stopped 2020-01-17T02:39:24.897Z,1579228764.897 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-01-17T02:39:24.897Z,1579228764.897 [Startup:StartupSatComms] Stopped 2020-01-17T02:39:24.897Z,1579228764.897 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-01-17T02:39:24.898Z,1579228764.898 [Startup](INFO): Completed Startup 2020-01-17T02:39:24.898Z,1579228764.898 [MissionManager](INFO): Startup is completed. 2020-01-17T02:39:24.898Z,1579228764.898 [MissionManager](INFO): Uninitializing Mission Startup 2020-01-17T02:39:24.898Z,1579228764.898 [Startup] Stopped 2020-01-17T02:39:24.898Z,1579228764.898 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-01-17T02:39:24.898Z,1579228764.898 [Startup:A.GoToSurface] Stopped 2020-01-17T02:39:24.898Z,1579228764.898 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-17T02:39:25.279Z,1579228765.279 [MissionManager](IMPORTANT): Started mission Default 2020-01-17T02:39:25.279Z,1579228765.279 [Default] Running Loop=1 2020-01-17T02:39:25.279Z,1579228765.279 [Default](DEBUG): Aggregate::initialize Default 2020-01-17T02:39:25.279Z,1579228765.279 [Default:B.GoToSurface] Running Loop=1 2020-01-17T02:39:25.279Z,1579228765.279 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-17T02:39:25.280Z,1579228765.280 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-17T02:39:25.280Z,1579228765.280 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-17T02:39:25.280Z,1579228765.280 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-17T02:39:25.280Z,1579228765.280 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-17T02:39:25.281Z,1579228765.281 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-17T02:39:25.281Z,1579228765.281 [Default:A.Wait] Running Loop=1 2020-01-17T02:39:25.281Z,1579228765.281 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-01-17T02:39:25.490Z,1579228765.490 [DataOverHttps](INFO): Sending 767 bytes from file Logs/20200117T023540/Express0001.lzma 2020-01-17T02:39:27.494Z,1579228767.494 [DataOverHttps](INFO): Moved sent file to Logs/20200117T023540/Express0001.lzma.bak 2020-01-17T02:39:27.494Z,1579228767.494 [DataOverHttps](INFO): SBD MOMSN=12196476 2020-01-17T02:39:29.738Z,1579228769.738 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-17T02:39:29.738Z,1579228769.738 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-17T02:39:38.619Z,1579228778.619 [Default:A.Wait](INFO): Done Waiting. 2020-01-17T02:39:38.619Z,1579228778.619 [Default:A.Wait] Stopped 2020-01-17T02:39:38.619Z,1579228778.619 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-01-17T02:39:39.026Z,1579228779.026 [Default:CheckIn] Running Loop=1 2020-01-17T02:39:39.027Z,1579228779.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-17T02:39:39.027Z,1579228779.027 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-17T02:39:39.419Z,1579228779.419 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-01-17T02:40:01.050Z,1579228801.050 [CommandLine](IMPORTANT): got command run ./Missions/Docked.xml 2020-01-17T02:40:01.051Z,1579228801.051 [MissionManager](INFO): Loading Mission: ./Missions/Docked.xml 2020-01-17T02:40:01.106Z,1579228801.106 [MissionManager](INFO): DefineArg Docked.MaxDockRange = 8.000000 m 2020-01-17T02:40:01.110Z,1579228801.110 [MissionManager](INFO): DefineArg Docked.DetachedTimeout = 20.000000 s 2020-01-17T02:40:01.130Z,1579228801.130 [MissionManager](INFO): DefineArg Docked.DockedTimeout = 30.000000 s 2020-01-17T02:40:01.137Z,1579228801.137 [MissionManager](INFO): DefineArg Docked.TransponderCode = 2.000000 count 2020-01-17T02:40:01.149Z,1579228801.149 [MissionManager](INFO): DefineArg Docked.TrackingUpdatePeriod = 60.000000 s 2020-01-17T02:40:01.152Z,1579228801.152 [MissionManager](INFO): DefineArg Docked.NumberOfPings = 1.000000 count 2020-01-17T02:40:01.160Z,1579228801.160 [MissionManager](INFO): DefineArg Docked.DepthDeadband = 1.500000 m 2020-01-17T02:40:01.168Z,1579228801.168 [MissionManager](INFO): DefineArg Docked.PitchLimit = 20.000000 arcdeg 2020-01-17T02:40:01.176Z,1579228801.176 [MissionManager](INFO): DefineArg Docked.MinDepth = 1.000000 m 2020-01-17T02:40:01.184Z,1579228801.184 [MissionManager](INFO): DefineArg Docked.MaxDepth = 215.000000 m 2020-01-17T02:40:01.213Z,1579228801.213 [Docked:A.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2020-01-17T02:40:01.464Z,1579228801.464 [Docked:E.Docked](DEBUG): Construct. 2020-01-17T02:40:01.475Z,1579228801.475 [Docked:G.Wait](DEBUG): Construct Wait. 2020-01-17T02:40:01.491Z,1579228801.491 [MissionManager](DEBUG): Maximum range allowed from the dock. 8 Time duration limit for determining if the vehicle is detached from the dock. 20 Time duration limit for determining if the vehicle is ON the dock. 30 Transponder Address. 2 How long to wait between acoustic queries. 60 Number of pings requested each time. 1 How much vertical drift from the specified depth is allowed while docked. 1.5 Max vehicle pitch (+/-) while on dock. 20 Minimum depth while docked. Maximum depth while docked. Docked satisfied! 5 2020-01-17T02:40:01.498Z,1579228801.498 [CommandLine](IMPORTANT): Running ./Missions/Docked.xml 2020-01-17T02:40:01.677Z,1579228801.677 [Default] Stopped 2020-01-17T02:40:01.677Z,1579228801.677 [Default](DEBUG): Aggregate::uninitialize Default 2020-01-17T02:40:01.677Z,1579228801.677 [Default:B.GoToSurface] Stopped 2020-01-17T02:40:01.678Z,1579228801.678 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-17T02:40:01.678Z,1579228801.678 [Default:CheckIn] Stopped 2020-01-17T02:40:01.678Z,1579228801.678 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-17T02:40:01.678Z,1579228801.678 [Default:CheckIn:Read_GPS] Stopped 2020-01-17T02:40:01.678Z,1579228801.678 [MissionManager](IMPORTANT): Started mission Docked 2020-01-17T02:40:01.678Z,1579228801.678 [Docked] Running Loop=1 2020-01-17T02:40:01.678Z,1579228801.678 [Docked](DEBUG): Aggregate::initialize Docked 2020-01-17T02:40:01.678Z,1579228801.678 [Docked:A.DepthEnvelope] Running Loop=1 2020-01-17T02:40:01.678Z,1579228801.678 [Docked:A.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2020-01-17T02:40:01.679Z,1579228801.679 [Docked:B.] Running Loop=1 2020-01-17T02:40:01.679Z,1579228801.679 [Docked:B.](INFO): Initializing TrackAcousticContact. 2020-01-17T02:40:01.679Z,1579228801.679 [Docked:C] Running Loop=1 2020-01-17T02:40:01.679Z,1579228801.679 [Docked:D] Running Loop=1 2020-01-17T02:40:01.679Z,1579228801.679 [Docked:E.Docked] Running Loop=1 2020-01-17T02:40:01.679Z,1579228801.679 [Docked:E.Docked](DEBUG): Initialize. 2020-01-17T02:40:01.680Z,1579228801.680 [Docked:E.Docked](INFO): Depth unspecified. Maintaining workSite beacon depth of 6.000000 m. 2020-01-17T02:40:01.680Z,1579228801.680 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:01.686Z,1579228801.686 [Docked:D] Running Loop=1 2020-01-17T02:40:01.687Z,1579228801.687 [Docked:C] Running Loop=1 2020-01-17T02:40:01.687Z,1579228801.687 [Docked:B.] Running Loop=1 2020-01-17T02:40:01.693Z,1579228801.693 [Docked:A.DepthEnvelope] Running Loop=1 2020-01-17T02:40:02.079Z,1579228802.079 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:02.479Z,1579228802.479 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:02.956Z,1579228802.956 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:02.956Z,1579228802.956 [Docked:E.Docked](INFO): State switch to DETACHED 2020-01-17T02:40:03.284Z,1579228803.284 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:03.284Z,1579228803.284 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:03.698Z,1579228803.698 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:03.699Z,1579228803.699 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:04.130Z,1579228804.130 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:04.130Z,1579228804.130 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:04.496Z,1579228804.496 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:04.497Z,1579228804.497 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:04.909Z,1579228804.909 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:04.910Z,1579228804.910 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:05.323Z,1579228805.323 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:05.323Z,1579228805.323 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:05.696Z,1579228805.696 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:05.696Z,1579228805.696 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:06.117Z,1579228806.117 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:06.117Z,1579228806.117 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:06.546Z,1579228806.546 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:06.547Z,1579228806.547 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:06.982Z,1579228806.982 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:06.983Z,1579228806.983 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:07.421Z,1579228807.421 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:07.421Z,1579228807.421 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:07.782Z,1579228807.782 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:07.782Z,1579228807.782 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:08.194Z,1579228808.194 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:08.194Z,1579228808.194 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:08.554Z,1579228808.554 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:08.554Z,1579228808.554 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:08.965Z,1579228808.965 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:08.966Z,1579228808.966 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:09.343Z,1579228809.343 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:09.343Z,1579228809.343 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:09.802Z,1579228809.802 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:09.803Z,1579228809.803 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:10.146Z,1579228810.146 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:10.146Z,1579228810.146 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:10.562Z,1579228810.562 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:10.562Z,1579228810.562 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:10.971Z,1579228810.971 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:10.971Z,1579228810.971 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:11.362Z,1579228811.362 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:11.362Z,1579228811.362 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:11.814Z,1579228811.814 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:11.814Z,1579228811.814 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:12.209Z,1579228812.209 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:12.210Z,1579228812.210 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:12.591Z,1579228812.591 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:12.591Z,1579228812.591 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:13.014Z,1579228813.014 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:13.014Z,1579228813.014 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:13.385Z,1579228813.385 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:13.385Z,1579228813.385 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:13.811Z,1579228813.811 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:13.811Z,1579228813.811 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:14.182Z,1579228814.182 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:14.182Z,1579228814.182 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:14.586Z,1579228814.586 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:14.586Z,1579228814.586 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:15.008Z,1579228815.008 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:15.008Z,1579228815.008 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:15.411Z,1579228815.411 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:15.411Z,1579228815.411 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:15.808Z,1579228815.808 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:15.808Z,1579228815.808 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:16.212Z,1579228816.212 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:16.229Z,1579228816.229 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:16.613Z,1579228816.613 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:16.613Z,1579228816.613 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:17.034Z,1579228817.034 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:17.034Z,1579228817.034 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:17.442Z,1579228817.442 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:17.443Z,1579228817.443 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:17.847Z,1579228817.847 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:17.847Z,1579228817.847 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:18.246Z,1579228818.246 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:18.246Z,1579228818.246 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:18.640Z,1579228818.640 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:18.641Z,1579228818.641 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:19.050Z,1579228819.050 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:19.050Z,1579228819.050 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:19.440Z,1579228819.440 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:19.441Z,1579228819.441 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:19.875Z,1579228819.875 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:19.875Z,1579228819.875 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:20.247Z,1579228820.247 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:20.247Z,1579228820.247 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:20.742Z,1579228820.742 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:20.743Z,1579228820.743 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:21.166Z,1579228821.166 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:21.166Z,1579228821.166 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:21.491Z,1579228821.491 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:21.491Z,1579228821.491 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:21.918Z,1579228821.918 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:21.918Z,1579228821.918 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:22.308Z,1579228822.308 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:22.308Z,1579228822.308 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:22.737Z,1579228822.737 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:22.737Z,1579228822.737 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:23.106Z,1579228823.106 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:23.106Z,1579228823.106 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:23.513Z,1579228823.513 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:23.514Z,1579228823.514 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:23.908Z,1579228823.908 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:23.908Z,1579228823.908 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:24.299Z,1579228824.299 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:24.299Z,1579228824.299 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:24.691Z,1579228824.691 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:24.691Z,1579228824.691 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:25.111Z,1579228825.111 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:25.111Z,1579228825.111 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:25.470Z,1579228825.470 [CommandLine](IMPORTANT): got command report touch DDM.cablePresent 2020-01-17T02:40:25.522Z,1579228825.522 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:25.522Z,1579228825.522 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:25.929Z,1579228825.929 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:25.929Z,1579228825.929 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:26.014Z,1579228826.014 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:26.309Z,1579228826.309 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:26.309Z,1579228826.309 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:26.386Z,1579228826.386 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:26.715Z,1579228826.715 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:26.715Z,1579228826.715 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:26.794Z,1579228826.794 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:27.145Z,1579228827.145 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:27.145Z,1579228827.145 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:27.222Z,1579228827.222 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:27.547Z,1579228827.547 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:27.547Z,1579228827.547 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:27.646Z,1579228827.646 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:27.946Z,1579228827.946 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:27.946Z,1579228827.946 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:27.982Z,1579228827.982 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:28.345Z,1579228828.345 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:28.345Z,1579228828.345 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:28.418Z,1579228828.418 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:28.764Z,1579228828.764 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:28.764Z,1579228828.764 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:28.843Z,1579228828.843 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:29.134Z,1579228829.134 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:29.134Z,1579228829.134 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:29.191Z,1579228829.191 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:29.537Z,1579228829.537 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:29.537Z,1579228829.537 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:29.614Z,1579228829.614 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:29.957Z,1579228829.957 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:29.958Z,1579228829.958 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:30.078Z,1579228830.078 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:30.346Z,1579228830.346 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:30.346Z,1579228830.346 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:30.814Z,1579228830.814 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:30.908Z,1579228830.908 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:30.925Z,1579228830.925 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:31.038Z,1579228831.038 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:31.287Z,1579228831.287 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:31.287Z,1579228831.287 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:31.414Z,1579228831.414 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:31.728Z,1579228831.728 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:31.728Z,1579228831.728 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:31.856Z,1579228831.856 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:32.105Z,1579228832.105 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:32.105Z,1579228832.105 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:32.199Z,1579228832.199 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:32.507Z,1579228832.507 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:32.508Z,1579228832.508 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:32.713Z,1579228832.713 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:32.925Z,1579228832.925 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:32.925Z,1579228832.925 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:33.002Z,1579228833.002 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:33.330Z,1579228833.330 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:33.330Z,1579228833.330 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:33.406Z,1579228833.406 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:33.751Z,1579228833.751 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:33.751Z,1579228833.751 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:33.827Z,1579228833.827 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:34.114Z,1579228834.114 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-17T02:40:34.115Z,1579228834.115 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20011619523439,35.0, -0.1, 0.0,1448.9,, 0.00, 0.00, 0.00, 0.00, 0.00 2020-01-17T02:40:34.127Z,1579228834.127 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:34.127Z,1579228834.127 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:34.206Z,1579228834.206 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:34.575Z,1579228834.575 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:34.575Z,1579228834.575 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:34.662Z,1579228834.662 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:35.015Z,1579228835.015 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:35.015Z,1579228835.015 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:35.058Z,1579228835.058 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:35.366Z,1579228835.366 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:35.367Z,1579228835.367 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:35.446Z,1579228835.446 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:35.771Z,1579228835.771 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:35.771Z,1579228835.771 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:35.842Z,1579228835.842 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:36.163Z,1579228836.163 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:36.163Z,1579228836.163 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:36.246Z,1579228836.246 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:36.539Z,1579228836.539 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:36.539Z,1579228836.539 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:36.642Z,1579228836.642 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:36.947Z,1579228836.947 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:36.947Z,1579228836.947 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:36.986Z,1579228836.986 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:37.362Z,1579228837.362 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:37.362Z,1579228837.362 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:37.438Z,1579228837.438 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:37.770Z,1579228837.770 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:37.770Z,1579228837.770 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:37.862Z,1579228837.862 [Reporter](INFO): DDM.cablePresent 1 bool 2020-01-17T02:40:38.183Z,1579228838.183 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:38.183Z,1579228838.183 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:38.229Z,1579228838.229 [CommandLine](IMPORTANT): got command report clear 2020-01-17T02:40:38.583Z,1579228838.583 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:38.583Z,1579228838.583 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:38.977Z,1579228838.977 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:38.977Z,1579228838.977 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:39.368Z,1579228839.368 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:39.369Z,1579228839.369 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:39.804Z,1579228839.804 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:39.805Z,1579228839.805 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:40.179Z,1579228840.179 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:40.179Z,1579228840.179 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:40.584Z,1579228840.584 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:40.585Z,1579228840.585 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:40.992Z,1579228840.992 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:40.992Z,1579228840.992 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:41.385Z,1579228841.385 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:41.385Z,1579228841.385 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:41.799Z,1579228841.799 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:41.800Z,1579228841.800 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:42.207Z,1579228842.207 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:42.207Z,1579228842.207 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:42.606Z,1579228842.606 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:42.606Z,1579228842.606 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:43.028Z,1579228843.028 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:43.028Z,1579228843.028 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:43.419Z,1579228843.419 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:43.419Z,1579228843.419 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:43.839Z,1579228843.839 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:43.839Z,1579228843.839 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:44.242Z,1579228844.242 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:44.242Z,1579228844.242 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:44.622Z,1579228844.622 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:44.622Z,1579228844.622 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:45.032Z,1579228845.032 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:45.032Z,1579228845.032 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:45.423Z,1579228845.423 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:45.423Z,1579228845.423 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:45.835Z,1579228845.835 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:45.835Z,1579228845.835 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:46.249Z,1579228846.249 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:46.250Z,1579228846.250 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:46.648Z,1579228846.648 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:46.649Z,1579228846.649 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:47.072Z,1579228847.072 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:47.077Z,1579228847.077 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:47.464Z,1579228847.464 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:47.465Z,1579228847.465 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:47.863Z,1579228847.863 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:47.863Z,1579228847.863 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:48.414Z,1579228848.414 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:48.414Z,1579228848.414 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:40:48.715Z,1579228848.715 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:48.715Z,1579228848.715 [Docked:E.Docked](INFO): State switch to DETACHED 2020-01-17T02:40:49.112Z,1579228849.112 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:49.513Z,1579228849.513 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:49.896Z,1579228849.896 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:50.271Z,1579228850.271 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:50.683Z,1579228850.683 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:51.097Z,1579228851.097 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:51.494Z,1579228851.494 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:51.920Z,1579228851.920 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:52.296Z,1579228852.296 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:52.735Z,1579228852.735 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:53.111Z,1579228853.111 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:53.524Z,1579228853.524 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:53.956Z,1579228853.956 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:54.335Z,1579228854.335 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:54.735Z,1579228854.735 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:55.128Z,1579228855.128 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:55.535Z,1579228855.535 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:55.959Z,1579228855.959 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:56.341Z,1579228856.341 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:56.739Z,1579228856.739 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:57.156Z,1579228857.156 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:57.553Z,1579228857.553 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:57.950Z,1579228857.950 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:58.380Z,1579228858.380 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:58.796Z,1579228858.796 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:59.179Z,1579228859.179 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:40:59.583Z,1579228859.583 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:00.003Z,1579228860.003 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:00.379Z,1579228860.379 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:00.824Z,1579228860.824 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:01.247Z,1579228861.247 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:01.681Z,1579228861.681 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:01.683Z,1579228861.683 [Docked:B.](INFO): *** querying acoustic contact *** 2020-01-17T02:41:02.043Z,1579228862.043 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:02.380Z,1579228862.380 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77 2020-01-17T02:41:02.488Z,1579228862.488 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:02.781Z,1579228862.781 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A 2020-01-17T02:41:02.870Z,1579228862.870 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:03.204Z,1579228863.204 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:03.595Z,1579228863.595 [Micromodem](INFO): Nmea in: $SNTTA,,,,,024102.49*56 2020-01-17T02:41:03.618Z,1579228863.618 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:04.061Z,1579228864.061 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:04.626Z,1579228864.626 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:05.018Z,1579228865.018 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:05.419Z,1579228865.419 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:05.782Z,1579228865.782 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-01-17T02:41:05.827Z,1579228865.827 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:06.223Z,1579228866.223 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:06.619Z,1579228866.619 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:07.028Z,1579228867.028 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:07.445Z,1579228867.445 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:07.829Z,1579228867.829 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:08.247Z,1579228868.247 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:08.640Z,1579228868.640 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:09.061Z,1579228869.061 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:09.061Z,1579228869.061 [Docked:E.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2020-01-17T02:41:09.061Z,1579228869.061 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:09.061Z,1579228869.061 [Docked:F] Running Loop=1 2020-01-17T02:41:09.477Z,1579228869.477 [Docked:F](INFO): Docked satisfied! 2020-01-17T02:41:09.477Z,1579228869.477 [Docked:F] Stopped 2020-01-17T02:41:09.477Z,1579228869.477 [Docked:G.Wait] Running Loop=1 2020-01-17T02:41:09.477Z,1579228869.477 [Docked:G.Wait](DEBUG): Initialize Wait Component. 2020-01-17T02:41:09.477Z,1579228869.477 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:09.477Z,1579228869.477 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:09.871Z,1579228869.871 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:09.871Z,1579228869.871 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:10.272Z,1579228870.272 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:10.272Z,1579228870.272 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:10.678Z,1579228870.678 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:10.678Z,1579228870.678 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:11.075Z,1579228871.075 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:11.075Z,1579228871.075 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:11.467Z,1579228871.467 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:11.468Z,1579228871.468 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:11.882Z,1579228871.882 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:11.882Z,1579228871.882 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:12.282Z,1579228872.282 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:12.282Z,1579228872.282 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:12.681Z,1579228872.681 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:12.682Z,1579228872.682 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:13.095Z,1579228873.095 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:13.095Z,1579228873.095 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:13.482Z,1579228873.482 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:13.483Z,1579228873.483 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:13.903Z,1579228873.903 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:13.903Z,1579228873.903 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:14.351Z,1579228874.351 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:14.351Z,1579228874.351 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:14.727Z,1579228874.727 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:14.727Z,1579228874.727 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:15.207Z,1579228875.207 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:15.208Z,1579228875.208 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:15.564Z,1579228875.564 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:15.564Z,1579228875.564 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:15.977Z,1579228875.977 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:15.977Z,1579228875.977 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:16.334Z,1579228876.334 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:16.334Z,1579228876.334 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:16.730Z,1579228876.730 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:16.730Z,1579228876.730 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:17.178Z,1579228877.178 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:17.178Z,1579228877.178 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:17.534Z,1579228877.534 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:17.534Z,1579228877.534 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:17.962Z,1579228877.962 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:17.962Z,1579228877.962 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:18.343Z,1579228878.343 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:18.343Z,1579228878.343 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:18.793Z,1579228878.793 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:18.793Z,1579228878.793 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:19.166Z,1579228879.166 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:19.167Z,1579228879.167 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:19.556Z,1579228879.556 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:19.556Z,1579228879.556 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:20.002Z,1579228880.002 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:20.002Z,1579228880.002 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:20.375Z,1579228880.375 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:20.376Z,1579228880.376 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:20.787Z,1579228880.787 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:20.787Z,1579228880.787 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:21.178Z,1579228881.178 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:21.178Z,1579228881.178 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:21.575Z,1579228881.575 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:21.575Z,1579228881.575 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:21.995Z,1579228881.995 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:21.995Z,1579228881.995 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:22.370Z,1579228882.370 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:22.370Z,1579228882.370 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:22.823Z,1579228882.823 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:22.823Z,1579228882.823 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:23.185Z,1579228883.185 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:23.186Z,1579228883.186 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:23.603Z,1579228883.603 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:23.603Z,1579228883.603 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:24.021Z,1579228884.021 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:24.021Z,1579228884.021 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:24.399Z,1579228884.399 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:24.399Z,1579228884.399 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:24.838Z,1579228884.838 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:24.838Z,1579228884.838 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:25.219Z,1579228885.219 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:25.219Z,1579228885.219 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:25.619Z,1579228885.619 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:25.619Z,1579228885.619 [Docked:E.Docked](INFO): State switch to DOCKED 2020-01-17T02:41:26.053Z,1579228886.053 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:26.434Z,1579228886.434 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:26.826Z,1579228886.826 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:27.248Z,1579228887.248 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:27.690Z,1579228887.690 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:28.145Z,1579228888.145 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:28.463Z,1579228888.463 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:28.867Z,1579228888.867 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:29.259Z,1579228889.259 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:29.647Z,1579228889.647 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:30.063Z,1579228890.063 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:30.467Z,1579228890.467 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:30.897Z,1579228890.897 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:31.231Z,1579228891.231 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-17T02:41:31.272Z,1579228891.272 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:31.672Z,1579228891.672 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:32.089Z,1579228892.089 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:32.504Z,1579228892.504 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:32.897Z,1579228892.897 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:33.284Z,1579228893.284 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:33.680Z,1579228893.680 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:34.099Z,1579228894.099 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:34.507Z,1579228894.507 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:34.923Z,1579228894.923 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:35.315Z,1579228895.315 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:35.707Z,1579228895.707 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:36.122Z,1579228896.122 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:36.521Z,1579228896.521 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:36.915Z,1579228896.915 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:37.332Z,1579228897.332 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:37.739Z,1579228897.739 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:38.298Z,1579228898.298 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:38.715Z,1579228898.715 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:39.094Z,1579228899.094 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:39.490Z,1579228899.490 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:39.909Z,1579228899.909 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:40.293Z,1579228900.293 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:40.732Z,1579228900.732 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-17T02:41:40.732Z,1579228900.732 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-3:BE,-32768,-32768,-32768,V 2020-01-17T02:41:40.745Z,1579228900.745 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:41.233Z,1579228901.233 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:41.546Z,1579228901.546 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:41.960Z,1579228901.960 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:42.353Z,1579228902.353 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:42.749Z,1579228902.749 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:43.119Z,1579228903.119 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:43.559Z,1579228903.559 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:43.953Z,1579228903.953 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:44.363Z,1579228904.363 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:44.755Z,1579228904.755 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:45.152Z,1579228905.152 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:45.567Z,1579228905.567 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:45.975Z,1579228905.975 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:46.372Z,1579228906.372 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:46.785Z,1579228906.785 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:47.182Z,1579228907.182 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:47.567Z,1579228907.567 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:47.969Z,1579228907.969 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:48.413Z,1579228908.413 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:48.828Z,1579228908.828 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:49.199Z,1579228909.199 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:49.583Z,1579228909.583 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:49.000Z,1579228910.000 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:50.411Z,1579228910.411 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:50.825Z,1579228910.825 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:51.223Z,1579228911.223 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:51.614Z,1579228911.614 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:52.026Z,1579228912.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-01-17T02:41:52.026Z,1579228912.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:41:52.027Z,1579228912.027 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:52.129Z,1579228912.129 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:41:52.430Z,1579228912.430 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:52.509Z,1579228912.509 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:41:52.509Z,1579228912.509 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-01-17T02:41:52.858Z,1579228912.858 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:53.248Z,1579228913.248 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:53.639Z,1579228913.639 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:54.063Z,1579228914.063 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:54.547Z,1579228914.547 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:55.030Z,1579228915.030 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:55.287Z,1579228915.287 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:55.698Z,1579228915.698 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:55.698Z,1579228915.698 [Docked:E.Docked](IMPORTANT): VEHICLE DOCKED. 2020-01-17T02:41:55.698Z,1579228915.698 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:56.091Z,1579228916.091 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:56.091Z,1579228916.091 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:56.483Z,1579228916.483 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:56.483Z,1579228916.483 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:56.860Z,1579228916.860 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:56.860Z,1579228916.860 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:57.268Z,1579228917.268 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:57.268Z,1579228917.268 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:57.679Z,1579228917.679 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:57.680Z,1579228917.680 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:58.098Z,1579228918.098 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:58.098Z,1579228918.098 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:58.491Z,1579228918.491 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:58.492Z,1579228918.492 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:58.895Z,1579228918.895 [RDI_Pathfinder](ERROR): Failed to parse: : +0.00, +0.00, +0.00, 0.00,299.00 2020-01-17T02:41:58.903Z,1579228918.903 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:58.903Z,1579228918.903 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:59.300Z,1579228919.300 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:59.300Z,1579228919.300 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:41:59.695Z,1579228919.695 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:41:59.696Z,1579228919.696 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:00.100Z,1579228920.100 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:00.100Z,1579228920.100 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:00.494Z,1579228920.494 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:00.494Z,1579228920.494 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:00.899Z,1579228920.899 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:00.899Z,1579228920.899 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:01.314Z,1579228921.314 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:01.314Z,1579228921.314 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:01.743Z,1579228921.743 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:01.744Z,1579228921.744 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:01.757Z,1579228921.757 [Docked:B.](INFO): *** querying acoustic contact *** 2020-01-17T02:42:02.127Z,1579228922.127 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:02.127Z,1579228922.127 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:02.492Z,1579228922.492 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77 2020-01-17T02:42:02.578Z,1579228922.578 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:02.579Z,1579228922.579 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:02.889Z,1579228922.889 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A 2020-01-17T02:42:02.970Z,1579228922.970 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:02.970Z,1579228922.970 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:03.324Z,1579228923.324 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:03.325Z,1579228923.325 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:03.704Z,1579228923.704 [Micromodem](INFO): Nmea in: $SNTTA,0.275234,,,,024202.60*45 2020-01-17T02:42:03.788Z,1579228923.788 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:03.788Z,1579228923.788 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:04.131Z,1579228924.131 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:04.131Z,1579228924.131 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:04.547Z,1579228924.547 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:04.547Z,1579228924.547 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:04.965Z,1579228924.965 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:04.965Z,1579228924.965 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:05.361Z,1579228925.361 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:05.362Z,1579228925.362 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:05.751Z,1579228925.751 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:05.751Z,1579228925.751 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:06.122Z,1579228926.122 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-01-17T02:42:06.170Z,1579228926.170 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:06.170Z,1579228926.170 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:06.566Z,1579228926.566 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:06.566Z,1579228926.566 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:06.952Z,1579228926.952 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:06.952Z,1579228926.952 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:07.377Z,1579228927.377 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:07.377Z,1579228927.377 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:07.782Z,1579228927.782 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:07.782Z,1579228927.782 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:08.266Z,1579228928.266 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:08.266Z,1579228928.266 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:08.657Z,1579228928.657 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:08.657Z,1579228928.657 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:09.039Z,1579228929.039 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:09.039Z,1579228929.039 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:09.423Z,1579228929.423 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:09.423Z,1579228929.423 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:09.839Z,1579228929.839 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:09.839Z,1579228929.839 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:10.206Z,1579228930.206 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:10.206Z,1579228930.206 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:10.599Z,1579228930.599 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:10.599Z,1579228930.599 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:11.007Z,1579228931.007 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:11.007Z,1579228931.007 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:11.416Z,1579228931.416 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:11.416Z,1579228931.416 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:11.999Z,1579228931.999 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:11.999Z,1579228931.999 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:12.383Z,1579228932.383 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:12.383Z,1579228932.383 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:12.798Z,1579228932.798 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:12.798Z,1579228932.798 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:13.188Z,1579228933.188 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:13.188Z,1579228933.188 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:13.573Z,1579228933.573 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:13.573Z,1579228933.573 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:13.994Z,1579228933.994 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:13.995Z,1579228933.995 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:14.399Z,1579228934.399 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:14.399Z,1579228934.399 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:14.822Z,1579228934.822 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:14.822Z,1579228934.822 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:15.203Z,1579228935.203 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:15.203Z,1579228935.203 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:15.601Z,1579228935.601 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:15.601Z,1579228935.601 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:16.008Z,1579228936.008 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:16.009Z,1579228936.009 [Docked:E.Docked](INFO): State switch to DETACHED 2020-01-17T02:42:16.427Z,1579228936.427 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:16.832Z,1579228936.832 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:17.229Z,1579228937.229 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:17.615Z,1579228937.615 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:18.018Z,1579228938.018 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:18.465Z,1579228938.465 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:18.855Z,1579228938.855 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:19.251Z,1579228939.251 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:19.635Z,1579228939.635 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:20.047Z,1579228940.047 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:20.450Z,1579228940.450 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:20.857Z,1579228940.857 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:21.313Z,1579228941.313 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:21.730Z,1579228941.730 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:22.110Z,1579228942.110 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:22.547Z,1579228942.547 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:22.907Z,1579228942.907 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:23.293Z,1579228943.293 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:23.715Z,1579228943.715 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:24.091Z,1579228944.091 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:24.507Z,1579228944.507 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:24.926Z,1579228944.926 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:25.299Z,1579228945.299 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:25.697Z,1579228945.697 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:26.113Z,1579228946.113 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:26.498Z,1579228946.498 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:26.916Z,1579228946.916 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:27.323Z,1579228947.323 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:27.735Z,1579228947.735 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:28.156Z,1579228948.156 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:28.543Z,1579228948.543 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:28.941Z,1579228948.941 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:29.344Z,1579228949.344 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:29.778Z,1579228949.778 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:30.155Z,1579228950.155 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:30.570Z,1579228950.570 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:30.991Z,1579228950.991 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:31.371Z,1579228951.371 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:31.790Z,1579228951.790 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:32.172Z,1579228952.172 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:32.578Z,1579228952.578 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:32.980Z,1579228952.980 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:33.391Z,1579228953.391 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:33.815Z,1579228953.815 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:34.202Z,1579228954.202 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:34.646Z,1579228954.646 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:35.012Z,1579228955.012 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:35.427Z,1579228955.427 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:35.919Z,1579228955.919 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:36.224Z,1579228956.224 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:36.224Z,1579228956.224 [Docked:E.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2020-01-17T02:42:36.241Z,1579228956.241 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:36.619Z,1579228956.619 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:36.619Z,1579228956.619 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:37.032Z,1579228957.032 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:37.032Z,1579228957.032 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:37.445Z,1579228957.445 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:37.445Z,1579228957.445 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:37.822Z,1579228957.822 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:37.823Z,1579228957.823 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:38.242Z,1579228958.242 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:38.242Z,1579228958.242 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:38.618Z,1579228958.618 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:38.618Z,1579228958.618 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:39.039Z,1579228959.039 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:39.039Z,1579228959.039 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:39.433Z,1579228959.433 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:39.433Z,1579228959.433 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:39.859Z,1579228959.859 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:39.859Z,1579228959.859 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:40.233Z,1579228960.233 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:40.233Z,1579228960.233 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:40.664Z,1579228960.664 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:40.664Z,1579228960.664 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:41.074Z,1579228961.074 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:41.074Z,1579228961.074 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:41.470Z,1579228961.470 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:41.470Z,1579228961.470 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:41.871Z,1579228961.871 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:41.871Z,1579228961.871 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:42.271Z,1579228962.271 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:42.271Z,1579228962.271 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:42.679Z,1579228962.679 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:42.679Z,1579228962.679 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:43.091Z,1579228963.091 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:43.091Z,1579228963.091 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:43.502Z,1579228963.502 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:43.502Z,1579228963.502 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:43.896Z,1579228963.896 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:43.896Z,1579228963.896 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:44.299Z,1579228964.299 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:44.299Z,1579228964.299 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:44.680Z,1579228964.680 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:44.681Z,1579228964.681 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:45.098Z,1579228965.098 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:45.098Z,1579228965.098 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:45.579Z,1579228965.579 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:45.580Z,1579228965.580 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:45.980Z,1579228965.980 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:45.980Z,1579228965.980 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:46.391Z,1579228966.391 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:46.391Z,1579228966.391 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:46.795Z,1579228966.795 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:46.795Z,1579228966.795 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:47.213Z,1579228967.213 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:47.213Z,1579228967.213 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:47.586Z,1579228967.586 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:47.586Z,1579228967.586 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:48.084Z,1579228968.084 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:48.084Z,1579228968.084 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:48.428Z,1579228968.428 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:48.428Z,1579228968.428 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:48.922Z,1579228968.922 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:48.922Z,1579228968.922 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:49.251Z,1579228969.251 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:49.251Z,1579228969.251 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:49.631Z,1579228969.631 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:49.631Z,1579228969.631 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:50.035Z,1579228970.035 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:50.035Z,1579228970.035 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:50.447Z,1579228970.447 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:50.447Z,1579228970.447 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:50.838Z,1579228970.838 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:50.838Z,1579228970.838 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:51.243Z,1579228971.243 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:51.243Z,1579228971.243 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:51.625Z,1579228971.625 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:51.625Z,1579228971.625 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:52.041Z,1579228972.041 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:52.041Z,1579228972.041 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:52.438Z,1579228972.438 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:52.438Z,1579228972.438 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:52.870Z,1579228972.870 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:52.870Z,1579228972.870 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:53.237Z,1579228973.237 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:53.238Z,1579228973.238 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:53.674Z,1579228973.674 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:53.674Z,1579228973.674 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:54.068Z,1579228974.068 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:54.069Z,1579228974.069 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:54.480Z,1579228974.480 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:54.480Z,1579228974.480 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:54.875Z,1579228974.875 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:54.876Z,1579228974.876 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:55.264Z,1579228975.264 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:55.264Z,1579228975.264 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:55.662Z,1579228975.662 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:55.662Z,1579228975.662 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:56.080Z,1579228976.080 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:56.080Z,1579228976.080 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:56.483Z,1579228976.483 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:56.483Z,1579228976.483 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:56.895Z,1579228976.895 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:56.895Z,1579228976.895 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:57.306Z,1579228977.306 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:57.306Z,1579228977.306 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:57.686Z,1579228977.686 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:57.686Z,1579228977.686 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:58.128Z,1579228978.128 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:58.128Z,1579228978.128 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:58.503Z,1579228978.503 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:58.503Z,1579228978.503 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:58.936Z,1579228978.936 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:58.937Z,1579228978.937 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:59.312Z,1579228979.312 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:59.312Z,1579228979.312 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:42:59.748Z,1579228979.748 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:42:59.748Z,1579228979.748 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:00.131Z,1579228980.131 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:00.131Z,1579228980.131 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:00.535Z,1579228980.535 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:00.535Z,1579228980.535 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:00.778Z,1579228980.778 [CommandLine](IMPORTANT): got command get VerticalControl.depthCmd 2020-01-17T02:43:00.778Z,1579228980.778 [CommandLine](IMPORTANT): VerticalControl.depthCmd 6.000000 m 2020-01-17T02:43:00.936Z,1579228980.936 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:00.936Z,1579228980.936 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:01.385Z,1579228981.385 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:01.385Z,1579228981.385 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:01.760Z,1579228981.760 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:01.760Z,1579228981.760 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:01.762Z,1579228981.762 [Docked:B.](INFO): *** querying acoustic contact *** 2020-01-17T02:43:02.203Z,1579228982.203 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:02.203Z,1579228982.203 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:02.514Z,1579228982.514 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77 2020-01-17T02:43:02.620Z,1579228982.620 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:02.620Z,1579228982.620 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:02.913Z,1579228982.913 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A 2020-01-17T02:43:03.009Z,1579228983.009 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:03.010Z,1579228983.010 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:03.371Z,1579228983.371 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:03.371Z,1579228983.371 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:03.731Z,1579228983.731 [Micromodem](INFO): Nmea in: $SNTTA,,,,,024302.62*5D 2020-01-17T02:43:03.801Z,1579228983.801 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:03.802Z,1579228983.802 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:04.163Z,1579228984.163 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:04.163Z,1579228984.163 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:04.562Z,1579228984.562 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:04.562Z,1579228984.562 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:04.983Z,1579228984.983 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:04.984Z,1579228984.984 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:05.386Z,1579228985.386 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:05.386Z,1579228985.386 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:05.807Z,1579228985.807 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:05.807Z,1579228985.807 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:06.162Z,1579228986.162 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-01-17T02:43:06.264Z,1579228986.264 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:06.273Z,1579228986.273 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:06.598Z,1579228986.598 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:06.599Z,1579228986.599 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:07.010Z,1579228987.010 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:07.010Z,1579228987.010 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:07.469Z,1579228987.469 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:07.470Z,1579228987.470 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:07.844Z,1579228987.844 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:07.844Z,1579228987.844 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:08.327Z,1579228988.327 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:08.327Z,1579228988.327 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:08.644Z,1579228988.644 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:08.644Z,1579228988.644 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:09.120Z,1579228989.120 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:09.120Z,1579228989.120 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:09.446Z,1579228989.446 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:09.447Z,1579228989.447 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:09.903Z,1579228989.903 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:09.903Z,1579228989.903 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:10.227Z,1579228990.227 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:10.227Z,1579228990.227 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:10.703Z,1579228990.703 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:10.704Z,1579228990.704 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:11.061Z,1579228991.061 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:11.061Z,1579228991.061 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:11.576Z,1579228991.576 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:11.585Z,1579228991.585 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:11.931Z,1579228991.931 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:11.932Z,1579228991.932 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:12.251Z,1579228992.251 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:12.251Z,1579228992.251 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:12.676Z,1579228992.676 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:12.685Z,1579228992.685 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:13.104Z,1579228993.104 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:13.113Z,1579228993.113 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:13.484Z,1579228993.484 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:13.484Z,1579228993.484 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:14.081Z,1579228994.081 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:14.081Z,1579228994.081 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:14.272Z,1579228994.272 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-01-17T02:43:14.276Z,1579228994.276 [BPC1](INFO): Received data from all battery sticks. 2020-01-17T02:43:14.335Z,1579228994.335 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:14.335Z,1579228994.335 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:14.672Z,1579228994.672 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:14.672Z,1579228994.672 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:15.072Z,1579228995.072 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:15.072Z,1579228995.072 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:15.482Z,1579228995.482 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:15.483Z,1579228995.483 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:15.930Z,1579228995.930 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:15.930Z,1579228995.930 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:16.295Z,1579228996.295 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:16.295Z,1579228996.295 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:16.707Z,1579228996.707 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:16.707Z,1579228996.707 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:17.131Z,1579228997.131 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:17.131Z,1579228997.131 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:17.507Z,1579228997.507 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:17.507Z,1579228997.507 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:17.910Z,1579228997.910 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:17.910Z,1579228997.910 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:18.311Z,1579228998.311 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:18.312Z,1579228998.312 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:18.749Z,1579228998.749 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:18.750Z,1579228998.750 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:19.117Z,1579228999.117 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:19.117Z,1579228999.117 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:19.603Z,1579228999.603 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:19.603Z,1579228999.603 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:20.017Z,1579229000.017 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:20.018Z,1579229000.018 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:20.417Z,1579229000.417 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:20.417Z,1579229000.417 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:20.815Z,1579229000.815 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:20.815Z,1579229000.815 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:21.215Z,1579229001.215 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:21.215Z,1579229001.215 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:21.620Z,1579229001.620 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:21.620Z,1579229001.620 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:22.019Z,1579229002.019 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:22.020Z,1579229002.020 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:22.439Z,1579229002.439 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:22.440Z,1579229002.440 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:22.851Z,1579229002.851 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:22.851Z,1579229002.851 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:23.240Z,1579229003.240 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:23.240Z,1579229003.240 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:23.514Z,1579229003.514 [CommandLine](IMPORTANT): got command get VerticalControl.verticalMode 2020-01-17T02:43:23.514Z,1579229003.514 [CommandLine](IMPORTANT): VerticalControl.verticalMode 3 enum 2020-01-17T02:43:23.635Z,1579229003.635 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:23.635Z,1579229003.635 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:24.049Z,1579229004.049 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:24.050Z,1579229004.050 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:24.446Z,1579229004.446 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:24.446Z,1579229004.446 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:24.870Z,1579229004.870 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:24.870Z,1579229004.870 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:25.251Z,1579229005.251 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:25.251Z,1579229005.251 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:25.658Z,1579229005.658 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:25.659Z,1579229005.659 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:26.078Z,1579229006.078 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:26.079Z,1579229006.079 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:26.479Z,1579229006.479 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:26.479Z,1579229006.479 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:26.866Z,1579229006.866 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:26.866Z,1579229006.866 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:27.281Z,1579229007.281 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:27.281Z,1579229007.281 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:27.673Z,1579229007.673 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:27.673Z,1579229007.673 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:28.101Z,1579229008.101 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:28.101Z,1579229008.101 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:28.505Z,1579229008.505 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:28.506Z,1579229008.506 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:28.896Z,1579229008.896 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:28.897Z,1579229008.897 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:29.303Z,1579229009.303 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:29.303Z,1579229009.303 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:29.731Z,1579229009.731 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:29.731Z,1579229009.731 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:30.107Z,1579229010.107 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:30.107Z,1579229010.107 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:30.501Z,1579229010.501 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:30.502Z,1579229010.502 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:30.915Z,1579229010.915 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:30.915Z,1579229010.915 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:31.301Z,1579229011.301 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:31.301Z,1579229011.301 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:31.757Z,1579229011.757 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:31.757Z,1579229011.757 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:32.135Z,1579229012.135 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:32.135Z,1579229012.135 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:32.539Z,1579229012.539 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:32.539Z,1579229012.539 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:32.927Z,1579229012.927 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:32.927Z,1579229012.927 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:33.330Z,1579229013.330 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:33.330Z,1579229013.330 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:33.768Z,1579229013.768 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:33.768Z,1579229013.768 [Docked:E.Docked](INFO): State switch to DOCKED 2020-01-17T02:43:34.145Z,1579229014.145 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:34.562Z,1579229014.562 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:34.994Z,1579229014.994 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:35.363Z,1579229015.363 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:35.363Z,1579229015.363 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:35.757Z,1579229015.757 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:35.758Z,1579229015.758 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:36.154Z,1579229016.154 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:36.155Z,1579229016.155 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:36.559Z,1579229016.559 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:36.559Z,1579229016.559 [Docked:E.Docked](INFO): State switch to DOCKED 2020-01-17T02:43:36.971Z,1579229016.971 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:37.395Z,1579229017.395 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:37.395Z,1579229017.395 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:37.792Z,1579229017.792 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:37.792Z,1579229017.792 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:38.223Z,1579229018.223 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:38.223Z,1579229018.223 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:38.594Z,1579229018.594 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:38.594Z,1579229018.594 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:43:39.005Z,1579229019.005 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:39.005Z,1579229019.005 [Docked:E.Docked](INFO): State switch to DOCKED 2020-01-17T02:43:39.406Z,1579229019.406 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:39.836Z,1579229019.836 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:40.214Z,1579229020.214 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:40.615Z,1579229020.615 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:41.028Z,1579229021.028 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:41.438Z,1579229021.438 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:41.860Z,1579229021.860 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:42.238Z,1579229022.238 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:42.624Z,1579229022.624 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:43.028Z,1579229023.028 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:43.428Z,1579229023.428 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:43.847Z,1579229023.847 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:44.240Z,1579229024.240 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:44.671Z,1579229024.671 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:45.080Z,1579229025.080 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:45.463Z,1579229025.463 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:45.855Z,1579229025.855 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:46.284Z,1579229026.284 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:46.658Z,1579229026.658 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:47.090Z,1579229027.090 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:47.488Z,1579229027.488 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:47.895Z,1579229027.895 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:48.314Z,1579229028.314 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:48.682Z,1579229028.682 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:49.093Z,1579229029.093 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:49.502Z,1579229029.502 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:49.899Z,1579229029.899 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:50.299Z,1579229030.299 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:50.749Z,1579229030.749 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:51.123Z,1579229031.123 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:51.532Z,1579229031.532 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:51.925Z,1579229031.925 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:52.315Z,1579229032.315 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:52.750Z,1579229032.750 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:53.120Z,1579229033.120 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:53.616Z,1579229033.616 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:54.015Z,1579229034.015 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:54.419Z,1579229034.419 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:54.827Z,1579229034.827 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:55.209Z,1579229035.209 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:55.616Z,1579229035.616 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:56.025Z,1579229036.025 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:56.438Z,1579229036.438 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:56.843Z,1579229036.843 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:57.250Z,1579229037.250 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:57.638Z,1579229037.638 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:58.032Z,1579229038.032 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:58.451Z,1579229038.451 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:58.860Z,1579229038.860 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:59.253Z,1579229039.253 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:43:59.667Z,1579229039.667 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:00.105Z,1579229040.105 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:00.480Z,1579229040.480 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:00.873Z,1579229040.873 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:01.261Z,1579229041.261 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:01.687Z,1579229041.687 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:02.088Z,1579229042.088 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:02.089Z,1579229042.089 [Docked:B.](INFO): *** querying acoustic contact *** 2020-01-17T02:44:02.499Z,1579229042.499 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:02.856Z,1579229042.856 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77 2020-01-17T02:44:02.968Z,1579229042.968 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:03.269Z,1579229043.269 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A 2020-01-17T02:44:03.336Z,1579229043.336 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:03.700Z,1579229043.700 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:04.071Z,1579229044.071 [Micromodem](INFO): Nmea in: $SNTTA,,,,,024402.96*51 2020-01-17T02:44:04.125Z,1579229044.125 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:04.499Z,1579229044.499 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:04.917Z,1579229044.917 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:05.311Z,1579229045.311 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:05.731Z,1579229045.731 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:06.149Z,1579229046.149 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:06.497Z,1579229046.497 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-01-17T02:44:06.563Z,1579229046.563 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:06.927Z,1579229046.927 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:07.352Z,1579229047.352 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:07.731Z,1579229047.731 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:08.181Z,1579229048.181 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:08.559Z,1579229048.559 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:08.968Z,1579229048.968 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:09.375Z,1579229049.375 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:09.375Z,1579229049.375 [Docked:E.Docked](IMPORTANT): VEHICLE DOCKED. 2020-01-17T02:44:09.376Z,1579229049.376 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:09.784Z,1579229049.784 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:09.789Z,1579229049.789 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:10.167Z,1579229050.167 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:10.167Z,1579229050.167 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:10.566Z,1579229050.566 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:10.566Z,1579229050.566 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:10.987Z,1579229050.987 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:10.988Z,1579229050.988 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:11.387Z,1579229051.387 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:11.387Z,1579229051.387 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:11.825Z,1579229051.825 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:11.825Z,1579229051.825 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:12.199Z,1579229052.199 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:12.199Z,1579229052.199 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:12.583Z,1579229052.583 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:12.583Z,1579229052.583 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:12.995Z,1579229052.995 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:12.996Z,1579229052.996 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:13.407Z,1579229053.407 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:13.407Z,1579229053.407 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:13.832Z,1579229053.832 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:13.833Z,1579229053.833 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:14.220Z,1579229054.220 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:14.220Z,1579229054.220 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:14.607Z,1579229054.607 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:14.607Z,1579229054.607 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:15.016Z,1579229055.016 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:15.016Z,1579229055.016 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:15.425Z,1579229055.425 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:15.425Z,1579229055.425 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:15.864Z,1579229055.864 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:15.869Z,1579229055.869 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:16.280Z,1579229056.280 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:16.280Z,1579229056.280 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:16.662Z,1579229056.662 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:16.662Z,1579229056.662 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:17.095Z,1579229057.095 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:17.095Z,1579229057.095 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:17.475Z,1579229057.475 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:17.476Z,1579229057.476 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:17.878Z,1579229057.878 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:17.878Z,1579229057.878 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:18.301Z,1579229058.301 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:18.307Z,1579229058.307 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:18.682Z,1579229058.682 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:18.682Z,1579229058.682 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:19.087Z,1579229059.087 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:19.087Z,1579229059.087 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:19.499Z,1579229059.499 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:19.499Z,1579229059.499 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:19.887Z,1579229059.887 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:19.887Z,1579229059.887 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:20.291Z,1579229060.291 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:20.291Z,1579229060.291 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:20.700Z,1579229060.700 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:20.701Z,1579229060.701 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:21.091Z,1579229061.091 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:21.091Z,1579229061.091 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:21.511Z,1579229061.511 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:21.512Z,1579229061.512 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:21.920Z,1579229061.920 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:21.920Z,1579229061.920 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:22.315Z,1579229062.315 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:22.316Z,1579229062.316 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:22.727Z,1579229062.727 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:22.727Z,1579229062.727 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:23.116Z,1579229063.116 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:23.116Z,1579229063.116 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:23.516Z,1579229063.516 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:23.516Z,1579229063.516 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:23.925Z,1579229063.925 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:23.926Z,1579229063.926 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:24.319Z,1579229064.319 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:24.319Z,1579229064.319 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:24.747Z,1579229064.747 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:24.747Z,1579229064.747 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:25.146Z,1579229065.146 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:25.146Z,1579229065.146 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:25.551Z,1579229065.551 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:25.551Z,1579229065.551 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:25.958Z,1579229065.958 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:25.958Z,1579229065.958 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:26.346Z,1579229066.346 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:26.346Z,1579229066.346 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:26.750Z,1579229066.750 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:26.750Z,1579229066.750 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:27.160Z,1579229067.160 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:27.160Z,1579229067.160 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:27.570Z,1579229067.570 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:27.570Z,1579229067.570 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:28.088Z,1579229068.088 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:28.088Z,1579229068.088 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:28.465Z,1579229068.465 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:28.466Z,1579229068.466 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:28.852Z,1579229068.852 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:28.852Z,1579229068.852 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:29.259Z,1579229069.259 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:29.259Z,1579229069.259 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:29.663Z,1579229069.663 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:29.663Z,1579229069.663 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:30.070Z,1579229070.070 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:30.071Z,1579229070.071 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:30.482Z,1579229070.482 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:30.482Z,1579229070.482 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:30.912Z,1579229070.912 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:30.914Z,1579229070.914 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:31.291Z,1579229071.291 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:31.291Z,1579229071.291 [Docked:E.Docked](INFO): State switch to DETACHED 2020-01-17T02:44:31.688Z,1579229071.688 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:32.084Z,1579229072.084 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:32.491Z,1579229072.491 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:32.904Z,1579229072.904 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:33.294Z,1579229073.294 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:33.737Z,1579229073.737 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:34.137Z,1579229074.137 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:34.531Z,1579229074.531 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:34.929Z,1579229074.929 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:35.330Z,1579229075.330 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:35.723Z,1579229075.723 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:36.136Z,1579229076.136 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:36.554Z,1579229076.554 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:36.946Z,1579229076.946 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:37.361Z,1579229077.361 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:37.747Z,1579229077.747 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:38.159Z,1579229078.159 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:38.537Z,1579229078.537 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:38.952Z,1579229078.952 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:39.352Z,1579229079.352 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:39.813Z,1579229079.813 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:40.179Z,1579229080.179 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:40.587Z,1579229080.587 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:40.979Z,1579229080.979 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:41.387Z,1579229081.387 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:41.803Z,1579229081.803 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:42.175Z,1579229082.175 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:42.603Z,1579229082.603 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:43.009Z,1579229083.009 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:43.407Z,1579229083.407 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:43.823Z,1579229083.823 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:44.202Z,1579229084.202 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:44.615Z,1579229084.615 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:45.023Z,1579229085.023 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:45.434Z,1579229085.434 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:45.827Z,1579229085.827 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:46.240Z,1579229086.240 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:46.630Z,1579229086.630 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:47.046Z,1579229087.046 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:47.440Z,1579229087.440 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:47.839Z,1579229087.839 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:48.249Z,1579229088.249 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:48.663Z,1579229088.663 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:49.086Z,1579229089.086 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:49.459Z,1579229089.459 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:49.865Z,1579229089.865 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:50.273Z,1579229090.273 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:50.661Z,1579229090.661 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:51.084Z,1579229091.084 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:51.502Z,1579229091.502 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:51.502Z,1579229091.502 [Docked:E.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2020-01-17T02:44:51.502Z,1579229091.502 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:51.903Z,1579229091.903 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:51.903Z,1579229091.903 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:52.309Z,1579229092.309 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:52.309Z,1579229092.309 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:52.728Z,1579229092.728 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:52.728Z,1579229092.728 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:53.100Z,1579229093.100 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-01-17T02:44:53.100Z,1579229093.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:44:53.105Z,1579229093.105 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:53.105Z,1579229093.105 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:53.143Z,1579229093.143 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:44:53.501Z,1579229093.501 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:53.501Z,1579229093.501 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:53.519Z,1579229093.519 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:44:53.519Z,1579229093.519 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-01-17T02:44:53.900Z,1579229093.900 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:53.900Z,1579229093.900 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:54.307Z,1579229094.307 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:54.307Z,1579229094.307 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:54.722Z,1579229094.722 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:54.722Z,1579229094.722 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:55.130Z,1579229095.130 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:55.130Z,1579229095.130 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:55.527Z,1579229095.527 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:55.527Z,1579229095.527 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:55.947Z,1579229095.947 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:55.948Z,1579229095.948 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:56.327Z,1579229096.327 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:56.327Z,1579229096.327 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:56.728Z,1579229096.728 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:56.729Z,1579229096.729 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:57.154Z,1579229097.154 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:57.154Z,1579229097.154 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:57.550Z,1579229097.550 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:57.550Z,1579229097.550 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:57.955Z,1579229097.955 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:57.955Z,1579229097.955 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:58.367Z,1579229098.367 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:58.368Z,1579229098.368 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:58.767Z,1579229098.767 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:58.767Z,1579229098.767 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:59.149Z,1579229099.149 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:59.149Z,1579229099.149 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:59.562Z,1579229099.562 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:59.562Z,1579229099.562 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:44:59.966Z,1579229099.966 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:44:59.967Z,1579229099.967 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:00.359Z,1579229100.359 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:00.359Z,1579229100.359 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:00.810Z,1579229100.810 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:00.811Z,1579229100.811 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:01.186Z,1579229101.186 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:01.186Z,1579229101.186 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:01.691Z,1579229101.691 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:01.691Z,1579229101.691 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:02.076Z,1579229102.076 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:02.077Z,1579229102.077 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:02.475Z,1579229102.475 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:02.476Z,1579229102.476 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:02.476Z,1579229102.476 [Docked:B.](INFO): *** querying acoustic contact *** 2020-01-17T02:45:02.888Z,1579229102.888 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:02.888Z,1579229102.888 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:03.244Z,1579229103.244 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77 2020-01-17T02:45:03.330Z,1579229103.330 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:03.331Z,1579229103.331 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:03.658Z,1579229103.658 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A 2020-01-17T02:45:03.751Z,1579229103.751 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:03.751Z,1579229103.751 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:04.102Z,1579229104.102 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:04.102Z,1579229104.102 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:04.463Z,1579229104.463 [Micromodem](INFO): Nmea in: $SNTTA,,,,,024503.35*58 2020-01-17T02:45:04.522Z,1579229104.522 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:04.522Z,1579229104.522 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:04.903Z,1579229104.903 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:04.904Z,1579229104.904 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:05.296Z,1579229105.296 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:05.296Z,1579229105.296 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:05.705Z,1579229105.705 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:05.705Z,1579229105.705 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:06.104Z,1579229106.104 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:06.104Z,1579229106.104 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:06.520Z,1579229106.520 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:06.520Z,1579229106.520 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:06.879Z,1579229106.879 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-01-17T02:45:06.925Z,1579229106.925 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:06.925Z,1579229106.925 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:07.333Z,1579229107.333 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:07.333Z,1579229107.333 [Docked:E.Docked](INFO): State switch to DOCKED 2020-01-17T02:45:07.713Z,1579229107.713 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:08.166Z,1579229108.166 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:08.529Z,1579229108.529 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:08.529Z,1579229108.529 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:08.959Z,1579229108.959 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:08.959Z,1579229108.959 [Docked:E.Docked](INFO): State switch to DOCKED 2020-01-17T02:45:09.345Z,1579229109.345 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:09.767Z,1579229109.767 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:10.147Z,1579229110.147 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:10.559Z,1579229110.559 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:10.972Z,1579229110.972 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:11.348Z,1579229111.348 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:11.776Z,1579229111.776 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:12.166Z,1579229112.166 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:12.583Z,1579229112.583 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:13.001Z,1579229113.001 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:13.378Z,1579229113.378 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:13.803Z,1579229113.803 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:14.194Z,1579229114.194 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:14.583Z,1579229114.583 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:14.999Z,1579229114.999 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:15.411Z,1579229115.411 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:15.815Z,1579229115.815 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:16.228Z,1579229116.228 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:16.609Z,1579229116.609 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:17.008Z,1579229117.008 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:17.432Z,1579229117.432 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:17.840Z,1579229117.840 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:18.236Z,1579229118.236 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:18.623Z,1579229118.623 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:19.053Z,1579229119.053 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:19.431Z,1579229119.431 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:19.848Z,1579229119.848 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:20.246Z,1579229120.246 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:20.663Z,1579229120.663 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:21.079Z,1579229121.079 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:21.458Z,1579229121.458 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:21.865Z,1579229121.865 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:22.257Z,1579229122.257 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:22.665Z,1579229122.665 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:23.079Z,1579229123.079 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:23.494Z,1579229123.494 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:23.905Z,1579229123.905 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:24.303Z,1579229124.303 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:24.719Z,1579229124.719 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:25.158Z,1579229125.158 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:25.503Z,1579229125.503 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:25.936Z,1579229125.936 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:26.321Z,1579229126.321 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:26.730Z,1579229126.730 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:27.145Z,1579229127.145 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:27.539Z,1579229127.539 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:27.915Z,1579229127.915 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:28.333Z,1579229128.333 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:28.748Z,1579229128.748 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:29.135Z,1579229129.135 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:29.538Z,1579229129.538 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:29.959Z,1579229129.959 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:30.359Z,1579229130.359 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:30.778Z,1579229130.778 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:31.152Z,1579229131.152 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:31.555Z,1579229131.555 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:31.979Z,1579229131.979 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:32.385Z,1579229132.385 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:32.808Z,1579229132.808 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:33.187Z,1579229133.187 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:33.570Z,1579229133.570 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:33.983Z,1579229133.983 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:34.386Z,1579229134.386 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:34.839Z,1579229134.839 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:35.206Z,1579229135.206 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:35.686Z,1579229135.686 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:36.098Z,1579229136.098 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:36.507Z,1579229136.507 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:36.916Z,1579229136.916 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:37.344Z,1579229137.344 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:37.699Z,1579229137.699 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:38.120Z,1579229138.120 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:38.506Z,1579229138.506 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:38.924Z,1579229138.924 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:39.315Z,1579229139.315 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:39.315Z,1579229139.315 [Docked:E.Docked](IMPORTANT): VEHICLE DOCKED. 2020-01-17T02:45:39.315Z,1579229139.315 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:39.767Z,1579229139.767 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:39.767Z,1579229139.767 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:40.146Z,1579229140.146 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:40.146Z,1579229140.146 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:40.535Z,1579229140.535 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:40.535Z,1579229140.535 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:40.944Z,1579229140.944 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:40.945Z,1579229140.945 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:41.349Z,1579229141.349 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:41.349Z,1579229141.349 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:41.766Z,1579229141.766 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:41.766Z,1579229141.766 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:42.148Z,1579229142.148 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:42.148Z,1579229142.148 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:42.563Z,1579229142.563 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:42.563Z,1579229142.563 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:42.977Z,1579229142.977 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:42.977Z,1579229142.977 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:43.381Z,1579229143.381 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:43.381Z,1579229143.381 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:43.803Z,1579229143.803 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:43.803Z,1579229143.803 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:44.163Z,1579229144.163 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:44.163Z,1579229144.163 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:44.568Z,1579229144.568 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:44.568Z,1579229144.568 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:44.979Z,1579229144.979 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:44.979Z,1579229144.979 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:45.387Z,1579229145.387 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:45.387Z,1579229145.387 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:45.799Z,1579229145.799 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:45.799Z,1579229145.799 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:46.209Z,1579229146.209 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:46.209Z,1579229146.209 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:46.591Z,1579229146.591 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:46.591Z,1579229146.591 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:46.996Z,1579229146.996 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:46.996Z,1579229146.996 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:47.416Z,1579229147.416 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:47.416Z,1579229147.416 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:47.818Z,1579229147.818 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:47.818Z,1579229147.818 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:48.236Z,1579229148.236 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:48.236Z,1579229148.236 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:48.610Z,1579229148.610 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:48.610Z,1579229148.610 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:49.015Z,1579229149.015 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:49.016Z,1579229149.016 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:49.435Z,1579229149.435 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:49.435Z,1579229149.435 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:49.860Z,1579229149.860 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:49.860Z,1579229149.860 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:50.239Z,1579229150.239 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:50.239Z,1579229150.239 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:50.633Z,1579229150.633 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:50.633Z,1579229150.633 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:51.036Z,1579229151.036 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:51.036Z,1579229151.036 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:51.436Z,1579229151.436 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:51.436Z,1579229151.436 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:51.861Z,1579229151.861 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:51.861Z,1579229151.861 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:52.247Z,1579229152.247 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:52.247Z,1579229152.247 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:52.668Z,1579229152.668 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:52.668Z,1579229152.668 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:53.096Z,1579229153.096 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:53.097Z,1579229153.097 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:53.471Z,1579229153.471 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:53.471Z,1579229153.471 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:53.867Z,1579229153.867 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:53.867Z,1579229153.867 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:54.257Z,1579229154.257 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:54.257Z,1579229154.257 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:54.670Z,1579229154.670 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:54.670Z,1579229154.670 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:55.084Z,1579229155.084 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:55.085Z,1579229155.085 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:55.495Z,1579229155.495 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:55.495Z,1579229155.495 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:55.916Z,1579229155.916 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:55.917Z,1579229155.917 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:56.299Z,1579229156.299 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:56.299Z,1579229156.299 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:56.699Z,1579229156.699 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:56.699Z,1579229156.699 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:57.111Z,1579229157.111 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:57.111Z,1579229157.111 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:57.489Z,1579229157.489 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:57.489Z,1579229157.489 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:57.901Z,1579229157.901 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:57.901Z,1579229157.901 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:58.316Z,1579229158.316 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:58.316Z,1579229158.316 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:58.745Z,1579229158.745 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:58.745Z,1579229158.745 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:59.143Z,1579229159.143 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:59.143Z,1579229159.143 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:59.535Z,1579229159.535 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:59.535Z,1579229159.535 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:45:59.933Z,1579229159.933 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:45:59.933Z,1579229159.933 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:00.329Z,1579229160.329 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:00.330Z,1579229160.330 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:00.769Z,1579229160.769 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:00.769Z,1579229160.769 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:01.142Z,1579229161.142 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:01.142Z,1579229161.142 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:01.585Z,1579229161.585 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:01.585Z,1579229161.585 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:01.960Z,1579229161.960 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:01.965Z,1579229161.965 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:02.370Z,1579229162.370 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:02.371Z,1579229162.371 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:02.817Z,1579229162.817 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:02.818Z,1579229162.818 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:02.819Z,1579229162.819 [Docked:B.](INFO): *** querying acoustic contact *** 2020-01-17T02:46:03.160Z,1579229163.160 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:03.160Z,1579229163.160 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:03.523Z,1579229163.523 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77 2020-01-17T02:46:03.632Z,1579229163.632 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:03.632Z,1579229163.632 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:03.937Z,1579229163.937 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A 2020-01-17T02:46:04.034Z,1579229164.034 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:04.035Z,1579229164.035 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:04.400Z,1579229164.400 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:04.400Z,1579229164.400 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:04.740Z,1579229164.740 [Micromodem](INFO): Nmea in: $SNTTA,0.325578,,,,024603.63*48 2020-01-17T02:46:04.833Z,1579229164.833 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:04.834Z,1579229164.834 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:05.187Z,1579229165.187 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:05.188Z,1579229165.188 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:05.587Z,1579229165.587 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:05.588Z,1579229165.588 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:05.996Z,1579229165.996 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:05.996Z,1579229165.996 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:06.403Z,1579229166.403 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:06.403Z,1579229166.403 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:06.816Z,1579229166.816 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:06.816Z,1579229166.816 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:07.170Z,1579229167.170 [DUSBL_Hydroid](ERROR): No response from remote modem. 2020-01-17T02:46:07.232Z,1579229167.232 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:07.232Z,1579229167.232 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:07.603Z,1579229167.603 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:07.603Z,1579229167.603 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:08.015Z,1579229168.015 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:08.016Z,1579229168.016 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:08.435Z,1579229168.435 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:08.435Z,1579229168.435 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:08.841Z,1579229168.841 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:08.842Z,1579229168.842 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:09.235Z,1579229169.235 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:09.235Z,1579229169.235 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:09.772Z,1579229169.772 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:09.772Z,1579229169.772 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:10.129Z,1579229170.129 [Docked:G.Wait](INFO): Done Waiting. 2020-01-17T02:46:10.129Z,1579229170.129 [Docked:G.Wait] Stopped 2020-01-17T02:46:10.129Z,1579229170.129 [Docked:G.Wait](DEBUG): Uninitialize Wait Component. 2020-01-17T02:46:10.129Z,1579229170.129 [Docked:E.Docked](INFO): runIfUnsatisfied 2020-01-17T02:46:10.130Z,1579229170.130 [Docked:E.Docked](INFO): Resetting timers 2020-01-17T02:46:10.131Z,1579229170.131 [Docked](INFO): Completed Docked 2020-01-17T02:46:10.131Z,1579229170.131 [MissionManager](INFO): Docked is completed. 2020-01-17T02:46:10.132Z,1579229170.132 [MissionManager](INFO): Uninitializing Mission Docked 2020-01-17T02:46:10.132Z,1579229170.132 [Docked] Stopped 2020-01-17T02:46:10.132Z,1579229170.132 [Docked](DEBUG): Aggregate::uninitialize Docked 2020-01-17T02:46:10.132Z,1579229170.132 [Docked:A.DepthEnvelope] Stopped 2020-01-17T02:46:10.132Z,1579229170.132 [Docked:A.DepthEnvelope](DEBUG): Uninitialize. 2020-01-17T02:46:10.132Z,1579229170.132 [Docked:B.] Stopped 2020-01-17T02:46:10.132Z,1579229170.132 [Docked:C] Stopped 2020-01-17T02:46:10.132Z,1579229170.132 [Docked:D] Stopped 2020-01-17T02:46:10.132Z,1579229170.132 [Docked:E.Docked] Stopped 2020-01-17T02:46:10.515Z,1579229170.515 [MissionManager](IMPORTANT): Started mission Default 2020-01-17T02:46:10.515Z,1579229170.515 [Default] Running Loop=1 2020-01-17T02:46:10.515Z,1579229170.515 [Default](DEBUG): Aggregate::initialize Default 2020-01-17T02:46:10.515Z,1579229170.515 [Default:B.GoToSurface] Running Loop=1 2020-01-17T02:46:10.516Z,1579229170.516 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-17T02:46:10.516Z,1579229170.516 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-17T02:46:10.516Z,1579229170.516 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-17T02:46:10.516Z,1579229170.516 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-17T02:46:10.517Z,1579229170.517 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-17T02:46:10.517Z,1579229170.517 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-17T02:46:10.518Z,1579229170.518 [Default:A.Wait] Running Loop=1 2020-01-17T02:46:10.518Z,1579229170.518 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-01-17T02:46:23.860Z,1579229183.860 [Default:A.Wait](INFO): Done Waiting. 2020-01-17T02:46:23.860Z,1579229183.860 [Default:A.Wait] Stopped 2020-01-17T02:46:23.860Z,1579229183.860 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-01-17T02:46:24.247Z,1579229184.247 [Default:CheckIn] Running Loop=1 2020-01-17T02:46:24.247Z,1579229184.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-17T02:46:24.247Z,1579229184.247 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-17T02:46:30.687Z,1579229190.687 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-01-17T02:46:30.687Z,1579229190.687 [NAL9602] Data Fault, FailCount= 1 2020-01-17T02:46:30.687Z,1579229190.687 [NAL9602](ERROR): Data Fault 2020-01-17T02:46:30.798Z,1579229190.798 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-17T02:46:31.087Z,1579229191.087 [NAL9602](INFO): Powering down 2020-01-17T02:46:31.973Z,1579229191.973 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-17T02:46:31.973Z,1579229191.973 [NAL9602] No Fault, FailCount= 1 2020-01-17T02:46:35.162Z,1579229195.162 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-17T02:46:35.162Z,1579229195.162 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200116195835390.1, 0.0,1448.9, 0 2020-01-17T02:47:01.383Z,1579229221.383 [NAL9602](INFO): Powering up NAL9602 2020-01-17T02:47:12.291Z,1579229232.291 [NAL9602](INFO): NAL9602 initialized 2020-01-17T02:47:53.944Z,1579229273.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-01-17T02:47:53.944Z,1579229273.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:47:53.954Z,1579229273.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:47:54.350Z,1579229274.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:47:54.350Z,1579229274.350 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-01-17T02:49:51.909Z,1579229391.909 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-17T02:49:51.909Z,1579229391.909 [RDI_Pathfinder](ERROR): Failed to parse: :TS7 2020-01-17T02:50:54.941Z,1579229454.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-01-17T02:50:54.941Z,1579229454.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:50:54.951Z,1579229454.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:50:55.350Z,1579229455.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:50:55.351Z,1579229455.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-01-17T02:51:24.431Z,1579229484.431 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-17T02:46:24.2Z 2020-01-17T02:51:24.431Z,1579229484.431 [Default:CheckIn:Read_GPS] Stopped 2020-01-17T02:51:24.432Z,1579229484.432 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-17T02:51:24.858Z,1579229484.858 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-01-17T02:51:25.697Z,1579229485.697 [DataOverHttps](INFO): Sending 3084 bytes from file Logs/20200117T023540/Courier0004.lzma 2020-01-17T02:51:27.662Z,1579229487.662 [DataOverHttps](INFO): Moved sent file to Logs/20200117T023540/Courier0004.lzma.bak 2020-01-17T02:51:27.662Z,1579229487.662 [DataOverHttps](INFO): SBD MOMSN=12196495 2020-01-17T02:51:47.681Z,1579229507.681 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20200117T023540/Express0005.lzma 2020-01-17T02:51:49.690Z,1579229509.690 [DataOverHttps](INFO): Moved sent file to Logs/20200117T023540/Express0005.lzma.bak 2020-01-17T02:51:49.695Z,1579229509.695 [DataOverHttps](INFO): SBD MOMSN=12196497 2020-01-17T02:51:50.900Z,1579229510.900 [Default:CheckIn:Read_Iridium] Stopped 2020-01-17T02:51:50.901Z,1579229510.901 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-17T02:51:50.901Z,1579229510.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-17T02:53:56.134Z,1579229636.134 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-01-17T02:53:56.134Z,1579229636.134 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:53:56.144Z,1579229636.144 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:53:56.538Z,1579229636.538 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:53:56.538Z,1579229636.538 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-01-17T02:55:33.082Z,1579229733.082 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2020-01-17T02:56:51.460Z,1579229811.460 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-17T02:56:51.460Z,1579229811.460 [Default:CheckIn:C.Wait] Stopped 2020-01-17T02:56:51.460Z,1579229811.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-17T02:56:51.460Z,1579229811.460 [Default:CheckIn:D] Running Loop=1 2020-01-17T02:56:51.880Z,1579229811.880 [Default:CheckIn:D] Stopped 2020-01-17T02:56:51.880Z,1579229811.880 [Default:CheckIn:E] Running Loop=1 2020-01-17T02:56:52.286Z,1579229812.286 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.689412 min 2020-01-17T02:56:52.286Z,1579229812.286 [Default:CheckIn:E] Stopped 2020-01-17T02:56:52.286Z,1579229812.286 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-17T02:56:52.287Z,1579229812.287 [Default:CheckIn] Stopped 2020-01-17T02:56:52.287Z,1579229812.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-17T02:56:52.287Z,1579229812.287 [Default:CheckIn](INFO): Running loop #2 2020-01-17T02:56:52.287Z,1579229812.287 [Default:CheckIn] Running Loop=2 2020-01-17T02:56:52.287Z,1579229812.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-17T02:56:52.287Z,1579229812.287 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-17T02:56:57.146Z,1579229817.146 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-01-17T02:56:57.146Z,1579229817.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:56:57.164Z,1579229817.164 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:56:57.522Z,1579229817.522 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:56:57.522Z,1579229817.522 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-01-17T02:57:14.052Z,1579229834.052 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-01-17T02:57:14.052Z,1579229834.052 [NAL9602] Data Fault, FailCount= 2 2020-01-17T02:57:14.052Z,1579229834.052 [NAL9602](ERROR): Data Fault 2020-01-17T02:57:14.181Z,1579229834.181 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-17T02:57:14.467Z,1579229834.467 [NAL9602](INFO): Powering down 2020-01-17T02:57:15.327Z,1579229835.327 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-17T02:57:15.327Z,1579229835.327 [NAL9602] No Fault, FailCount= 2 2020-01-17T02:57:44.766Z,1579229864.766 [NAL9602](INFO): Powering up NAL9602 2020-01-17T02:57:55.668Z,1579229875.668 [NAL9602](INFO): NAL9602 initialized 2020-01-17T02:58:24.792Z,1579229904.792 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-17T02:58:24.792Z,1579229904.792 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-2768,-32768,V 2020-01-17T02:59:58.135Z,1579229998.135 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-01-17T02:59:58.135Z,1579229998.135 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-17T02:59:58.145Z,1579229998.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-17T02:59:58.525Z,1579229998.525 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-17T02:59:58.525Z,1579229998.525 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-01-17T03:00:59.110Z,1579230059.110 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-17T03:00:59.110Z,1579230059.110 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20011620125939,35.0, -0.1, 0.0,1448.9A, 0.00, 0.00, 0.00, 0.00, 0.00 2020-01-17T03:01:51.827Z,1579230111.827 [CommandLine](IMPORTANT): got command restart application 2020-01-17T03:01:52.459Z,1579230112.459 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-17T02:56:52.3Z 2020-01-17T03:01:52.459Z,1579230112.459 [Default:CheckIn:Read_GPS] Stopped 2020-01-17T03:01:52.459Z,1579230112.459 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-17T03:01:52.833Z,1579230112.833 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2020-01-17T03:01:52.834Z,1579230112.834 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:52.834Z,1579230112.834 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:52.925Z,1579230112.925 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-01-17T03:01:52.925Z,1579230112.925 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:52.929Z,1579230112.929 [CommandLine](INFO): Join timeout helper Thread ID is 7856 2020-01-17T03:01:52.943Z,1579230112.943 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-01-17T03:01:52.943Z,1579230112.943 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:52.943Z,1579230112.943 [NavChartDb](INFO): Join timeout helper Thread ID is 7857 2020-01-17T03:01:53.178Z,1579230113.178 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:53.178Z,1579230113.178 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.197Z,1579230113.197 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-01-17T03:01:53.197Z,1579230113.197 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.197Z,1579230113.197 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7858 2020-01-17T03:01:53.341Z,1579230113.341 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:53.341Z,1579230113.341 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-17T03:01:53.342Z,1579230113.342 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.361Z,1579230113.361 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2020-01-17T03:01:53.361Z,1579230113.361 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.361Z,1579230113.361 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7859 2020-01-17T03:01:53.521Z,1579230113.521 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:53.521Z,1579230113.521 [CTD_NeilBrown](INFO): Powering down 2020-01-17T03:01:53.533Z,1579230113.533 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.549Z,1579230113.549 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-01-17T03:01:53.549Z,1579230113.549 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.549Z,1579230113.549 [Radio_Surface](INFO): Join timeout helper Thread ID is 7860 2020-01-17T03:01:53.581Z,1579230113.581 [Radio_Surface](INFO): Powering down 2020-01-17T03:01:53.582Z,1579230113.582 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:53.582Z,1579230113.582 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.598Z,1579230113.598 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-01-17T03:01:53.598Z,1579230113.598 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.598Z,1579230113.598 [DataOverHttps](INFO): Join timeout helper Thread ID is 7861 2020-01-17T03:01:53.738Z,1579230113.738 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-01-17T03:01:53.741Z,1579230113.741 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:53.741Z,1579230113.741 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.761Z,1579230113.761 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-01-17T03:01:53.762Z,1579230113.762 [logger ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.762Z,1579230113.762 [logger](INFO): Join timeout helper Thread ID is 7862 2020-01-17T03:01:53.773Z,1579230113.773 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:53.773Z,1579230113.773 [logger ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.781Z,1579230113.781 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-01-17T03:01:53.782Z,1579230113.782 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.782Z,1579230113.782 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-01-17T03:01:53.782Z,1579230113.782 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:53.782Z,1579230113.782 [controlThread](INFO): Join timeout helper Thread ID is 7863 2020-01-17T03:01:54.017Z,1579230114.017 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-17T03:01:54.017Z,1579230114.017 [controlThread](DEBUG): Uninitializing ControlThread 2020-01-17T03:01:54.018Z,1579230114.018 [AHRS_M2](INFO): Powering down 2020-01-17T03:01:54.089Z,1579230114.089 [DDM](INFO): Powering down 2020-01-17T03:01:54.182Z,1579230114.182 [DUSBL_Hydroid](INFO): Powering down 2020-01-17T03:01:54.253Z,1579230114.253 [Micromodem](INFO): Powering down 2020-01-17T03:01:54.349Z,1579230114.349 [NAL9602](INFO): Powering down 2020-01-17T03:01:54.421Z,1579230114.421 [RDI_Pathfinder](INFO): Powering down 2020-01-17T03:01:54.422Z,1579230114.422 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-01-17T03:01:54.423Z,1579230114.423 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-01-17T03:01:54.424Z,1579230114.424 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-01-17T03:01:54.424Z,1579230114.424 [MissionManager](INFO): Uninitializing Mission Default 2020-01-17T03:01:54.424Z,1579230114.424 [Default] Stopped 2020-01-17T03:01:54.424Z,1579230114.424 [Default](DEBUG): Aggregate::uninitialize Default 2020-01-17T03:01:54.424Z,1579230114.424 [Default:B.GoToSurface] Stopped 2020-01-17T03:01:54.425Z,1579230114.425 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-17T03:01:54.425Z,1579230114.425 [Default:CheckIn] Stopped 2020-01-17T03:01:54.425Z,1579230114.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-17T03:01:54.425Z,1579230114.425 [Default:CheckIn:Read_Iridium] Stopped 2020-01-17T03:01:54.427Z,1579230114.427 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-01-17T03:01:54.427Z,1579230114.427 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-01-17T03:01:54.428Z,1579230114.428 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-01-17T03:01:54.428Z,1579230114.428 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-01-17T03:01:54.428Z,1579230114.428 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-01-17T03:01:54.428Z,1579230114.428 [BuoyancyServo](INFO): Powering down 2020-01-17T03:01:54.441Z,1579230114.441 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-01-17T03:01:54.441Z,1579230114.441 [ElevatorServo](INFO): Powering down 2020-01-17T03:01:54.442Z,1579230114.442 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-01-17T03:01:54.442Z,1579230114.442 [MassServo](INFO): Powering down 2020-01-17T03:01:54.443Z,1579230114.443 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-17T03:01:54.443Z,1579230114.443 [RudderServo](INFO): Powering down 2020-01-17T03:01:54.444Z,1579230114.444 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-01-17T03:01:54.444Z,1579230114.444 [ThrusterServo](INFO): Powering down 2020-01-17T03:01:54.444Z,1579230114.444 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-01-17T03:01:54.445Z,1579230114.445 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-01-17T03:01:54.445Z,1579230114.445 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-01-17T03:01:54.445Z,1579230114.445 [CBIT](DEBUG): Powering off loads. 2020-01-17T03:01:54.457Z,1579230114.457 [CBIT](DEBUG): Disabling WDT. 2020-01-17T03:01:54.469Z,1579230114.469 [CBIT](DEBUG): Opening all GF detection circuits. 2020-01-17T03:01:54.470Z,1579230114.470 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:54.518Z,1579230114.518 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:54.528Z,1579230114.528 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:54.577Z,1579230114.577 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:54.580Z,1579230114.580 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:54.654Z,1579230114.654 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-17T03:01:54.713Z,1579230114.713 [logger ThreadHandler](INFO): Thread cancelled.