2019-02-08T07:17:17.946Z,1549610237.946 [Supervisor](DEBUG): Initializing supervisor.
2019-02-08T07:17:17.949Z,1549610237.949 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-08T07:17:17.949Z,1549610237.949 [SyncHandler](INFO): Protected caller Thread ID is 9852
2019-02-08T07:17:17.950Z,1549610237.950 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-08T07:17:17.951Z,1549610237.951 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-08T07:17:17.951Z,1549610237.951 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9853
2019-02-08T07:17:17.954Z,1549610237.954 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-08T07:17:17.966Z,1549610237.966 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-08T07:17:17.967Z,1549610237.967 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-08T07:17:17.967Z,1549610237.967 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9854
2019-02-08T07:17:17.968Z,1549610237.968 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-08T07:17:17.969Z,1549610237.969 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-08T07:17:17.970Z,1549610237.970 [logger ThreadHandler](INFO): Protected caller Thread ID is 9855
2019-02-08T07:17:17.971Z,1549610237.971 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-08T07:17:17.972Z,1549610237.972 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-08T07:17:17.974Z,1549610237.974 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-08T07:17:18.067Z,1549610238.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-08T07:17:18.068Z,1549610238.068 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-08T07:17:18.264Z,1549610238.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-08T07:17:18.265Z,1549610238.265 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-08T07:17:18.399Z,1549610238.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-08T07:17:18.400Z,1549610238.400 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-08T07:17:18.861Z,1549610238.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-08T07:17:18.862Z,1549610238.862 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-08T07:17:19.300Z,1549610239.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-08T07:17:19.300Z,1549610239.300 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-08T07:17:19.775Z,1549610239.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-08T07:17:19.776Z,1549610239.776 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-08T07:17:20.084Z,1549610240.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-08T07:17:20.085Z,1549610240.085 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-08T07:17:20.432Z,1549610240.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-08T07:17:20.433Z,1549610240.433 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-08T07:17:20.822Z,1549610240.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-08T07:17:20.822Z,1549610240.822 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-08T07:17:20.971Z,1549610240.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-08T07:17:20.971Z,1549610240.971 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-08T07:17:21.077Z,1549610241.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-08T07:17:21.078Z,1549610241.078 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-08T07:17:21.157Z,1549610241.157 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-08T07:17:21.258Z,1549610241.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-08T07:17:21.258Z,1549610241.258 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-08T07:17:21.456Z,1549610241.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-08T07:17:21.456Z,1549610241.456 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-08T07:17:21.662Z,1549610241.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-08T07:17:21.664Z,1549610241.664 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-02-08T07:17:21.665Z,1549610241.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-02-08T07:17:21.746Z,1549610241.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-02-08T07:17:22.052Z,1549610242.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-08T07:17:22.053Z,1549610242.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-02-08T07:17:22.150Z,1549610242.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-02-08T07:17:22.309Z,1549610242.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-02-08T07:17:22.513Z,1549610242.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-02-08T07:17:22.597Z,1549610242.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-02-08T07:17:22.722Z,1549610242.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-02-08T07:17:22.918Z,1549610242.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-02-08T07:17:23.293Z,1549610243.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-02-08T07:17:23.443Z,1549610243.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-02-08T07:17:23.635Z,1549610243.635 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-08T07:17:23.637Z,1549610243.637 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-08T07:17:23.744Z,1549610243.744 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-08T07:17:23.744Z,1549610243.744 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-08T07:17:23.853Z,1549610243.853 [BuoyancyServo] Loaded
2019-02-08T07:17:23.854Z,1549610243.854 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-08T07:17:23.869Z,1549610243.869 [ElevatorServo] Loaded
2019-02-08T07:17:23.869Z,1549610243.869 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-08T07:17:23.884Z,1549610243.884 [MassServo] Loaded
2019-02-08T07:17:23.885Z,1549610243.885 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-08T07:17:23.900Z,1549610243.900 [RudderServo] Loaded
2019-02-08T07:17:23.900Z,1549610243.900 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-08T07:17:23.915Z,1549610243.915 [ThrusterServo] Loaded
2019-02-08T07:17:23.915Z,1549610243.915 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-08T07:17:23.916Z,1549610243.916 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-08T07:17:23.916Z,1549610243.916 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-08T07:17:24.059Z,1549610244.059 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-08T07:17:24.088Z,1549610244.088 [SBIT] Loaded
2019-02-08T07:17:24.089Z,1549610244.089 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-08T07:17:24.089Z,1549610244.089 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-08T07:17:24.118Z,1549610244.118 [IBIT] Loaded
2019-02-08T07:17:24.118Z,1549610244.118 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-08T07:17:24.121Z,1549610244.121 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-08T07:17:24.520Z,1549610244.520 [CBIT] Loaded
2019-02-08T07:17:24.520Z,1549610244.520 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-08T07:17:24.520Z,1549610244.520 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-08T07:17:24.521Z,1549610244.521 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-08T07:17:24.710Z,1549610244.710 [Aanderaa_O2] Loaded
2019-02-08T07:17:24.710Z,1549610244.710 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-08T07:17:24.720Z,1549610244.720 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-08T07:17:24.725Z,1549610244.725 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-08T07:17:24.727Z,1549610244.727 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-08T07:17:24.732Z,1549610244.732 [CTD_Seabird](INFO): created writer for : depth
2019-02-08T07:17:24.733Z,1549610244.733 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-08T07:17:24.738Z,1549610244.738 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-08T07:17:24.739Z,1549610244.739 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-08T07:17:24.744Z,1549610244.744 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-08T07:17:24.745Z,1549610244.745 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-08T07:17:24.750Z,1549610244.750 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-08T07:17:24.751Z,1549610244.751 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-08T07:17:24.757Z,1549610244.757 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-08T07:17:24.757Z,1549610244.757 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-08T07:17:24.763Z,1549610244.763 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-08T07:17:24.790Z,1549610244.790 [CTD_Seabird] Loaded
2019-02-08T07:17:24.790Z,1549610244.790 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-08T07:17:24.792Z,1549610244.792 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062E4E0
2019-02-08T07:17:24.792Z,1549610244.792 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9935
2019-02-08T07:17:24.814Z,1549610244.814 [ESPComponent] Loaded
2019-02-08T07:17:24.814Z,1549610244.814 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-02-08T07:17:24.828Z,1549610244.828 [PAR_Licor] Loaded
2019-02-08T07:17:24.828Z,1549610244.828 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-08T07:17:24.878Z,1549610244.878 [WetLabsBB2FL] Loaded
2019-02-08T07:17:24.878Z,1549610244.878 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-08T07:17:24.879Z,1549610244.879 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065E4E0
2019-02-08T07:17:24.879Z,1549610244.879 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9936
2019-02-08T07:17:24.880Z,1549610244.880 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-08T07:17:24.881Z,1549610244.881 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-08T07:17:24.925Z,1549610244.925 [DepthRateCalculator] Loaded
2019-02-08T07:17:24.925Z,1549610244.925 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-08T07:17:24.931Z,1549610244.931 [PitchRateCalculator] Loaded
2019-02-08T07:17:24.931Z,1549610244.931 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-08T07:17:24.947Z,1549610244.947 [SpeedCalculator] Loaded
2019-02-08T07:17:24.947Z,1549610244.947 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-08T07:17:24.969Z,1549610244.969 [TempGradientCalculator] Loaded
2019-02-08T07:17:24.969Z,1549610244.969 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-08T07:17:24.975Z,1549610244.975 [YawRateCalculator] Loaded
2019-02-08T07:17:24.975Z,1549610244.975 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-08T07:17:25.010Z,1549610245.010 [ElevatorOffsetCalculator] Loaded
2019-02-08T07:17:25.010Z,1549610245.010 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-08T07:17:25.010Z,1549610245.010 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-08T07:17:25.011Z,1549610245.011 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-08T07:17:25.040Z,1549610245.040 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-08T07:17:25.041Z,1549610245.041 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-08T07:17:25.146Z,1549610245.146 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-08T07:17:25.147Z,1549610245.147 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-08T07:17:25.208Z,1549610245.208 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-08T07:17:25.208Z,1549610245.208 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-08T07:17:25.270Z,1549610245.270 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-08T07:17:25.270Z,1549610245.270 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-08T07:17:25.286Z,1549610245.286 [NavChart] Loaded
2019-02-08T07:17:25.287Z,1549610245.287 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-08T07:17:25.291Z,1549610245.291 [UniversalFixResidualReporter] Loaded
2019-02-08T07:17:25.291Z,1549610245.291 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-08T07:17:25.291Z,1549610245.291 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-08T07:17:25.292Z,1549610245.292 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-08T07:17:25.591Z,1549610245.591 [AcousticModem_Benthos_ATM900] Loaded
2019-02-08T07:17:25.591Z,1549610245.591 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-02-08T07:17:25.660Z,1549610245.660 [DataOverHttps] Loaded
2019-02-08T07:17:25.660Z,1549610245.660 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-08T07:17:25.673Z,1549610245.673 [Depth_Keller] Loaded
2019-02-08T07:17:25.673Z,1549610245.673 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-08T07:17:25.678Z,1549610245.678 [DropWeight] Loaded
2019-02-08T07:17:25.679Z,1549610245.679 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-08T07:17:25.772Z,1549610245.772 [NAL9602] Loaded
2019-02-08T07:17:25.772Z,1549610245.772 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-08T07:17:25.777Z,1549610245.777 [Onboard] Loaded
2019-02-08T07:17:25.778Z,1549610245.778 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-08T07:17:25.785Z,1549610245.785 [Radio_Surface] Loaded
2019-02-08T07:17:25.785Z,1549610245.785 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-08T07:17:25.786Z,1549610245.786 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409254E0
2019-02-08T07:17:25.786Z,1549610245.786 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9937
2019-02-08T07:17:25.834Z,1549610245.834 [PNI_TCM] Loaded
2019-02-08T07:17:25.835Z,1549610245.835 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-08T07:17:25.897Z,1549610245.897 [Rowe_600LCM] Loaded
2019-02-08T07:17:25.897Z,1549610245.897 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-08T07:17:25.898Z,1549610245.898 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409554E0
2019-02-08T07:17:25.899Z,1549610245.899 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 9938
2019-02-08T07:17:27.530Z,1549610247.530 [BPC1] Loaded
2019-02-08T07:17:27.531Z,1549610247.531 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-08T07:17:27.531Z,1549610247.531 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-08T07:17:27.531Z,1549610247.531 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-08T07:17:27.602Z,1549610247.602 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-08T07:17:27.603Z,1549610247.603 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-08T07:17:27.674Z,1549610247.674 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-08T07:17:27.759Z,1549610247.759 [VerticalControl] Loaded
2019-02-08T07:17:27.759Z,1549610247.759 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-08T07:17:27.760Z,1549610247.760 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-08T07:17:27.821Z,1549610247.821 [HorizontalControl] Loaded
2019-02-08T07:17:27.821Z,1549610247.821 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-08T07:17:27.821Z,1549610247.821 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-08T07:17:27.823Z,1549610247.823 [SpeedControl] Loaded
2019-02-08T07:17:27.823Z,1549610247.823 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-08T07:17:27.824Z,1549610247.824 [LoopControl](DEBUG): Construct LoopControl.
2019-02-08T07:17:27.825Z,1549610247.825 [LoopControl] Loaded
2019-02-08T07:17:27.825Z,1549610247.825 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-08T07:17:27.825Z,1549610247.825 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-08T07:17:27.826Z,1549610247.826 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-08T07:17:27.872Z,1549610247.872 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-08T07:17:27.873Z,1549610247.873 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-08T07:17:28.189Z,1549610248.189 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-08T07:17:28.193Z,1549610248.193 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-08T07:17:28.194Z,1549610248.194 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-08T07:17:28.201Z,1549610248.201 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-08T07:17:28.202Z,1549610248.202 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2019-02-08T07:17:28.202Z,1549610248.202 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9939
2019-02-08T07:17:28.207Z,1549610248.207 [Supervisor](INFO): Main Thread ID is 9851
2019-02-08T07:17:28.207Z,1549610248.207 [Supervisor](DEBUG): Running supervisor.
2019-02-08T07:17:28.208Z,1549610248.208 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9940
2019-02-08T07:17:28.211Z,1549610248.211 [controlThread ThreadHandler](INFO): Handler Thread ID is 9941
2019-02-08T07:17:28.211Z,1549610248.211 [controlThread](DEBUG): Initializing ControlThread
2019-02-08T07:17:28.213Z,1549610248.213 [SBIT](INFO): Initialize SBIT Component.
2019-02-08T07:17:28.213Z,1549610248.213 [SBIT](IMPORTANT): git: 2019-01-14A-57-g72ba51e
2019-02-08T07:17:28.214Z,1549610248.214 [SBIT](INFO): git hash: 72ba51edeec12b341e49a5bae224e2abbf93cde2
2019-02-08T07:17:28.214Z,1549610248.214 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-08T07:17:28.215Z,1549610248.215 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-08T07:17:28.216Z,1549610248.216 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-02-08T07:17:28.217Z,1549610248.217 [IBIT](INFO): Initialize IBIT Component.
2019-02-08T07:17:28.218Z,1549610248.218 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-08T07:17:28.219Z,1549610248.219 [logger ThreadHandler](INFO): Handler Thread ID is 9942
2019-02-08T07:17:28.232Z,1549610248.232 [CBIT](DEBUG): Initialized mux pins.
2019-02-08T07:17:28.232Z,1549610248.232 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-08T07:17:28.241Z,1549610248.241 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9943
2019-02-08T07:17:28.241Z,1549610248.241 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-08T07:17:28.245Z,1549610248.245 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-08T07:17:28.246Z,1549610248.246 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9945
2019-02-08T07:17:28.247Z,1549610248.247 [WetLabsBB2FL](INFO): Powering down
2019-02-08T07:17:28.260Z,1549610248.260 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-02-08T07:17:28.260Z,1549610248.260 [CBIT](DEBUG): Initializing heartbeat.
2019-02-08T07:17:28.293Z,1549610248.293 [logger ThreadHandler](INFO): Cycle time: 0.073160, Period: 0.050000, Run time: -1549610248.218983, Sleep time: -0.023144, Sleep: 0.000000
2019-02-08T07:17:28.300Z,1549610248.300 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9946
2019-02-08T07:17:28.306Z,1549610248.306 [Radio_Surface](INFO): Powering up
2019-02-08T07:17:28.324Z,1549610248.324 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 9947
2019-02-08T07:17:28.325Z,1549610248.325 [Rowe_600LCM](INFO): Initializing
2019-02-08T07:17:28.332Z,1549610248.332 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-08T07:17:28.332Z,1549610248.332 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-08T07:17:28.335Z,1549610248.335 [Rowe_600LCM](INFO): Powering up
2019-02-08T07:17:28.346Z,1549610248.346 [logger ThreadHandler](INFO): Cycle time: 0.051269, Period: 0.050000, Run time: -1549610248.293233, Sleep time: -0.001253, Sleep: 0.000000
2019-02-08T07:17:28.352Z,1549610248.352 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9948
2019-02-08T07:17:28.356Z,1549610248.356 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-08T07:17:28.356Z,1549610248.356 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-08T07:17:28.356Z,1549610248.356 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-08T07:17:28.356Z,1549610248.356 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-08T07:17:28.357Z,1549610248.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-08T07:17:28.357Z,1549610248.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-08T07:17:28.357Z,1549610248.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-08T07:17:28.357Z,1549610248.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-08T07:17:28.357Z,1549610248.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-08T07:17:28.358Z,1549610248.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-08T07:17:28.358Z,1549610248.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-08T07:17:28.358Z,1549610248.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-08T07:17:28.358Z,1549610248.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-08T07:17:28.358Z,1549610248.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-08T07:17:28.359Z,1549610248.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-08T07:17:28.359Z,1549610248.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-08T07:17:28.372Z,1549610248.372 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-08T07:17:28.373Z,1549610248.373 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-08T07:17:28.373Z,1549610248.373 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-08T07:17:28.373Z,1549610248.373 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-08T07:17:28.374Z,1549610248.374 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-08T07:17:28.374Z,1549610248.374 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-08T07:17:28.376Z,1549610248.376 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-08T07:17:28.377Z,1549610248.377 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-08T07:17:28.377Z,1549610248.377 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-08T07:17:28.398Z,1549610248.398 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-08T07:17:28.400Z,1549610248.400 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-08T07:17:28.401Z,1549610248.401 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-08T07:17:28.401Z,1549610248.401 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-08T07:17:28.402Z,1549610248.402 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-08T07:17:28.433Z,1549610248.433 [logger ThreadHandler](INFO): Cycle time: 0.086708, Period: 0.050000, Run time: -1549610248.345620, Sleep time: -0.036690, Sleep: 0.000000
2019-02-08T07:17:28.462Z,1549610248.462 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-08T07:17:28.506Z,1549610248.506 [logger ThreadHandler](INFO): Cycle time: 0.071639, Period: 0.050000, Run time: -1549610248.433452, Sleep time: -0.021623, Sleep: 0.000000
2019-02-08T07:17:28.511Z,1549610248.511 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-02-08T07:17:28.512Z,1549610248.512 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-08T07:17:28.562Z,1549610248.562 [logger ThreadHandler](INFO): Cycle time: 0.054415, Period: 0.050000, Run time: -1549610248.506280, Sleep time: -0.004400, Sleep: 0.000000
2019-02-08T07:17:28.631Z,1549610248.631 [logger ThreadHandler](INFO): Cycle time: 0.067689, Period: 0.050000, Run time: -1549610248.561833, Sleep time: -0.017673, Sleep: 0.000000
2019-02-08T07:17:28.636Z,1549610248.636 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-08T07:17:28.637Z,1549610248.637 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-08T07:17:28.639Z,1549610248.639 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-08T07:17:28.666Z,1549610248.666 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-08T07:17:28.684Z,1549610248.684 [logger ThreadHandler](INFO): Cycle time: 0.051881, Period: 0.050000, Run time: -1549610248.630609, Sleep time: -0.001866, Sleep: 0.000000
2019-02-08T07:17:28.710Z,1549610248.710 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-08T07:17:28.715Z,1549610248.715 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-08T07:17:28.747Z,1549610248.747 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-02-08T07:17:28.752Z,1549610248.752 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-08T07:17:28.779Z,1549610248.779 [logger ThreadHandler](INFO): Cycle time: 0.094658, Period: 0.050000, Run time: -1549610248.683656, Sleep time: -0.044643, Sleep: 0.000000
2019-02-08T07:17:28.830Z,1549610248.830 [ESPComponent](INFO): powering down ESP
2019-02-08T07:17:28.859Z,1549610248.859 [logger ThreadHandler](INFO): Cycle time: 0.078582, Period: 0.050000, Run time: -1549610248.779439, Sleep time: -0.028566, Sleep: 0.000000
2019-02-08T07:17:28.945Z,1549610248.945 [logger ThreadHandler](INFO): Cycle time: 0.085075, Period: 0.050000, Run time: -1549610248.859154, Sleep time: -0.035058, Sleep: 0.000000
2019-02-08T07:17:29.015Z,1549610249.015 [logger ThreadHandler](INFO): Cycle time: 0.067941, Period: 0.050000, Run time: -1549610248.945544, Sleep time: -0.017925, Sleep: 0.000000
2019-02-08T07:17:29.067Z,1549610249.067 [logger ThreadHandler](INFO): Cycle time: 0.051090, Period: 0.050000, Run time: -1549610249.014841, Sleep time: -0.001074, Sleep: 0.000000
2019-02-08T07:17:29.137Z,1549610249.137 [logger ThreadHandler](INFO): Cycle time: 0.069253, Period: 0.050000, Run time: -1549610249.067125, Sleep time: -0.019237, Sleep: 0.000000
2019-02-08T07:17:29.218Z,1549610249.218 [logger ThreadHandler](INFO): Cycle time: 0.078803, Period: 0.050000, Run time: -1549610249.137568, Sleep time: -0.028788, Sleep: 0.000000
2019-02-08T07:17:29.267Z,1549610249.267 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-02-08T07:17:29.267Z,1549610249.267 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-02-08T07:17:29.289Z,1549610249.289 [logger ThreadHandler](INFO): Cycle time: 0.070694, Period: 0.050000, Run time: -1549610249.217683, Sleep time: -0.020679, Sleep: 0.000000
2019-02-08T07:17:29.310Z,1549610249.310 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-08T07:17:29.351Z,1549610249.351 [logger ThreadHandler](INFO): Cycle time: 0.060084, Period: 0.050000, Run time: -1549610249.289605, Sleep time: -0.010068, Sleep: 0.000000
2019-02-08T07:17:29.431Z,1549610249.431 [logger ThreadHandler](INFO): Cycle time: 0.078663, Period: 0.050000, Run time: -1549610249.350799, Sleep time: -0.028648, Sleep: 0.000000
2019-02-08T07:17:29.497Z,1549610249.497 [logger ThreadHandler](INFO): Cycle time: 0.063835, Period: 0.050000, Run time: -1549610249.430620, Sleep time: -0.013819, Sleep: 0.000000
2019-02-08T07:17:29.556Z,1549610249.556 [logger ThreadHandler](INFO): Cycle time: 0.058146, Period: 0.050000, Run time: -1549610249.496773, Sleep time: -0.008130, Sleep: 0.000000
2019-02-08T07:17:29.642Z,1549610249.642 [logger ThreadHandler](INFO): Cycle time: 0.084629, Period: 0.050000, Run time: -1549610249.556309, Sleep time: -0.034614, Sleep: 0.000000
2019-02-08T07:17:29.707Z,1549610249.707 [logger ThreadHandler](INFO): Cycle time: 0.064053, Period: 0.050000, Run time: -1549610249.642021, Sleep time: -0.014036, Sleep: 0.000000
2019-02-08T07:17:29.759Z,1549610249.759 [logger ThreadHandler](INFO): Cycle time: 0.050618, Period: 0.050000, Run time: -1549610249.707149, Sleep time: -0.000603, Sleep: 0.000000
2019-02-08T07:17:29.835Z,1549610249.835 [logger ThreadHandler](INFO): Cycle time: 0.074875, Period: 0.050000, Run time: -1549610249.758941, Sleep time: -0.024861, Sleep: 0.000000
2019-02-08T07:17:29.894Z,1549610249.894 [logger ThreadHandler](INFO): Cycle time: 0.057996, Period: 0.050000, Run time: -1549610249.834909, Sleep time: -0.007979, Sleep: 0.000000
2019-02-08T07:17:29.955Z,1549610249.955 [logger ThreadHandler](INFO): Cycle time: 0.060240, Period: 0.050000, Run time: -1549610249.894223, Sleep time: -0.010226, Sleep: 0.000000
2019-02-08T07:17:29.960Z,1549610249.960 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-08T07:17:29.968Z,1549610249.968 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-08T07:17:30.000Z,1549610250.000 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-08T07:17:30.007Z,1549610250.007 [logger ThreadHandler](INFO): Cycle time: 0.050816, Period: 0.050000, Run time: -1549610249.955546, Sleep time: -0.000800, Sleep: 0.000000
2019-02-08T07:17:30.012Z,1549610250.012 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-08T07:17:30.039Z,1549610250.039 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-08T07:17:30.048Z,1549610250.048 [MassServo](DEBUG): Initializing MassServo.
2019-02-08T07:17:30.069Z,1549610250.069 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-08T07:17:30.071Z,1549610250.071 [logger ThreadHandler](INFO): Cycle time: 0.062403, Period: 0.050000, Run time: -1549610250.007443, Sleep time: -0.012387, Sleep: 0.000000
2019-02-08T07:17:30.080Z,1549610250.080 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-08T07:17:30.086Z,1549610250.086 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-08T07:17:30.096Z,1549610250.096 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-08T07:17:30.137Z,1549610250.137 [CommandLine](FAULT): Scheduling is paused
2019-02-08T07:17:30.137Z,1549610250.137 [CBIT](INFO): Critical error at 20190208T071728
2019-02-08T07:17:30.138Z,1549610250.138 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-08T07:17:30.151Z,1549610250.151 [logger ThreadHandler](INFO): Cycle time: 0.079282, Period: 0.050000, Run time: -1549610250.070957, Sleep time: -0.029268, Sleep: 0.000000
2019-02-08T07:17:30.194Z,1549610250.194 [controlThread ThreadHandler](INFO): Cycle time: 1.440461, Period: 0.400000, Run time: -1549610248.752481, Sleep time: -1.040444, Sleep: 0.000000
2019-02-08T07:17:30.214Z,1549610250.214 [logger ThreadHandler](INFO): Cycle time: 0.061796, Period: 0.050000, Run time: -1549610250.151356, Sleep time: -0.011781, Sleep: 0.000000
2019-02-08T07:17:30.290Z,1549610250.290 [logger ThreadHandler](INFO): Cycle time: 0.067734, Period: 0.050000, Run time: -1549610250.214273, Sleep time: -0.017660, Sleep: 0.000000
2019-02-08T07:17:30.362Z,1549610250.362 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-08T07:17:30.362Z,1549610250.362 [DropWeight] Hardware Fault, FailCount= 1
2019-02-08T07:17:30.362Z,1549610250.362 [DropWeight](ERROR): Hardware Fault
2019-02-08T07:17:30.366Z,1549610250.366 [logger ThreadHandler](INFO): Cycle time: 0.073032, Period: 0.050000, Run time: -1549610250.290298, Sleep time: -0.023017, Sleep: 0.000000
2019-02-08T07:17:30.426Z,1549610250.426 [logger ThreadHandler](INFO): Cycle time: 0.058959, Period: 0.050000, Run time: -1549610250.365626, Sleep time: -0.008943, Sleep: 0.000000
2019-02-08T07:17:30.492Z,1549610250.492 [logger ThreadHandler](INFO): Cycle time: 0.061809, Period: 0.050000, Run time: -1549610250.425709, Sleep time: -0.011733, Sleep: 0.000000
2019-02-08T07:17:30.565Z,1549610250.565 [logger ThreadHandler](INFO): Cycle time: 0.067417, Period: 0.050000, Run time: -1549610250.492807, Sleep time: -0.017345, Sleep: 0.000000
2019-02-08T07:17:30.626Z,1549610250.626 [logger ThreadHandler](INFO): Cycle time: 0.058850, Period: 0.050000, Run time: -1549610250.565763, Sleep time: -0.008834, Sleep: 0.000000
2019-02-08T07:17:30.682Z,1549610250.682 [logger ThreadHandler](INFO): Cycle time: 0.054966, Period: 0.050000, Run time: -1549610250.625719, Sleep time: -0.004951, Sleep: 0.000000
2019-02-08T07:17:30.750Z,1549610250.750 [CBIT](INFO): Critical error at 20190208T071730
2019-02-08T07:17:30.753Z,1549610250.753 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-08T07:17:30.753Z,1549610250.753 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-08T07:17:30.756Z,1549610250.756 [controlThread ThreadHandler](INFO): Cycle time: 0.560684, Period: 0.400000, Run time: -1549610250.194098, Sleep time: -0.160670, Sleep: 0.000000
2019-02-08T07:17:30.773Z,1549610250.773 [logger ThreadHandler](INFO): Cycle time: 0.090193, Period: 0.050000, Run time: -1549610250.681796, Sleep time: -0.040175, Sleep: 0.000000
2019-02-08T07:17:30.834Z,1549610250.834 [logger ThreadHandler](INFO): Cycle time: 0.059582, Period: 0.050000, Run time: -1549610250.773130, Sleep time: -0.009566, Sleep: 0.000000
2019-02-08T07:17:30.907Z,1549610250.907 [logger ThreadHandler](INFO): Cycle time: 0.071739, Period: 0.050000, Run time: -1549610250.833808, Sleep time: -0.021724, Sleep: 0.000000
2019-02-08T07:17:30.975Z,1549610250.975 [logger ThreadHandler](INFO): Cycle time: 0.066409, Period: 0.050000, Run time: -1549610250.907424, Sleep time: -0.016393, Sleep: 0.000000
2019-02-08T07:17:31.050Z,1549610251.050 [logger ThreadHandler](INFO): Cycle time: 0.074349, Period: 0.050000, Run time: -1549610250.974941, Sleep time: -0.024335, Sleep: 0.000000
2019-02-08T07:17:31.126Z,1549610251.126 [logger ThreadHandler](INFO): Cycle time: 0.074870, Period: 0.050000, Run time: -1549610251.050391, Sleep time: -0.024855, Sleep: 0.000000
2019-02-08T07:17:31.181Z,1549610251.181 [logger ThreadHandler](INFO): Cycle time: 0.052917, Period: 0.050000, Run time: -1549610251.126464, Sleep time: -0.002901, Sleep: 0.000000
2019-02-08T07:17:31.250Z,1549610251.250 [logger ThreadHandler](INFO): Cycle time: 0.067524, Period: 0.050000, Run time: -1549610251.181172, Sleep time: -0.017509, Sleep: 0.000000
2019-02-08T07:17:31.305Z,1549610251.305 [logger ThreadHandler](INFO): Cycle time: 0.054430, Period: 0.050000, Run time: -1549610251.249948, Sleep time: -0.004415, Sleep: 0.000000
2019-02-08T07:17:31.374Z,1549610251.374 [logger ThreadHandler](INFO): Cycle time: 0.067116, Period: 0.050000, Run time: -1549610251.305562, Sleep time: -0.017102, Sleep: 0.000000
2019-02-08T07:17:31.438Z,1549610251.438 [logger ThreadHandler](INFO): Cycle time: 0.063492, Period: 0.050000, Run time: -1549610251.373852, Sleep time: -0.013478, Sleep: 0.000000
2019-02-08T07:17:31.511Z,1549610251.511 [logger ThreadHandler](INFO): Cycle time: 0.071120, Period: 0.050000, Run time: -1549610251.438451, Sleep time: -0.021105, Sleep: 0.000000
2019-02-08T07:17:31.585Z,1549610251.585 [logger ThreadHandler](INFO): Cycle time: 0.073737, Period: 0.050000, Run time: -1549610251.510743, Sleep time: -0.023721, Sleep: 0.000000
2019-02-08T07:17:31.666Z,1549610251.666 [logger ThreadHandler](INFO): Cycle time: 0.079369, Period: 0.050000, Run time: -1549610251.585568, Sleep time: -0.029354, Sleep: 0.000000
2019-02-08T07:17:31.733Z,1549610251.733 [logger ThreadHandler](INFO): Cycle time: 0.066180, Period: 0.050000, Run time: -1549610251.666105, Sleep time: -0.016166, Sleep: 0.000000
2019-02-08T07:17:31.814Z,1549610251.814 [logger ThreadHandler](INFO): Cycle time: 0.080018, Period: 0.050000, Run time: -1549610251.733405, Sleep time: -0.030002, Sleep: 0.000000
2019-02-08T07:17:31.877Z,1549610251.877 [logger ThreadHandler](INFO): Cycle time: 0.061445, Period: 0.050000, Run time: -1549610251.814527, Sleep time: -0.011430, Sleep: 0.000000
2019-02-08T07:17:31.947Z,1549610251.947 [logger ThreadHandler](INFO): Cycle time: 0.068378, Period: 0.050000, Run time: -1549610251.877068, Sleep time: -0.018361, Sleep: 0.000000
2019-02-08T07:17:32.022Z,1549610252.022 [logger ThreadHandler](INFO): Cycle time: 0.073574, Period: 0.050000, Run time: -1549610251.947294, Sleep time: -0.023559, Sleep: 0.000000
2019-02-08T07:17:32.089Z,1549610252.089 [logger ThreadHandler](INFO): Cycle time: 0.066412, Period: 0.050000, Run time: -1549610252.021963, Sleep time: -0.016398, Sleep: 0.000000
2019-02-08T07:17:32.153Z,1549610252.153 [logger ThreadHandler](INFO): Cycle time: 0.062529, Period: 0.050000, Run time: -1549610252.089472, Sleep time: -0.012513, Sleep: 0.000000
2019-02-08T07:17:32.234Z,1549610252.234 [logger ThreadHandler](INFO): Cycle time: 0.079883, Period: 0.050000, Run time: -1549610252.153100, Sleep time: -0.029867, Sleep: 0.000000
2019-02-08T07:17:32.298Z,1549610252.298 [logger ThreadHandler](INFO): Cycle time: 0.063281, Period: 0.050000, Run time: -1549610252.234098, Sleep time: -0.013265, Sleep: 0.000000
2019-02-08T07:17:32.365Z,1549610252.365 [logger ThreadHandler](INFO): Cycle time: 0.065342, Period: 0.050000, Run time: -1549610252.298566, Sleep time: -0.015327, Sleep: 0.000000
2019-02-08T07:17:32.427Z,1549610252.427 [logger ThreadHandler](INFO): Cycle time: 0.061069, Period: 0.050000, Run time: -1549610252.365034, Sleep time: -0.011053, Sleep: 0.000000
2019-02-08T07:17:32.483Z,1549610252.483 [logger ThreadHandler](INFO): Cycle time: 0.054612, Period: 0.050000, Run time: -1549610252.427457, Sleep time: -0.004597, Sleep: 0.000000
2019-02-08T07:17:32.549Z,1549610252.549 [logger ThreadHandler](INFO): Cycle time: 0.064990, Period: 0.050000, Run time: -1549610252.483269, Sleep time: -0.014975, Sleep: 0.000000
2019-02-08T07:17:32.611Z,1549610252.611 [logger ThreadHandler](INFO): Cycle time: 0.060395, Period: 0.050000, Run time: -1549610252.549405, Sleep time: -0.010379, Sleep: 0.000000
2019-02-08T07:17:32.663Z,1549610252.663 [logger ThreadHandler](INFO): Cycle time: 0.050897, Period: 0.050000, Run time: -1549610252.611037, Sleep time: -0.000880, Sleep: 0.000000
2019-02-08T07:17:32.715Z,1549610252.715 [logger ThreadHandler](INFO): Cycle time: 0.050978, Period: 0.050000, Run time: -1549610252.663203, Sleep time: -0.000961, Sleep: 0.000000
2019-02-08T07:17:32.724Z,1549610252.724 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-08T07:17:32.774Z,1549610252.774 [logger ThreadHandler](INFO): Cycle time: 0.057432, Period: 0.050000, Run time: -1549610252.715467, Sleep time: -0.007416, Sleep: 0.000000
2019-02-08T07:17:32.830Z,1549610252.830 [logger ThreadHandler](INFO): Cycle time: 0.054817, Period: 0.050000, Run time: -1549610252.774251, Sleep time: -0.004802, Sleep: 0.000000
2019-02-08T07:17:32.886Z,1549610252.886 [logger ThreadHandler](INFO): Cycle time: 0.054725, Period: 0.050000, Run time: -1549610252.830228, Sleep time: -0.004709, Sleep: 0.000000
2019-02-08T07:17:32.912Z,1549610252.912 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-08T07:17:32.947Z,1549610252.947 [logger ThreadHandler](INFO): Cycle time: 0.059241, Period: 0.050000, Run time: -1549610252.886171, Sleep time: -0.009225, Sleep: 0.000000
2019-02-08T07:17:33.014Z,1549610253.014 [logger ThreadHandler](INFO): Cycle time: 0.065613, Period: 0.050000, Run time: -1549610252.947219, Sleep time: -0.015597, Sleep: 0.000000
2019-02-08T07:17:33.190Z,1549610253.190 [logger ThreadHandler](INFO): Cycle time: 0.174737, Period: 0.050000, Run time: -1549610253.014050, Sleep time: -0.124722, Sleep: 0.000000
2019-02-08T07:17:33.250Z,1549610253.250 [logger ThreadHandler](INFO): Cycle time: 0.058852, Period: 0.050000, Run time: -1549610253.189945, Sleep time: -0.008835, Sleep: 0.000000
2019-02-08T07:17:33.330Z,1549610253.330 [logger ThreadHandler](INFO): Cycle time: 0.079234, Period: 0.050000, Run time: -1549610253.250035, Sleep time: -0.029217, Sleep: 0.000000
2019-02-08T07:17:33.390Z,1549610253.390 [logger ThreadHandler](INFO): Cycle time: 0.058272, Period: 0.050000, Run time: -1549610253.330411, Sleep time: -0.008257, Sleep: 0.000000
2019-02-08T07:17:33.458Z,1549610253.458 [logger ThreadHandler](INFO): Cycle time: 0.067615, Period: 0.050000, Run time: -1549610253.389813, Sleep time: -0.017600, Sleep: 0.000000
2019-02-08T07:17:33.522Z,1549610253.522 [logger ThreadHandler](INFO): Cycle time: 0.062320, Period: 0.050000, Run time: -1549610253.458580, Sleep time: -0.012304, Sleep: 0.000000
2019-02-08T07:17:33.583Z,1549610253.583 [logger ThreadHandler](INFO): Cycle time: 0.058800, Period: 0.050000, Run time: -1549610253.522672, Sleep time: -0.008784, Sleep: 0.000000
2019-02-08T07:17:33.653Z,1549610253.653 [logger ThreadHandler](INFO): Cycle time: 0.068300, Period: 0.050000, Run time: -1549610253.582622, Sleep time: -0.018283, Sleep: 0.000000
2019-02-08T07:17:33.727Z,1549610253.727 [logger ThreadHandler](INFO): Cycle time: 0.072328, Period: 0.050000, Run time: -1549610253.653220, Sleep time: -0.022312, Sleep: 0.000000
2019-02-08T07:17:33.821Z,1549610253.821 [logger ThreadHandler](INFO): Cycle time: 0.092473, Period: 0.050000, Run time: -1549610253.727073, Sleep time: -0.042453, Sleep: 0.000000
2019-02-08T07:17:33.882Z,1549610253.882 [logger ThreadHandler](INFO): Cycle time: 0.059900, Period: 0.050000, Run time: -1549610253.821191, Sleep time: -0.009884, Sleep: 0.000000
2019-02-08T07:17:33.948Z,1549610253.948 [logger ThreadHandler](INFO): Cycle time: 0.063753, Period: 0.050000, Run time: -1549610253.882225, Sleep time: -0.013738, Sleep: 0.000000
2019-02-08T07:17:34.030Z,1549610254.030 [logger ThreadHandler](INFO): Cycle time: 0.080596, Period: 0.050000, Run time: -1549610253.948096, Sleep time: -0.030576, Sleep: 0.000000
2019-02-08T07:17:34.091Z,1549610254.091 [logger ThreadHandler](INFO): Cycle time: 0.058632, Period: 0.050000, Run time: -1549610254.030485, Sleep time: -0.008614, Sleep: 0.000000
2019-02-08T07:17:34.151Z,1549610254.151 [logger ThreadHandler](INFO): Cycle time: 0.058564, Period: 0.050000, Run time: -1549610254.090699, Sleep time: -0.008547, Sleep: 0.000000
2019-02-08T07:17:34.226Z,1549610254.226 [logger ThreadHandler](INFO): Cycle time: 0.073530, Period: 0.050000, Run time: -1549610254.151100, Sleep time: -0.023515, Sleep: 0.000000
2019-02-08T07:17:34.306Z,1549610254.306 [logger ThreadHandler](INFO): Cycle time: 0.079324, Period: 0.050000, Run time: -1549610254.225833, Sleep time: -0.029310, Sleep: 0.000000
2019-02-08T07:17:34.383Z,1549610254.383 [logger ThreadHandler](INFO): Cycle time: 0.075538, Period: 0.050000, Run time: -1549610254.306377, Sleep time: -0.025521, Sleep: 0.000000
2019-02-08T07:17:34.448Z,1549610254.448 [logger ThreadHandler](INFO): Cycle time: 0.062799, Period: 0.050000, Run time: -1549610254.383439, Sleep time: -0.012783, Sleep: 0.000000
2019-02-08T07:17:34.547Z,1549610254.547 [logger ThreadHandler](INFO): Cycle time: 0.097843, Period: 0.050000, Run time: -1549610254.447962, Sleep time: -0.047823, Sleep: 0.000000
2019-02-08T07:17:34.617Z,1549610254.617 [logger ThreadHandler](INFO): Cycle time: 0.067777, Period: 0.050000, Run time: -1549610254.547346, Sleep time: -0.017756, Sleep: 0.000000
2019-02-08T07:17:34.677Z,1549610254.677 [logger ThreadHandler](INFO): Cycle time: 0.057489, Period: 0.050000, Run time: -1549610254.617245, Sleep time: -0.007473, Sleep: 0.000000
2019-02-08T07:17:34.744Z,1549610254.744 [logger ThreadHandler](INFO): Cycle time: 0.065212, Period: 0.050000, Run time: -1549610254.676802, Sleep time: -0.015130, Sleep: 0.000000
2019-02-08T07:17:34.863Z,1549610254.863 [logger ThreadHandler](INFO): Cycle time: 0.117051, Period: 0.050000, Run time: -1549610254.743635, Sleep time: -0.067031, Sleep: 0.000000
2019-02-08T07:17:34.929Z,1549610254.929 [logger ThreadHandler](INFO): Cycle time: 0.064687, Period: 0.050000, Run time: -1549610254.862676, Sleep time: -0.014667, Sleep: 0.000000
2019-02-08T07:17:35.020Z,1549610255.020 [logger ThreadHandler](INFO): Cycle time: 0.088121, Period: 0.050000, Run time: -1549610254.929473, Sleep time: -0.038099, Sleep: 0.000000
2019-02-08T07:17:35.103Z,1549610255.103 [logger ThreadHandler](INFO): Cycle time: 0.081284, Period: 0.050000, Run time: -1549610255.020026, Sleep time: -0.031265, Sleep: 0.000000
2019-02-08T07:17:35.173Z,1549610255.173 [logger ThreadHandler](INFO): Cycle time: 0.067775, Period: 0.050000, Run time: -1549610255.102815, Sleep time: -0.017756, Sleep: 0.000000
2019-02-08T07:17:35.238Z,1549610255.238 [logger ThreadHandler](INFO): Cycle time: 0.064161, Period: 0.050000, Run time: -1549610255.172844, Sleep time: -0.014146, Sleep: 0.000000
2019-02-08T07:17:35.302Z,1549610255.302 [logger ThreadHandler](INFO): Cycle time: 0.062616, Period: 0.050000, Run time: -1549610255.238146, Sleep time: -0.012601, Sleep: 0.000000
2019-02-08T07:17:35.367Z,1549610255.367 [logger ThreadHandler](INFO): Cycle time: 0.064046, Period: 0.050000, Run time: -1549610255.301960, Sleep time: -0.014030, Sleep: 0.000000
2019-02-08T07:17:35.427Z,1549610255.427 [logger ThreadHandler](INFO): Cycle time: 0.058591, Period: 0.050000, Run time: -1549610255.367134, Sleep time: -0.008576, Sleep: 0.000000
2019-02-08T07:17:35.495Z,1549610255.495 [logger ThreadHandler](INFO): Cycle time: 0.066720, Period: 0.050000, Run time: -1549610255.426819, Sleep time: -0.016704, Sleep: 0.000000
2019-02-08T07:17:35.562Z,1549610255.562 [logger ThreadHandler](INFO): Cycle time: 0.066589, Period: 0.050000, Run time: -1549610255.494645, Sleep time: -0.016574, Sleep: 0.000000
2019-02-08T07:17:35.631Z,1549610255.631 [logger ThreadHandler](INFO): Cycle time: 0.067280, Period: 0.050000, Run time: -1549610255.562348, Sleep time: -0.017265, Sleep: 0.000000
2019-02-08T07:17:35.683Z,1549610255.683 [logger ThreadHandler](INFO): Cycle time: 0.050992, Period: 0.050000, Run time: -1549610255.630735, Sleep time: -0.000976, Sleep: 0.000000
2019-02-08T07:17:35.749Z,1549610255.749 [logger ThreadHandler](INFO): Cycle time: 0.065057, Period: 0.050000, Run time: -1549610255.682848, Sleep time: -0.015039, Sleep: 0.000000
2019-02-08T07:17:35.802Z,1549610255.802 [logger ThreadHandler](INFO): Cycle time: 0.051761, Period: 0.050000, Run time: -1549610255.749084, Sleep time: -0.001745, Sleep: 0.000000
2019-02-08T07:17:35.861Z,1549610255.861 [logger ThreadHandler](INFO): Cycle time: 0.057955, Period: 0.050000, Run time: -1549610255.801969, Sleep time: -0.007938, Sleep: 0.000000
2019-02-08T07:17:35.922Z,1549610255.922 [logger ThreadHandler](INFO): Cycle time: 0.060322, Period: 0.050000, Run time: -1549610255.861055, Sleep time: -0.010306, Sleep: 0.000000
2019-02-08T07:17:35.982Z,1549610255.982 [logger ThreadHandler](INFO): Cycle time: 0.057776, Period: 0.050000, Run time: -1549610255.922496, Sleep time: -0.007758, Sleep: 0.000000
2019-02-08T07:17:36.051Z,1549610256.051 [logger ThreadHandler](INFO): Cycle time: 0.067939, Period: 0.050000, Run time: -1549610255.981853, Sleep time: -0.017866, Sleep: 0.000000
2019-02-08T07:17:36.113Z,1549610256.113 [logger ThreadHandler](INFO): Cycle time: 0.060616, Period: 0.050000, Run time: -1549610256.051458, Sleep time: -0.010598, Sleep: 0.000000
2019-02-08T07:17:36.173Z,1549610256.173 [logger ThreadHandler](INFO): Cycle time: 0.058816, Period: 0.050000, Run time: -1549610256.113202, Sleep time: -0.008799, Sleep: 0.000000
2019-02-08T07:17:36.233Z,1549610256.233 [logger ThreadHandler](INFO): Cycle time: 0.058877, Period: 0.050000, Run time: -1549610256.173143, Sleep time: -0.008862, Sleep: 0.000000
2019-02-08T07:17:36.293Z,1549610256.293 [logger ThreadHandler](INFO): Cycle time: 0.058738, Period: 0.050000, Run time: -1549610256.233158, Sleep time: -0.008722, Sleep: 0.000000
2019-02-08T07:17:36.362Z,1549610256.362 [logger ThreadHandler](INFO): Cycle time: 0.067957, Period: 0.050000, Run time: -1549610256.293027, Sleep time: -0.017941, Sleep: 0.000000
2019-02-08T07:17:36.414Z,1549610256.414 [logger ThreadHandler](INFO): Cycle time: 0.050740, Period: 0.050000, Run time: -1549610256.362097, Sleep time: -0.000726, Sleep: 0.000000
2019-02-08T07:17:36.470Z,1549610256.470 [logger ThreadHandler](INFO): Cycle time: 0.054573, Period: 0.050000, Run time: -1549610256.413955, Sleep time: -0.004558, Sleep: 0.000000
2019-02-08T07:17:36.530Z,1549610256.530 [logger ThreadHandler](INFO): Cycle time: 0.059443, Period: 0.050000, Run time: -1549610256.469647, Sleep time: -0.009427, Sleep: 0.000000
2019-02-08T07:17:36.589Z,1549610256.589 [logger ThreadHandler](INFO): Cycle time: 0.058195, Period: 0.050000, Run time: -1549610256.530211, Sleep time: -0.008179, Sleep: 0.000000
2019-02-08T07:17:36.649Z,1549610256.649 [logger ThreadHandler](INFO): Cycle time: 0.058895, Period: 0.050000, Run time: -1549610256.589529, Sleep time: -0.008880, Sleep: 0.000000
2019-02-08T07:17:36.711Z,1549610256.711 [logger ThreadHandler](INFO): Cycle time: 0.060253, Period: 0.050000, Run time: -1549610256.649535, Sleep time: -0.010237, Sleep: 0.000000
2019-02-08T07:17:36.766Z,1549610256.766 [logger ThreadHandler](INFO): Cycle time: 0.054184, Period: 0.050000, Run time: -1549610256.710905, Sleep time: -0.004169, Sleep: 0.000000
2019-02-08T07:17:36.830Z,1549610256.830 [logger ThreadHandler](INFO): Cycle time: 0.062754, Period: 0.050000, Run time: -1549610256.766213, Sleep time: -0.012738, Sleep: 0.000000
2019-02-08T07:17:36.898Z,1549610256.898 [logger ThreadHandler](INFO): Cycle time: 0.066697, Period: 0.050000, Run time: -1549610256.830091, Sleep time: -0.016682, Sleep: 0.000000
2019-02-08T07:17:36.965Z,1549610256.965 [logger ThreadHandler](INFO): Cycle time: 0.066544, Period: 0.050000, Run time: -1549610256.897905, Sleep time: -0.016529, Sleep: 0.000000
2019-02-08T07:17:37.020Z,1549610257.020 [logger ThreadHandler](INFO): Cycle time: 0.052170, Period: 0.050000, Run time: -1549610256.965575, Sleep time: -0.002154, Sleep: 0.000000
2019-02-08T07:17:37.073Z,1549610257.073 [logger ThreadHandler](INFO): Cycle time: 0.051584, Period: 0.050000, Run time: -1549610257.019621, Sleep time: -0.001461, Sleep: 0.000000
2019-02-08T07:17:37.141Z,1549610257.141 [logger ThreadHandler](INFO): Cycle time: 0.066903, Period: 0.050000, Run time: -1549610257.073010, Sleep time: -0.016886, Sleep: 0.000000
2019-02-08T07:17:37.194Z,1549610257.194 [logger ThreadHandler](INFO): Cycle time: 0.051519, Period: 0.050000, Run time: -1549610257.141049, Sleep time: -0.001504, Sleep: 0.000000
2019-02-08T07:17:37.246Z,1549610257.246 [logger ThreadHandler](INFO): Cycle time: 0.051400, Period: 0.050000, Run time: -1549610257.193685, Sleep time: -0.001385, Sleep: 0.000000
2019-02-08T07:17:37.298Z,1549610257.298 [logger ThreadHandler](INFO): Cycle time: 0.050689, Period: 0.050000, Run time: -1549610257.246210, Sleep time: -0.000674, Sleep: 0.000000
2019-02-08T07:17:37.370Z,1549610257.370 [logger ThreadHandler](INFO): Cycle time: 0.071236, Period: 0.050000, Run time: -1549610257.298019, Sleep time: -0.021221, Sleep: 0.000000
2019-02-08T07:17:37.429Z,1549610257.429 [logger ThreadHandler](INFO): Cycle time: 0.057528, Period: 0.050000, Run time: -1549610257.370372, Sleep time: -0.007514, Sleep: 0.000000
2019-02-08T07:17:37.497Z,1549610257.497 [logger ThreadHandler](INFO): Cycle time: 0.066888, Period: 0.050000, Run time: -1549610257.429152, Sleep time: -0.016870, Sleep: 0.000000
2019-02-08T07:17:37.565Z,1549610257.565 [logger ThreadHandler](INFO): Cycle time: 0.066843, Period: 0.050000, Run time: -1549610257.497159, Sleep time: -0.016826, Sleep: 0.000000
2019-02-08T07:17:37.618Z,1549610257.618 [logger ThreadHandler](INFO): Cycle time: 0.051363, Period: 0.050000, Run time: -1549610257.565126, Sleep time: -0.001348, Sleep: 0.000000
2019-02-08T07:17:37.669Z,1549610257.669 [logger ThreadHandler](INFO): Cycle time: 0.050654, Period: 0.050000, Run time: -1549610257.617620, Sleep time: -0.000639, Sleep: 0.000000
2019-02-08T07:17:37.729Z,1549610257.729 [logger ThreadHandler](INFO): Cycle time: 0.059030, Period: 0.050000, Run time: -1549610257.669403, Sleep time: -0.009016, Sleep: 0.000000
2019-02-08T07:17:37.790Z,1549610257.790 [logger ThreadHandler](INFO): Cycle time: 0.059103, Period: 0.050000, Run time: -1549610257.729550, Sleep time: -0.009088, Sleep: 0.000000
2019-02-08T07:17:37.850Z,1549610257.850 [logger ThreadHandler](INFO): Cycle time: 0.058829, Period: 0.050000, Run time: -1549610257.789778, Sleep time: -0.008814, Sleep: 0.000000
2019-02-08T07:17:37.913Z,1549610257.913 [logger ThreadHandler](INFO): Cycle time: 0.062684, Period: 0.050000, Run time: -1549610257.849737, Sleep time: -0.012668, Sleep: 0.000000
2019-02-08T07:17:37.973Z,1549610257.973 [logger ThreadHandler](INFO): Cycle time: 0.058719, Period: 0.050000, Run time: -1549610257.913552, Sleep time: -0.008702, Sleep: 0.000000
2019-02-08T07:17:38.027Z,1549610258.027 [logger ThreadHandler](INFO): Cycle time: 0.052260, Period: 0.050000, Run time: -1549610257.973402, Sleep time: -0.002244, Sleep: 0.000000
2019-02-08T07:17:38.095Z,1549610258.095 [logger ThreadHandler](INFO): Cycle time: 0.065772, Period: 0.050000, Run time: -1549610258.027536, Sleep time: -0.015756, Sleep: 0.000000
2019-02-08T07:17:38.155Z,1549610258.155 [logger ThreadHandler](INFO): Cycle time: 0.058766, Period: 0.050000, Run time: -1549610258.095157, Sleep time: -0.008750, Sleep: 0.000000
2019-02-08T07:17:38.173Z,1549610258.173 [NavChartDb ThreadHandler](INFO): Cycle time: 9.783323, Period: 0.100000, Run time: -1549610248.388472, Sleep time: -9.683304, Sleep: 0.000000
2019-02-08T07:17:38.213Z,1549610258.213 [logger ThreadHandler](INFO): Cycle time: 0.056961, Period: 0.050000, Run time: -1549610258.155059, Sleep time: -0.006945, Sleep: 0.000000
2019-02-08T07:17:38.270Z,1549610258.270 [logger ThreadHandler](INFO): Cycle time: 0.055894, Period: 0.050000, Run time: -1549610258.213156, Sleep time: -0.005878, Sleep: 0.000000
2019-02-08T07:17:38.330Z,1549610258.330 [logger ThreadHandler](INFO): Cycle time: 0.058318, Period: 0.050000, Run time: -1549610258.270187, Sleep time: -0.008303, Sleep: 0.000000
2019-02-08T07:17:38.387Z,1549610258.387 [logger ThreadHandler](INFO): Cycle time: 0.053183, Period: 0.050000, Run time: -1549610258.330356, Sleep time: -0.003162, Sleep: 0.000000
2019-02-08T07:17:38.448Z,1549610258.448 [logger ThreadHandler](INFO): Cycle time: 0.059048, Period: 0.050000, Run time: -1549610258.386854, Sleep time: -0.008964, Sleep: 0.000000
2019-02-08T07:17:38.511Z,1549610258.511 [logger ThreadHandler](INFO): Cycle time: 0.061958, Period: 0.050000, Run time: -1549610258.447638, Sleep time: -0.011938, Sleep: 0.000000
2019-02-08T07:17:38.563Z,1549610258.563 [logger ThreadHandler](INFO): Cycle time: 0.050896, Period: 0.050000, Run time: -1549610258.510733, Sleep time: -0.000881, Sleep: 0.000000
2019-02-08T07:17:38.617Z,1549610258.617 [logger ThreadHandler](INFO): Cycle time: 0.053340, Period: 0.050000, Run time: -1549610258.562753, Sleep time: -0.003322, Sleep: 0.000000
2019-02-08T07:17:38.685Z,1549610258.685 [logger ThreadHandler](INFO): Cycle time: 0.067084, Period: 0.050000, Run time: -1549610258.617234, Sleep time: -0.017069, Sleep: 0.000000
2019-02-08T07:17:38.737Z,1549610258.737 [logger ThreadHandler](INFO): Cycle time: 0.050838, Period: 0.050000, Run time: -1549610258.685464, Sleep time: -0.000821, Sleep: 0.000000
2019-02-08T07:17:38.805Z,1549610258.805 [logger ThreadHandler](INFO): Cycle time: 0.066482, Period: 0.050000, Run time: -1549610258.737434, Sleep time: -0.016464, Sleep: 0.000000
2019-02-08T07:17:38.875Z,1549610258.875 [logger ThreadHandler](INFO): Cycle time: 0.068604, Period: 0.050000, Run time: -1549610258.805047, Sleep time: -0.018590, Sleep: 0.000000
2019-02-08T07:17:38.930Z,1549610258.930 [logger ThreadHandler](INFO): Cycle time: 0.054180, Period: 0.050000, Run time: -1549610258.874785, Sleep time: -0.004165, Sleep: 0.000000
2019-02-08T07:17:38.991Z,1549610258.991 [logger ThreadHandler](INFO): Cycle time: 0.059402, Period: 0.050000, Run time: -1549610258.930103, Sleep time: -0.009387, Sleep: 0.000000
2019-02-08T07:17:39.051Z,1549610259.051 [logger ThreadHandler](INFO): Cycle time: 0.059337, Period: 0.050000, Run time: -1549610258.990718, Sleep time: -0.009285, Sleep: 0.000000
2019-02-08T07:17:39.121Z,1549610259.121 [logger ThreadHandler](INFO): Cycle time: 0.067435, Period: 0.050000, Run time: -1549610259.051576, Sleep time: -0.017418, Sleep: 0.000000
2019-02-08T07:17:39.173Z,1549610259.173 [logger ThreadHandler](INFO): Cycle time: 0.050685, Period: 0.050000, Run time: -1549610259.120970, Sleep time: -0.000669, Sleep: 0.000000
2019-02-08T07:17:39.229Z,1549610259.229 [logger ThreadHandler](INFO): Cycle time: 0.055062, Period: 0.050000, Run time: -1549610259.172840, Sleep time: -0.005045, Sleep: 0.000000
2019-02-08T07:17:39.294Z,1549610259.294 [logger ThreadHandler](INFO): Cycle time: 0.064404, Period: 0.050000, Run time: -1549610259.229042, Sleep time: -0.014388, Sleep: 0.000000
2019-02-08T07:17:39.358Z,1549610259.358 [logger ThreadHandler](INFO): Cycle time: 0.062378, Period: 0.050000, Run time: -1549610259.294576, Sleep time: -0.012296, Sleep: 0.000000
2019-02-08T07:17:39.418Z,1549610259.418 [logger ThreadHandler](INFO): Cycle time: 0.058380, Period: 0.050000, Run time: -1549610259.358573, Sleep time: -0.008361, Sleep: 0.000000
2019-02-08T07:17:39.478Z,1549610259.478 [logger ThreadHandler](INFO): Cycle time: 0.057175, Period: 0.050000, Run time: -1549610259.418675, Sleep time: -0.007157, Sleep: 0.000000
2019-02-08T07:17:39.531Z,1549610259.531 [logger ThreadHandler](INFO): Cycle time: 0.052139, Period: 0.050000, Run time: -1549610259.477810, Sleep time: -0.002124, Sleep: 0.000000
2019-02-08T07:17:39.583Z,1549610259.583 [logger ThreadHandler](INFO): Cycle time: 0.051038, Period: 0.050000, Run time: -1549610259.531073, Sleep time: -0.001023, Sleep: 0.000000
2019-02-08T07:17:39.635Z,1549610259.635 [logger ThreadHandler](INFO): Cycle time: 0.051095, Period: 0.050000, Run time: -1549610259.583248, Sleep time: -0.001079, Sleep: 0.000000
2019-02-08T07:17:39.697Z,1549610259.697 [logger ThreadHandler](INFO): Cycle time: 0.060927, Period: 0.050000, Run time: -1549610259.635472, Sleep time: -0.010912, Sleep: 0.000000
2019-02-08T07:17:39.765Z,1549610259.765 [logger ThreadHandler](INFO): Cycle time: 0.066671, Period: 0.050000, Run time: -1549610259.697525, Sleep time: -0.016656, Sleep: 0.000000
2019-02-08T07:17:39.822Z,1549610259.822 [logger ThreadHandler](INFO): Cycle time: 0.055157, Period: 0.050000, Run time: -1549610259.765328, Sleep time: -0.005141, Sleep: 0.000000
2019-02-08T07:17:39.878Z,1549610259.878 [logger ThreadHandler](INFO): Cycle time: 0.055718, Period: 0.050000, Run time: -1549610259.821613, Sleep time: -0.005703, Sleep: 0.000000
2019-02-08T07:17:39.943Z,1549610259.943 [logger ThreadHandler](INFO): Cycle time: 0.063689, Period: 0.050000, Run time: -1549610259.878453, Sleep time: -0.013673, Sleep: 0.000000
2019-02-08T07:17:40.011Z,1549610260.011 [logger ThreadHandler](INFO): Cycle time: 0.066282, Period: 0.050000, Run time: -1549610259.943267, Sleep time: -0.016264, Sleep: 0.000000
2019-02-08T07:17:40.076Z,1549610260.076 [logger ThreadHandler](INFO): Cycle time: 0.063371, Period: 0.050000, Run time: -1549610260.010666, Sleep time: -0.013354, Sleep: 0.000000
2019-02-08T07:17:40.135Z,1549610260.135 [logger ThreadHandler](INFO): Cycle time: 0.055609, Period: 0.050000, Run time: -1549610260.077375, Sleep time: -0.005540, Sleep: 0.000000
2019-02-08T07:17:40.195Z,1549610260.195 [logger ThreadHandler](INFO): Cycle time: 0.058967, Period: 0.050000, Run time: -1549610260.134617, Sleep time: -0.008951, Sleep: 0.000000
2019-02-08T07:17:40.254Z,1549610260.254 [logger ThreadHandler](INFO): Cycle time: 0.058352, Period: 0.050000, Run time: -1549610260.194713, Sleep time: -0.008337, Sleep: 0.000000
2019-02-08T07:17:40.321Z,1549610260.321 [logger ThreadHandler](INFO): Cycle time: 0.065878, Period: 0.050000, Run time: -1549610260.254193, Sleep time: -0.015861, Sleep: 0.000000
2019-02-08T07:17:40.386Z,1549610260.386 [logger ThreadHandler](INFO): Cycle time: 0.063081, Period: 0.050000, Run time: -1549610260.321199, Sleep time: -0.013061, Sleep: 0.000000
2019-02-08T07:17:40.452Z,1549610260.452 [logger ThreadHandler](INFO): Cycle time: 0.063692, Period: 0.050000, Run time: -1549610260.386231, Sleep time: -0.013540, Sleep: 0.000000
2019-02-08T07:17:40.530Z,1549610260.530 [logger ThreadHandler](INFO): Cycle time: 0.076087, Period: 0.050000, Run time: -1549610260.452635, Sleep time: -0.026072, Sleep: 0.000000
2019-02-08T07:17:40.593Z,1549610260.593 [logger ThreadHandler](INFO): Cycle time: 0.062071, Period: 0.050000, Run time: -1549610260.529841, Sleep time: -0.012054, Sleep: 0.000000
2019-02-08T07:17:40.654Z,1549610260.654 [logger ThreadHandler](INFO): Cycle time: 0.059972, Period: 0.050000, Run time: -1549610260.593057, Sleep time: -0.009957, Sleep: 0.000000
2019-02-08T07:17:40.706Z,1549610260.706 [logger ThreadHandler](INFO): Cycle time: 0.050856, Period: 0.050000, Run time: -1549610260.654165, Sleep time: -0.000840, Sleep: 0.000000
2019-02-08T07:17:40.770Z,1549610260.770 [logger ThreadHandler](INFO): Cycle time: 0.062255, Period: 0.050000, Run time: -1549610260.706300, Sleep time: -0.012240, Sleep: 0.000000
2019-02-08T07:17:40.822Z,1549610260.822 [logger ThreadHandler](INFO): Cycle time: 0.051249, Period: 0.050000, Run time: -1549610260.769677, Sleep time: -0.001233, Sleep: 0.000000
2019-02-08T07:17:40.874Z,1549610260.874 [logger ThreadHandler](INFO): Cycle time: 0.050753, Period: 0.050000, Run time: -1549610260.822047, Sleep time: -0.000738, Sleep: 0.000000
2019-02-08T07:17:40.933Z,1549610260.933 [logger ThreadHandler](INFO): Cycle time: 0.057996, Period: 0.050000, Run time: -1549610260.873916, Sleep time: -0.007979, Sleep: 0.000000
2019-02-08T07:17:40.993Z,1549610260.993 [logger ThreadHandler](INFO): Cycle time: 0.058829, Period: 0.050000, Run time: -1549610260.933045, Sleep time: -0.008811, Sleep: 0.000000
2019-02-08T07:17:41.050Z,1549610261.050 [logger ThreadHandler](INFO): Cycle time: 0.056029, Period: 0.050000, Run time: -1549610260.993001, Sleep time: -0.006014, Sleep: 0.000000
2019-02-08T07:17:41.111Z,1549610261.111 [logger ThreadHandler](INFO): Cycle time: 0.059510, Period: 0.050000, Run time: -1549610261.050159, Sleep time: -0.009494, Sleep: 0.000000
2019-02-08T07:17:41.170Z,1549610261.170 [logger ThreadHandler](INFO): Cycle time: 0.057694, Period: 0.050000, Run time: -1549610261.111453, Sleep time: -0.007678, Sleep: 0.000000
2019-02-08T07:17:41.238Z,1549610261.238 [logger ThreadHandler](INFO): Cycle time: 0.066388, Period: 0.050000, Run time: -1549610261.170269, Sleep time: -0.016373, Sleep: 0.000000
2019-02-08T07:17:41.290Z,1549610261.290 [logger ThreadHandler](INFO): Cycle time: 0.051013, Period: 0.050000, Run time: -1549610261.237770, Sleep time: -0.000999, Sleep: 0.000000
2019-02-08T07:17:41.361Z,1549610261.361 [logger ThreadHandler](INFO): Cycle time: 0.068910, Period: 0.050000, Run time: -1549610261.289903, Sleep time: -0.018890, Sleep: 0.000000
2019-02-08T07:17:41.422Z,1549610261.422 [logger ThreadHandler](INFO): Cycle time: 0.058648, Period: 0.050000, Run time: -1549610261.361006, Sleep time: -0.008629, Sleep: 0.000000
2019-02-08T07:17:41.478Z,1549610261.478 [logger ThreadHandler](INFO): Cycle time: 0.052952, Period: 0.050000, Run time: -1549610261.422551, Sleep time: -0.002849, Sleep: 0.000000
2019-02-08T07:17:41.531Z,1549610261.531 [logger ThreadHandler](INFO): Cycle time: 0.051555, Period: 0.050000, Run time: -1549610261.478205, Sleep time: -0.001539, Sleep: 0.000000
2019-02-08T07:17:41.584Z,1549610261.584 [logger ThreadHandler](INFO): Cycle time: 0.051710, Period: 0.050000, Run time: -1549610261.530880, Sleep time: -0.001694, Sleep: 0.000000
2019-02-08T07:17:41.643Z,1549610261.643 [logger ThreadHandler](INFO): Cycle time: 0.058177, Period: 0.050000, Run time: -1549610261.583779, Sleep time: -0.008161, Sleep: 0.000000
2019-02-08T07:17:41.703Z,1549610261.703 [logger ThreadHandler](INFO): Cycle time: 0.058689, Period: 0.050000, Run time: -1549610261.643088, Sleep time: -0.008673, Sleep: 0.000000
2019-02-08T07:17:41.758Z,1549610261.758 [logger ThreadHandler](INFO): Cycle time: 0.054355, Period: 0.050000, Run time: -1549610261.702903, Sleep time: -0.004338, Sleep: 0.000000
2019-02-08T07:17:41.829Z,1549610261.829 [logger ThreadHandler](INFO): Cycle time: 0.069721, Period: 0.050000, Run time: -1549610261.758386, Sleep time: -0.019704, Sleep: 0.000000
2019-02-08T07:17:41.893Z,1549610261.893 [logger ThreadHandler](INFO): Cycle time: 0.062671, Period: 0.050000, Run time: -1549610261.829242, Sleep time: -0.012654, Sleep: 0.000000
2019-02-08T07:17:41.955Z,1549610261.955 [logger ThreadHandler](INFO): Cycle time: 0.060439, Period: 0.050000, Run time: -1549610261.893039, Sleep time: -0.010423, Sleep: 0.000000
2019-02-08T07:17:42.016Z,1549610262.016 [logger ThreadHandler](INFO): Cycle time: 0.059336, Period: 0.050000, Run time: -1549610261.954606, Sleep time: -0.009320, Sleep: 0.000000
2019-02-08T07:17:42.069Z,1549610262.069 [logger ThreadHandler](INFO): Cycle time: 0.051368, Period: 0.050000, Run time: -1549610262.016253, Sleep time: -0.001353, Sleep: 0.000000
2019-02-08T07:17:42.139Z,1549610262.139 [logger ThreadHandler](INFO): Cycle time: 0.068123, Period: 0.050000, Run time: -1549610262.068814, Sleep time: -0.018109, Sleep: 0.000000
2019-02-08T07:17:42.155Z,1549610262.155 [CBIT](INFO): Critical error at 20190208T071730
2019-02-08T07:17:42.158Z,1549610262.158 [CBIT](CRITICAL): Environmental Failure. Press:14.887811 PSI. Humidity:27%. Temp:25 C. ABORTING MISSION
2019-02-08T07:17:42.179Z,1549610262.179 [controlThread ThreadHandler](INFO): Cycle time: 11.421519, Period: 0.400000, Run time: -1549610250.755968, Sleep time: -11.021504, Sleep: 0.000000
2019-02-08T07:17:42.187Z,1549610262.187 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-08T07:17:42.187Z,1549610262.187 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-08T07:17:42.187Z,1549610262.187 [Aanderaa_O2](ERROR): Communications Fault
2019-02-08T07:17:42.203Z,1549610262.203 [logger ThreadHandler](INFO): Cycle time: 0.063684, Period: 0.050000, Run time: -1549610262.138782, Sleep time: -0.013668, Sleep: 0.000000
2019-02-08T07:17:42.258Z,1549610262.258 [logger ThreadHandler](INFO): Cycle time: 0.053389, Period: 0.050000, Run time: -1549610262.203582, Sleep time: -0.003373, Sleep: 0.000000
2019-02-08T07:17:42.311Z,1549610262.311 [logger ThreadHandler](INFO): Cycle time: 0.051841, Period: 0.050000, Run time: -1549610262.258087, Sleep time: -0.001826, Sleep: 0.000000
2019-02-08T07:17:42.371Z,1549610262.371 [logger ThreadHandler](INFO): Cycle time: 0.057763, Period: 0.050000, Run time: -1549610262.311052, Sleep time: -0.007743, Sleep: 0.000000
2019-02-08T07:17:42.427Z,1549610262.427 [CBIT](INFO): Critical error at 20190208T071742
2019-02-08T07:17:42.431Z,1549610262.431 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-08T07:17:42.451Z,1549610262.451 [logger ThreadHandler](INFO): Cycle time: 0.079198, Period: 0.050000, Run time: -1549610262.370683, Sleep time: -0.029129, Sleep: 0.000000
2019-02-08T07:17:42.511Z,1549610262.511 [logger ThreadHandler](INFO): Cycle time: 0.055893, Period: 0.050000, Run time: -1549610262.451407, Sleep time: -0.005875, Sleep: 0.000000
2019-02-08T07:17:42.566Z,1549610262.566 [logger ThreadHandler](INFO): Cycle time: 0.053719, Period: 0.050000, Run time: -1549610262.511088, Sleep time: -0.003703, Sleep: 0.000000
2019-02-08T07:17:42.581Z,1549610262.581 [controlThread ThreadHandler](INFO): Cycle time: 0.401231, Period: 0.400000, Run time: 0.254902, Sleep time: 0.145170, Sleep: 0.146287
2019-02-08T07:17:42.586Z,1549610262.586 [Aanderaa_O2](INFO): Powering down
2019-02-08T07:17:42.626Z,1549610262.626 [logger ThreadHandler](INFO): Cycle time: 0.059088, Period: 0.050000, Run time: -1549610262.565931, Sleep time: -0.009073, Sleep: 0.000000
2019-02-08T07:17:42.686Z,1549610262.686 [logger ThreadHandler](INFO): Cycle time: 0.058433, Period: 0.050000, Run time: -1549610262.626142, Sleep time: -0.008418, Sleep: 0.000000
2019-02-08T07:17:42.749Z,1549610262.749 [logger ThreadHandler](INFO): Cycle time: 0.062187, Period: 0.050000, Run time: -1549610262.685693, Sleep time: -0.012172, Sleep: 0.000000
2019-02-08T07:17:42.802Z,1549610262.802 [logger ThreadHandler](INFO): Cycle time: 0.051897, Period: 0.050000, Run time: -1549610262.748998, Sleep time: -0.001881, Sleep: 0.000000
2019-02-08T07:17:42.867Z,1549610262.867 [logger ThreadHandler](INFO): Cycle time: 0.063639, Period: 0.050000, Run time: -1549610262.802016, Sleep time: -0.013624, Sleep: 0.000000
2019-02-08T07:17:42.923Z,1549610262.923 [logger ThreadHandler](INFO): Cycle time: 0.055556, Period: 0.050000, Run time: -1549610262.866767, Sleep time: -0.005541, Sleep: 0.000000
2019-02-08T07:17:42.985Z,1549610262.985 [controlThread ThreadHandler](INFO): Cycle time: 0.402915, Period: 0.400000, Run time: 0.149353, Sleep time: 0.250661, Sleep: 0.253542
2019-02-08T07:17:42.999Z,1549610262.999 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005075
2019-02-08T07:17:43.009Z,1549610263.009 [logger ThreadHandler](INFO): Cycle time: 0.083165, Period: 0.050000, Run time: -1549610262.923438, Sleep time: -0.033150, Sleep: 0.000000
2019-02-08T07:17:43.063Z,1549610263.063 [logger ThreadHandler](INFO): Cycle time: 0.053268, Period: 0.050000, Run time: -1549610263.008893, Sleep time: -0.003252, Sleep: 0.000000
2019-02-08T07:17:43.125Z,1549610263.125 [logger ThreadHandler](INFO): Cycle time: 0.060653, Period: 0.050000, Run time: -1549610263.063279, Sleep time: -0.010637, Sleep: 0.000000
2019-02-08T07:17:43.184Z,1549610263.184 [logger ThreadHandler](INFO): Cycle time: 0.055641, Period: 0.050000, Run time: -1549610263.125061, Sleep time: -0.005574, Sleep: 0.000000
2019-02-08T07:17:43.238Z,1549610263.238 [logger ThreadHandler](INFO): Cycle time: 0.052544, Period: 0.050000, Run time: -1549610263.184303, Sleep time: -0.002530, Sleep: 0.000000
2019-02-08T07:17:43.294Z,1549610263.294 [logger ThreadHandler](INFO): Cycle time: 0.054545, Period: 0.050000, Run time: -1549610263.237958, Sleep time: -0.004529, Sleep: 0.000000
2019-02-08T07:17:43.350Z,1549610263.350 [logger ThreadHandler](INFO): Cycle time: 0.054889, Period: 0.050000, Run time: -1549610263.293615, Sleep time: -0.004875, Sleep: 0.000000
2019-02-08T07:17:43.390Z,1549610263.390 [controlThread ThreadHandler](INFO): Cycle time: 0.403349, Period: 0.400000, Run time: 0.245456, Sleep time: 0.154558, Sleep: 0.157806
2019-02-08T07:17:43.430Z,1549610263.430 [logger ThreadHandler](INFO): Cycle time: 0.077988, Period: 0.050000, Run time: -1549610263.349623, Sleep time: -0.026691, Sleep: 0.000000
2019-02-08T07:17:43.503Z,1549610263.503 [logger ThreadHandler](INFO): Cycle time: 0.071721, Period: 0.050000, Run time: -1549610263.429789, Sleep time: -0.021650, Sleep: 0.000000
2019-02-08T07:17:43.556Z,1549610263.556 [logger ThreadHandler](INFO): Cycle time: 0.052103, Period: 0.050000, Run time: -1549610263.503204, Sleep time: -0.002038, Sleep: 0.000000
2019-02-08T07:17:43.625Z,1549610263.625 [logger ThreadHandler](INFO): Cycle time: 0.067356, Period: 0.050000, Run time: -1549610263.556572, Sleep time: -0.017338, Sleep: 0.000000
2019-02-08T07:17:43.695Z,1549610263.695 [logger ThreadHandler](INFO): Cycle time: 0.068566, Period: 0.050000, Run time: -1549610263.625037, Sleep time: -0.018550, Sleep: 0.000000
2019-02-08T07:17:43.710Z,1549610263.710 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-08T07:17:43.710Z,1549610263.710 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-08T07:17:43.747Z,1549610263.747 [logger ThreadHandler](INFO): Cycle time: 0.051600, Period: 0.050000, Run time: -1549610263.694714, Sleep time: -0.001584, Sleep: 0.000000
2019-02-08T07:17:43.793Z,1549610263.793 [controlThread ThreadHandler](INFO): Cycle time: 0.402230, Period: 0.400000, Run time: 0.332142, Sleep time: 0.067873, Sleep: 0.070042
2019-02-08T07:17:43.810Z,1549610263.810 [logger ThreadHandler](INFO): Cycle time: 0.061167, Period: 0.050000, Run time: -1549610263.747431, Sleep time: -0.011151, Sleep: 0.000000
2019-02-08T07:17:43.865Z,1549610263.865 [logger ThreadHandler](INFO): Cycle time: 0.054751, Period: 0.050000, Run time: -1549610263.809712, Sleep time: -0.004736, Sleep: 0.000000
2019-02-08T07:17:43.920Z,1549610263.920 [logger ThreadHandler](INFO): Cycle time: 0.052617, Period: 0.050000, Run time: -1549610263.865577, Sleep time: -0.002601, Sleep: 0.000000
2019-02-08T07:17:43.982Z,1549610263.982 [logger ThreadHandler](INFO): Cycle time: 0.060979, Period: 0.050000, Run time: -1549610263.920082, Sleep time: -0.010964, Sleep: 0.000000
2019-02-08T07:17:44.038Z,1549610264.038 [logger ThreadHandler](INFO): Cycle time: 0.054721, Period: 0.050000, Run time: -1549610263.982175, Sleep time: -0.004706, Sleep: 0.000000
2019-02-08T07:17:44.093Z,1549610264.093 [logger ThreadHandler](INFO): Cycle time: 0.054390, Period: 0.050000, Run time: -1549610264.038011, Sleep time: -0.004375, Sleep: 0.000000
2019-02-08T07:17:44.147Z,1549610264.147 [logger ThreadHandler](INFO): Cycle time: 0.052034, Period: 0.050000, Run time: -1549610264.093514, Sleep time: -0.002018, Sleep: 0.000000
2019-02-08T07:17:44.197Z,1549610264.197 [controlThread ThreadHandler](INFO): Cycle time: 0.402859, Period: 0.400000, Run time: 0.134371, Sleep time: 0.265643, Sleep: 0.268468
2019-02-08T07:17:44.230Z,1549610264.230 [logger ThreadHandler](INFO): Cycle time: 0.081483, Period: 0.050000, Run time: -1549610264.146669, Sleep time: -0.031467, Sleep: 0.000000
2019-02-08T07:17:44.286Z,1549610264.286 [logger ThreadHandler](INFO): Cycle time: 0.054303, Period: 0.050000, Run time: -1549610264.230296, Sleep time: -0.004288, Sleep: 0.000000
2019-02-08T07:17:44.359Z,1549610264.359 [logger ThreadHandler](INFO): Cycle time: 0.071515, Period: 0.050000, Run time: -1549610264.285719, Sleep time: -0.021495, Sleep: 0.000000
2019-02-08T07:17:44.431Z,1549610264.431 [logger ThreadHandler](INFO): Cycle time: 0.067983, Period: 0.050000, Run time: -1549610264.359395, Sleep time: -0.017968, Sleep: 0.000000
2019-02-08T07:17:44.484Z,1549610264.484 [logger ThreadHandler](INFO): Cycle time: 0.051902, Period: 0.050000, Run time: -1549610264.430607, Sleep time: -0.001882, Sleep: 0.000000
2019-02-08T07:17:44.542Z,1549610264.542 [logger ThreadHandler](INFO): Cycle time: 0.056347, Period: 0.050000, Run time: -1549610264.484553, Sleep time: -0.006331, Sleep: 0.000000
2019-02-08T07:17:44.601Z,1549610264.601 [controlThread ThreadHandler](INFO): Cycle time: 0.403200, Period: 0.400000, Run time: 0.256910, Sleep time: 0.143105, Sleep: 0.146265
2019-02-08T07:17:44.648Z,1549610264.648 [logger ThreadHandler](INFO): Cycle time: 0.105240, Period: 0.050000, Run time: -1549610264.542022, Sleep time: -0.055224, Sleep: 0.000000
2019-02-08T07:17:44.715Z,1549610264.715 [logger ThreadHandler](INFO): Cycle time: 0.065687, Period: 0.050000, Run time: -1549610264.648492, Sleep time: -0.015671, Sleep: 0.000000
2019-02-08T07:17:44.767Z,1549610264.767 [logger ThreadHandler](INFO): Cycle time: 0.050867, Period: 0.050000, Run time: -1549610264.715293, Sleep time: -0.000851, Sleep: 0.000000
2019-02-08T07:17:44.826Z,1549610264.826 [logger ThreadHandler](INFO): Cycle time: 0.057464, Period: 0.050000, Run time: -1549610264.767370, Sleep time: -0.007445, Sleep: 0.000000
2019-02-08T07:17:44.882Z,1549610264.882 [logger ThreadHandler](INFO): Cycle time: 0.055201, Period: 0.050000, Run time: -1549610264.825998, Sleep time: -0.005185, Sleep: 0.000000
2019-02-08T07:17:44.935Z,1549610264.935 [logger ThreadHandler](INFO): Cycle time: 0.051383, Period: 0.050000, Run time: -1549610264.882341, Sleep time: -0.001368, Sleep: 0.000000
2019-02-08T07:17:44.991Z,1549610264.991 [logger ThreadHandler](INFO): Cycle time: 0.054793, Period: 0.050000, Run time: -1549610264.934863, Sleep time: -0.004778, Sleep: 0.000000
2019-02-08T07:17:45.005Z,1549610265.005 [controlThread ThreadHandler](INFO): Cycle time: 0.402889, Period: 0.400000, Run time: 0.137621, Sleep time: 0.262394, Sleep: 0.265222
2019-02-08T07:17:45.057Z,1549610265.057 [logger ThreadHandler](INFO): Cycle time: 0.065092, Period: 0.050000, Run time: -1549610264.990796, Sleep time: -0.015076, Sleep: 0.000000
2019-02-08T07:17:45.111Z,1549610265.111 [logger ThreadHandler](INFO): Cycle time: 0.052169, Period: 0.050000, Run time: -1549610265.057339, Sleep time: -0.002154, Sleep: 0.000000
2019-02-08T07:17:45.170Z,1549610265.170 [logger ThreadHandler](INFO): Cycle time: 0.057939, Period: 0.050000, Run time: -1549610265.110644, Sleep time: -0.007923, Sleep: 0.000000
2019-02-08T07:17:45.230Z,1549610265.230 [logger ThreadHandler](INFO): Cycle time: 0.059054, Period: 0.050000, Run time: -1549610265.169696, Sleep time: -0.009038, Sleep: 0.000000
2019-02-08T07:17:45.289Z,1549610265.289 [logger ThreadHandler](INFO): Cycle time: 0.058478, Period: 0.050000, Run time: -1549610265.229859, Sleep time: -0.008463, Sleep: 0.000000
2019-02-08T07:17:45.352Z,1549610265.352 [logger ThreadHandler](INFO): Cycle time: 0.060595, Period: 0.050000, Run time: -1549610265.289429, Sleep time: -0.010515, Sleep: 0.000000
2019-02-08T07:17:45.409Z,1549610265.409 [controlThread ThreadHandler](INFO): Cycle time: 0.402538, Period: 0.400000, Run time: 0.277958, Sleep time: 0.122056, Sleep: 0.124556
2019-02-08T07:17:45.468Z,1549610265.468 [logger ThreadHandler](INFO): Cycle time: 0.114785, Period: 0.050000, Run time: -1549610265.351654, Sleep time: -0.064765, Sleep: 0.000000
2019-02-08T07:17:45.533Z,1549610265.533 [logger ThreadHandler](INFO): Cycle time: 0.063703, Period: 0.050000, Run time: -1549610265.468601, Sleep time: -0.013686, Sleep: 0.000000
2019-02-08T07:17:45.602Z,1549610265.602 [logger ThreadHandler](INFO): Cycle time: 0.067898, Period: 0.050000, Run time: -1549610265.533422, Sleep time: -0.017881, Sleep: 0.000000
2019-02-08T07:17:45.658Z,1549610265.658 [logger ThreadHandler](INFO): Cycle time: 0.054565, Period: 0.050000, Run time: -1549610265.602426, Sleep time: -0.004551, Sleep: 0.000000
2019-02-08T07:17:45.714Z,1549610265.714 [logger ThreadHandler](INFO): Cycle time: 0.055379, Period: 0.050000, Run time: -1549610265.658094, Sleep time: -0.005365, Sleep: 0.000000
2019-02-08T07:17:45.770Z,1549610265.770 [logger ThreadHandler](INFO): Cycle time: 0.054052, Period: 0.050000, Run time: -1549610265.714570, Sleep time: -0.004037, Sleep: 0.000000
2019-02-08T07:17:45.813Z,1549610265.813 [controlThread ThreadHandler](INFO): Cycle time: 0.402389, Period: 0.400000, Run time: 0.185821, Sleep time: 0.214194, Sleep: 0.216517
2019-02-08T07:17:45.848Z,1549610265.848 [logger ThreadHandler](INFO): Cycle time: 0.077093, Period: 0.050000, Run time: -1549610265.769740, Sleep time: -0.027078, Sleep: 0.000000
2019-02-08T07:17:45.917Z,1549610265.917 [logger ThreadHandler](INFO): Cycle time: 0.067908, Period: 0.050000, Run time: -1549610265.848092, Sleep time: -0.017892, Sleep: 0.000000
2019-02-08T07:17:45.970Z,1549610265.970 [logger ThreadHandler](INFO): Cycle time: 0.051061, Period: 0.050000, Run time: -1549610265.917147, Sleep time: -0.001045, Sleep: 0.000000
2019-02-08T07:17:46.029Z,1549610266.029 [logger ThreadHandler](INFO): Cycle time: 0.058334, Period: 0.050000, Run time: -1549610265.970113, Sleep time: -0.008319, Sleep: 0.000000
2019-02-08T07:17:46.093Z,1549610266.093 [logger ThreadHandler](INFO): Cycle time: 0.062859, Period: 0.050000, Run time: -1549610266.029594, Sleep time: -0.012843, Sleep: 0.000000
2019-02-08T07:17:46.150Z,1549610266.150 [logger ThreadHandler](INFO): Cycle time: 0.055055, Period: 0.050000, Run time: -1549610266.093567, Sleep time: -0.005040, Sleep: 0.000000
2019-02-08T07:17:46.205Z,1549610266.205 [logger ThreadHandler](INFO): Cycle time: 0.054702, Period: 0.050000, Run time: -1549610266.149742, Sleep time: -0.004687, Sleep: 0.000000
2019-02-08T07:17:46.217Z,1549610266.217 [controlThread ThreadHandler](INFO): Cycle time: 0.402965, Period: 0.400000, Run time: 0.166461, Sleep time: 0.233553, Sleep: 0.236451
2019-02-08T07:17:46.260Z,1549610266.260 [logger ThreadHandler](INFO): Cycle time: 0.052481, Period: 0.050000, Run time: -1549610266.205598, Sleep time: -0.002465, Sleep: 0.000000
2019-02-08T07:17:46.314Z,1549610266.314 [logger ThreadHandler](INFO): Cycle time: 0.052634, Period: 0.050000, Run time: -1549610266.260361, Sleep time: -0.002620, Sleep: 0.000000
2019-02-08T07:17:46.370Z,1549610266.370 [logger ThreadHandler](INFO): Cycle time: 0.052815, Period: 0.050000, Run time: -1549610266.314136, Sleep time: -0.002795, Sleep: 0.000000
2019-02-08T07:17:46.428Z,1549610266.428 [logger ThreadHandler](INFO): Cycle time: 0.056400, Period: 0.050000, Run time: -1549610266.369975, Sleep time: -0.006380, Sleep: 0.000000
2019-02-08T07:17:46.497Z,1549610266.497 [logger ThreadHandler](INFO): Cycle time: 0.066091, Period: 0.050000, Run time: -1549610266.428500, Sleep time: -0.015934, Sleep: 0.000000
2019-02-08T07:17:46.550Z,1549610266.550 [logger ThreadHandler](INFO): Cycle time: 0.051831, Period: 0.050000, Run time: -1549610266.497422, Sleep time: -0.001815, Sleep: 0.000000
2019-02-08T07:17:46.613Z,1549610266.613 [logger ThreadHandler](INFO): Cycle time: 0.061539, Period: 0.050000, Run time: -1549610266.550349, Sleep time: -0.011523, Sleep: 0.000000
2019-02-08T07:17:46.621Z,1549610266.621 [controlThread ThreadHandler](INFO): Cycle time: 0.402795, Period: 0.400000, Run time: 0.351586, Sleep time: 0.048427, Sleep: 0.051190
2019-02-08T07:17:46.672Z,1549610266.672 [logger ThreadHandler](INFO): Cycle time: 0.057675, Period: 0.050000, Run time: -1549610266.613012, Sleep time: -0.007660, Sleep: 0.000000
2019-02-08T07:17:46.738Z,1549610266.738 [logger ThreadHandler](INFO): Cycle time: 0.065260, Period: 0.050000, Run time: -1549610266.671880, Sleep time: -0.015244, Sleep: 0.000000
2019-02-08T07:17:46.791Z,1549610266.791 [logger ThreadHandler](INFO): Cycle time: 0.051559, Period: 0.050000, Run time: -1549610266.738242, Sleep time: -0.001544, Sleep: 0.000000
2019-02-08T07:17:46.846Z,1549610266.846 [logger ThreadHandler](INFO): Cycle time: 0.053716, Period: 0.050000, Run time: -1549610266.790922, Sleep time: -0.003699, Sleep: 0.000000
2019-02-08T07:17:46.910Z,1549610266.910 [logger ThreadHandler](INFO): Cycle time: 0.063402, Period: 0.050000, Run time: -1549610266.845779, Sleep time: -0.013385, Sleep: 0.000000
2019-02-08T07:17:46.967Z,1549610266.967 [logger ThreadHandler](INFO): Cycle time: 0.054701, Period: 0.050000, Run time: -1549610266.910278, Sleep time: -0.004685, Sleep: 0.000000
2019-02-08T07:17:47.022Z,1549610267.022 [logger ThreadHandler](INFO): Cycle time: 0.054240, Period: 0.050000, Run time: -1549610266.966834, Sleep time: -0.004223, Sleep: 0.000000
2019-02-08T07:17:47.025Z,1549610267.025 [controlThread ThreadHandler](INFO): Cycle time: 0.403336, Period: 0.400000, Run time: 0.138401, Sleep time: 0.261613, Sleep: 0.264915
2019-02-08T07:17:47.033Z,1549610267.033 [Aanderaa_O2](INFO): Powering down
2019-02-08T07:17:47.078Z,1549610267.078 [logger ThreadHandler](INFO): Cycle time: 0.054257, Period: 0.050000, Run time: -1549610267.022706, Sleep time: -0.004242, Sleep: 0.000000
2019-02-08T07:17:47.130Z,1549610267.130 [logger ThreadHandler](INFO): Cycle time: 0.051321, Period: 0.050000, Run time: -1549610267.078106, Sleep time: -0.001306, Sleep: 0.000000
2019-02-08T07:17:47.190Z,1549610267.190 [logger ThreadHandler](INFO): Cycle time: 0.058492, Period: 0.050000, Run time: -1549610267.130562, Sleep time: -0.008476, Sleep: 0.000000
2019-02-08T07:17:47.245Z,1549610267.245 [logger ThreadHandler](INFO): Cycle time: 0.053885, Period: 0.050000, Run time: -1549610267.190189, Sleep time: -0.003868, Sleep: 0.000000
2019-02-08T07:17:47.309Z,1549610267.309 [logger ThreadHandler](INFO): Cycle time: 0.062658, Period: 0.050000, Run time: -1549610267.245229, Sleep time: -0.012641, Sleep: 0.000000
2019-02-08T07:17:47.364Z,1549610267.364 [logger ThreadHandler](INFO): Cycle time: 0.052997, Period: 0.050000, Run time: -1549610267.309015, Sleep time: -0.002977, Sleep: 0.000000
2019-02-08T07:17:47.430Z,1549610267.430 [controlThread ThreadHandler](INFO): Cycle time: 0.402654, Period: 0.400000, Run time: 0.139462, Sleep time: 0.260551, Sleep: 0.263168
2019-02-08T07:17:47.478Z,1549610267.478 [logger ThreadHandler](INFO): Cycle time: 0.111100, Period: 0.050000, Run time: -1549610267.363984, Sleep time: -0.061068, Sleep: 0.000000
2019-02-08T07:17:47.536Z,1549610267.536 [logger ThreadHandler](INFO): Cycle time: 0.056175, Period: 0.050000, Run time: -1549610267.478193, Sleep time: -0.006098, Sleep: 0.000000
2019-02-08T07:17:47.593Z,1549610267.593 [logger ThreadHandler](INFO): Cycle time: 0.055283, Period: 0.050000, Run time: -1549610267.536358, Sleep time: -0.005267, Sleep: 0.000000
2019-02-08T07:17:47.646Z,1549610267.646 [logger ThreadHandler](INFO): Cycle time: 0.052310, Period: 0.050000, Run time: -1549610267.592873, Sleep time: -0.002294, Sleep: 0.000000
2019-02-08T07:17:47.699Z,1549610267.699 [logger ThreadHandler](INFO): Cycle time: 0.051748, Period: 0.050000, Run time: -1549610267.646327, Sleep time: -0.001734, Sleep: 0.000000
2019-02-08T07:17:47.752Z,1549610267.752 [logger ThreadHandler](INFO): Cycle time: 0.051862, Period: 0.050000, Run time: -1549610267.699198, Sleep time: -0.001846, Sleep: 0.000000
2019-02-08T07:17:47.805Z,1549610267.805 [logger ThreadHandler](INFO): Cycle time: 0.051330, Period: 0.050000, Run time: -1549610267.752265, Sleep time: -0.001315, Sleep: 0.000000
2019-02-08T07:17:47.833Z,1549610267.833 [controlThread ThreadHandler](INFO): Cycle time: 0.402194, Period: 0.400000, Run time: 0.187343, Sleep time: 0.212671, Sleep: 0.214806
2019-02-08T07:17:47.877Z,1549610267.877 [logger ThreadHandler](INFO): Cycle time: 0.070955, Period: 0.050000, Run time: -1549610267.804797, Sleep time: -0.020936, Sleep: 0.000000
2019-02-08T07:17:47.941Z,1549610267.941 [logger ThreadHandler](INFO): Cycle time: 0.063430, Period: 0.050000, Run time: -1549610267.876890, Sleep time: -0.013415, Sleep: 0.000000
2019-02-08T07:17:47.995Z,1549610267.995 [logger ThreadHandler](INFO): Cycle time: 0.052170, Period: 0.050000, Run time: -1549610267.941456, Sleep time: -0.002155, Sleep: 0.000000
2019-02-08T07:17:48.051Z,1549610268.051 [logger ThreadHandler](INFO): Cycle time: 0.054976, Period: 0.050000, Run time: -1549610267.994765, Sleep time: -0.004961, Sleep: 0.000000
2019-02-08T07:17:48.106Z,1549610268.106 [logger ThreadHandler](INFO): Cycle time: 0.053657, Period: 0.050000, Run time: -1549610268.050861, Sleep time: -0.003642, Sleep: 0.000000
2019-02-08T07:17:48.163Z,1549610268.163 [logger ThreadHandler](INFO): Cycle time: 0.055889, Period: 0.050000, Run time: -1549610268.105634, Sleep time: -0.005872, Sleep: 0.000000
2019-02-08T07:17:48.214Z,1549610268.214 [logger ThreadHandler](INFO): Cycle time: 0.050666, Period: 0.050000, Run time: -1549610268.162626, Sleep time: -0.000651, Sleep: 0.000000
2019-02-08T07:17:48.237Z,1549610268.237 [controlThread ThreadHandler](INFO): Cycle time: 0.402836, Period: 0.400000, Run time: 0.129838, Sleep time: 0.270176, Sleep: 0.272978
2019-02-08T07:17:48.281Z,1549610268.281 [logger ThreadHandler](INFO): Cycle time: 0.065827, Period: 0.050000, Run time: -1549610268.214391, Sleep time: -0.015810, Sleep: 0.000000
2019-02-08T07:17:48.350Z,1549610268.350 [logger ThreadHandler](INFO): Cycle time: 0.067397, Period: 0.050000, Run time: -1549610268.281320, Sleep time: -0.017383, Sleep: 0.000000
2019-02-08T07:17:48.405Z,1549610268.405 [logger ThreadHandler](INFO): Cycle time: 0.053644, Period: 0.050000, Run time: -1549610268.349812, Sleep time: -0.003625, Sleep: 0.000000
2019-02-08T07:17:48.472Z,1549610268.472 [logger ThreadHandler](INFO): Cycle time: 0.064692, Period: 0.050000, Run time: -1549610268.405508, Sleep time: -0.014672, Sleep: 0.000000
2019-02-08T07:17:48.533Z,1549610268.533 [logger ThreadHandler](INFO): Cycle time: 0.058670, Period: 0.050000, Run time: -1549610268.472207, Sleep time: -0.008651, Sleep: 0.000000
2019-02-08T07:17:48.590Z,1549610268.590 [logger ThreadHandler](INFO): Cycle time: 0.055780, Period: 0.050000, Run time: -1549610268.532938, Sleep time: -0.005763, Sleep: 0.000000
2019-02-08T07:17:48.641Z,1549610268.641 [controlThread ThreadHandler](INFO): Cycle time: 0.403058, Period: 0.400000, Run time: 0.157348, Sleep time: 0.242670, Sleep: 0.245666
2019-02-08T07:17:48.659Z,1549610268.659 [logger ThreadHandler](INFO): Cycle time: 0.067504, Period: 0.050000, Run time: -1549610268.589822, Sleep time: -0.017486, Sleep: 0.000000
2019-02-08T07:17:48.717Z,1549610268.717 [logger ThreadHandler](INFO): Cycle time: 0.054750, Period: 0.050000, Run time: -1549610268.659468, Sleep time: -0.004682, Sleep: 0.000000
2019-02-08T07:17:48.773Z,1549610268.773 [logger ThreadHandler](INFO): Cycle time: 0.054839, Period: 0.050000, Run time: -1549610268.717570, Sleep time: -0.004824, Sleep: 0.000000
2019-02-08T07:17:48.838Z,1549610268.838 [logger ThreadHandler](INFO): Cycle time: 0.063387, Period: 0.050000, Run time: -1549610268.773536, Sleep time: -0.013371, Sleep: 0.000000
2019-02-08T07:17:48.893Z,1549610268.893 [logger ThreadHandler](INFO): Cycle time: 0.054439, Period: 0.050000, Run time: -1549610268.838040, Sleep time: -0.004424, Sleep: 0.000000
2019-02-08T07:17:48.958Z,1549610268.958 [logger ThreadHandler](INFO): Cycle time: 0.062963, Period: 0.050000, Run time: -1549610268.893589, Sleep time: -0.012948, Sleep: 0.000000
2019-02-08T07:17:49.022Z,1549610269.022 [logger ThreadHandler](INFO): Cycle time: 0.063299, Period: 0.050000, Run time: -1549610268.957661, Sleep time: -0.013285, Sleep: 0.000000
2019-02-08T07:17:49.045Z,1549610269.045 [controlThread ThreadHandler](INFO): Cycle time: 0.403034, Period: 0.400000, Run time: 0.109765, Sleep time: 0.290249, Sleep: 0.293247
2019-02-08T07:17:49.089Z,1549610269.089 [logger ThreadHandler](INFO): Cycle time: 0.066312, Period: 0.050000, Run time: -1549610269.022063, Sleep time: -0.016297, Sleep: 0.000000
2019-02-08T07:17:49.150Z,1549610269.150 [logger ThreadHandler](INFO): Cycle time: 0.059272, Period: 0.050000, Run time: -1549610269.089459, Sleep time: -0.009257, Sleep: 0.000000
2019-02-08T07:17:49.206Z,1549610269.206 [logger ThreadHandler](INFO): Cycle time: 0.055041, Period: 0.050000, Run time: -1549610269.149839, Sleep time: -0.005026, Sleep: 0.000000
2019-02-08T07:17:49.258Z,1549610269.258 [logger ThreadHandler](INFO): Cycle time: 0.050674, Period: 0.050000, Run time: -1549610269.205988, Sleep time: -0.000658, Sleep: 0.000000
2019-02-08T07:17:49.310Z,1549610269.310 [logger ThreadHandler](INFO): Cycle time: 0.051687, Period: 0.050000, Run time: -1549610269.257758, Sleep time: -0.001672, Sleep: 0.000000
2019-02-08T07:17:49.378Z,1549610269.378 [logger ThreadHandler](INFO): Cycle time: 0.063534, Period: 0.050000, Run time: -1549610269.310541, Sleep time: -0.013354, Sleep: 0.000000
2019-02-08T07:17:49.439Z,1549610269.439 [logger ThreadHandler](INFO): Cycle time: 0.058717, Period: 0.050000, Run time: -1549610269.378173, Sleep time: -0.008697, Sleep: 0.000000
2019-02-08T07:17:49.450Z,1549610269.450 [controlThread ThreadHandler](INFO): Cycle time: 0.403855, Period: 0.400000, Run time: 0.110564, Sleep time: 0.289451, Sleep: 0.293214
2019-02-08T07:17:49.461Z,1549610269.461 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-02-08T07:17:49.462Z,1549610269.462 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-02-08T07:17:49.462Z,1549610269.462 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-02-08T07:17:49.514Z,1549610269.514 [logger ThreadHandler](INFO): Cycle time: 0.073282, Period: 0.050000, Run time: -1549610269.438829, Sleep time: -0.023263, Sleep: 0.000000
2019-02-08T07:17:49.571Z,1549610269.571 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-02-08T07:17:49.581Z,1549610269.581 [logger ThreadHandler](INFO): Cycle time: 0.066805, Period: 0.050000, Run time: -1549610269.513613, Sleep time: -0.016790, Sleep: 0.000000
2019-02-08T07:17:49.635Z,1549610269.635 [logger ThreadHandler](INFO): Cycle time: 0.052219, Period: 0.050000, Run time: -1549610269.581518, Sleep time: -0.002204, Sleep: 0.000000
2019-02-08T07:17:49.690Z,1549610269.690 [logger ThreadHandler](INFO): Cycle time: 0.053729, Period: 0.050000, Run time: -1549610269.634844, Sleep time: -0.003714, Sleep: 0.000000
2019-02-08T07:17:49.746Z,1549610269.746 [logger ThreadHandler](INFO): Cycle time: 0.055073, Period: 0.050000, Run time: -1549610269.689674, Sleep time: -0.005057, Sleep: 0.000000
2019-02-08T07:17:49.802Z,1549610269.802 [logger ThreadHandler](INFO): Cycle time: 0.054772, Period: 0.050000, Run time: -1549610269.745853, Sleep time: -0.004756, Sleep: 0.000000
2019-02-08T07:17:49.853Z,1549610269.853 [controlThread ThreadHandler](INFO): Cycle time: 0.401642, Period: 0.400000, Run time: 0.138028, Sleep time: 0.261987, Sleep: 0.263558
2019-02-08T07:17:49.861Z,1549610269.861 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-02-08T07:17:49.891Z,1549610269.891 [logger ThreadHandler](INFO): Cycle time: 0.087957, Period: 0.050000, Run time: -1549610269.801736, Sleep time: -0.037942, Sleep: 0.000000
2019-02-08T07:17:49.951Z,1549610269.951 [logger ThreadHandler](INFO): Cycle time: 0.059035, Period: 0.050000, Run time: -1549610269.890809, Sleep time: -0.009020, Sleep: 0.000000
2019-02-08T07:17:50.007Z,1549610270.007 [logger ThreadHandler](INFO): Cycle time: 0.055300, Period: 0.050000, Run time: -1549610269.950963, Sleep time: -0.005286, Sleep: 0.000000
2019-02-08T07:17:50.060Z,1549610270.060 [logger ThreadHandler](INFO): Cycle time: 0.051384, Period: 0.050000, Run time: -1549610270.007379, Sleep time: -0.001368, Sleep: 0.000000
2019-02-08T07:17:50.112Z,1549610270.112 [logger ThreadHandler](INFO): Cycle time: 0.051095, Period: 0.050000, Run time: -1549610270.059960, Sleep time: -0.001080, Sleep: 0.000000
2019-02-08T07:17:50.164Z,1549610270.164 [logger ThreadHandler](INFO): Cycle time: 0.051010, Period: 0.050000, Run time: -1549610270.112257, Sleep time: -0.000994, Sleep: 0.000000
2019-02-08T07:17:50.225Z,1549610270.225 [logger ThreadHandler](INFO): Cycle time: 0.059433, Period: 0.050000, Run time: -1549610270.164453, Sleep time: -0.009418, Sleep: 0.000000
2019-02-08T07:17:50.257Z,1549610270.257 [controlThread ThreadHandler](INFO): Cycle time: 0.402857, Period: 0.400000, Run time: 0.152621, Sleep time: 0.247392, Sleep: 0.250217
2019-02-08T07:17:50.282Z,1549610270.282 [logger ThreadHandler](INFO): Cycle time: 0.055562, Period: 0.050000, Run time: -1549610270.225083, Sleep time: -0.004479, Sleep: 0.000000
2019-02-08T07:17:50.361Z,1549610270.361 [logger ThreadHandler](INFO): Cycle time: 0.077499, Period: 0.050000, Run time: -1549610270.281791, Sleep time: -0.027350, Sleep: 0.000000
2019-02-08T07:17:50.425Z,1549610270.425 [logger ThreadHandler](INFO): Cycle time: 0.062078, Period: 0.050000, Run time: -1549610270.361320, Sleep time: -0.012059, Sleep: 0.000000
2019-02-08T07:17:50.495Z,1549610270.495 [logger ThreadHandler](INFO): Cycle time: 0.068630, Period: 0.050000, Run time: -1549610270.425368, Sleep time: -0.018612, Sleep: 0.000000
2019-02-08T07:17:50.558Z,1549610270.558 [logger ThreadHandler](INFO): Cycle time: 0.061774, Period: 0.050000, Run time: -1549610270.495525, Sleep time: -0.011759, Sleep: 0.000000
2019-02-08T07:17:50.615Z,1549610270.615 [logger ThreadHandler](INFO): Cycle time: 0.055559, Period: 0.050000, Run time: -1549610270.558440, Sleep time: -0.005543, Sleep: 0.000000
2019-02-08T07:17:50.657Z,1549610270.657 [NavChartDb ThreadHandler](INFO): Cycle time: 12.483038, Period: 0.100000, Run time: -1549610258.172995, Sleep time: -12.383021, Sleep: 0.000000
2019-02-08T07:17:50.661Z,1549610270.661 [controlThread ThreadHandler](INFO): Cycle time: 0.402950, Period: 0.400000, Run time: 0.175437, Sleep time: 0.224657, Sleep: 0.227490
2019-02-08T07:17:50.712Z,1549610270.712 [logger ThreadHandler](INFO): Cycle time: 0.093276, Period: 0.050000, Run time: -1549610270.615148, Sleep time: -0.043224, Sleep: 0.000000
2019-02-08T07:17:50.766Z,1549610270.766 [logger ThreadHandler](INFO): Cycle time: 0.052663, Period: 0.050000, Run time: -1549610270.712009, Sleep time: -0.002649, Sleep: 0.000000
2019-02-08T07:17:50.778Z,1549610270.778 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-02-08T07:17:50.778Z,1549610270.778 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-02-08T07:17:50.829Z,1549610270.829 [logger ThreadHandler](INFO): Cycle time: 0.062567, Period: 0.050000, Run time: -1549610270.765802, Sleep time: -0.012553, Sleep: 0.000000
2019-02-08T07:17:50.887Z,1549610270.887 [logger ThreadHandler](INFO): Cycle time: 0.056332, Period: 0.050000, Run time: -1549610270.829505, Sleep time: -0.006314, Sleep: 0.000000
2019-02-08T07:17:50.939Z,1549610270.939 [logger ThreadHandler](INFO): Cycle time: 0.051208, Period: 0.050000, Run time: -1549610270.887098, Sleep time: -0.001193, Sleep: 0.000000
2019-02-08T07:17:50.994Z,1549610270.994 [logger ThreadHandler](INFO): Cycle time: 0.053250, Period: 0.050000, Run time: -1549610270.939433, Sleep time: -0.003235, Sleep: 0.000000
2019-02-08T07:17:51.050Z,1549610271.050 [logger ThreadHandler](INFO): Cycle time: 0.054816, Period: 0.050000, Run time: -1549610270.993840, Sleep time: -0.004801, Sleep: 0.000000
2019-02-08T07:17:51.065Z,1549610271.065 [controlThread ThreadHandler](INFO): Cycle time: 0.402684, Period: 0.400000, Run time: 0.123782, Sleep time: 0.276231, Sleep: 0.278858
2019-02-08T07:17:51.125Z,1549610271.125 [logger ThreadHandler](INFO): Cycle time: 0.074534, Period: 0.050000, Run time: -1549610271.049792, Sleep time: -0.024518, Sleep: 0.000000
2019-02-08T07:17:51.189Z,1549610271.189 [logger ThreadHandler](INFO): Cycle time: 0.062957, Period: 0.050000, Run time: -1549610271.125468, Sleep time: -0.012941, Sleep: 0.000000
2019-02-08T07:17:51.246Z,1549610271.246 [logger ThreadHandler](INFO): Cycle time: 0.055409, Period: 0.050000, Run time: -1549610271.189581, Sleep time: -0.005394, Sleep: 0.000000
2019-02-08T07:17:51.310Z,1549610271.310 [logger ThreadHandler](INFO): Cycle time: 0.062340, Period: 0.050000, Run time: -1549610271.246142, Sleep time: -0.012326, Sleep: 0.000000
2019-02-08T07:17:51.368Z,1549610271.368 [logger ThreadHandler](INFO): Cycle time: 0.056692, Period: 0.050000, Run time: -1549610271.309682, Sleep time: -0.006610, Sleep: 0.000000
2019-02-08T07:17:51.424Z,1549610271.424 [logger ThreadHandler](INFO): Cycle time: 0.054706, Period: 0.050000, Run time: -1549610271.368141, Sleep time: -0.004686, Sleep: 0.000000
2019-02-08T07:17:51.469Z,1549610271.469 [controlThread ThreadHandler](INFO): Cycle time: 0.402833, Period: 0.400000, Run time: 0.118123, Sleep time: 0.281892, Sleep: 0.284687
2019-02-08T07:17:51.531Z,1549610271.531 [logger ThreadHandler](INFO): Cycle time: 0.103013, Period: 0.050000, Run time: -1549610271.424498, Sleep time: -0.052945, Sleep: 0.000000
2019-02-08T07:17:51.583Z,1549610271.583 [logger ThreadHandler](INFO): Cycle time: 0.050929, Period: 0.050000, Run time: -1549610271.531021, Sleep time: -0.000913, Sleep: 0.000000
2019-02-08T07:17:51.638Z,1549610271.638 [logger ThreadHandler](INFO): Cycle time: 0.053783, Period: 0.050000, Run time: -1549610271.583085, Sleep time: -0.003768, Sleep: 0.000000
2019-02-08T07:17:51.694Z,1549610271.694 [logger ThreadHandler](INFO): Cycle time: 0.055157, Period: 0.050000, Run time: -1549610271.638005, Sleep time: -0.005142, Sleep: 0.000000
2019-02-08T07:17:51.750Z,1549610271.750 [logger ThreadHandler](INFO): Cycle time: 0.054404, Period: 0.050000, Run time: -1549610271.694286, Sleep time: -0.004388, Sleep: 0.000000
2019-02-08T07:17:51.806Z,1549610271.806 [logger ThreadHandler](INFO): Cycle time: 0.054997, Period: 0.050000, Run time: -1549610271.749832, Sleep time: -0.004982, Sleep: 0.000000
2019-02-08T07:17:51.862Z,1549610271.862 [logger ThreadHandler](INFO): Cycle time: 0.054666, Period: 0.050000, Run time: -1549610271.805969, Sleep time: -0.004651, Sleep: 0.000000
2019-02-08T07:17:51.884Z,1549610271.884 [controlThread ThreadHandler](INFO): Cycle time: 0.402222, Period: 0.400000, Run time: 0.130757, Sleep time: 0.269258, Sleep: 0.271446
2019-02-08T07:17:51.934Z,1549610271.934 [logger ThreadHandler](INFO): Cycle time: 0.070977, Period: 0.050000, Run time: -1549610271.861778, Sleep time: -0.020962, Sleep: 0.000000
2019-02-08T07:17:51.994Z,1549610271.994 [logger ThreadHandler](INFO): Cycle time: 0.058807, Period: 0.050000, Run time: -1549610271.933881, Sleep time: -0.008791, Sleep: 0.000000
2019-02-08T07:17:52.058Z,1549610272.058 [logger ThreadHandler](INFO): Cycle time: 0.063065, Period: 0.050000, Run time: -1549610271.993826, Sleep time: -0.013051, Sleep: 0.000000
2019-02-08T07:17:52.121Z,1549610272.121 [logger ThreadHandler](INFO): Cycle time: 0.061869, Period: 0.050000, Run time: -1549610272.058030, Sleep time: -0.011852, Sleep: 0.000000
2019-02-08T07:17:52.173Z,1549610272.173 [logger ThreadHandler](INFO): Cycle time: 0.051256, Period: 0.050000, Run time: -1549610272.121047, Sleep time: -0.001239, Sleep: 0.000000
2019-02-08T07:17:52.225Z,1549610272.225 [logger ThreadHandler](INFO): Cycle time: 0.050997, Period: 0.050000, Run time: -1549610272.173443, Sleep time: -0.000981, Sleep: 0.000000
2019-02-08T07:17:52.283Z,1549610272.283 [logger ThreadHandler](INFO): Cycle time: 0.056225, Period: 0.050000, Run time: -1549610272.225575, Sleep time: -0.006209, Sleep: 0.000000
2019-02-08T07:17:52.289Z,1549610272.289 [controlThread ThreadHandler](INFO): Cycle time: 0.403903, Period: 0.400000, Run time: 0.101772, Sleep time: 0.298241, Sleep: 0.302088
2019-02-08T07:17:52.335Z,1549610272.335 [logger ThreadHandler](INFO): Cycle time: 0.050613, Period: 0.050000, Run time: -1549610272.282935, Sleep time: -0.000598, Sleep: 0.000000
2019-02-08T07:17:52.394Z,1549610272.394 [logger ThreadHandler](INFO): Cycle time: 0.057779, Period: 0.050000, Run time: -1549610272.334673, Sleep time: -0.007760, Sleep: 0.000000
2019-02-08T07:17:52.459Z,1549610272.459 [logger ThreadHandler](INFO): Cycle time: 0.064041, Period: 0.050000, Run time: -1549610272.393973, Sleep time: -0.014024, Sleep: 0.000000
2019-02-08T07:17:52.521Z,1549610272.521 [logger ThreadHandler](INFO): Cycle time: 0.059828, Period: 0.050000, Run time: -1549610272.459546, Sleep time: -0.009761, Sleep: 0.000000
2019-02-08T07:17:52.582Z,1549610272.582 [logger ThreadHandler](INFO): Cycle time: 0.059391, Period: 0.050000, Run time: -1549610272.521359, Sleep time: -0.009375, Sleep: 0.000000
2019-02-08T07:17:52.613Z,1549610272.613 [NavChartDb ThreadHandler](INFO): Cycle time: 1.954189, Period: 0.100000, Run time: -1549610270.657243, Sleep time: -1.854170, Sleep: 0.000000
2019-02-08T07:17:52.641Z,1549610272.641 [logger ThreadHandler](INFO): Cycle time: 0.058261, Period: 0.050000, Run time: -1549610272.581891, Sleep time: -0.008245, Sleep: 0.000000
2019-02-08T07:17:52.693Z,1549610272.693 [controlThread ThreadHandler](INFO): Cycle time: 0.402840, Period: 0.400000, Run time: 0.160032, Sleep time: 0.239985, Sleep: 0.242787
2019-02-08T07:17:52.718Z,1549610272.718 [logger ThreadHandler](INFO): Cycle time: 0.074726, Period: 0.050000, Run time: -1549610272.641428, Sleep time: -0.024709, Sleep: 0.000000
2019-02-08T07:17:52.771Z,1549610272.771 [logger ThreadHandler](INFO): Cycle time: 0.052053, Period: 0.050000, Run time: -1549610272.718320, Sleep time: -0.002036, Sleep: 0.000000
2019-02-08T07:17:52.816Z,1549610272.816 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-08T07:17:52.823Z,1549610272.823 [logger ThreadHandler](INFO): Cycle time: 0.050639, Period: 0.050000, Run time: -1549610272.771499, Sleep time: -0.000624, Sleep: 0.000000
2019-02-08T07:17:52.829Z,1549610272.829 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-08T07:17:52.878Z,1549610272.878 [logger ThreadHandler](INFO): Cycle time: 0.053331, Period: 0.050000, Run time: -1549610272.823259, Sleep time: -0.003316, Sleep: 0.000000
2019-02-08T07:17:52.934Z,1549610272.934 [logger ThreadHandler](INFO): Cycle time: 0.055153, Period: 0.050000, Run time: -1549610272.877721, Sleep time: -0.005137, Sleep: 0.000000
2019-02-08T07:17:52.987Z,1549610272.987 [logger ThreadHandler](INFO): Cycle time: 0.051598, Period: 0.050000, Run time: -1549610272.933992, Sleep time: -0.001582, Sleep: 0.000000
2019-02-08T07:17:53.039Z,1549610273.039 [logger ThreadHandler](INFO): Cycle time: 0.051025, Period: 0.050000, Run time: -1549610272.986698, Sleep time: -0.001010, Sleep: 0.000000
2019-02-08T07:17:53.097Z,1549610273.097 [controlThread ThreadHandler](INFO): Cycle time: 0.402953, Period: 0.400000, Run time: 0.137988, Sleep time: 0.262026, Sleep: 0.264944
2019-02-08T07:17:53.105Z,1549610273.105 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-02-08T07:17:53.105Z,1549610273.105 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-02-08T07:17:53.108Z,1549610273.108 [logger ThreadHandler](INFO): Cycle time: 0.068221, Period: 0.050000, Run time: -1549610273.038864, Sleep time: -0.018207, Sleep: 0.000000
2019-02-08T07:17:53.163Z,1549610273.163 [logger ThreadHandler](INFO): Cycle time: 0.053376, Period: 0.050000, Run time: -1549610273.108326, Sleep time: -0.003360, Sleep: 0.000000
2019-02-08T07:17:53.226Z,1549610273.226 [logger ThreadHandler](INFO): Cycle time: 0.062274, Period: 0.050000, Run time: -1549610273.162821, Sleep time: -0.012257, Sleep: 0.000000
2019-02-08T07:17:53.284Z,1549610273.284 [logger ThreadHandler](INFO): Cycle time: 0.056399, Period: 0.050000, Run time: -1549610273.226231, Sleep time: -0.006384, Sleep: 0.000000
2019-02-08T07:17:53.337Z,1549610273.337 [logger ThreadHandler](INFO): Cycle time: 0.052109, Period: 0.050000, Run time: -1549610273.283893, Sleep time: -0.002091, Sleep: 0.000000
2019-02-08T07:17:53.425Z,1549610273.425 [logger ThreadHandler](INFO): Cycle time: 0.085303, Period: 0.050000, Run time: -1549610273.337164, Sleep time: -0.035200, Sleep: 0.000000
2019-02-08T07:17:53.501Z,1549610273.501 [controlThread ThreadHandler](INFO): Cycle time: 0.403056, Period: 0.400000, Run time: 0.208665, Sleep time: 0.191349, Sleep: 0.194367
2019-02-08T07:17:53.529Z,1549610273.529 [logger ThreadHandler](INFO): Cycle time: 0.101227, Period: 0.050000, Run time: -1549610273.425283, Sleep time: -0.051210, Sleep: 0.000000
2019-02-08T07:17:53.592Z,1549610273.592 [logger ThreadHandler](INFO): Cycle time: 0.061989, Period: 0.050000, Run time: -1549610273.528682, Sleep time: -0.011972, Sleep: 0.000000
2019-02-08T07:17:53.647Z,1549610273.647 [logger ThreadHandler](INFO): Cycle time: 0.053684, Period: 0.050000, Run time: -1549610273.592020, Sleep time: -0.003668, Sleep: 0.000000
2019-02-08T07:17:53.711Z,1549610273.711 [logger ThreadHandler](INFO): Cycle time: 0.063354, Period: 0.050000, Run time: -1549610273.646833, Sleep time: -0.013339, Sleep: 0.000000
2019-02-08T07:17:53.768Z,1549610273.768 [logger ThreadHandler](INFO): Cycle time: 0.055563, Period: 0.050000, Run time: -1549610273.711407, Sleep time: -0.005547, Sleep: 0.000000
2019-02-08T07:17:53.830Z,1549610273.830 [logger ThreadHandler](INFO): Cycle time: 0.061117, Period: 0.050000, Run time: -1549610273.768259, Sleep time: -0.011100, Sleep: 0.000000
2019-02-08T07:17:53.893Z,1549610273.893 [logger ThreadHandler](INFO): Cycle time: 0.061360, Period: 0.050000, Run time: -1549610273.830532, Sleep time: -0.011344, Sleep: 0.000000
2019-02-08T07:17:53.905Z,1549610273.905 [controlThread ThreadHandler](INFO): Cycle time: 0.402033, Period: 0.400000, Run time: 0.175147, Sleep time: 0.224867, Sleep: 0.226864
2019-02-08T07:17:53.958Z,1549610273.958 [logger ThreadHandler](INFO): Cycle time: 0.064162, Period: 0.050000, Run time: -1549610273.893042, Sleep time: -0.014146, Sleep: 0.000000
2019-02-08T07:17:54.020Z,1549610274.020 [logger ThreadHandler](INFO): Cycle time: 0.060237, Period: 0.050000, Run time: -1549610273.958391, Sleep time: -0.010221, Sleep: 0.000000
2019-02-08T07:17:54.078Z,1549610274.078 [logger ThreadHandler](INFO): Cycle time: 0.056871, Period: 0.050000, Run time: -1549610274.019989, Sleep time: -0.006856, Sleep: 0.000000
2019-02-08T07:17:54.134Z,1549610274.134 [logger ThreadHandler](INFO): Cycle time: 0.054745, Period: 0.050000, Run time: -1549610274.077975, Sleep time: -0.004730, Sleep: 0.000000
2019-02-08T07:17:54.202Z,1549610274.202 [logger ThreadHandler](INFO): Cycle time: 0.066987, Period: 0.050000, Run time: -1549610274.133849, Sleep time: -0.016971, Sleep: 0.000000
2019-02-08T07:17:54.255Z,1549610274.255 [logger ThreadHandler](INFO): Cycle time: 0.052306, Period: 0.050000, Run time: -1549610274.201959, Sleep time: -0.002291, Sleep: 0.000000
2019-02-08T07:17:54.309Z,1549610274.309 [controlThread ThreadHandler](INFO): Cycle time: 0.402912, Period: 0.400000, Run time: 0.195261, Sleep time: 0.204753, Sleep: 0.207631
2019-02-08T07:17:54.354Z,1549610274.354 [logger ThreadHandler](INFO): Cycle time: 0.095145, Period: 0.050000, Run time: -1549610274.255371, Sleep time: -0.045125, Sleep: 0.000000
2019-02-08T07:17:54.442Z,1549610274.442 [logger ThreadHandler](INFO): Cycle time: 0.084882, Period: 0.050000, Run time: -1549610274.353998, Sleep time: -0.034863, Sleep: 0.000000
2019-02-08T07:17:54.507Z,1549610274.507 [logger ThreadHandler](INFO): Cycle time: 0.062960, Period: 0.050000, Run time: -1549610274.441974, Sleep time: -0.012891, Sleep: 0.000000
2019-02-08T07:17:54.562Z,1549610274.562 [logger ThreadHandler](INFO): Cycle time: 0.053820, Period: 0.050000, Run time: -1549610274.507270, Sleep time: -0.003804, Sleep: 0.000000
2019-02-08T07:17:54.616Z,1549610274.616 [logger ThreadHandler](INFO): Cycle time: 0.052194, Period: 0.050000, Run time: -1549610274.562291, Sleep time: -0.002178, Sleep: 0.000000
2019-02-08T07:17:54.674Z,1549610274.674 [logger ThreadHandler](INFO): Cycle time: 0.057336, Period: 0.050000, Run time: -1549610274.615619, Sleep time: -0.007320, Sleep: 0.000000
2019-02-08T07:17:54.727Z,1549610274.727 [logger ThreadHandler](INFO): Cycle time: 0.052029, Period: 0.050000, Run time: -1549610274.674119, Sleep time: -0.002013, Sleep: 0.000000
2019-02-08T07:17:54.736Z,1549610274.736 [controlThread ThreadHandler](INFO): Cycle time: 0.423986, Period: 0.400000, Run time: -1549610274.308813, Sleep time: -0.023919, Sleep: 0.000000
2019-02-08T07:17:54.781Z,1549610274.781 [logger ThreadHandler](INFO): Cycle time: 0.051301, Period: 0.050000, Run time: -1549610274.727286, Sleep time: -0.001284, Sleep: 0.000000
2019-02-08T07:17:54.845Z,1549610274.845 [logger ThreadHandler](INFO): Cycle time: 0.062965, Period: 0.050000, Run time: -1549610274.781177, Sleep time: -0.012948, Sleep: 0.000000
2019-02-08T07:17:54.898Z,1549610274.898 [logger ThreadHandler](INFO): Cycle time: 0.051782, Period: 0.050000, Run time: -1549610274.845277, Sleep time: -0.001766, Sleep: 0.000000
2019-02-08T07:17:54.954Z,1549610274.954 [logger ThreadHandler](INFO): Cycle time: 0.054729, Period: 0.050000, Run time: -1549610274.898190, Sleep time: -0.004715, Sleep: 0.000000
2019-02-08T07:17:55.006Z,1549610275.006 [logger ThreadHandler](INFO): Cycle time: 0.050825, Period: 0.050000, Run time: -1549610274.954046, Sleep time: -0.000808, Sleep: 0.000000
2019-02-08T07:17:55.061Z,1549610275.061 [logger ThreadHandler](INFO): Cycle time: 0.054372, Period: 0.050000, Run time: -1549610275.006084, Sleep time: -0.004358, Sleep: 0.000000
2019-02-08T07:17:55.130Z,1549610275.130 [logger ThreadHandler](INFO): Cycle time: 0.067117, Period: 0.050000, Run time: -1549610275.061583, Sleep time: -0.017044, Sleep: 0.000000
2019-02-08T07:17:55.182Z,1549610275.182 [logger ThreadHandler](INFO): Cycle time: 0.050701, Period: 0.050000, Run time: -1549610275.130044, Sleep time: -0.000686, Sleep: 0.000000
2019-02-08T07:17:55.243Z,1549610275.243 [logger ThreadHandler](INFO): Cycle time: 0.056373, Period: 0.050000, Run time: -1549610275.181966, Sleep time: -0.006300, Sleep: 0.000000
2019-02-08T07:17:55.293Z,1549610275.293 [controlThread ThreadHandler](INFO): Cycle time: 0.556362, Period: 0.400000, Run time: -1549610274.735605, Sleep time: -0.156346, Sleep: 0.000000
2019-02-08T07:17:55.297Z,1549610275.297 [NavChartDb ThreadHandler](INFO): Cycle time: 2.670804, Period: 0.100000, Run time: -1549610272.612694, Sleep time: -2.570788, Sleep: 0.000000
2019-02-08T07:17:55.310Z,1549610275.310 [logger ThreadHandler](INFO): Cycle time: 0.065176, Period: 0.050000, Run time: -1549610275.243594, Sleep time: -0.015160, Sleep: 0.000000
2019-02-08T07:17:55.369Z,1549610275.369 [logger ThreadHandler](INFO): Cycle time: 0.057215, Period: 0.050000, Run time: -1549610275.309905, Sleep time: -0.007147, Sleep: 0.000000
2019-02-08T07:17:55.432Z,1549610275.432 [logger ThreadHandler](INFO): Cycle time: 0.061060, Period: 0.050000, Run time: -1549610275.369618, Sleep time: -0.011037, Sleep: 0.000000
2019-02-08T07:17:55.501Z,1549610275.501 [logger ThreadHandler](INFO): Cycle time: 0.066507, Period: 0.050000, Run time: -1549610275.432450, Sleep time: -0.016487, Sleep: 0.000000
2019-02-08T07:17:55.571Z,1549610275.571 [logger ThreadHandler](INFO): Cycle time: 0.068546, Period: 0.050000, Run time: -1549610275.501118, Sleep time: -0.018532, Sleep: 0.000000
2019-02-08T07:17:55.623Z,1549610275.623 [logger ThreadHandler](INFO): Cycle time: 0.051081, Period: 0.050000, Run time: -1549610275.570787, Sleep time: -0.001066, Sleep: 0.000000
2019-02-08T07:17:55.679Z,1549610275.679 [logger ThreadHandler](INFO): Cycle time: 0.054324, Period: 0.050000, Run time: -1549610275.623001, Sleep time: -0.004308, Sleep: 0.000000
2019-02-08T07:17:55.697Z,1549610275.697 [controlThread ThreadHandler](INFO): Cycle time: 0.402799, Period: 0.400000, Run time: 0.398716, Sleep time: 0.001301, Sleep: 0.004038
2019-02-08T07:17:55.732Z,1549610275.732 [logger ThreadHandler](INFO): Cycle time: 0.050967, Period: 0.050000, Run time: -1549610275.678801, Sleep time: -0.000951, Sleep: 0.000000
2019-02-08T07:17:55.787Z,1549610275.787 [logger ThreadHandler](INFO): Cycle time: 0.054420, Period: 0.050000, Run time: -1549610275.732024, Sleep time: -0.004406, Sleep: 0.000000
2019-02-08T07:17:55.861Z,1549610275.861 [logger ThreadHandler](INFO): Cycle time: 0.071602, Period: 0.050000, Run time: -1549610275.787574, Sleep time: -0.021586, Sleep: 0.000000
2019-02-08T07:17:55.914Z,1549610275.914 [logger ThreadHandler](INFO): Cycle time: 0.052244, Period: 0.050000, Run time: -1549610275.860752, Sleep time: -0.002228, Sleep: 0.000000
2019-02-08T07:17:55.983Z,1549610275.983 [logger ThreadHandler](INFO): Cycle time: 0.067826, Period: 0.050000, Run time: -1549610275.914143, Sleep time: -0.017811, Sleep: 0.000000
2019-02-08T07:17:56.038Z,1549610276.038 [logger ThreadHandler](INFO): Cycle time: 0.053811, Period: 0.050000, Run time: -1549610275.983087, Sleep time: -0.003796, Sleep: 0.000000
2019-02-08T07:17:56.093Z,1549610276.093 [logger ThreadHandler](INFO): Cycle time: 0.054303, Period: 0.050000, Run time: -1549610276.038039, Sleep time: -0.004288, Sleep: 0.000000
2019-02-08T07:17:56.101Z,1549610276.101 [controlThread ThreadHandler](INFO): Cycle time: 0.402889, Period: 0.400000, Run time: 0.312362, Sleep time: 0.087651, Sleep: 0.090484
2019-02-08T07:17:56.125Z,1549610276.125 [NAL9602](INFO): Powering up NAL9602
2019-02-08T07:17:56.157Z,1549610276.157 [logger ThreadHandler](INFO): Cycle time: 0.062817, Period: 0.050000, Run time: -1549610276.093485, Sleep time: -0.012801, Sleep: 0.000000
2019-02-08T07:17:56.211Z,1549610276.211 [logger ThreadHandler](INFO): Cycle time: 0.053006, Period: 0.050000, Run time: -1549610276.157436, Sleep time: -0.002986, Sleep: 0.000000
2019-02-08T07:17:56.270Z,1549610276.270 [logger ThreadHandler](INFO): Cycle time: 0.056910, Period: 0.050000, Run time: -1549610276.211587, Sleep time: -0.006895, Sleep: 0.000000
2019-02-08T07:17:56.342Z,1549610276.342 [logger ThreadHandler](INFO): Cycle time: 0.069447, Period: 0.050000, Run time: -1549610276.269638, Sleep time: -0.019379, Sleep: 0.000000
2019-02-08T07:17:56.398Z,1549610276.398 [logger ThreadHandler](INFO): Cycle time: 0.054649, Period: 0.050000, Run time: -1549610276.341760, Sleep time: -0.004630, Sleep: 0.000000
2019-02-08T07:17:56.462Z,1549610276.462 [logger ThreadHandler](INFO): Cycle time: 0.062332, Period: 0.050000, Run time: -1549610276.397982, Sleep time: -0.012256, Sleep: 0.000000
2019-02-08T07:17:56.505Z,1549610276.505 [controlThread ThreadHandler](INFO): Cycle time: 0.403188, Period: 0.400000, Run time: 0.262404, Sleep time: 0.137612, Sleep: 0.140681
2019-02-08T07:17:56.566Z,1549610276.566 [logger ThreadHandler](INFO): Cycle time: 0.102593, Period: 0.050000, Run time: -1549610276.461947, Sleep time: -0.052578, Sleep: 0.000000
2019-02-08T07:17:56.622Z,1549610276.622 [logger ThreadHandler](INFO): Cycle time: 0.052814, Period: 0.050000, Run time: -1549610276.565668, Sleep time: -0.002798, Sleep: 0.000000
2019-02-08T07:17:56.678Z,1549610276.678 [logger ThreadHandler](INFO): Cycle time: 0.054403, Period: 0.050000, Run time: -1549610276.622469, Sleep time: -0.004388, Sleep: 0.000000
2019-02-08T07:17:56.739Z,1549610276.739 [logger ThreadHandler](INFO): Cycle time: 0.059494, Period: 0.050000, Run time: -1549610276.678014, Sleep time: -0.009479, Sleep: 0.000000
2019-02-08T07:17:56.791Z,1549610276.791 [logger ThreadHandler](INFO): Cycle time: 0.050907, Period: 0.050000, Run time: -1549610276.738650, Sleep time: -0.000892, Sleep: 0.000000
2019-02-08T07:17:56.843Z,1549610276.843 [logger ThreadHandler](INFO): Cycle time: 0.050975, Period: 0.050000, Run time: -1549610276.790695, Sleep time: -0.000955, Sleep: 0.000000
2019-02-08T07:17:56.898Z,1549610276.898 [logger ThreadHandler](INFO): Cycle time: 0.054529, Period: 0.050000, Run time: -1549610276.842829, Sleep time: -0.004514, Sleep: 0.000000
2019-02-08T07:17:56.909Z,1549610276.909 [controlThread ThreadHandler](INFO): Cycle time: 0.402307, Period: 0.400000, Run time: 0.228489, Sleep time: 0.171525, Sleep: 0.173766
2019-02-08T07:17:56.950Z,1549610276.950 [logger ThreadHandler](INFO): Cycle time: 0.050633, Period: 0.050000, Run time: -1549610276.898481, Sleep time: -0.000619, Sleep: 0.000000
2019-02-08T07:17:57.002Z,1549610277.002 [logger ThreadHandler](INFO): Cycle time: 0.050619, Period: 0.050000, Run time: -1549610276.950243, Sleep time: -0.000605, Sleep: 0.000000
2019-02-08T07:17:57.057Z,1549610277.057 [logger ThreadHandler](INFO): Cycle time: 0.054415, Period: 0.050000, Run time: -1549610277.001988, Sleep time: -0.004400, Sleep: 0.000000
2019-02-08T07:17:57.117Z,1549610277.117 [logger ThreadHandler](INFO): Cycle time: 0.056831, Period: 0.050000, Run time: -1549610277.057537, Sleep time: -0.006767, Sleep: 0.000000
2019-02-08T07:17:57.182Z,1549610277.182 [logger ThreadHandler](INFO): Cycle time: 0.062854, Period: 0.050000, Run time: -1549610277.117659, Sleep time: -0.012837, Sleep: 0.000000
2019-02-08T07:17:57.239Z,1549610277.239 [logger ThreadHandler](INFO): Cycle time: 0.056454, Period: 0.050000, Run time: -1549610277.181632, Sleep time: -0.006437, Sleep: 0.000000
2019-02-08T07:17:57.292Z,1549610277.292 [logger ThreadHandler](INFO): Cycle time: 0.051633, Period: 0.050000, Run time: -1549610277.239211, Sleep time: -0.001619, Sleep: 0.000000
2019-02-08T07:17:57.313Z,1549610277.313 [controlThread ThreadHandler](INFO): Cycle time: 0.402821, Period: 0.400000, Run time: 0.380236, Sleep time: 0.019777, Sleep: 0.022533
2019-02-08T07:17:57.345Z,1549610277.345 [logger ThreadHandler](INFO): Cycle time: 0.051106, Period: 0.050000, Run time: -1549610277.292077, Sleep time: -0.001089, Sleep: 0.000000
2019-02-08T07:17:57.414Z,1549610277.414 [logger ThreadHandler](INFO): Cycle time: 0.065751, Period: 0.050000, Run time: -1549610277.345411, Sleep time: -0.015731, Sleep: 0.000000
2019-02-08T07:17:57.489Z,1549610277.489 [logger ThreadHandler](INFO): Cycle time: 0.072697, Period: 0.050000, Run time: -1549610277.414085, Sleep time: -0.022676, Sleep: 0.000000
2019-02-08T07:17:57.545Z,1549610277.545 [logger ThreadHandler](INFO): Cycle time: 0.054180, Period: 0.050000, Run time: -1549610277.488990, Sleep time: -0.004160, Sleep: 0.000000
2019-02-08T07:17:57.600Z,1549610277.600 [logger ThreadHandler](INFO): Cycle time: 0.054085, Period: 0.050000, Run time: -1549610277.545238, Sleep time: -0.004069, Sleep: 0.000000
2019-02-08T07:17:57.660Z,1549610277.660 [logger ThreadHandler](INFO): Cycle time: 0.057735, Period: 0.050000, Run time: -1549610277.600537, Sleep time: -0.007720, Sleep: 0.000000
2019-02-08T07:17:57.717Z,1549610277.717 [logger ThreadHandler](INFO): Cycle time: 0.051285, Period: 0.050000, Run time: -1549610277.660259, Sleep time: -0.001269, Sleep: 0.000000
2019-02-08T07:17:57.744Z,1549610277.744 [controlThread ThreadHandler](INFO): Cycle time: 0.429843, Period: 0.400000, Run time: -1549610277.312977, Sleep time: -0.029829, Sleep: 0.000000
2019-02-08T07:17:57.778Z,1549610277.778 [logger ThreadHandler](INFO): Cycle time: 0.059285, Period: 0.050000, Run time: -1549610277.717041, Sleep time: -0.009268, Sleep: 0.000000
2019-02-08T07:17:57.834Z,1549610277.834 [logger ThreadHandler](INFO): Cycle time: 0.054063, Period: 0.050000, Run time: -1549610277.778480, Sleep time: -0.004047, Sleep: 0.000000
2019-02-08T07:17:57.890Z,1549610277.890 [logger ThreadHandler](INFO): Cycle time: 0.054862, Period: 0.050000, Run time: -1549610277.833762, Sleep time: -0.004845, Sleep: 0.000000
2019-02-08T07:17:57.950Z,1549610277.950 [logger ThreadHandler](INFO): Cycle time: 0.058386, Period: 0.050000, Run time: -1549610277.890435, Sleep time: -0.008372, Sleep: 0.000000
2019-02-08T07:17:58.006Z,1549610278.006 [logger ThreadHandler](INFO): Cycle time: 0.054595, Period: 0.050000, Run time: -1549610277.949956, Sleep time: -0.004578, Sleep: 0.000000
2019-02-08T07:17:58.058Z,1549610278.058 [logger ThreadHandler](INFO): Cycle time: 0.050974, Period: 0.050000, Run time: -1549610278.005686, Sleep time: -0.000958, Sleep: 0.000000
2019-02-08T07:17:58.114Z,1549610278.114 [logger ThreadHandler](INFO): Cycle time: 0.054952, Period: 0.050000, Run time: -1549610278.057818, Sleep time: -0.004932, Sleep: 0.000000
2019-02-08T07:17:58.149Z,1549610278.149 [controlThread ThreadHandler](INFO): Cycle time: 0.403935, Period: 0.400000, Run time: 0.203733, Sleep time: 0.196425, Sleep: 0.200180
2019-02-08T07:17:58.177Z,1549610278.177 [logger ThreadHandler](INFO): Cycle time: 0.060514, Period: 0.050000, Run time: -1549610278.113911, Sleep time: -0.010497, Sleep: 0.000000
2019-02-08T07:17:58.252Z,1549610278.252 [logger ThreadHandler](INFO): Cycle time: 0.074273, Period: 0.050000, Run time: -1549610278.176703, Sleep time: -0.024253, Sleep: 0.000000
2019-02-08T07:17:58.304Z,1549610278.304 [logger ThreadHandler](INFO): Cycle time: 0.051053, Period: 0.050000, Run time: -1549610278.252242, Sleep time: -0.001035, Sleep: 0.000000
2019-02-08T07:17:58.362Z,1549610278.362 [logger ThreadHandler](INFO): Cycle time: 0.056281, Period: 0.050000, Run time: -1549610278.304555, Sleep time: -0.006265, Sleep: 0.000000
2019-02-08T07:17:58.448Z,1549610278.448 [logger ThreadHandler](INFO): Cycle time: 0.084522, Period: 0.050000, Run time: -1549610278.361968, Sleep time: -0.034449, Sleep: 0.000000
2019-02-08T07:17:58.512Z,1549610278.512 [logger ThreadHandler](INFO): Cycle time: 0.061754, Period: 0.050000, Run time: -1549610278.448429, Sleep time: -0.011733, Sleep: 0.000000
2019-02-08T07:17:58.553Z,1549610278.553 [controlThread ThreadHandler](INFO): Cycle time: 0.402872, Period: 0.400000, Run time: 0.319420, Sleep time: 0.080597, Sleep: 0.083392
2019-02-08T07:17:58.568Z,1549610278.568 [logger ThreadHandler](INFO): Cycle time: 0.053860, Period: 0.050000, Run time: -1549610278.512185, Sleep time: -0.003845, Sleep: 0.000000
2019-02-08T07:17:58.634Z,1549610278.634 [logger ThreadHandler](INFO): Cycle time: 0.064297, Period: 0.050000, Run time: -1549610278.568319, Sleep time: -0.014283, Sleep: 0.000000
2019-02-08T07:17:58.708Z,1549610278.708 [logger ThreadHandler](INFO): Cycle time: 0.073573, Period: 0.050000, Run time: -1549610278.633748, Sleep time: -0.023558, Sleep: 0.000000
2019-02-08T07:17:58.783Z,1549610278.783 [logger ThreadHandler](INFO): Cycle time: 0.073552, Period: 0.050000, Run time: -1549610278.708547, Sleep time: -0.023536, Sleep: 0.000000
2019-02-08T07:17:58.836Z,1549610278.836 [logger ThreadHandler](INFO): Cycle time: 0.051965, Period: 0.050000, Run time: -1549610278.783230, Sleep time: -0.001950, Sleep: 0.000000
2019-02-08T07:17:58.900Z,1549610278.900 [logger ThreadHandler](INFO): Cycle time: 0.062336, Period: 0.050000, Run time: -1549610278.836555, Sleep time: -0.012316, Sleep: 0.000000
2019-02-08T07:17:58.957Z,1549610278.957 [controlThread ThreadHandler](INFO): Cycle time: 0.402761, Period: 0.400000, Run time: 0.210759, Sleep time: 0.189256, Sleep: 0.191983
2019-02-08T07:17:58.972Z,1549610278.972 [logger ThreadHandler](INFO): Cycle time: 0.071007, Period: 0.050000, Run time: -1549610278.900140, Sleep time: -0.020987, Sleep: 0.000000
2019-02-08T07:17:59.024Z,1549610279.024 [logger ThreadHandler](INFO): Cycle time: 0.050906, Period: 0.050000, Run time: -1549610278.972449, Sleep time: -0.000890, Sleep: 0.000000
2019-02-08T07:17:59.078Z,1549610279.078 [logger ThreadHandler](INFO): Cycle time: 0.052161, Period: 0.050000, Run time: -1549610279.024588, Sleep time: -0.002145, Sleep: 0.000000
2019-02-08T07:17:59.131Z,1549610279.131 [logger ThreadHandler](INFO): Cycle time: 0.050711, Period: 0.050000, Run time: -1549610279.078531, Sleep time: -0.000696, Sleep: 0.000000
2019-02-08T07:17:59.186Z,1549610279.186 [logger ThreadHandler](INFO): Cycle time: 0.053569, Period: 0.050000, Run time: -1549610279.131074, Sleep time: -0.003554, Sleep: 0.000000
2019-02-08T07:17:59.238Z,1549610279.238 [logger ThreadHandler](INFO): Cycle time: 0.050715, Period: 0.050000, Run time: -1549610279.185779, Sleep time: -0.000699, Sleep: 0.000000
2019-02-08T07:17:59.290Z,1549610279.290 [logger ThreadHandler](INFO): Cycle time: 0.050793, Period: 0.050000, Run time: -1549610279.237630, Sleep time: -0.000778, Sleep: 0.000000
2019-02-08T07:17:59.346Z,1549610279.346 [logger ThreadHandler](INFO): Cycle time: 0.054912, Period: 0.050000, Run time: -1549610279.289632, Sleep time: -0.004896, Sleep: 0.000000
2019-02-08T07:17:59.362Z,1549610279.362 [controlThread ThreadHandler](INFO): Cycle time: 0.403350, Period: 0.400000, Run time: 0.222906, Sleep time: 0.177109, Sleep: 0.180354
2019-02-08T07:17:59.427Z,1549610279.427 [logger ThreadHandler](INFO): Cycle time: 0.079467, Period: 0.050000, Run time: -1549610279.345671, Sleep time: -0.029451, Sleep: 0.000000
2019-02-08T07:17:59.482Z,1549610279.482 [logger ThreadHandler](INFO): Cycle time: 0.053910, Period: 0.050000, Run time: -1549610279.426926, Sleep time: -0.003895, Sleep: 0.000000
2019-02-08T07:17:59.545Z,1549610279.545 [logger ThreadHandler](INFO): Cycle time: 0.059544, Period: 0.050000, Run time: -1549610279.482329, Sleep time: -0.009471, Sleep: 0.000000
2019-02-08T07:17:59.598Z,1549610279.598 [logger ThreadHandler](INFO): Cycle time: 0.052089, Period: 0.050000, Run time: -1549610279.545176, Sleep time: -0.002074, Sleep: 0.000000
2019-02-08T07:17:59.658Z,1549610279.658 [logger ThreadHandler](INFO): Cycle time: 0.058657, Period: 0.050000, Run time: -1549610279.598391, Sleep time: -0.008642, Sleep: 0.000000
2019-02-08T07:17:59.710Z,1549610279.710 [logger ThreadHandler](INFO): Cycle time: 0.051093, Period: 0.050000, Run time: -1549610279.658202, Sleep time: -0.001078, Sleep: 0.000000
2019-02-08T07:17:59.765Z,1549610279.765 [controlThread ThreadHandler](INFO): Cycle time: 0.402031, Period: 0.400000, Run time: 0.294095, Sleep time: 0.105921, Sleep: 0.107881
2019-02-08T07:17:59.800Z,1549610279.800 [logger ThreadHandler](INFO): Cycle time: 0.079563, Period: 0.050000, Run time: -1549610279.710435, Sleep time: -0.029533, Sleep: 0.000000
2019-02-08T07:17:59.858Z,1549610279.858 [logger ThreadHandler](INFO): Cycle time: 0.056057, Period: 0.050000, Run time: -1549610279.800446, Sleep time: -0.006042, Sleep: 0.000000
2019-02-08T07:17:59.917Z,1549610279.917 [logger ThreadHandler](INFO): Cycle time: 0.058743, Period: 0.050000, Run time: -1549610279.857632, Sleep time: -0.008728, Sleep: 0.000000
2019-02-08T07:17:59.979Z,1549610279.979 [logger ThreadHandler](INFO): Cycle time: 0.059986, Period: 0.050000, Run time: -1549610279.917513, Sleep time: -0.009973, Sleep: 0.000000
2019-02-08T07:18:00.046Z,1549610280.046 [logger ThreadHandler](INFO): Cycle time: 0.066040, Period: 0.050000, Run time: -1549610279.978624, Sleep time: -0.016024, Sleep: 0.000000
2019-02-08T07:18:00.098Z,1549610280.098 [logger ThreadHandler](INFO): Cycle time: 0.050892, Period: 0.050000, Run time: -1549610280.045801, Sleep time: -0.000877, Sleep: 0.000000
2019-02-08T07:18:00.150Z,1549610280.150 [logger ThreadHandler](INFO): Cycle time: 0.051491, Period: 0.050000, Run time: -1549610280.097868, Sleep time: -0.001476, Sleep: 0.000000
2019-02-08T07:18:00.169Z,1549610280.169 [controlThread ThreadHandler](INFO): Cycle time: 0.402768, Period: 0.400000, Run time: 0.212237, Sleep time: 0.187776, Sleep: 0.190511
2019-02-08T07:18:00.214Z,1549610280.214 [logger ThreadHandler](INFO): Cycle time: 0.062189, Period: 0.050000, Run time: -1549610280.150506, Sleep time: -0.012174, Sleep: 0.000000
2019-02-08T07:18:00.266Z,1549610280.266 [logger ThreadHandler](INFO): Cycle time: 0.050759, Period: 0.050000, Run time: -1549610280.213829, Sleep time: -0.000742, Sleep: 0.000000
2019-02-08T07:18:00.326Z,1549610280.326 [logger ThreadHandler](INFO): Cycle time: 0.057170, Period: 0.050000, Run time: -1549610280.265699, Sleep time: -0.007152, Sleep: 0.000000
2019-02-08T07:18:00.385Z,1549610280.385 [logger ThreadHandler](INFO): Cycle time: 0.056571, Period: 0.050000, Run time: -1549610280.326245, Sleep time: -0.006551, Sleep: 0.000000
2019-02-08T07:18:00.444Z,1549610280.444 [logger ThreadHandler](INFO): Cycle time: 0.057257, Period: 0.050000, Run time: -1549610280.384826, Sleep time: -0.007237, Sleep: 0.000000
2019-02-08T07:18:00.502Z,1549610280.502 [logger ThreadHandler](INFO): Cycle time: 0.055677, Period: 0.050000, Run time: -1549610280.444123, Sleep time: -0.005654, Sleep: 0.000000
2019-02-08T07:18:00.554Z,1549610280.554 [logger ThreadHandler](INFO): Cycle time: 0.051749, Period: 0.050000, Run time: -1549610280.501685, Sleep time: -0.001733, Sleep: 0.000000
2019-02-08T07:18:00.573Z,1549610280.573 [controlThread ThreadHandler](INFO): Cycle time: 0.403082, Period: 0.400000, Run time: 0.126855, Sleep time: 0.273159, Sleep: 0.276173
2019-02-08T07:18:00.581Z,1549610280.581 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-02-08T07:18:00.621Z,1549610280.621 [logger ThreadHandler](INFO): Cycle time: 0.065769, Period: 0.050000, Run time: -1549610280.554565, Sleep time: -0.015754, Sleep: 0.000000
2019-02-08T07:18:00.674Z,1549610280.674 [logger ThreadHandler](INFO): Cycle time: 0.051498, Period: 0.050000, Run time: -1549610280.621460, Sleep time: -0.001481, Sleep: 0.000000
2019-02-08T07:18:00.733Z,1549610280.733 [logger ThreadHandler](INFO): Cycle time: 0.058063, Period: 0.050000, Run time: -1549610280.674093, Sleep time: -0.008047, Sleep: 0.000000
2019-02-08T07:18:00.785Z,1549610280.785 [logger ThreadHandler](INFO): Cycle time: 0.051169, Period: 0.050000, Run time: -1549610280.733295, Sleep time: -0.001152, Sleep: 0.000000
2019-02-08T07:18:00.842Z,1549610280.842 [logger ThreadHandler](INFO): Cycle time: 0.055599, Period: 0.050000, Run time: -1549610280.785584, Sleep time: -0.005583, Sleep: 0.000000
2019-02-08T07:18:00.895Z,1549610280.895 [NavChartDb ThreadHandler](INFO): Cycle time: 5.597004, Period: 0.100000, Run time: -1549610275.296834, Sleep time: -5.496986, Sleep: 0.000000
2019-02-08T07:18:00.898Z,1549610280.898 [logger ThreadHandler](INFO): Cycle time: 0.054366, Period: 0.050000, Run time: -1549610280.842330, Sleep time: -0.004350, Sleep: 0.000000
2019-02-08T07:18:00.956Z,1549610280.956 [logger ThreadHandler](INFO): Cycle time: 0.057139, Period: 0.050000, Run time: -1549610280.897823, Sleep time: -0.007124, Sleep: 0.000000
2019-02-08T07:18:00.977Z,1549610280.977 [controlThread ThreadHandler](INFO): Cycle time: 0.403009, Period: 0.400000, Run time: 0.154362, Sleep time: 0.245652, Sleep: 0.248593
2019-02-08T07:18:01.025Z,1549610281.025 [logger ThreadHandler](INFO): Cycle time: 0.068212, Period: 0.050000, Run time: -1549610280.956216, Sleep time: -0.018196, Sleep: 0.000000
2019-02-08T07:18:01.082Z,1549610281.082 [logger ThreadHandler](INFO): Cycle time: 0.055141, Period: 0.050000, Run time: -1549610281.025556, Sleep time: -0.005125, Sleep: 0.000000
2019-02-08T07:18:01.145Z,1549610281.145 [logger ThreadHandler](INFO): Cycle time: 0.060036, Period: 0.050000, Run time: -1549610281.081814, Sleep time: -0.010020, Sleep: 0.000000
2019-02-08T07:18:01.205Z,1549610281.205 [logger ThreadHandler](INFO): Cycle time: 0.058501, Period: 0.050000, Run time: -1549610281.145496, Sleep time: -0.008486, Sleep: 0.000000
2019-02-08T07:18:01.259Z,1549610281.259 [logger ThreadHandler](INFO): Cycle time: 0.053074, Period: 0.050000, Run time: -1549610281.205151, Sleep time: -0.003058, Sleep: 0.000000
2019-02-08T07:18:01.312Z,1549610281.312 [logger ThreadHandler](INFO): Cycle time: 0.051808, Period: 0.050000, Run time: -1549610281.259363, Sleep time: -0.001790, Sleep: 0.000000
2019-02-08T07:18:01.373Z,1549610281.373 [logger ThreadHandler](INFO): Cycle time: 0.057690, Period: 0.050000, Run time: -1549610281.312472, Sleep time: -0.007670, Sleep: 0.000000
2019-02-08T07:18:01.386Z,1549610281.386 [controlThread ThreadHandler](INFO): Cycle time: 0.403838, Period: 0.400000, Run time: 0.253321, Sleep time: 0.146692, Sleep: 0.150493
2019-02-08T07:18:01.439Z,1549610281.439 [logger ThreadHandler](INFO): Cycle time: 0.063458, Period: 0.050000, Run time: -1549610281.373394, Sleep time: -0.013444, Sleep: 0.000000
2019-02-08T07:18:01.508Z,1549610281.508 [logger ThreadHandler](INFO): Cycle time: 0.067601, Period: 0.050000, Run time: -1549610281.438657, Sleep time: -0.017582, Sleep: 0.000000
2019-02-08T07:18:01.561Z,1549610281.561 [logger ThreadHandler](INFO): Cycle time: 0.051467, Period: 0.050000, Run time: -1549610281.508512, Sleep time: -0.001450, Sleep: 0.000000
2019-02-08T07:18:01.617Z,1549610281.617 [logger ThreadHandler](INFO): Cycle time: 0.054964, Period: 0.050000, Run time: -1549610281.561116, Sleep time: -0.004946, Sleep: 0.000000
2019-02-08T07:18:01.673Z,1549610281.673 [logger ThreadHandler](INFO): Cycle time: 0.055171, Period: 0.050000, Run time: -1549610281.617216, Sleep time: -0.005155, Sleep: 0.000000
2019-02-08T07:18:01.731Z,1549610281.731 [logger ThreadHandler](INFO): Cycle time: 0.055584, Period: 0.050000, Run time: -1549610281.673506, Sleep time: -0.005569, Sleep: 0.000000
2019-02-08T07:18:01.790Z,1549610281.790 [controlThread ThreadHandler](INFO): Cycle time: 0.402272, Period: 0.400000, Run time: 0.210365, Sleep time: 0.189650, Sleep: 0.191817
2019-02-08T07:18:01.803Z,1549610281.803 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-02-08T07:18:01.804Z,1549610281.804 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-02-08T07:18:01.805Z,1549610281.805 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-02-08T07:18:01.806Z,1549610281.806 [AcousticModem_Benthos_ATM900](DEBUG): Feb  8 2019 07:21:14 
2019-02-08T07:18:01.817Z,1549610281.817 [logger ThreadHandler](INFO): Cycle time: 0.083785, Period: 0.050000, Run time: -1549610281.730847, Sleep time: -0.033757, Sleep: 0.000000
2019-02-08T07:18:01.878Z,1549610281.878 [logger ThreadHandler](INFO): Cycle time: 0.059858, Period: 0.050000, Run time: -1549610281.816860, Sleep time: -0.009843, Sleep: 0.000000
2019-02-08T07:18:01.940Z,1549610281.940 [logger ThreadHandler](INFO): Cycle time: 0.061291, Period: 0.050000, Run time: -1549610281.877852, Sleep time: -0.011276, Sleep: 0.000000
2019-02-08T07:18:01.992Z,1549610281.992 [logger ThreadHandler](INFO): Cycle time: 0.050705, Period: 0.050000, Run time: -1549610281.940412, Sleep time: -0.000690, Sleep: 0.000000
2019-02-08T07:18:02.044Z,1549610282.044 [logger ThreadHandler](INFO): Cycle time: 0.050902, Period: 0.050000, Run time: -1549610281.992356, Sleep time: -0.000887, Sleep: 0.000000
2019-02-08T07:18:02.096Z,1549610282.096 [logger ThreadHandler](INFO): Cycle time: 0.050847, Period: 0.050000, Run time: -1549610282.044503, Sleep time: -0.000832, Sleep: 0.000000
2019-02-08T07:18:02.162Z,1549610282.162 [logger ThreadHandler](INFO): Cycle time: 0.064096, Period: 0.050000, Run time: -1549610282.096556, Sleep time: -0.014082, Sleep: 0.000000
2019-02-08T07:18:02.193Z,1549610282.193 [controlThread ThreadHandler](INFO): Cycle time: 0.402069, Period: 0.400000, Run time: 0.161737, Sleep time: 0.238277, Sleep: 0.240312
2019-02-08T07:18:02.246Z,1549610282.246 [logger ThreadHandler](INFO): Cycle time: 0.082198, Period: 0.050000, Run time: -1549610282.161828, Sleep time: -0.032179, Sleep: 0.000000
2019-02-08T07:18:02.301Z,1549610282.301 [logger ThreadHandler](INFO): Cycle time: 0.054031, Period: 0.050000, Run time: -1549610282.245951, Sleep time: -0.004016, Sleep: 0.000000
2019-02-08T07:18:02.362Z,1549610282.362 [logger ThreadHandler](INFO): Cycle time: 0.059488, Period: 0.050000, Run time: -1549610282.301125, Sleep time: -0.009473, Sleep: 0.000000
2019-02-08T07:18:02.417Z,1549610282.417 [logger ThreadHandler](INFO): Cycle time: 0.053159, Period: 0.050000, Run time: -1549610282.361761, Sleep time: -0.003080, Sleep: 0.000000
2019-02-08T07:18:02.503Z,1549610282.503 [logger ThreadHandler](INFO): Cycle time: 0.084132, Period: 0.050000, Run time: -1549610282.416996, Sleep time: -0.034109, Sleep: 0.000000
2019-02-08T07:18:02.562Z,1549610282.562 [logger ThreadHandler](INFO): Cycle time: 0.057963, Period: 0.050000, Run time: -1549610282.502615, Sleep time: -0.007948, Sleep: 0.000000
2019-02-08T07:18:02.597Z,1549610282.597 [controlThread ThreadHandler](INFO): Cycle time: 0.402978, Period: 0.400000, Run time: 0.268466, Sleep time: 0.131549, Sleep: 0.134458
2019-02-08T07:18:02.609Z,1549610282.609 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-02-08T07:18:02.635Z,1549610282.635 [logger ThreadHandler](INFO): Cycle time: 0.071299, Period: 0.050000, Run time: -1549610282.561717, Sleep time: -0.021282, Sleep: 0.000000
2019-02-08T07:18:02.689Z,1549610282.689 [logger ThreadHandler](INFO): Cycle time: 0.052859, Period: 0.050000, Run time: -1549610282.635177, Sleep time: -0.002844, Sleep: 0.000000
2019-02-08T07:18:02.754Z,1549610282.754 [logger ThreadHandler](INFO): Cycle time: 0.063957, Period: 0.050000, Run time: -1549610282.689168, Sleep time: -0.013942, Sleep: 0.000000
2019-02-08T07:18:02.817Z,1549610282.817 [logger ThreadHandler](INFO): Cycle time: 0.061879, Period: 0.050000, Run time: -1549610282.754267, Sleep time: -0.011862, Sleep: 0.000000
2019-02-08T07:18:02.870Z,1549610282.870 [logger ThreadHandler](INFO): Cycle time: 0.051193, Period: 0.050000, Run time: -1549610282.817288, Sleep time: -0.001178, Sleep: 0.000000
2019-02-08T07:18:02.925Z,1549610282.925 [logger ThreadHandler](INFO): Cycle time: 0.054785, Period: 0.050000, Run time: -1549610282.869620, Sleep time: -0.004768, Sleep: 0.000000
2019-02-08T07:18:02.982Z,1549610282.982 [logger ThreadHandler](INFO): Cycle time: 0.055029, Period: 0.050000, Run time: -1549610282.925577, Sleep time: -0.005012, Sleep: 0.000000
2019-02-08T07:18:03.001Z,1549610283.001 [controlThread ThreadHandler](INFO): Cycle time: 0.402967, Period: 0.400000, Run time: 0.154890, Sleep time: 0.245129, Sleep: 0.248025
2019-02-08T07:18:03.045Z,1549610283.045 [logger ThreadHandler](INFO): Cycle time: 0.061091, Period: 0.050000, Run time: -1549610282.981740, Sleep time: -0.011075, Sleep: 0.000000
2019-02-08T07:18:03.115Z,1549610283.115 [logger ThreadHandler](INFO): Cycle time: 0.068396, Period: 0.050000, Run time: -1549610283.045074, Sleep time: -0.018376, Sleep: 0.000000
2019-02-08T07:18:03.170Z,1549610283.170 [logger ThreadHandler](INFO): Cycle time: 0.054033, Period: 0.050000, Run time: -1549610283.114614, Sleep time: -0.004018, Sleep: 0.000000
2019-02-08T07:18:03.225Z,1549610283.225 [logger ThreadHandler](INFO): Cycle time: 0.054587, Period: 0.050000, Run time: -1549610283.169785, Sleep time: -0.004571, Sleep: 0.000000
2019-02-08T07:18:03.283Z,1549610283.283 [logger ThreadHandler](INFO): Cycle time: 0.056099, Period: 0.050000, Run time: -1549610283.225505, Sleep time: -0.006084, Sleep: 0.000000
2019-02-08T07:18:03.337Z,1549610283.337 [logger ThreadHandler](INFO): Cycle time: 0.053065, Period: 0.050000, Run time: -1549610283.282711, Sleep time: -0.003049, Sleep: 0.000000
2019-02-08T07:18:03.402Z,1549610283.402 [logger ThreadHandler](INFO): Cycle time: 0.062357, Period: 0.050000, Run time: -1549610283.336943, Sleep time: -0.012277, Sleep: 0.000000
2019-02-08T07:18:03.406Z,1549610283.406 [controlThread ThreadHandler](INFO): Cycle time: 0.403139, Period: 0.400000, Run time: 0.150368, Sleep time: 0.249646, Sleep: 0.252670
2019-02-08T07:18:03.426Z,1549610283.426 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-02-08T07:18:03.474Z,1549610283.474 [logger ThreadHandler](INFO): Cycle time: 0.070498, Period: 0.050000, Run time: -1549610283.401865, Sleep time: -0.020483, Sleep: 0.000000
2019-02-08T07:18:03.562Z,1549610283.562 [logger ThreadHandler](INFO): Cycle time: 0.086574, Period: 0.050000, Run time: -1549610283.474226, Sleep time: -0.036559, Sleep: 0.000000
2019-02-08T07:18:03.620Z,1549610283.620 [logger ThreadHandler](INFO): Cycle time: 0.056142, Period: 0.050000, Run time: -1549610283.561914, Sleep time: -0.006126, Sleep: 0.000000
2019-02-08T07:18:03.681Z,1549610283.681 [logger ThreadHandler](INFO): Cycle time: 0.060411, Period: 0.050000, Run time: -1549610283.619993, Sleep time: -0.010396, Sleep: 0.000000
2019-02-08T07:18:03.746Z,1549610283.746 [logger ThreadHandler](INFO): Cycle time: 0.063248, Period: 0.050000, Run time: -1549610283.681591, Sleep time: -0.013232, Sleep: 0.000000
2019-02-08T07:18:03.806Z,1549610283.806 [logger ThreadHandler](INFO): Cycle time: 0.058758, Period: 0.050000, Run time: -1549610283.745967, Sleep time: -0.008743, Sleep: 0.000000
2019-02-08T07:18:03.809Z,1549610283.809 [controlThread ThreadHandler](INFO): Cycle time: 0.402207, Period: 0.400000, Run time: 0.327665, Sleep time: 0.072350, Sleep: 0.074521
2019-02-08T07:18:03.817Z,1549610283.817 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-02-08T07:18:03.818Z,1549610283.818 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-02-08T07:18:03.818Z,1549610283.818 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-02-08T07:18:03.862Z,1549610283.862 [logger ThreadHandler](INFO): Cycle time: 0.054754, Period: 0.050000, Run time: -1549610283.805854, Sleep time: -0.004740, Sleep: 0.000000
2019-02-08T07:18:03.931Z,1549610283.931 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009663
CHAN A1 (24V): -0.012410
CHAN A2 (12V): -0.004834
CHAN A3 (5V): -0.004688
CHAN B0 (3.3V): 0.000260
CHAN B1 (3.15aV): -0.001514
CHAN B2 (3.15bV): -0.002233
CHAN B3 (GND): 0.000187
OPEN: 0.004743
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-08T07:18:04.179Z,1549610284.179 [NavChartDb ThreadHandler](INFO): Cycle time: 3.283184, Period: 0.100000, Run time: -1549610280.894981, Sleep time: -3.183166, Sleep: 0.000000
2019-02-08T07:18:04.185Z,1549610284.185 [logger ThreadHandler](INFO): Cycle time: 0.322599, Period: 0.050000, Run time: -1549610283.861738, Sleep time: -0.272582, Sleep: 0.000000
2019-02-08T07:18:04.213Z,1549610284.213 [controlThread ThreadHandler](INFO): Cycle time: 0.403028, Period: 0.400000, Run time: 0.124333, Sleep time: 0.275680, Sleep: 0.278644
2019-02-08T07:18:04.237Z,1549610284.237 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-02-08T07:18:04.250Z,1549610284.250 [logger ThreadHandler](INFO): Cycle time: 0.061231, Period: 0.050000, Run time: -1549610284.185534, Sleep time: -0.011160, Sleep: 0.000000
2019-02-08T07:18:04.322Z,1549610284.322 [logger ThreadHandler](INFO): Cycle time: 0.070960, Period: 0.050000, Run time: -1549610284.249788, Sleep time: -0.020776, Sleep: 0.000000
2019-02-08T07:18:04.397Z,1549610284.397 [logger ThreadHandler](INFO): Cycle time: 0.073112, Period: 0.050000, Run time: -1549610284.322422, Sleep time: -0.023039, Sleep: 0.000000
2019-02-08T07:18:04.467Z,1549610284.467 [logger ThreadHandler](INFO): Cycle time: 0.067797, Period: 0.050000, Run time: -1549610284.397359, Sleep time: -0.017777, Sleep: 0.000000
2019-02-08T07:18:04.559Z,1549610284.559 [logger ThreadHandler](INFO): Cycle time: 0.091192, Period: 0.050000, Run time: -1549610284.467014, Sleep time: -0.041176, Sleep: 0.000000
2019-02-08T07:18:04.612Z,1549610284.612 [logger ThreadHandler](INFO): Cycle time: 0.052051, Period: 0.050000, Run time: -1549610284.559306, Sleep time: -0.002035, Sleep: 0.000000
2019-02-08T07:18:04.617Z,1549610284.617 [controlThread ThreadHandler](INFO): Cycle time: 0.402917, Period: 0.400000, Run time: 0.332304, Sleep time: 0.067710, Sleep: 0.070560
2019-02-08T07:18:04.625Z,1549610284.625 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-02-08T07:18:04.626Z,1549610284.626 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-02-08T07:18:04.729Z,1549610284.729 [NavChartDb ThreadHandler](INFO): Cycle time: 0.548218, Period: 0.100000, Run time: -1549610284.179364, Sleep time: -0.448202, Sleep: 0.000000
2019-02-08T07:18:05.021Z,1549610285.021 [controlThread ThreadHandler](INFO): Cycle time: 0.402798, Period: 0.400000, Run time: 0.106431, Sleep time: 0.293582, Sleep: 0.296345
2019-02-08T07:18:05.030Z,1549610285.030 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-02-08T07:18:05.114Z,1549610285.114 [logger ThreadHandler](INFO): Cycle time: 0.500206, Period: 0.050000, Run time: -1549610284.612592, Sleep time: -0.450189, Sleep: 0.000000
2019-02-08T07:18:05.174Z,1549610285.174 [logger ThreadHandler](INFO): Cycle time: 0.059178, Period: 0.050000, Run time: -1549610285.113915, Sleep time: -0.009163, Sleep: 0.000000
2019-02-08T07:18:05.234Z,1549610285.234 [logger ThreadHandler](INFO): Cycle time: 0.058751, Period: 0.050000, Run time: -1549610285.174226, Sleep time: -0.008736, Sleep: 0.000000
2019-02-08T07:18:05.296Z,1549610285.296 [logger ThreadHandler](INFO): Cycle time: 0.060527, Period: 0.050000, Run time: -1549610285.234095, Sleep time: -0.010511, Sleep: 0.000000
2019-02-08T07:18:05.426Z,1549610285.426 [controlThread ThreadHandler](INFO): Cycle time: 0.402942, Period: 0.400000, Run time: 0.146379, Sleep time: 0.253634, Sleep: 0.256540
2019-02-08T07:18:05.677Z,1549610285.677 [CommandLine](IMPORTANT): got command quit
2019-02-08T07:18:05.679Z,1549610285.679 [logger ThreadHandler](INFO): Cycle time: 0.382487, Period: 0.050000, Run time: -1549610285.295838, Sleep time: -0.332468, Sleep: 0.000000
2019-02-08T07:18:05.734Z,1549610285.734 [logger ThreadHandler](INFO): Cycle time: 0.053351, Period: 0.050000, Run time: -1549610285.679500, Sleep time: -0.003335, Sleep: 0.000000
2019-02-08T07:18:05.801Z,1549610285.801 [logger ThreadHandler](INFO): Cycle time: 0.066380, Period: 0.050000, Run time: -1549610285.733994, Sleep time: -0.016363, Sleep: 0.000000
2019-02-08T07:18:05.829Z,1549610285.829 [controlThread ThreadHandler](INFO): Cycle time: 0.402051, Period: 0.400000, Run time: 0.173805, Sleep time: 0.226209, Sleep: 0.228226
2019-02-08T07:18:05.870Z,1549610285.870 [logger ThreadHandler](INFO): Cycle time: 0.067538, Period: 0.050000, Run time: -1549610285.801518, Sleep time: -0.017522, Sleep: 0.000000
2019-02-08T07:18:05.934Z,1549610285.934 [logger ThreadHandler](INFO): Cycle time: 0.062457, Period: 0.050000, Run time: -1549610285.870182, Sleep time: -0.012442, Sleep: 0.000000
2019-02-08T07:18:05.997Z,1549610285.997 [logger ThreadHandler](INFO): Cycle time: 0.062134, Period: 0.050000, Run time: -1549610285.933770, Sleep time: -0.012117, Sleep: 0.000000
2019-02-08T07:18:06.049Z,1549610286.049 [logger ThreadHandler](INFO): Cycle time: 0.051034, Period: 0.050000, Run time: -1549610285.997077, Sleep time: -0.001018, Sleep: 0.000000
2019-02-08T07:18:06.101Z,1549610286.101 [logger ThreadHandler](INFO): Cycle time: 0.051050, Period: 0.050000, Run time: -1549610286.049311, Sleep time: -0.001034, Sleep: 0.000000
2019-02-08T07:18:06.158Z,1549610286.158 [logger ThreadHandler](INFO): Cycle time: 0.055522, Period: 0.050000, Run time: -1549610286.101540, Sleep time: -0.005502, Sleep: 0.000000
2019-02-08T07:18:06.213Z,1549610286.213 [logger ThreadHandler](INFO): Cycle time: 0.054191, Period: 0.050000, Run time: -1549610286.158207, Sleep time: -0.004176, Sleep: 0.000000
2019-02-08T07:18:06.233Z,1549610286.233 [controlThread ThreadHandler](INFO): Cycle time: 0.402892, Period: 0.400000, Run time: 0.127735, Sleep time: 0.272281, Sleep: 0.275136
2019-02-08T07:18:06.274Z,1549610286.274 [logger ThreadHandler](INFO): Cycle time: 0.059274, Period: 0.050000, Run time: -1549610286.213524, Sleep time: -0.009259, Sleep: 0.000000
2019-02-08T07:18:06.326Z,1549610286.326 [logger ThreadHandler](INFO): Cycle time: 0.050724, Period: 0.050000, Run time: -1549610286.273924, Sleep time: -0.000709, Sleep: 0.000000
2019-02-08T07:18:06.386Z,1549610286.386 [logger ThreadHandler](INFO): Cycle time: 0.056889, Period: 0.050000, Run time: -1549610286.325829, Sleep time: -0.006869, Sleep: 0.000000
2019-02-08T07:18:06.444Z,1549610286.444 [logger ThreadHandler](INFO): Cycle time: 0.056362, Period: 0.050000, Run time: -1549610286.385971, Sleep time: -0.006342, Sleep: 0.000000
2019-02-08T07:18:06.509Z,1549610286.509 [logger ThreadHandler](INFO): Cycle time: 0.062322, Period: 0.050000, Run time: -1549610286.444346, Sleep time: -0.012302, Sleep: 0.000000
2019-02-08T07:18:06.566Z,1549610286.566 [logger ThreadHandler](INFO): Cycle time: 0.055877, Period: 0.050000, Run time: -1549610286.508686, Sleep time: -0.005861, Sleep: 0.000000
2019-02-08T07:18:06.622Z,1549610286.622 [logger ThreadHandler](INFO): Cycle time: 0.055006, Period: 0.050000, Run time: -1549610286.565686, Sleep time: -0.004992, Sleep: 0.000000
2019-02-08T07:18:06.637Z,1549610286.637 [controlThread ThreadHandler](INFO): Cycle time: 0.402984, Period: 0.400000, Run time: 0.099408, Sleep time: 0.300608, Sleep: 0.303529
2019-02-08T07:18:06.683Z,1549610286.683 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:06.683Z,1549610286.683 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:06.695Z,1549610286.695 [logger ThreadHandler](INFO): Cycle time: 0.072250, Period: 0.050000, Run time: -1549610286.621838, Sleep time: -0.022236, Sleep: 0.000000
2019-02-08T07:18:06.754Z,1549610286.754 [logger ThreadHandler](INFO): Cycle time: 0.057309, Period: 0.050000, Run time: -1549610286.695221, Sleep time: -0.007294, Sleep: 0.000000
2019-02-08T07:18:06.796Z,1549610286.796 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-08T07:18:06.796Z,1549610286.796 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:06.797Z,1549610286.797 [CommandLine](INFO): Join timeout helper Thread ID is 9963
2019-02-08T07:18:06.811Z,1549610286.811 [logger ThreadHandler](INFO): Cycle time: 0.056270, Period: 0.050000, Run time: -1549610286.753659, Sleep time: -0.006254, Sleep: 0.000000
2019-02-08T07:18:06.812Z,1549610286.812 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-08T07:18:06.812Z,1549610286.812 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:06.813Z,1549610286.813 [NavChartDb](INFO): Join timeout helper Thread ID is 9964
2019-02-08T07:18:06.866Z,1549610286.866 [logger ThreadHandler](INFO): Cycle time: 0.053503, Period: 0.050000, Run time: -1549610286.811018, Sleep time: -0.003487, Sleep: 0.000000
2019-02-08T07:18:06.922Z,1549610286.922 [logger ThreadHandler](INFO): Cycle time: 0.055190, Period: 0.050000, Run time: -1549610286.865641, Sleep time: -0.005175, Sleep: 0.000000
2019-02-08T07:18:06.978Z,1549610286.978 [logger ThreadHandler](INFO): Cycle time: 0.054784, Period: 0.050000, Run time: -1549610286.921955, Sleep time: -0.004768, Sleep: 0.000000
2019-02-08T07:18:07.034Z,1549610287.034 [logger ThreadHandler](INFO): Cycle time: 0.054860, Period: 0.050000, Run time: -1549610286.977876, Sleep time: -0.004845, Sleep: 0.000000
2019-02-08T07:18:07.041Z,1549610287.041 [controlThread ThreadHandler](INFO): Cycle time: 0.402919, Period: 0.400000, Run time: 0.126142, Sleep time: 0.273873, Sleep: 0.276736
2019-02-08T07:18:07.069Z,1549610287.069 [NAL9602](INFO): NAL9602 initialized
2019-02-08T07:18:07.101Z,1549610287.101 [logger ThreadHandler](INFO): Cycle time: 0.066521, Period: 0.050000, Run time: -1549610287.033862, Sleep time: -0.016505, Sleep: 0.000000
2019-02-08T07:18:07.154Z,1549610287.154 [logger ThreadHandler](INFO): Cycle time: 0.051306, Period: 0.050000, Run time: -1549610287.101514, Sleep time: -0.001292, Sleep: 0.000000
2019-02-08T07:18:07.210Z,1549610287.210 [logger ThreadHandler](INFO): Cycle time: 0.054672, Period: 0.050000, Run time: -1549610287.153949, Sleep time: -0.004657, Sleep: 0.000000
2019-02-08T07:18:07.273Z,1549610287.273 [logger ThreadHandler](INFO): Cycle time: 0.062227, Period: 0.050000, Run time: -1549610287.209745, Sleep time: -0.012211, Sleep: 0.000000
2019-02-08T07:18:07.333Z,1549610287.333 [logger ThreadHandler](INFO): Cycle time: 0.058869, Period: 0.050000, Run time: -1549610287.273104, Sleep time: -0.008853, Sleep: 0.000000
2019-02-08T07:18:07.395Z,1549610287.395 [logger ThreadHandler](INFO): Cycle time: 0.060091, Period: 0.050000, Run time: -1549610287.333107, Sleep time: -0.010071, Sleep: 0.000000
2019-02-08T07:18:07.445Z,1549610287.445 [controlThread ThreadHandler](INFO): Cycle time: 0.402885, Period: 0.400000, Run time: 0.122222, Sleep time: 0.277791, Sleep: 0.280643
2019-02-08T07:18:07.485Z,1549610287.485 [logger ThreadHandler](INFO): Cycle time: 0.088321, Period: 0.050000, Run time: -1549610287.394748, Sleep time: -0.038301, Sleep: 0.000000
2019-02-08T07:18:07.540Z,1549610287.540 [logger ThreadHandler](INFO): Cycle time: 0.053281, Period: 0.050000, Run time: -1549610287.485070, Sleep time: -0.003261, Sleep: 0.000000
2019-02-08T07:18:07.602Z,1549610287.602 [logger ThreadHandler](INFO): Cycle time: 0.060127, Period: 0.050000, Run time: -1549610287.540482, Sleep time: -0.010112, Sleep: 0.000000
2019-02-08T07:18:07.663Z,1549610287.663 [logger ThreadHandler](INFO): Cycle time: 0.056750, Period: 0.050000, Run time: -1549610287.601743, Sleep time: -0.006685, Sleep: 0.000000
2019-02-08T07:18:07.717Z,1549610287.717 [logger ThreadHandler](INFO): Cycle time: 0.052959, Period: 0.050000, Run time: -1549610287.663432, Sleep time: -0.002945, Sleep: 0.000000
2019-02-08T07:18:07.773Z,1549610287.773 [logger ThreadHandler](INFO): Cycle time: 0.054948, Period: 0.050000, Run time: -1549610287.717499, Sleep time: -0.004932, Sleep: 0.000000
2019-02-08T07:18:07.829Z,1549610287.829 [logger ThreadHandler](INFO): Cycle time: 0.054898, Period: 0.050000, Run time: -1549610287.773558, Sleep time: -0.004882, Sleep: 0.000000
2019-02-08T07:18:07.849Z,1549610287.849 [controlThread ThreadHandler](INFO): Cycle time: 0.402526, Period: 0.400000, Run time: 0.253631, Sleep time: 0.146383, Sleep: 0.148875
2019-02-08T07:18:07.883Z,1549610287.883 [logger ThreadHandler](INFO): Cycle time: 0.052893, Period: 0.050000, Run time: -1549610287.829564, Sleep time: -0.002877, Sleep: 0.000000
2019-02-08T07:18:07.892Z,1549610287.892 [NAL9602](DEBUG): Fix Requested
2019-02-08T07:18:07.949Z,1549610287.949 [logger ThreadHandler](INFO): Cycle time: 0.062168, Period: 0.050000, Run time: -1549610287.883582, Sleep time: -0.012102, Sleep: 0.000000
2019-02-08T07:18:08.001Z,1549610288.001 [logger ThreadHandler](INFO): Cycle time: 0.051687, Period: 0.050000, Run time: -1549610287.948724, Sleep time: -0.001671, Sleep: 0.000000
2019-02-08T07:18:08.058Z,1549610288.058 [logger ThreadHandler](INFO): Cycle time: 0.055178, Period: 0.050000, Run time: -1549610288.001549, Sleep time: -0.005163, Sleep: 0.000000
2019-02-08T07:18:08.110Z,1549610288.110 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:08.110Z,1549610288.110 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:08.112Z,1549610288.112 [logger ThreadHandler](INFO): Cycle time: 0.052972, Period: 0.050000, Run time: -1549610288.057858, Sleep time: -0.002957, Sleep: 0.000000
2019-02-08T07:18:08.120Z,1549610288.120 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-02-08T07:18:08.120Z,1549610288.120 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:08.121Z,1549610288.121 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 9965
2019-02-08T07:18:08.164Z,1549610288.164 [logger ThreadHandler](INFO): Cycle time: 0.050877, Period: 0.050000, Run time: -1549610288.112350, Sleep time: -0.000862, Sleep: 0.000000
2019-02-08T07:18:08.216Z,1549610288.216 [logger ThreadHandler](INFO): Cycle time: 0.050916, Period: 0.050000, Run time: -1549610288.164452, Sleep time: -0.000900, Sleep: 0.000000
2019-02-08T07:18:08.269Z,1549610288.269 [logger ThreadHandler](INFO): Cycle time: 0.050894, Period: 0.050000, Run time: -1549610288.216574, Sleep time: -0.000878, Sleep: 0.000000
2019-02-08T07:18:08.321Z,1549610288.321 [logger ThreadHandler](INFO): Cycle time: 0.050636, Period: 0.050000, Run time: -1549610288.268937, Sleep time: -0.000621, Sleep: 0.000000
2019-02-08T07:18:08.373Z,1549610288.373 [logger ThreadHandler](INFO): Cycle time: 0.051266, Period: 0.050000, Run time: -1549610288.320797, Sleep time: -0.001250, Sleep: 0.000000
2019-02-08T07:18:08.426Z,1549610288.426 [logger ThreadHandler](INFO): Cycle time: 0.051820, Period: 0.050000, Run time: -1549610288.373181, Sleep time: -0.001803, Sleep: 0.000000
2019-02-08T07:18:08.433Z,1549610288.433 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:08.434Z,1549610288.434 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-08T07:18:08.501Z,1549610288.501 [logger ThreadHandler](INFO): Cycle time: 0.071713, Period: 0.050000, Run time: -1549610288.426792, Sleep time: -0.021696, Sleep: 0.000000
2019-02-08T07:18:08.526Z,1549610288.526 [controlThread ThreadHandler](INFO): Cycle time: 0.674801, Period: 0.400000, Run time: -1549610287.848856, Sleep time: -0.274785, Sleep: 0.000000
2019-02-08T07:18:08.565Z,1549610288.565 [logger ThreadHandler](INFO): Cycle time: 0.062421, Period: 0.050000, Run time: -1549610288.501460, Sleep time: -0.012401, Sleep: 0.000000
2019-02-08T07:18:08.619Z,1549610288.619 [logger ThreadHandler](INFO): Cycle time: 0.052428, Period: 0.050000, Run time: -1549610288.565426, Sleep time: -0.002412, Sleep: 0.000000
2019-02-08T07:18:08.678Z,1549610288.678 [logger ThreadHandler](INFO): Cycle time: 0.057642, Period: 0.050000, Run time: -1549610288.619185, Sleep time: -0.007626, Sleep: 0.000000
2019-02-08T07:18:08.746Z,1549610288.746 [Rowe_600LCM](INFO): Powering down
2019-02-08T07:18:08.748Z,1549610288.748 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:08.750Z,1549610288.750 [logger ThreadHandler](INFO): Cycle time: 0.070623, Period: 0.050000, Run time: -1549610288.678223, Sleep time: -0.020604, Sleep: 0.000000
2019-02-08T07:18:08.756Z,1549610288.756 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-08T07:18:08.756Z,1549610288.756 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:08.757Z,1549610288.757 [Radio_Surface](INFO): Join timeout helper Thread ID is 9967
2019-02-08T07:18:08.785Z,1549610288.785 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:08.785Z,1549610288.785 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:08.788Z,1549610288.788 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-02-08T07:18:08.788Z,1549610288.788 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:08.789Z,1549610288.789 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9968
2019-02-08T07:18:08.805Z,1549610288.805 [logger ThreadHandler](INFO): Cycle time: 0.053409, Period: 0.050000, Run time: -1549610288.750132, Sleep time: -0.003342, Sleep: 0.000000
2019-02-08T07:18:08.863Z,1549610288.863 [logger ThreadHandler](INFO): Cycle time: 0.056613, Period: 0.050000, Run time: -1549610288.804931, Sleep time: -0.006599, Sleep: 0.000000
2019-02-08T07:18:08.915Z,1549610288.915 [logger ThreadHandler](INFO): Cycle time: 0.050825, Period: 0.050000, Run time: -1549610288.862726, Sleep time: -0.000809, Sleep: 0.000000
2019-02-08T07:18:08.929Z,1549610288.929 [controlThread ThreadHandler](INFO): Cycle time: 0.401861, Period: 0.400000, Run time: 0.333317, Sleep time: 0.066696, Sleep: 0.068497
2019-02-08T07:18:08.970Z,1549610288.970 [logger ThreadHandler](INFO): Cycle time: 0.053122, Period: 0.050000, Run time: -1549610288.914671, Sleep time: -0.003105, Sleep: 0.000000
2019-02-08T07:18:09.022Z,1549610289.022 [logger ThreadHandler](INFO): Cycle time: 0.051138, Period: 0.050000, Run time: -1549610288.969937, Sleep time: -0.001123, Sleep: 0.000000
2019-02-08T07:18:09.078Z,1549610289.078 [logger ThreadHandler](INFO): Cycle time: 0.054745, Period: 0.050000, Run time: -1549610289.022261, Sleep time: -0.004728, Sleep: 0.000000
2019-02-08T07:18:09.130Z,1549610289.130 [logger ThreadHandler](INFO): Cycle time: 0.050998, Period: 0.050000, Run time: -1549610289.078144, Sleep time: -0.000984, Sleep: 0.000000
2019-02-08T07:18:09.144Z,1549610289.144 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:09.144Z,1549610289.144 [WetLabsBB2FL](INFO): Powering down
2019-02-08T07:18:09.145Z,1549610289.145 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.161Z,1549610289.161 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-02-08T07:18:09.161Z,1549610289.161 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.162Z,1549610289.162 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9969
2019-02-08T07:18:09.182Z,1549610289.182 [logger ThreadHandler](INFO): Cycle time: 0.050981, Period: 0.050000, Run time: -1549610289.130244, Sleep time: -0.000966, Sleep: 0.000000
2019-02-08T07:18:09.234Z,1549610289.234 [logger ThreadHandler](INFO): Cycle time: 0.050628, Period: 0.050000, Run time: -1549610289.182336, Sleep time: -0.000613, Sleep: 0.000000
2019-02-08T07:18:09.286Z,1549610289.286 [logger ThreadHandler](INFO): Cycle time: 0.050847, Period: 0.050000, Run time: -1549610289.234094, Sleep time: -0.000832, Sleep: 0.000000
2019-02-08T07:18:09.333Z,1549610289.333 [controlThread ThreadHandler](INFO): Cycle time: 0.402872, Period: 0.400000, Run time: 0.174962, Sleep time: 0.225052, Sleep: 0.227861
2019-02-08T07:18:09.352Z,1549610289.352 [logger ThreadHandler](INFO): Cycle time: 0.064486, Period: 0.050000, Run time: -1549610289.286047, Sleep time: -0.014470, Sleep: 0.000000
2019-02-08T07:18:09.404Z,1549610289.404 [logger ThreadHandler](INFO): Cycle time: 0.050824, Period: 0.050000, Run time: -1549610289.351750, Sleep time: -0.000809, Sleep: 0.000000
2019-02-08T07:18:09.458Z,1549610289.458 [logger ThreadHandler](INFO): Cycle time: 0.052846, Period: 0.050000, Run time: -1549610289.403831, Sleep time: -0.002831, Sleep: 0.000000
2019-02-08T07:18:09.513Z,1549610289.513 [logger ThreadHandler](INFO): Cycle time: 0.054332, Period: 0.050000, Run time: -1549610289.457789, Sleep time: -0.004316, Sleep: 0.000000
2019-02-08T07:18:09.532Z,1549610289.532 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:09.533Z,1549610289.533 [CTD_Seabird](INFO): Powering down
2019-02-08T07:18:09.545Z,1549610289.545 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.550Z,1549610289.550 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-08T07:18:09.550Z,1549610289.550 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.551Z,1549610289.551 [logger](INFO): Join timeout helper Thread ID is 9970
2019-02-08T07:18:09.564Z,1549610289.564 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:09.565Z,1549610289.565 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.577Z,1549610289.577 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-08T07:18:09.577Z,1549610289.577 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.577Z,1549610289.577 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-08T07:18:09.577Z,1549610289.577 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:09.577Z,1549610289.577 [controlThread](INFO): Join timeout helper Thread ID is 9971
2019-02-08T07:18:09.737Z,1549610289.737 [controlThread ThreadHandler](INFO): Cycle time: 0.402774, Period: 0.400000, Run time: 0.178354, Sleep time: 0.221660, Sleep: 0.224400
2019-02-08T07:18:09.737Z,1549610289.737 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T07:18:09.738Z,1549610289.738 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-08T07:18:09.738Z,1549610289.738 [Aanderaa_O2](INFO): Powering down
2019-02-08T07:18:09.740Z,1549610289.740 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-02-08T07:18:09.861Z,1549610289.861 [NAL9602](INFO): Powering down
2019-02-08T07:18:09.862Z,1549610289.862 [PNI_TCM](INFO): Powering down
2019-02-08T07:18:09.949Z,1549610289.949 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-08T07:18:09.950Z,1549610289.950 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-08T07:18:09.951Z,1549610289.951 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-08T07:18:09.951Z,1549610289.951 [MissionManager](INFO): Uninitializing Mission Default
2019-02-08T07:18:09.954Z,1549610289.954 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-08T07:18:09.955Z,1549610289.955 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-08T07:18:09.956Z,1549610289.956 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-08T07:18:09.956Z,1549610289.956 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-08T07:18:09.956Z,1549610289.956 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-08T07:18:09.957Z,1549610289.957 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-08T07:18:09.957Z,1549610289.957 [BuoyancyServo](INFO): Powering down
2019-02-08T07:18:09.972Z,1549610289.972 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-08T07:18:09.972Z,1549610289.972 [ElevatorServo](INFO): Powering down
2019-02-08T07:18:09.973Z,1549610289.973 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-08T07:18:09.973Z,1549610289.973 [MassServo](INFO): Powering down
2019-02-08T07:18:09.974Z,1549610289.974 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-08T07:18:09.974Z,1549610289.974 [RudderServo](INFO): Powering down
2019-02-08T07:18:09.975Z,1549610289.975 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-08T07:18:09.975Z,1549610289.975 [ThrusterServo](INFO): Powering down
2019-02-08T07:18:09.976Z,1549610289.976 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-08T07:18:09.976Z,1549610289.976 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-08T07:18:09.977Z,1549610289.977 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-08T07:18:09.977Z,1549610289.977 [CBIT](DEBUG): Powering off loads.
2019-02-08T07:18:09.988Z,1549610289.988 [CBIT](DEBUG): Disabling WDT.
2019-02-08T07:18:09.000Z,1549610290.000 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-08T07:18:10.001Z,1549610290.001 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:10.411Z,1549610290.411 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:10.417Z,1549610290.417 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:10.496Z,1549610290.496 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:10.501Z,1549610290.501 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:10.553Z,1549610290.553 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T07:18:10.609Z,1549610290.609 [logger ThreadHandler](INFO): Thread cancelled.