2019-02-07T23:45:13.815Z,1549583113.815 [Supervisor](DEBUG): Initializing supervisor.
2019-02-07T23:45:13.818Z,1549583113.818 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-07T23:45:13.819Z,1549583113.819 [SyncHandler](INFO): Protected caller Thread ID is 1169
2019-02-07T23:45:13.819Z,1549583113.819 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-07T23:45:13.820Z,1549583113.820 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-07T23:45:13.821Z,1549583113.821 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1170
2019-02-07T23:45:13.825Z,1549583113.825 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-07T23:45:13.836Z,1549583113.836 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-07T23:45:13.837Z,1549583113.837 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-07T23:45:13.837Z,1549583113.837 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1171
2019-02-07T23:45:13.838Z,1549583113.838 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-07T23:45:13.839Z,1549583113.839 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-07T23:45:13.840Z,1549583113.840 [logger ThreadHandler](INFO): Protected caller Thread ID is 1172
2019-02-07T23:45:13.842Z,1549583113.842 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-07T23:45:13.842Z,1549583113.842 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-07T23:45:13.844Z,1549583113.844 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-07T23:45:13.939Z,1549583113.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-07T23:45:13.940Z,1549583113.940 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-07T23:45:14.137Z,1549583114.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-07T23:45:14.138Z,1549583114.138 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-07T23:45:14.274Z,1549583114.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-07T23:45:14.275Z,1549583114.275 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-07T23:45:14.737Z,1549583114.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-07T23:45:14.738Z,1549583114.738 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-07T23:45:15.173Z,1549583115.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-07T23:45:15.174Z,1549583115.174 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-07T23:45:15.644Z,1549583115.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-07T23:45:15.644Z,1549583115.644 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-07T23:45:15.953Z,1549583115.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-07T23:45:15.953Z,1549583115.953 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-07T23:45:16.302Z,1549583116.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-07T23:45:16.303Z,1549583116.303 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-07T23:45:16.691Z,1549583116.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-07T23:45:16.692Z,1549583116.692 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-07T23:45:16.841Z,1549583116.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-07T23:45:16.841Z,1549583116.841 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-07T23:45:16.948Z,1549583116.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-07T23:45:16.948Z,1549583116.948 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-07T23:45:17.030Z,1549583117.030 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-07T23:45:17.133Z,1549583117.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-07T23:45:17.133Z,1549583117.133 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-07T23:45:17.331Z,1549583117.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-07T23:45:17.332Z,1549583117.332 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-07T23:45:17.539Z,1549583117.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-07T23:45:17.541Z,1549583117.541 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-02-07T23:45:17.542Z,1549583117.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-02-07T23:45:17.626Z,1549583117.626 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-02-07T23:45:18.006Z,1549583118.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-07T23:45:18.006Z,1549583118.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-02-07T23:45:18.267Z,1549583118.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-02-07T23:45:18.729Z,1549583118.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-02-07T23:45:18.936Z,1549583118.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-02-07T23:45:19.021Z,1549583119.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-02-07T23:45:19.120Z,1549583119.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-02-07T23:45:19.214Z,1549583119.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-02-07T23:45:19.366Z,1549583119.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-02-07T23:45:19.483Z,1549583119.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-02-07T23:45:19.581Z,1549583119.581 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-02-07T23:45:19.582Z,1549583119.582 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-07T23:45:19.584Z,1549583119.584 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-07T23:45:19.682Z,1549583119.682 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-07T23:45:19.683Z,1549583119.683 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-07T23:45:19.808Z,1549583119.808 [BuoyancyServo] Loaded
2019-02-07T23:45:19.809Z,1549583119.809 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-07T23:45:19.824Z,1549583119.824 [ElevatorServo] Loaded
2019-02-07T23:45:19.824Z,1549583119.824 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-07T23:45:19.839Z,1549583119.839 [MassServo] Loaded
2019-02-07T23:45:19.839Z,1549583119.839 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-07T23:45:19.854Z,1549583119.854 [RudderServo] Loaded
2019-02-07T23:45:19.854Z,1549583119.854 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-07T23:45:19.869Z,1549583119.869 [ThrusterServo] Loaded
2019-02-07T23:45:19.869Z,1549583119.869 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-07T23:45:19.869Z,1549583119.869 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-07T23:45:19.870Z,1549583119.870 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-07T23:45:20.000Z,1549583120.000 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-07T23:45:20.029Z,1549583120.029 [SBIT] Loaded
2019-02-07T23:45:20.029Z,1549583120.029 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-07T23:45:20.030Z,1549583120.030 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-07T23:45:20.057Z,1549583120.057 [IBIT] Loaded
2019-02-07T23:45:20.058Z,1549583120.058 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-07T23:45:20.061Z,1549583120.061 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-07T23:45:20.444Z,1549583120.444 [CBIT] Loaded
2019-02-07T23:45:20.444Z,1549583120.444 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-07T23:45:20.444Z,1549583120.444 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-07T23:45:20.445Z,1549583120.445 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-07T23:45:20.623Z,1549583120.623 [Aanderaa_O2] Loaded
2019-02-07T23:45:20.624Z,1549583120.624 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-07T23:45:20.633Z,1549583120.633 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-07T23:45:20.639Z,1549583120.639 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-07T23:45:20.641Z,1549583120.641 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-07T23:45:20.645Z,1549583120.645 [CTD_Seabird](INFO): created writer for : depth
2019-02-07T23:45:20.646Z,1549583120.646 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-07T23:45:20.651Z,1549583120.651 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-07T23:45:20.652Z,1549583120.652 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-07T23:45:20.657Z,1549583120.657 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-07T23:45:20.657Z,1549583120.657 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-07T23:45:20.663Z,1549583120.663 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-07T23:45:20.663Z,1549583120.663 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-07T23:45:20.669Z,1549583120.669 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-07T23:45:20.670Z,1549583120.670 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-07T23:45:20.675Z,1549583120.675 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-07T23:45:20.701Z,1549583120.701 [CTD_Seabird] Loaded
2019-02-07T23:45:20.702Z,1549583120.702 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-07T23:45:20.703Z,1549583120.703 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062E4E0
2019-02-07T23:45:20.703Z,1549583120.703 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1251
2019-02-07T23:45:20.724Z,1549583120.724 [ESPComponent] Loaded
2019-02-07T23:45:20.725Z,1549583120.725 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-02-07T23:45:20.738Z,1549583120.738 [PAR_Licor] Loaded
2019-02-07T23:45:20.739Z,1549583120.739 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-07T23:45:20.786Z,1549583120.786 [WetLabsBB2FL] Loaded
2019-02-07T23:45:20.787Z,1549583120.787 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-07T23:45:20.788Z,1549583120.788 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065E4E0
2019-02-07T23:45:20.788Z,1549583120.788 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1252
2019-02-07T23:45:20.789Z,1549583120.789 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-07T23:45:20.789Z,1549583120.789 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-07T23:45:20.830Z,1549583120.830 [DepthRateCalculator] Loaded
2019-02-07T23:45:20.830Z,1549583120.830 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-07T23:45:20.835Z,1549583120.835 [PitchRateCalculator] Loaded
2019-02-07T23:45:20.836Z,1549583120.836 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-07T23:45:20.851Z,1549583120.851 [SpeedCalculator] Loaded
2019-02-07T23:45:20.851Z,1549583120.851 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-07T23:45:20.872Z,1549583120.872 [TempGradientCalculator] Loaded
2019-02-07T23:45:20.872Z,1549583120.872 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-07T23:45:20.878Z,1549583120.878 [YawRateCalculator] Loaded
2019-02-07T23:45:20.878Z,1549583120.878 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-07T23:45:20.912Z,1549583120.912 [ElevatorOffsetCalculator] Loaded
2019-02-07T23:45:20.912Z,1549583120.912 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-07T23:45:20.913Z,1549583120.913 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-07T23:45:20.913Z,1549583120.913 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-07T23:45:20.940Z,1549583120.940 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-07T23:45:20.941Z,1549583120.941 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-07T23:45:21.041Z,1549583121.041 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-07T23:45:21.041Z,1549583121.041 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-07T23:45:21.100Z,1549583121.100 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-07T23:45:21.100Z,1549583121.100 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-07T23:45:21.159Z,1549583121.159 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-07T23:45:21.159Z,1549583121.159 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-07T23:45:21.175Z,1549583121.175 [NavChart] Loaded
2019-02-07T23:45:21.175Z,1549583121.175 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-07T23:45:21.180Z,1549583121.180 [UniversalFixResidualReporter] Loaded
2019-02-07T23:45:21.180Z,1549583121.180 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-07T23:45:21.180Z,1549583121.180 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-07T23:45:21.181Z,1549583121.181 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-07T23:45:21.463Z,1549583121.463 [AcousticModem_Benthos_ATM900] Loaded
2019-02-07T23:45:21.463Z,1549583121.463 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-02-07T23:45:21.536Z,1549583121.536 [DataOverHttps] Loaded
2019-02-07T23:45:21.536Z,1549583121.536 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-07T23:45:21.549Z,1549583121.549 [Depth_Keller] Loaded
2019-02-07T23:45:21.550Z,1549583121.550 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-07T23:45:21.554Z,1549583121.554 [DropWeight] Loaded
2019-02-07T23:45:21.555Z,1549583121.555 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-07T23:45:21.644Z,1549583121.644 [NAL9602] Loaded
2019-02-07T23:45:21.644Z,1549583121.644 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-07T23:45:21.649Z,1549583121.649 [Onboard] Loaded
2019-02-07T23:45:21.650Z,1549583121.650 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-07T23:45:21.656Z,1549583121.656 [Radio_Surface] Loaded
2019-02-07T23:45:21.657Z,1549583121.657 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-07T23:45:21.658Z,1549583121.658 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409254E0
2019-02-07T23:45:21.658Z,1549583121.658 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1253
2019-02-07T23:45:21.704Z,1549583121.704 [PNI_TCM] Loaded
2019-02-07T23:45:21.704Z,1549583121.704 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-07T23:45:21.765Z,1549583121.765 [Rowe_600LCM] Loaded
2019-02-07T23:45:21.765Z,1549583121.765 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-07T23:45:21.766Z,1549583121.766 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409554E0
2019-02-07T23:45:21.767Z,1549583121.767 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1254
2019-02-07T23:45:23.324Z,1549583123.324 [BPC1] Loaded
2019-02-07T23:45:23.324Z,1549583123.324 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-07T23:45:23.324Z,1549583123.324 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-07T23:45:23.325Z,1549583123.325 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-07T23:45:23.571Z,1549583123.571 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-07T23:45:23.571Z,1549583123.571 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-07T23:45:23.947Z,1549583123.947 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-07T23:45:24.033Z,1549583124.033 [VerticalControl] Loaded
2019-02-07T23:45:24.034Z,1549583124.034 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-07T23:45:24.034Z,1549583124.034 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-07T23:45:24.092Z,1549583124.092 [HorizontalControl] Loaded
2019-02-07T23:45:24.092Z,1549583124.092 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-07T23:45:24.093Z,1549583124.093 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-07T23:45:24.094Z,1549583124.094 [SpeedControl] Loaded
2019-02-07T23:45:24.095Z,1549583124.095 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-07T23:45:24.095Z,1549583124.095 [LoopControl](DEBUG): Construct LoopControl.
2019-02-07T23:45:24.096Z,1549583124.096 [LoopControl] Loaded
2019-02-07T23:45:24.096Z,1549583124.096 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-07T23:45:24.097Z,1549583124.097 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-07T23:45:24.097Z,1549583124.097 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-07T23:45:24.111Z,1549583124.111 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-07T23:45:24.111Z,1549583124.111 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-07T23:45:24.343Z,1549583124.343 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-07T23:45:24.347Z,1549583124.347 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-07T23:45:24.348Z,1549583124.348 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-07T23:45:24.355Z,1549583124.355 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-07T23:45:24.356Z,1549583124.356 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2019-02-07T23:45:24.356Z,1549583124.356 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1255
2019-02-07T23:45:24.361Z,1549583124.361 [Supervisor](INFO): Main Thread ID is 806
2019-02-07T23:45:24.361Z,1549583124.361 [Supervisor](DEBUG): Running supervisor.
2019-02-07T23:45:24.362Z,1549583124.362 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1256
2019-02-07T23:45:24.369Z,1549583124.369 [controlThread ThreadHandler](INFO): Handler Thread ID is 1257
2019-02-07T23:45:24.369Z,1549583124.369 [controlThread](DEBUG): Initializing ControlThread
2019-02-07T23:45:24.371Z,1549583124.371 [SBIT](INFO): Initialize SBIT Component.
2019-02-07T23:45:24.371Z,1549583124.371 [SBIT](IMPORTANT): git: 2019-01-14A-57-g72ba51e
2019-02-07T23:45:24.372Z,1549583124.372 [SBIT](INFO): git hash: 72ba51edeec12b341e49a5bae224e2abbf93cde2
2019-02-07T23:45:24.372Z,1549583124.372 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-07T23:45:24.373Z,1549583124.373 [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-07T23:45:24.374Z,1549583124.374 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-02-07T23:45:24.375Z,1549583124.375 [IBIT](INFO): Initialize IBIT Component.
2019-02-07T23:45:24.376Z,1549583124.376 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-07T23:45:24.377Z,1549583124.377 [logger ThreadHandler](INFO): Handler Thread ID is 1258
2019-02-07T23:45:24.390Z,1549583124.390 [CBIT](DEBUG): Initialized mux pins.
2019-02-07T23:45:24.390Z,1549583124.390 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-07T23:45:24.398Z,1549583124.398 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1259
2019-02-07T23:45:24.399Z,1549583124.399 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-07T23:45:24.403Z,1549583124.403 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-07T23:45:24.404Z,1549583124.404 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1261
2019-02-07T23:45:24.405Z,1549583124.405 [WetLabsBB2FL](INFO): Powering down
2019-02-07T23:45:24.414Z,1549583124.414 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-07T23:45:24.414Z,1549583124.414 [CBIT](DEBUG): Initializing heartbeat.
2019-02-07T23:45:24.458Z,1549583124.458 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1262
2019-02-07T23:45:24.474Z,1549583124.474 [Radio_Surface](INFO): Powering up
2019-02-07T23:45:24.486Z,1549583124.486 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-07T23:45:24.486Z,1549583124.486 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-07T23:45:24.489Z,1549583124.489 [logger ThreadHandler](INFO): Cycle time is:0.011731
2019-02-07T23:45:24.494Z,1549583124.494 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1263
2019-02-07T23:45:24.495Z,1549583124.495 [Rowe_600LCM](INFO): Initializing
2019-02-07T23:45:24.504Z,1549583124.504 [Rowe_600LCM](INFO): Powering up
2019-02-07T23:45:24.518Z,1549583124.518 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1264
2019-02-07T23:45:24.526Z,1549583124.526 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-07T23:45:24.526Z,1549583124.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-07T23:45:24.526Z,1549583124.526 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-07T23:45:24.527Z,1549583124.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-07T23:45:24.527Z,1549583124.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-07T23:45:24.527Z,1549583124.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-07T23:45:24.527Z,1549583124.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-07T23:45:24.527Z,1549583124.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-07T23:45:24.527Z,1549583124.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-07T23:45:24.528Z,1549583124.528 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-07T23:45:24.528Z,1549583124.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-07T23:45:24.528Z,1549583124.528 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-07T23:45:24.528Z,1549583124.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-07T23:45:24.528Z,1549583124.528 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-07T23:45:24.529Z,1549583124.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-07T23:45:24.529Z,1549583124.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-07T23:45:24.530Z,1549583124.530 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-07T23:45:24.531Z,1549583124.531 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-07T23:45:24.531Z,1549583124.531 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-07T23:45:24.531Z,1549583124.531 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-07T23:45:24.532Z,1549583124.532 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-07T23:45:24.532Z,1549583124.532 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-07T23:45:24.534Z,1549583124.534 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-07T23:45:24.539Z,1549583124.539 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-07T23:45:24.539Z,1549583124.539 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-07T23:45:24.549Z,1549583124.549 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-07T23:45:24.563Z,1549583124.563 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-07T23:45:24.563Z,1549583124.563 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-07T23:45:24.564Z,1549583124.564 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-07T23:45:24.565Z,1549583124.565 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-07T23:45:24.591Z,1549583124.591 [logger ThreadHandler](INFO): Cycle time is:0.001845
2019-02-07T23:45:24.620Z,1549583124.620 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:45:24.655Z,1549583124.655 [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-07T23:45:24.656Z,1549583124.656 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-07T23:45:24.707Z,1549583124.707 [logger ThreadHandler](INFO): Cycle time is:0.015979
2019-02-07T23:45:24.764Z,1549583124.764 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-07T23:45:24.765Z,1549583124.765 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-07T23:45:24.795Z,1549583124.795 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:45:24.820Z,1549583124.820 [logger ThreadHandler](INFO): Cycle time is:0.012208
2019-02-07T23:45:24.838Z,1549583124.838 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-07T23:45:24.841Z,1549583124.841 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-07T23:45:24.875Z,1549583124.875 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-07T23:45:24.930Z,1549583124.930 [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-07T23:45:24.935Z,1549583124.935 [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-07T23:45:24.977Z,1549583124.977 [logger ThreadHandler](INFO): Cycle time is:0.057112
2019-02-07T23:45:25.030Z,1549583125.030 [ESPComponent](INFO): powering down ESP
2019-02-07T23:45:25.079Z,1549583125.079 [logger ThreadHandler](INFO): Cycle time is:0.001290
2019-02-07T23:45:25.199Z,1549583125.199 [logger ThreadHandler](INFO): Cycle time is:0.019244
2019-02-07T23:45:25.303Z,1549583125.303 [logger ThreadHandler](INFO): Cycle time is:0.004188
2019-02-07T23:45:25.438Z,1549583125.438 [logger ThreadHandler](INFO): Cycle time is:0.034905
2019-02-07T23:45:25.492Z,1549583125.492 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-02-07T23:45:25.492Z,1549583125.492 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-02-07T23:45:25.524Z,1549583125.524 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-07T23:45:25.544Z,1549583125.544 [logger ThreadHandler](INFO): Cycle time is:0.005111
2019-02-07T23:45:25.650Z,1549583125.650 [logger ThreadHandler](INFO): Cycle time is:0.005785
2019-02-07T23:45:25.766Z,1549583125.766 [logger ThreadHandler](INFO): Cycle time is:0.015992
2019-02-07T23:45:25.886Z,1549583125.886 [logger ThreadHandler](INFO): Cycle time is:0.019841
2019-02-07T23:45:26.002Z,1549583126.002 [logger ThreadHandler](INFO): Cycle time is:0.015447
2019-02-07T23:45:26.112Z,1549583126.112 [logger ThreadHandler](INFO): Cycle time is:0.009419
2019-02-07T23:45:26.147Z,1549583126.147 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:45:26.174Z,1549583126.174 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:45:26.202Z,1549583126.202 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:45:26.218Z,1549583126.218 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-07T23:45:26.227Z,1549583126.227 [logger ThreadHandler](INFO): Cycle time is:0.014358
2019-02-07T23:45:26.251Z,1549583126.251 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:45:26.274Z,1549583126.274 [MassServo](DEBUG): Initializing MassServo.
2019-02-07T23:45:26.296Z,1549583126.296 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:45:26.302Z,1549583126.302 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-07T23:45:26.320Z,1549583126.320 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:45:26.328Z,1549583126.328 [logger ThreadHandler](INFO): Cycle time is:0.001285
2019-02-07T23:45:26.330Z,1549583126.330 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-07T23:45:26.459Z,1549583126.459 [logger ThreadHandler](INFO): Cycle time is:0.030241
2019-02-07T23:45:26.472Z,1549583126.472 [controlThread ThreadHandler](INFO): Cycle time is:1.135665
2019-02-07T23:45:26.567Z,1549583126.567 [logger ThreadHandler](INFO): Cycle time is:0.008037
2019-02-07T23:45:26.582Z,1549583126.582 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-07T23:45:26.582Z,1549583126.582 [DropWeight] Hardware Fault, FailCount= 1
2019-02-07T23:45:26.582Z,1549583126.582 [DropWeight](ERROR): Hardware Fault
2019-02-07T23:45:26.672Z,1549583126.672 [logger ThreadHandler](INFO): Cycle time is:0.004188
2019-02-07T23:45:26.783Z,1549583126.783 [logger ThreadHandler](INFO): Cycle time is:0.011449
2019-02-07T23:45:26.887Z,1549583126.887 [logger ThreadHandler](INFO): Cycle time is:0.003255
2019-02-07T23:45:26.993Z,1549583126.993 [logger ThreadHandler](INFO): Cycle time is:0.005219
2019-02-07T23:45:27.006Z,1549583127.006 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-07T23:45:27.006Z,1549583127.006 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-07T23:45:27.006Z,1549583127.006 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-07T23:45:27.006Z,1549583127.006 [BuoyancyServo](ERROR): Communications Fault
2019-02-07T23:45:27.099Z,1549583127.099 [logger ThreadHandler](INFO): Cycle time is:0.006620
2019-02-07T23:45:27.216Z,1549583127.216 [logger ThreadHandler](INFO): Cycle time is:0.015955
2019-02-07T23:45:27.288Z,1549583127.288 [CommandLine](FAULT): Scheduling is paused
2019-02-07T23:45:27.288Z,1549583127.288 [CBIT](INFO): Critical error at 20190207T234526
2019-02-07T23:45:27.288Z,1549583127.288 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-07T23:45:27.291Z,1549583127.291 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-07T23:45:27.291Z,1549583127.291 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-07T23:45:27.292Z,1549583127.292 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-07T23:45:27.294Z,1549583127.294 [controlThread ThreadHandler](INFO): Cycle time is:0.421765
2019-02-07T23:45:27.317Z,1549583127.317 [logger ThreadHandler](INFO): Cycle time is:0.000833
2019-02-07T23:45:27.423Z,1549583127.423 [CBIT](INFO): Critical error at 20190207T234527
2019-02-07T23:45:27.438Z,1549583127.438 [logger ThreadHandler](INFO): Cycle time is:0.021103
2019-02-07T23:45:27.552Z,1549583127.552 [logger ThreadHandler](INFO): Cycle time is:0.012897
2019-02-07T23:45:27.659Z,1549583127.659 [logger ThreadHandler](INFO): Cycle time is:0.007228
2019-02-07T23:45:27.698Z,1549583127.698 [controlThread ThreadHandler](INFO): Cycle time is:0.004003
2019-02-07T23:45:27.803Z,1549583127.803 [logger ThreadHandler](INFO): Cycle time is:0.043478
2019-02-07T23:45:27.906Z,1549583127.906 [logger ThreadHandler](INFO): Cycle time is:0.003047
2019-02-07T23:45:28.024Z,1549583128.024 [logger ThreadHandler](INFO): Cycle time is:0.017463
2019-02-07T23:45:28.102Z,1549583128.102 [controlThread ThreadHandler](INFO): Cycle time is:0.003647
2019-02-07T23:45:28.145Z,1549583128.145 [logger ThreadHandler](INFO): Cycle time is:0.018745
2019-02-07T23:45:28.259Z,1549583128.259 [logger ThreadHandler](INFO): Cycle time is:0.013944
2019-02-07T23:45:28.360Z,1549583128.360 [logger ThreadHandler](INFO): Cycle time is:0.001177
2019-02-07T23:45:28.467Z,1549583128.467 [logger ThreadHandler](INFO): Cycle time is:0.006362
2019-02-07T23:45:28.506Z,1549583128.506 [controlThread ThreadHandler](INFO): Cycle time is:0.003701
2019-02-07T23:45:28.602Z,1549583128.602 [logger ThreadHandler](INFO): Cycle time is:0.034949
2019-02-07T23:45:28.703Z,1549583128.703 [logger ThreadHandler](INFO): Cycle time is:0.000679
2019-02-07T23:45:28.814Z,1549583128.814 [logger ThreadHandler](INFO): Cycle time is:0.010416
2019-02-07T23:45:28.910Z,1549583128.910 [controlThread ThreadHandler](INFO): Cycle time is:0.003709
2019-02-07T23:45:28.926Z,1549583128.926 [logger ThreadHandler](INFO): Cycle time is:0.011869
2019-02-07T23:45:28.965Z,1549583128.965 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-07T23:45:29.040Z,1549583129.040 [logger ThreadHandler](INFO): Cycle time is:0.012913
2019-02-07T23:45:29.137Z,1549583129.137 [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-07T23:45:29.156Z,1549583129.156 [logger ThreadHandler](INFO): Cycle time is:0.016319
2019-02-07T23:45:29.258Z,1549583129.258 [logger ThreadHandler](INFO): Cycle time is:0.000964
2019-02-07T23:45:29.314Z,1549583129.314 [controlThread ThreadHandler](INFO): Cycle time is:0.003824
2019-02-07T23:45:29.380Z,1549583129.380 [logger ThreadHandler](INFO): Cycle time is:0.021646
2019-02-07T23:45:29.519Z,1549583129.519 [logger ThreadHandler](INFO): Cycle time is:0.038956
2019-02-07T23:45:29.579Z,1549583129.579 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-07T23:45:29.579Z,1549583129.579 [BuoyancyServo] No Fault, FailCount= 1
2019-02-07T23:45:29.620Z,1549583129.620 [logger ThreadHandler](INFO): Cycle time is:0.000773
2019-02-07T23:45:29.718Z,1549583129.718 [controlThread ThreadHandler](INFO): Cycle time is:0.003556
2019-02-07T23:45:29.795Z,1549583129.795 [logger ThreadHandler](INFO): Cycle time is:0.074361
2019-02-07T23:45:29.879Z,1549583129.879 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:45:29.879Z,1549583129.879 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:45:29.899Z,1549583129.899 [logger ThreadHandler](INFO): Cycle time is:0.004204
2019-02-07T23:45:30.024Z,1549583130.024 [logger ThreadHandler](INFO): Cycle time is:0.023754
2019-02-07T23:45:30.123Z,1549583130.123 [controlThread ThreadHandler](INFO): Cycle time is:0.003681
2019-02-07T23:45:30.130Z,1549583130.130 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:45:30.191Z,1549583130.191 [logger ThreadHandler](INFO): Cycle time is:0.066919
2019-02-07T23:45:30.301Z,1549583130.301 [logger ThreadHandler](INFO): Cycle time is:0.008360
2019-02-07T23:45:30.424Z,1549583130.424 [logger ThreadHandler](INFO): Cycle time is:0.022104
2019-02-07T23:45:30.540Z,1549583130.540 [logger ThreadHandler](INFO): Cycle time is:0.015456
2019-02-07T23:45:30.651Z,1549583130.651 [logger ThreadHandler](INFO): Cycle time is:0.010589
2019-02-07T23:45:30.744Z,1549583130.744 [controlThread ThreadHandler](INFO): Cycle time is:0.221279
2019-02-07T23:45:30.758Z,1549583130.758 [logger ThreadHandler](INFO): Cycle time is:0.006545
2019-02-07T23:45:30.873Z,1549583130.873 [logger ThreadHandler](INFO): Cycle time is:0.010097
2019-02-07T23:45:31.034Z,1549583131.034 [logger ThreadHandler](INFO): Cycle time is:0.060080
2019-02-07T23:45:31.142Z,1549583131.142 [logger ThreadHandler](INFO): Cycle time is:0.007014
2019-02-07T23:45:31.245Z,1549583131.245 [logger ThreadHandler](INFO): Cycle time is:0.002845
2019-02-07T23:45:31.322Z,1549583131.322 [controlThread ThreadHandler](INFO): Cycle time is:0.176556
2019-02-07T23:45:31.377Z,1549583131.377 [logger ThreadHandler](INFO): Cycle time is:0.031381
2019-02-07T23:45:31.492Z,1549583131.492 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-02-07T23:45:31.500Z,1549583131.500 [logger ThreadHandler](INFO): Cycle time is:0.022020
2019-02-07T23:45:31.604Z,1549583131.604 [logger ThreadHandler](INFO): Cycle time is:0.003665
2019-02-07T23:45:31.711Z,1549583131.711 [logger ThreadHandler](INFO): Cycle time is:0.006432
2019-02-07T23:45:31.726Z,1549583131.726 [controlThread ThreadHandler](INFO): Cycle time is:0.004092
2019-02-07T23:45:31.819Z,1549583131.819 [logger ThreadHandler](INFO): Cycle time is:0.008514
2019-02-07T23:45:31.921Z,1549583131.921 [logger ThreadHandler](INFO): Cycle time is:0.001280
2019-02-07T23:45:32.032Z,1549583132.032 [logger ThreadHandler](INFO): Cycle time is:0.010619
2019-02-07T23:45:32.130Z,1549583132.130 [controlThread ThreadHandler](INFO): Cycle time is:0.003697
2019-02-07T23:45:32.171Z,1549583132.171 [logger ThreadHandler](INFO): Cycle time is:0.038896
2019-02-07T23:45:32.279Z,1549583132.279 [logger ThreadHandler](INFO): Cycle time is:0.007754
2019-02-07T23:45:32.383Z,1549583132.383 [logger ThreadHandler](INFO): Cycle time is:0.003264
2019-02-07T23:45:32.495Z,1549583132.495 [logger ThreadHandler](INFO): Cycle time is:0.012076
2019-02-07T23:45:32.534Z,1549583132.534 [controlThread ThreadHandler](INFO): Cycle time is:0.003795
2019-02-07T23:45:32.599Z,1549583132.599 [logger ThreadHandler](INFO): Cycle time is:0.002955
2019-02-07T23:45:32.700Z,1549583132.700 [logger ThreadHandler](INFO): Cycle time is:0.001213
2019-02-07T23:45:32.803Z,1549583132.803 [logger ThreadHandler](INFO): Cycle time is:0.002328
2019-02-07T23:45:32.907Z,1549583132.907 [logger ThreadHandler](INFO): Cycle time is:0.003975
2019-02-07T23:45:32.938Z,1549583132.938 [controlThread ThreadHandler](INFO): Cycle time is:0.003628
2019-02-07T23:45:32.947Z,1549583132.947 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-02-07T23:45:33.018Z,1549583133.018 [logger ThreadHandler](INFO): Cycle time is:0.010303
2019-02-07T23:45:33.123Z,1549583133.123 [logger ThreadHandler](INFO): Cycle time is:0.005065
2019-02-07T23:45:33.225Z,1549583133.225 [logger ThreadHandler](INFO): Cycle time is:0.001827
2019-02-07T23:45:33.327Z,1549583133.327 [logger ThreadHandler](INFO): Cycle time is:0.001248
2019-02-07T23:45:33.342Z,1549583133.342 [controlThread ThreadHandler](INFO): Cycle time is:0.003663
2019-02-07T23:45:33.432Z,1549583133.432 [logger ThreadHandler](INFO): Cycle time is:0.004859
2019-02-07T23:45:33.536Z,1549583133.536 [logger ThreadHandler](INFO): Cycle time is:0.003120
2019-02-07T23:45:33.637Z,1549583133.637 [logger ThreadHandler](INFO): Cycle time is:0.000920
2019-02-07T23:45:33.747Z,1549583133.747 [controlThread ThreadHandler](INFO): Cycle time is:0.004123
2019-02-07T23:45:33.761Z,1549583133.761 [logger ThreadHandler](INFO): Cycle time is:0.024168
2019-02-07T23:45:33.873Z,1549583133.873 [logger ThreadHandler](INFO): Cycle time is:0.011136
2019-02-07T23:45:33.982Z,1549583133.982 [logger ThreadHandler](INFO): Cycle time is:0.009147
2019-02-07T23:45:34.085Z,1549583134.085 [logger ThreadHandler](INFO): Cycle time is:0.002205
2019-02-07T23:45:34.150Z,1549583134.150 [controlThread ThreadHandler](INFO): Cycle time is:0.003309
2019-02-07T23:45:34.161Z,1549583134.161 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-02-07T23:45:34.161Z,1549583134.161 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-02-07T23:45:34.163Z,1549583134.163 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-02-07T23:45:34.163Z,1549583134.163 [AcousticModem_Benthos_ATM900](DEBUG): Feb  7 2019 23:48:52 
2019-02-07T23:45:34.211Z,1549583134.211 [logger ThreadHandler](INFO): Cycle time is:0.025582
2019-02-07T23:45:34.313Z,1549583134.313 [logger ThreadHandler](INFO): Cycle time is:0.001195
2019-02-07T23:45:34.380Z,1549583134.380 [CBIT](CRITICAL): Environmental Failure. Press:14.887811 PSI. Humidity:24%. Temp:26 C. ABORTING MISSION
2019-02-07T23:45:34.419Z,1549583134.419 [logger ThreadHandler](INFO): Cycle time is:0.005849
2019-02-07T23:45:34.464Z,1549583134.464 [NavChartDb ThreadHandler](INFO): Cycle time is:9.792678
2019-02-07T23:45:34.539Z,1549583134.539 [logger ThreadHandler](INFO): Cycle time is:0.019729
2019-02-07T23:45:34.554Z,1549583134.554 [controlThread ThreadHandler](INFO): Cycle time is:0.003293
2019-02-07T23:45:34.568Z,1549583134.568 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-02-07T23:45:34.695Z,1549583134.695 [logger ThreadHandler](INFO): Cycle time is:0.055276
2019-02-07T23:45:34.753Z,1549583134.753 [CBIT](INFO): Critical error at 20190207T234534
2019-02-07T23:45:34.796Z,1549583134.796 [logger ThreadHandler](INFO): Cycle time is:0.000779
2019-02-07T23:45:34.899Z,1549583134.899 [logger ThreadHandler](INFO): Cycle time is:0.003013
2019-02-07T23:45:34.958Z,1549583134.958 [controlThread ThreadHandler](INFO): Cycle time is:0.003590
2019-02-07T23:45:35.012Z,1549583135.012 [logger ThreadHandler](INFO): Cycle time is:0.012086
2019-02-07T23:45:35.119Z,1549583135.119 [logger ThreadHandler](INFO): Cycle time is:0.007514
2019-02-07T23:45:35.232Z,1549583135.232 [logger ThreadHandler](INFO): Cycle time is:0.012275
2019-02-07T23:45:35.343Z,1549583135.343 [logger ThreadHandler](INFO): Cycle time is:0.010304
2019-02-07T23:45:35.362Z,1549583135.362 [controlThread ThreadHandler](INFO): Cycle time is:0.003622
2019-02-07T23:45:35.375Z,1549583135.375 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-02-07T23:45:35.451Z,1549583135.451 [logger ThreadHandler](INFO): Cycle time is:0.007577
2019-02-07T23:45:35.557Z,1549583135.557 [logger ThreadHandler](INFO): Cycle time is:0.005295
2019-02-07T23:45:35.662Z,1549583135.662 [logger ThreadHandler](INFO): Cycle time is:0.005423
2019-02-07T23:45:35.766Z,1549583135.766 [controlThread ThreadHandler](INFO): Cycle time is:0.003712
2019-02-07T23:45:35.775Z,1549583135.775 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-02-07T23:45:35.776Z,1549583135.776 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-02-07T23:45:35.776Z,1549583135.776 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-02-07T23:45:35.789Z,1549583135.789 [logger ThreadHandler](INFO): Cycle time is:0.025641
2019-02-07T23:45:35.895Z,1549583135.895 [logger ThreadHandler](INFO): Cycle time is:0.005573
2019-02-07T23:45:36.003Z,1549583136.003 [logger ThreadHandler](INFO): Cycle time is:0.007148
2019-02-07T23:45:36.107Z,1549583136.107 [logger ThreadHandler](INFO): Cycle time is:0.004099
2019-02-07T23:45:36.171Z,1549583136.171 [controlThread ThreadHandler](INFO): Cycle time is:0.003896
2019-02-07T23:45:36.179Z,1549583136.179 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-02-07T23:45:36.215Z,1549583136.215 [logger ThreadHandler](INFO): Cycle time is:0.006309
2019-02-07T23:45:36.328Z,1549583136.328 [logger ThreadHandler](INFO): Cycle time is:0.012817
2019-02-07T23:45:36.429Z,1549583136.429 [logger ThreadHandler](INFO): Cycle time is:0.000792
2019-02-07T23:45:36.544Z,1549583136.544 [logger ThreadHandler](INFO): Cycle time is:0.015174
2019-02-07T23:45:36.574Z,1549583136.574 [controlThread ThreadHandler](INFO): Cycle time is:0.003022
2019-02-07T23:45:36.583Z,1549583136.583 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-02-07T23:45:36.584Z,1549583136.584 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-02-07T23:45:36.647Z,1549583136.647 [logger ThreadHandler](INFO): Cycle time is:0.002272
2019-02-07T23:45:36.748Z,1549583136.748 [logger ThreadHandler](INFO): Cycle time is:0.000918
2019-02-07T23:45:36.849Z,1549583136.849 [logger ThreadHandler](INFO): Cycle time is:0.000616
2019-02-07T23:45:36.952Z,1549583136.952 [logger ThreadHandler](INFO): Cycle time is:0.002683
2019-02-07T23:45:36.978Z,1549583136.978 [controlThread ThreadHandler](INFO): Cycle time is:0.003659
2019-02-07T23:45:36.988Z,1549583136.988 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-02-07T23:45:37.055Z,1549583137.055 [logger ThreadHandler](INFO): Cycle time is:0.002266
2019-02-07T23:45:37.161Z,1549583137.161 [logger ThreadHandler](INFO): Cycle time is:0.005773
2019-02-07T23:45:37.273Z,1549583137.273 [logger ThreadHandler](INFO): Cycle time is:0.011415
2019-02-07T23:45:37.375Z,1549583137.375 [logger ThreadHandler](INFO): Cycle time is:0.001747
2019-02-07T23:45:37.382Z,1549583137.382 [controlThread ThreadHandler](INFO): Cycle time is:0.003716
2019-02-07T23:45:37.479Z,1549583137.479 [logger ThreadHandler](INFO): Cycle time is:0.004129
2019-02-07T23:45:37.583Z,1549583137.583 [logger ThreadHandler](INFO): Cycle time is:0.003049
2019-02-07T23:45:37.688Z,1549583137.688 [logger ThreadHandler](INFO): Cycle time is:0.004573
2019-02-07T23:45:37.786Z,1549583137.786 [controlThread ThreadHandler](INFO): Cycle time is:0.003605
2019-02-07T23:45:37.843Z,1549583137.843 [logger ThreadHandler](INFO): Cycle time is:0.055332
2019-02-07T23:45:37.945Z,1549583137.945 [logger ThreadHandler](INFO): Cycle time is:0.001012
2019-02-07T23:45:38.046Z,1549583138.046 [logger ThreadHandler](INFO): Cycle time is:0.000819
2019-02-07T23:45:38.149Z,1549583138.149 [logger ThreadHandler](INFO): Cycle time is:0.002534
2019-02-07T23:45:38.190Z,1549583138.190 [controlThread ThreadHandler](INFO): Cycle time is:0.003947
2019-02-07T23:45:38.257Z,1549583138.257 [logger ThreadHandler](INFO): Cycle time is:0.007956
2019-02-07T23:45:38.364Z,1549583138.364 [logger ThreadHandler](INFO): Cycle time is:0.006360
2019-02-07T23:45:38.467Z,1549583138.467 [logger ThreadHandler](INFO): Cycle time is:0.002180
2019-02-07T23:45:38.568Z,1549583138.568 [logger ThreadHandler](INFO): Cycle time is:0.000799
2019-02-07T23:45:38.594Z,1549583138.594 [controlThread ThreadHandler](INFO): Cycle time is:0.003387
2019-02-07T23:45:38.669Z,1549583138.669 [logger ThreadHandler](INFO): Cycle time is:0.000594
2019-02-07T23:45:38.771Z,1549583138.771 [logger ThreadHandler](INFO): Cycle time is:0.001423
2019-02-07T23:45:38.875Z,1549583138.875 [logger ThreadHandler](INFO): Cycle time is:0.004467
2019-02-07T23:45:38.977Z,1549583138.977 [logger ThreadHandler](INFO): Cycle time is:0.000878
2019-02-07T23:45:38.998Z,1549583138.998 [controlThread ThreadHandler](INFO): Cycle time is:0.003644
2019-02-07T23:45:39.096Z,1549583139.096 [logger ThreadHandler](INFO): Cycle time is:0.018663
2019-02-07T23:45:39.213Z,1549583139.213 [logger ThreadHandler](INFO): Cycle time is:0.016979
2019-02-07T23:45:39.331Z,1549583139.331 [logger ThreadHandler](INFO): Cycle time is:0.017341
2019-02-07T23:45:39.402Z,1549583139.402 [controlThread ThreadHandler](INFO): Cycle time is:0.003801
2019-02-07T23:45:39.449Z,1549583139.449 [logger ThreadHandler](INFO): Cycle time is:0.017268
2019-02-07T23:45:39.550Z,1549583139.550 [logger ThreadHandler](INFO): Cycle time is:0.001451
2019-02-07T23:45:39.659Z,1549583139.659 [logger ThreadHandler](INFO): Cycle time is:0.007972
2019-02-07T23:45:39.771Z,1549583139.771 [logger ThreadHandler](INFO): Cycle time is:0.012220
2019-02-07T23:45:39.806Z,1549583139.806 [controlThread ThreadHandler](INFO): Cycle time is:0.003541
2019-02-07T23:45:39.891Z,1549583139.891 [logger ThreadHandler](INFO): Cycle time is:0.019892
2019-02-07T23:45:39.995Z,1549583139.995 [logger ThreadHandler](INFO): Cycle time is:0.003723
2019-02-07T23:45:40.107Z,1549583140.107 [logger ThreadHandler](INFO): Cycle time is:0.011414
2019-02-07T23:45:40.211Z,1549583140.211 [controlThread ThreadHandler](INFO): Cycle time is:0.003800
2019-02-07T23:45:40.263Z,1549583140.263 [logger ThreadHandler](INFO): Cycle time is:0.055549
2019-02-07T23:45:40.367Z,1549583140.367 [logger ThreadHandler](INFO): Cycle time is:0.003156
2019-02-07T23:45:40.475Z,1549583140.475 [logger ThreadHandler](INFO): Cycle time is:0.007681
2019-02-07T23:45:40.576Z,1549583140.576 [logger ThreadHandler](INFO): Cycle time is:0.001072
2019-02-07T23:45:40.614Z,1549583140.614 [controlThread ThreadHandler](INFO): Cycle time is:0.003092
2019-02-07T23:45:40.679Z,1549583140.679 [logger ThreadHandler](INFO): Cycle time is:0.002212
2019-02-07T23:45:40.781Z,1549583140.781 [logger ThreadHandler](INFO): Cycle time is:0.002089
2019-02-07T23:45:40.883Z,1549583140.883 [logger ThreadHandler](INFO): Cycle time is:0.001358
2019-02-07T23:45:40.987Z,1549583140.987 [logger ThreadHandler](INFO): Cycle time is:0.003963
2019-02-07T23:45:41.018Z,1549583141.018 [controlThread ThreadHandler](INFO): Cycle time is:0.003680
2019-02-07T23:45:41.099Z,1549583141.099 [logger ThreadHandler](INFO): Cycle time is:0.011868
2019-02-07T23:45:41.206Z,1549583141.206 [logger ThreadHandler](INFO): Cycle time is:0.006439
2019-02-07T23:45:41.310Z,1549583141.310 [logger ThreadHandler](INFO): Cycle time is:0.002972
2019-02-07T23:45:41.422Z,1549583141.422 [controlThread ThreadHandler](INFO): Cycle time is:0.003625
2019-02-07T23:45:41.437Z,1549583141.437 [logger ThreadHandler](INFO): Cycle time is:0.025744
2019-02-07T23:45:41.539Z,1549583141.539 [logger ThreadHandler](INFO): Cycle time is:0.001852
2019-02-07T23:45:41.644Z,1549583141.644 [logger ThreadHandler](INFO): Cycle time is:0.004202
2019-02-07T23:45:41.747Z,1549583141.747 [logger ThreadHandler](INFO): Cycle time is:0.002974
2019-02-07T23:45:41.826Z,1549583141.826 [controlThread ThreadHandler](INFO): Cycle time is:0.003688
2019-02-07T23:45:41.866Z,1549583141.866 [logger ThreadHandler](INFO): Cycle time is:0.019058
2019-02-07T23:45:41.968Z,1549583141.968 [logger ThreadHandler](INFO): Cycle time is:0.001202
2019-02-07T23:45:42.071Z,1549583142.071 [logger ThreadHandler](INFO): Cycle time is:0.002526
2019-02-07T23:45:42.178Z,1549583142.178 [logger ThreadHandler](INFO): Cycle time is:0.006096
2019-02-07T23:45:42.231Z,1549583142.231 [controlThread ThreadHandler](INFO): Cycle time is:0.003721
2019-02-07T23:45:42.309Z,1549583142.309 [logger ThreadHandler](INFO): Cycle time is:0.030870
2019-02-07T23:45:42.415Z,1549583142.415 [logger ThreadHandler](INFO): Cycle time is:0.005747
2019-02-07T23:45:42.519Z,1549583142.519 [logger ThreadHandler](INFO): Cycle time is:0.003514
2019-02-07T23:45:42.625Z,1549583142.625 [logger ThreadHandler](INFO): Cycle time is:0.005097
2019-02-07T23:45:42.634Z,1549583142.634 [controlThread ThreadHandler](INFO): Cycle time is:0.003265
2019-02-07T23:45:42.731Z,1549583142.731 [logger ThreadHandler](INFO): Cycle time is:0.005642
2019-02-07T23:45:42.832Z,1549583142.832 [logger ThreadHandler](INFO): Cycle time is:0.001188
2019-02-07T23:45:42.938Z,1549583142.938 [logger ThreadHandler](INFO): Cycle time is:0.005683
2019-02-07T23:45:43.038Z,1549583143.038 [controlThread ThreadHandler](INFO): Cycle time is:0.003560
2019-02-07T23:45:43.061Z,1549583143.061 [logger ThreadHandler](INFO): Cycle time is:0.021580
2019-02-07T23:45:43.169Z,1549583143.169 [logger ThreadHandler](INFO): Cycle time is:0.007257
2019-02-07T23:45:43.281Z,1549583143.281 [logger ThreadHandler](INFO): Cycle time is:0.010627
2019-02-07T23:45:43.387Z,1549583143.387 [logger ThreadHandler](INFO): Cycle time is:0.006542
2019-02-07T23:45:43.442Z,1549583143.442 [controlThread ThreadHandler](INFO): Cycle time is:0.003680
2019-02-07T23:45:43.491Z,1549583143.491 [logger ThreadHandler](INFO): Cycle time is:0.003163
2019-02-07T23:45:43.593Z,1549583143.593 [logger ThreadHandler](INFO): Cycle time is:0.001316
2019-02-07T23:45:43.696Z,1549583143.696 [logger ThreadHandler](INFO): Cycle time is:0.003216
2019-02-07T23:45:43.799Z,1549583143.799 [logger ThreadHandler](INFO): Cycle time is:0.002352
2019-02-07T23:45:43.846Z,1549583143.846 [controlThread ThreadHandler](INFO): Cycle time is:0.003689
2019-02-07T23:45:43.907Z,1549583143.907 [logger ThreadHandler](INFO): Cycle time is:0.008011
2019-02-07T23:45:44.008Z,1549583144.008 [logger ThreadHandler](INFO): Cycle time is:0.001059
2019-02-07T23:45:44.111Z,1549583144.111 [logger ThreadHandler](INFO): Cycle time is:0.001779
2019-02-07T23:45:44.220Z,1549583144.220 [logger ThreadHandler](INFO): Cycle time is:0.008764
2019-02-07T23:45:44.250Z,1549583144.250 [controlThread ThreadHandler](INFO): Cycle time is:0.003949
2019-02-07T23:45:44.335Z,1549583144.335 [logger ThreadHandler](INFO): Cycle time is:0.013994
2019-02-07T23:45:44.437Z,1549583144.437 [logger ThreadHandler](INFO): Cycle time is:0.001521
2019-02-07T23:45:44.539Z,1549583144.539 [logger ThreadHandler](INFO): Cycle time is:0.002130
2019-02-07T23:45:44.644Z,1549583144.644 [logger ThreadHandler](INFO): Cycle time is:0.004502
2019-02-07T23:45:44.655Z,1549583144.655 [controlThread ThreadHandler](INFO): Cycle time is:0.004162
2019-02-07T23:45:44.751Z,1549583144.751 [logger ThreadHandler](INFO): Cycle time is:0.007210
2019-02-07T23:45:44.855Z,1549583144.855 [logger ThreadHandler](INFO): Cycle time is:0.003073
2019-02-07T23:45:44.967Z,1549583144.967 [logger ThreadHandler](INFO): Cycle time is:0.012204
2019-02-07T23:45:45.058Z,1549583145.058 [controlThread ThreadHandler](INFO): Cycle time is:0.002962
2019-02-07T23:45:45.079Z,1549583145.079 [logger ThreadHandler](INFO): Cycle time is:0.010481
2019-02-07T23:45:45.182Z,1549583145.182 [logger ThreadHandler](INFO): Cycle time is:0.001994
2019-02-07T23:45:45.298Z,1549583145.298 [logger ThreadHandler](INFO): Cycle time is:0.015243
2019-02-07T23:45:45.412Z,1549583145.412 [logger ThreadHandler](INFO): Cycle time is:0.013253
2019-02-07T23:45:45.462Z,1549583145.462 [controlThread ThreadHandler](INFO): Cycle time is:0.003693
2019-02-07T23:45:45.516Z,1549583145.516 [logger ThreadHandler](INFO): Cycle time is:0.004035
2019-02-07T23:45:45.617Z,1549583145.617 [logger ThreadHandler](INFO): Cycle time is:0.000602
2019-02-07T23:45:45.719Z,1549583145.719 [logger ThreadHandler](INFO): Cycle time is:0.001768
2019-02-07T23:45:45.802Z,1549583145.802 [NavChartDb ThreadHandler](INFO): Cycle time is:11.238457
2019-02-07T23:45:45.823Z,1549583145.823 [logger ThreadHandler](INFO): Cycle time is:0.003514
2019-02-07T23:45:45.866Z,1549583145.866 [controlThread ThreadHandler](INFO): Cycle time is:0.003729
2019-02-07T23:45:45.931Z,1549583145.931 [logger ThreadHandler](INFO): Cycle time is:0.007970
2019-02-07T23:45:46.035Z,1549583146.035 [logger ThreadHandler](INFO): Cycle time is:0.003857
2019-02-07T23:45:46.139Z,1549583146.139 [logger ThreadHandler](INFO): Cycle time is:0.003235
2019-02-07T23:45:46.241Z,1549583146.241 [logger ThreadHandler](INFO): Cycle time is:0.001917
2019-02-07T23:45:46.271Z,1549583146.271 [controlThread ThreadHandler](INFO): Cycle time is:0.003696
2019-02-07T23:45:46.351Z,1549583146.351 [logger ThreadHandler](INFO): Cycle time is:0.010020
2019-02-07T23:45:46.452Z,1549583146.452 [logger ThreadHandler](INFO): Cycle time is:0.000763
2019-02-07T23:45:46.555Z,1549583146.555 [logger ThreadHandler](INFO): Cycle time is:0.002175
2019-02-07T23:45:46.672Z,1549583146.672 [logger ThreadHandler](INFO): Cycle time is:0.016603
2019-02-07T23:45:46.674Z,1549583146.674 [controlThread ThreadHandler](INFO): Cycle time is:0.003209
2019-02-07T23:45:46.775Z,1549583146.775 [logger ThreadHandler](INFO): Cycle time is:0.002800
2019-02-07T23:45:46.892Z,1549583146.892 [logger ThreadHandler](INFO): Cycle time is:0.016605
2019-02-07T23:45:46.993Z,1549583146.993 [logger ThreadHandler](INFO): Cycle time is:0.000739
2019-02-07T23:45:47.078Z,1549583147.078 [controlThread ThreadHandler](INFO): Cycle time is:0.003900
2019-02-07T23:45:47.127Z,1549583147.127 [logger ThreadHandler](INFO): Cycle time is:0.033547
2019-02-07T23:45:47.231Z,1549583147.231 [logger ThreadHandler](INFO): Cycle time is:0.004059
2019-02-07T23:45:47.336Z,1549583147.336 [logger ThreadHandler](INFO): Cycle time is:0.004298
2019-02-07T23:45:47.442Z,1549583147.442 [logger ThreadHandler](INFO): Cycle time is:0.005430
2019-02-07T23:45:47.482Z,1549583147.482 [controlThread ThreadHandler](INFO): Cycle time is:0.003607
2019-02-07T23:45:47.545Z,1549583147.545 [logger ThreadHandler](INFO): Cycle time is:0.003240
2019-02-07T23:45:47.651Z,1549583147.651 [logger ThreadHandler](INFO): Cycle time is:0.005151
2019-02-07T23:45:47.755Z,1549583147.755 [logger ThreadHandler](INFO): Cycle time is:0.003462
2019-02-07T23:45:47.794Z,1549583147.794 [NavChartDb ThreadHandler](INFO): Cycle time is:1.891529
2019-02-07T23:45:47.857Z,1549583147.857 [logger ThreadHandler](INFO): Cycle time is:0.002108
2019-02-07T23:45:47.886Z,1549583147.886 [controlThread ThreadHandler](INFO): Cycle time is:0.003559
2019-02-07T23:45:47.960Z,1549583147.960 [logger ThreadHandler](INFO): Cycle time is:0.002178
2019-02-07T23:45:48.063Z,1549583148.063 [logger ThreadHandler](INFO): Cycle time is:0.003545
2019-02-07T23:45:48.167Z,1549583148.167 [logger ThreadHandler](INFO): Cycle time is:0.002671
2019-02-07T23:45:48.279Z,1549583148.279 [logger ThreadHandler](INFO): Cycle time is:0.012289
2019-02-07T23:45:48.290Z,1549583148.290 [controlThread ThreadHandler](INFO): Cycle time is:0.003779
2019-02-07T23:45:48.383Z,1549583148.383 [logger ThreadHandler](INFO): Cycle time is:0.003281
2019-02-07T23:45:48.492Z,1549583148.492 [logger ThreadHandler](INFO): Cycle time is:0.008801
2019-02-07T23:45:48.594Z,1549583148.594 [logger ThreadHandler](INFO): Cycle time is:0.001734
2019-02-07T23:45:48.694Z,1549583148.694 [controlThread ThreadHandler](INFO): Cycle time is:0.003150
2019-02-07T23:45:48.715Z,1549583148.715 [logger ThreadHandler](INFO): Cycle time is:0.019381
2019-02-07T23:45:48.785Z,1549583148.785 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-07T23:45:48.802Z,1549583148.802 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-07T23:45:48.816Z,1549583148.816 [logger ThreadHandler](INFO): Cycle time is:0.001049
2019-02-07T23:45:48.919Z,1549583148.919 [logger ThreadHandler](INFO): Cycle time is:0.002170
2019-02-07T23:45:49.020Z,1549583149.020 [logger ThreadHandler](INFO): Cycle time is:0.000861
2019-02-07T23:45:49.098Z,1549583149.098 [controlThread ThreadHandler](INFO): Cycle time is:0.003719
2019-02-07T23:45:49.125Z,1549583149.125 [logger ThreadHandler](INFO): Cycle time is:0.003782
2019-02-07T23:45:49.275Z,1549583149.275 [logger ThreadHandler](INFO): Cycle time is:0.049633
2019-02-07T23:45:49.391Z,1549583149.391 [logger ThreadHandler](INFO): Cycle time is:0.015630
2019-02-07T23:45:49.495Z,1549583149.495 [logger ThreadHandler](INFO): Cycle time is:0.003157
2019-02-07T23:45:49.502Z,1549583149.502 [controlThread ThreadHandler](INFO): Cycle time is:0.003691
2019-02-07T23:45:49.607Z,1549583149.607 [logger ThreadHandler](INFO): Cycle time is:0.011556
2019-02-07T23:45:49.711Z,1549583149.711 [logger ThreadHandler](INFO): Cycle time is:0.004503
2019-02-07T23:45:49.815Z,1549583149.815 [logger ThreadHandler](INFO): Cycle time is:0.002920
2019-02-07T23:45:49.906Z,1549583149.906 [controlThread ThreadHandler](INFO): Cycle time is:0.003573
2019-02-07T23:45:49.925Z,1549583149.925 [logger ThreadHandler](INFO): Cycle time is:0.008935
2019-02-07T23:45:50.031Z,1549583150.031 [logger ThreadHandler](INFO): Cycle time is:0.005320
2019-02-07T23:45:50.132Z,1549583150.132 [logger ThreadHandler](INFO): Cycle time is:0.000689
2019-02-07T23:45:50.241Z,1549583150.241 [logger ThreadHandler](INFO): Cycle time is:0.009074
2019-02-07T23:45:50.311Z,1549583150.311 [controlThread ThreadHandler](INFO): Cycle time is:0.003985
2019-02-07T23:45:50.345Z,1549583150.345 [logger ThreadHandler](INFO): Cycle time is:0.002468
2019-02-07T23:45:50.455Z,1549583150.455 [logger ThreadHandler](INFO): Cycle time is:0.008269
2019-02-07T23:45:50.517Z,1549583150.517 [NavChartDb ThreadHandler](INFO): Cycle time is:2.621851
2019-02-07T23:45:50.583Z,1549583150.583 [logger ThreadHandler](INFO): Cycle time is:0.027346
2019-02-07T23:45:50.687Z,1549583150.687 [logger ThreadHandler](INFO): Cycle time is:0.003767
2019-02-07T23:45:50.714Z,1549583150.714 [controlThread ThreadHandler](INFO): Cycle time is:0.003336
2019-02-07T23:45:50.791Z,1549583150.791 [logger ThreadHandler](INFO): Cycle time is:0.003746
2019-02-07T23:45:50.895Z,1549583150.895 [logger ThreadHandler](INFO): Cycle time is:0.003790
2019-02-07T23:45:51.007Z,1549583151.007 [logger ThreadHandler](INFO): Cycle time is:0.011639
2019-02-07T23:45:51.115Z,1549583151.115 [logger ThreadHandler](INFO): Cycle time is:0.007923
2019-02-07T23:45:51.220Z,1549583151.220 [logger ThreadHandler](INFO): Cycle time is:0.004663
2019-02-07T23:45:51.272Z,1549583151.272 [controlThread ThreadHandler](INFO): Cycle time is:0.157505
2019-02-07T23:45:51.324Z,1549583151.324 [logger ThreadHandler](INFO): Cycle time is:0.003855
2019-02-07T23:45:51.427Z,1549583151.427 [logger ThreadHandler](INFO): Cycle time is:0.002008
2019-02-07T23:45:51.550Z,1549583151.550 [logger ThreadHandler](INFO): Cycle time is:0.023330
2019-02-07T23:45:51.651Z,1549583151.651 [logger ThreadHandler](INFO): Cycle time is:0.000652
2019-02-07T23:45:51.674Z,1549583151.674 [controlThread ThreadHandler](INFO): Cycle time is:0.001377
2019-02-07T23:45:51.688Z,1549583151.688 [NAL9602](INFO): Powering up NAL9602
2019-02-07T23:45:51.773Z,1549583151.773 [logger ThreadHandler](INFO): Cycle time is:0.020604
2019-02-07T23:45:51.879Z,1549583151.879 [logger ThreadHandler](INFO): Cycle time is:0.006130
2019-02-07T23:45:51.983Z,1549583151.983 [logger ThreadHandler](INFO): Cycle time is:0.003365
2019-02-07T23:45:52.078Z,1549583152.078 [controlThread ThreadHandler](INFO): Cycle time is:0.003862
2019-02-07T23:45:52.100Z,1549583152.100 [logger ThreadHandler](INFO): Cycle time is:0.016031
2019-02-07T23:45:52.203Z,1549583152.203 [logger ThreadHandler](INFO): Cycle time is:0.002334
2019-02-07T23:45:52.326Z,1549583152.326 [logger ThreadHandler](INFO): Cycle time is:0.022401
2019-02-07T23:45:52.436Z,1549583152.436 [logger ThreadHandler](INFO): Cycle time is:0.010235
2019-02-07T23:45:52.482Z,1549583152.482 [controlThread ThreadHandler](INFO): Cycle time is:0.003645
2019-02-07T23:45:52.539Z,1549583152.539 [logger ThreadHandler](INFO): Cycle time is:0.002176
2019-02-07T23:45:52.654Z,1549583152.654 [logger ThreadHandler](INFO): Cycle time is:0.015157
2019-02-07T23:45:52.759Z,1549583152.759 [logger ThreadHandler](INFO): Cycle time is:0.004282
2019-02-07T23:45:52.860Z,1549583152.860 [logger ThreadHandler](INFO): Cycle time is:0.000937
2019-02-07T23:45:52.886Z,1549583152.886 [controlThread ThreadHandler](INFO): Cycle time is:0.003538
2019-02-07T23:45:52.963Z,1549583152.963 [logger ThreadHandler](INFO): Cycle time is:0.002252
2019-02-07T23:45:53.068Z,1549583153.068 [logger ThreadHandler](INFO): Cycle time is:0.004871
2019-02-07T23:45:53.174Z,1549583153.174 [logger ThreadHandler](INFO): Cycle time is:0.004632
2019-02-07T23:45:53.278Z,1549583153.278 [logger ThreadHandler](INFO): Cycle time is:0.003452
2019-02-07T23:45:53.291Z,1549583153.291 [controlThread ThreadHandler](INFO): Cycle time is:0.004219
2019-02-07T23:45:53.380Z,1549583153.380 [logger ThreadHandler](INFO): Cycle time is:0.001318
2019-02-07T23:45:53.486Z,1549583153.486 [logger ThreadHandler](INFO): Cycle time is:0.005384
2019-02-07T23:45:53.591Z,1549583153.591 [logger ThreadHandler](INFO): Cycle time is:0.004395
2019-02-07T23:45:53.694Z,1549583153.694 [controlThread ThreadHandler](INFO): Cycle time is:0.002941
2019-02-07T23:45:53.707Z,1549583153.707 [logger ThreadHandler](INFO): Cycle time is:0.015196
2019-02-07T23:45:53.811Z,1549583153.811 [logger ThreadHandler](INFO): Cycle time is:0.003817
2019-02-07T23:45:53.912Z,1549583153.912 [logger ThreadHandler](INFO): Cycle time is:0.000677
2019-02-07T23:45:54.015Z,1549583154.015 [logger ThreadHandler](INFO): Cycle time is:0.002519
2019-02-07T23:45:54.098Z,1549583154.098 [controlThread ThreadHandler](INFO): Cycle time is:0.003800
2019-02-07T23:45:54.143Z,1549583154.143 [logger ThreadHandler](INFO): Cycle time is:0.028240
2019-02-07T23:45:54.258Z,1549583154.258 [logger ThreadHandler](INFO): Cycle time is:0.013782
2019-02-07T23:45:54.360Z,1549583154.360 [logger ThreadHandler](INFO): Cycle time is:0.001724
2019-02-07T23:45:54.461Z,1549583154.461 [logger ThreadHandler](INFO): Cycle time is:0.000785
2019-02-07T23:45:54.502Z,1549583154.502 [controlThread ThreadHandler](INFO): Cycle time is:0.003513
2019-02-07T23:45:54.569Z,1549583154.569 [logger ThreadHandler](INFO): Cycle time is:0.007144
2019-02-07T23:45:54.675Z,1549583154.675 [logger ThreadHandler](INFO): Cycle time is:0.005746
2019-02-07T23:45:54.776Z,1549583154.776 [logger ThreadHandler](INFO): Cycle time is:0.000682
2019-02-07T23:45:54.877Z,1549583154.877 [logger ThreadHandler](INFO): Cycle time is:0.000771
2019-02-07T23:45:54.906Z,1549583154.906 [controlThread ThreadHandler](INFO): Cycle time is:0.003651
2019-02-07T23:45:54.978Z,1549583154.978 [logger ThreadHandler](INFO): Cycle time is:0.001074
2019-02-07T23:45:55.083Z,1549583155.083 [logger ThreadHandler](INFO): Cycle time is:0.004383
2019-02-07T23:45:55.190Z,1549583155.190 [logger ThreadHandler](INFO): Cycle time is:0.005955
2019-02-07T23:45:55.295Z,1549583155.295 [logger ThreadHandler](INFO): Cycle time is:0.003634
2019-02-07T23:45:55.311Z,1549583155.311 [controlThread ThreadHandler](INFO): Cycle time is:0.004020
2019-02-07T23:45:55.400Z,1549583155.400 [logger ThreadHandler](INFO): Cycle time is:0.004336
2019-02-07T23:45:55.500Z,1549583155.500 [logger ThreadHandler](INFO): Cycle time is:0.000580
2019-02-07T23:45:55.565Z,1549583155.565 [NavChartDb ThreadHandler](INFO): Cycle time is:4.947896
2019-02-07T23:45:55.601Z,1549583155.601 [logger ThreadHandler](INFO): Cycle time is:0.000709
2019-02-07T23:45:55.703Z,1549583155.703 [logger ThreadHandler](INFO): Cycle time is:0.000904
2019-02-07T23:45:55.714Z,1549583155.714 [controlThread ThreadHandler](INFO): Cycle time is:0.003223
2019-02-07T23:45:55.804Z,1549583155.804 [logger ThreadHandler](INFO): Cycle time is:0.000833
2019-02-07T23:45:55.905Z,1549583155.905 [logger ThreadHandler](INFO): Cycle time is:0.000696
2019-02-07T23:45:56.017Z,1549583156.017 [logger ThreadHandler](INFO): Cycle time is:0.011642
2019-02-07T23:45:56.118Z,1549583156.118 [controlThread ThreadHandler](INFO): Cycle time is:0.003608
2019-02-07T23:45:56.132Z,1549583156.132 [logger ThreadHandler](INFO): Cycle time is:0.014598
2019-02-07T23:45:56.258Z,1549583156.258 [logger ThreadHandler](INFO): Cycle time is:0.025162
2019-02-07T23:45:56.360Z,1549583156.360 [logger ThreadHandler](INFO): Cycle time is:0.001981
2019-02-07T23:45:56.461Z,1549583156.461 [logger ThreadHandler](INFO): Cycle time is:0.000666
2019-02-07T23:45:56.522Z,1549583156.522 [controlThread ThreadHandler](INFO): Cycle time is:0.003667
2019-02-07T23:45:56.567Z,1549583156.567 [logger ThreadHandler](INFO): Cycle time is:0.005280
2019-02-07T23:45:56.671Z,1549583156.671 [logger ThreadHandler](INFO): Cycle time is:0.003677
2019-02-07T23:45:56.772Z,1549583156.772 [logger ThreadHandler](INFO): Cycle time is:0.000948
2019-02-07T23:45:56.873Z,1549583156.873 [logger ThreadHandler](INFO): Cycle time is:0.000611
2019-02-07T23:45:56.926Z,1549583156.926 [controlThread ThreadHandler](INFO): Cycle time is:0.003736
2019-02-07T23:45:56.975Z,1549583156.975 [logger ThreadHandler](INFO): Cycle time is:0.001658
2019-02-07T23:45:57.083Z,1549583157.083 [logger ThreadHandler](INFO): Cycle time is:0.007857
2019-02-07T23:45:57.190Z,1549583157.190 [logger ThreadHandler](INFO): Cycle time is:0.006110
2019-02-07T23:45:57.298Z,1549583157.298 [logger ThreadHandler](INFO): Cycle time is:0.007079
2019-02-07T23:45:57.330Z,1549583157.330 [controlThread ThreadHandler](INFO): Cycle time is:0.003853
2019-02-07T23:45:57.405Z,1549583157.405 [logger ThreadHandler](INFO): Cycle time is:0.006551
2019-02-07T23:45:57.517Z,1549583157.517 [logger ThreadHandler](INFO): Cycle time is:0.012125
2019-02-07T23:45:57.619Z,1549583157.619 [logger ThreadHandler](INFO): Cycle time is:0.001276
2019-02-07T23:45:57.723Z,1549583157.723 [logger ThreadHandler](INFO): Cycle time is:0.003686
2019-02-07T23:45:57.734Z,1549583157.734 [controlThread ThreadHandler](INFO): Cycle time is:0.003681
2019-02-07T23:45:57.827Z,1549583157.827 [logger ThreadHandler](INFO): Cycle time is:0.003658
2019-02-07T23:45:57.929Z,1549583157.929 [logger ThreadHandler](INFO): Cycle time is:0.001982
2019-02-07T23:45:58.036Z,1549583158.036 [logger ThreadHandler](INFO): Cycle time is:0.006848
2019-02-07T23:45:58.137Z,1549583158.137 [logger ThreadHandler](INFO): Cycle time is:0.000969
2019-02-07T23:45:58.138Z,1549583158.138 [controlThread ThreadHandler](INFO): Cycle time is:0.003538
2019-02-07T23:45:58.265Z,1549583158.265 [logger ThreadHandler](INFO): Cycle time is:0.026710
2019-02-07T23:45:58.372Z,1549583158.372 [logger ThreadHandler](INFO): Cycle time is:0.006331
2019-02-07T23:45:58.475Z,1549583158.475 [logger ThreadHandler](INFO): Cycle time is:0.002720
2019-02-07T23:45:58.542Z,1549583158.542 [controlThread ThreadHandler](INFO): Cycle time is:0.003572
2019-02-07T23:45:58.583Z,1549583158.583 [logger ThreadHandler](INFO): Cycle time is:0.008111
2019-02-07T23:45:58.691Z,1549583158.691 [logger ThreadHandler](INFO): Cycle time is:0.007286
2019-02-07T23:45:58.793Z,1549583158.793 [logger ThreadHandler](INFO): Cycle time is:0.001626
2019-02-07T23:45:58.899Z,1549583158.899 [logger ThreadHandler](INFO): Cycle time is:0.005510
2019-02-07T23:45:58.946Z,1549583158.946 [controlThread ThreadHandler](INFO): Cycle time is:0.003658
2019-02-07T23:45:59.006Z,1549583159.006 [logger ThreadHandler](INFO): Cycle time is:0.006166
2019-02-07T23:45:59.127Z,1549583159.127 [logger ThreadHandler](INFO): Cycle time is:0.020525
2019-02-07T23:45:59.214Z,1549583159.214 [NavChartDb ThreadHandler](INFO): Cycle time is:3.548293
2019-02-07T23:45:59.230Z,1549583159.230 [logger ThreadHandler](INFO): Cycle time is:0.002908
2019-02-07T23:45:59.351Z,1549583159.351 [controlThread ThreadHandler](INFO): Cycle time is:0.004013
2019-02-07T23:45:59.415Z,1549583159.415 [logger ThreadHandler](INFO): Cycle time is:0.084088
2019-02-07T23:45:59.523Z,1549583159.523 [logger ThreadHandler](INFO): Cycle time is:0.008246
2019-02-07T23:45:59.629Z,1549583159.629 [logger ThreadHandler](INFO): Cycle time is:0.005295
2019-02-07T23:45:59.667Z,1549583159.667 [NavChartDb ThreadHandler](INFO): Cycle time is:0.352140
2019-02-07T23:45:59.735Z,1549583159.735 [logger ThreadHandler](INFO): Cycle time is:0.006047
2019-02-07T23:45:59.754Z,1549583159.754 [controlThread ThreadHandler](INFO): Cycle time is:0.003286
2019-02-07T23:45:59.839Z,1549583159.839 [logger ThreadHandler](INFO): Cycle time is:0.003161
2019-02-07T23:45:59.945Z,1549583159.945 [logger ThreadHandler](INFO): Cycle time is:0.006026
2019-02-07T23:46:00.063Z,1549583160.063 [logger ThreadHandler](INFO): Cycle time is:0.017641
2019-02-07T23:46:00.158Z,1549583160.158 [controlThread ThreadHandler](INFO): Cycle time is:0.003499
2019-02-07T23:46:00.213Z,1549583160.213 [logger ThreadHandler](INFO): Cycle time is:0.048814
2019-02-07T23:46:00.359Z,1549583160.359 [logger ThreadHandler](INFO): Cycle time is:0.046154
2019-02-07T23:46:00.444Z,1549583160.444 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009676
CHAN A1 (24V): -0.014900
CHAN A2 (12V): -0.005293
CHAN A3 (5V): -0.002722
CHAN B0 (3.3V): -0.001563
CHAN B1 (3.15aV): -0.001844
CHAN B2 (3.15bV): -0.000952
CHAN B3 (GND): 0.000247
OPEN: 0.004840
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-07T23:46:00.461Z,1549583160.461 [logger ThreadHandler](INFO): Cycle time is:0.002006
2019-02-07T23:46:00.562Z,1549583160.562 [controlThread ThreadHandler](INFO): Cycle time is:0.003813
2019-02-07T23:46:00.579Z,1549583160.579 [logger ThreadHandler](INFO): Cycle time is:0.017547
2019-02-07T23:46:00.683Z,1549583160.683 [logger ThreadHandler](INFO): Cycle time is:0.003969
2019-02-07T23:46:00.785Z,1549583160.785 [logger ThreadHandler](INFO): Cycle time is:0.000913
2019-02-07T23:46:00.886Z,1549583160.886 [logger ThreadHandler](INFO): Cycle time is:0.001295
2019-02-07T23:46:00.966Z,1549583160.966 [controlThread ThreadHandler](INFO): Cycle time is:0.003475
2019-02-07T23:46:01.031Z,1549583161.031 [logger ThreadHandler](INFO): Cycle time is:0.044117
2019-02-07T23:46:01.137Z,1549583161.137 [logger ThreadHandler](INFO): Cycle time is:0.005674
2019-02-07T23:46:01.281Z,1549583161.281 [logger ThreadHandler](INFO): Cycle time is:0.043096
2019-02-07T23:46:01.370Z,1549583161.370 [controlThread ThreadHandler](INFO): Cycle time is:0.003767
2019-02-07T23:46:01.406Z,1549583161.406 [logger ThreadHandler](INFO): Cycle time is:0.025546
2019-02-07T23:46:01.507Z,1549583161.507 [logger ThreadHandler](INFO): Cycle time is:0.000643
2019-02-07T23:46:01.611Z,1549583161.611 [logger ThreadHandler](INFO): Cycle time is:0.002977
2019-02-07T23:46:01.712Z,1549583161.712 [logger ThreadHandler](INFO): Cycle time is:0.000857
2019-02-07T23:46:01.774Z,1549583161.774 [controlThread ThreadHandler](INFO): Cycle time is:0.003637
2019-02-07T23:46:01.819Z,1549583161.819 [logger ThreadHandler](INFO): Cycle time is:0.006956
2019-02-07T23:46:01.935Z,1549583161.935 [logger ThreadHandler](INFO): Cycle time is:0.015563
2019-02-07T23:46:02.046Z,1549583162.046 [logger ThreadHandler](INFO): Cycle time is:0.011011
2019-02-07T23:46:02.149Z,1549583162.149 [logger ThreadHandler](INFO): Cycle time is:0.002858
2019-02-07T23:46:02.179Z,1549583162.179 [controlThread ThreadHandler](INFO): Cycle time is:0.003836
2019-02-07T23:46:02.268Z,1549583162.268 [logger ThreadHandler](INFO): Cycle time is:0.017536
2019-02-07T23:46:02.387Z,1549583162.387 [logger ThreadHandler](INFO): Cycle time is:0.018597
2019-02-07T23:46:02.491Z,1549583162.491 [logger ThreadHandler](INFO): Cycle time is:0.004072
2019-02-07T23:46:02.582Z,1549583162.582 [controlThread ThreadHandler](INFO): Cycle time is:0.003283
2019-02-07T23:46:02.619Z,1549583162.619 [logger ThreadHandler](INFO): Cycle time is:0.027464
2019-02-07T23:46:02.623Z,1549583162.623 [NAL9602](INFO): NAL9602 initialized
2019-02-07T23:46:02.720Z,1549583162.720 [logger ThreadHandler](INFO): Cycle time is:0.001072
2019-02-07T23:46:02.827Z,1549583162.827 [logger ThreadHandler](INFO): Cycle time is:0.006532
2019-02-07T23:46:02.928Z,1549583162.928 [logger ThreadHandler](INFO): Cycle time is:0.000651
2019-02-07T23:46:02.986Z,1549583162.986 [controlThread ThreadHandler](INFO): Cycle time is:0.003813
2019-02-07T23:46:03.037Z,1549583163.037 [logger ThreadHandler](INFO): Cycle time is:0.008782
2019-02-07T23:46:03.143Z,1549583163.143 [logger ThreadHandler](INFO): Cycle time is:0.005481
2019-02-07T23:46:03.253Z,1549583163.253 [logger ThreadHandler](INFO): Cycle time is:0.009600
2019-02-07T23:46:03.360Z,1549583163.360 [logger ThreadHandler](INFO): Cycle time is:0.007048
2019-02-07T23:46:03.390Z,1549583163.390 [controlThread ThreadHandler](INFO): Cycle time is:0.003589
2019-02-07T23:46:03.414Z,1549583163.414 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:46:03.470Z,1549583163.470 [logger ThreadHandler](INFO): Cycle time is:0.009664
2019-02-07T23:46:03.575Z,1549583163.575 [logger ThreadHandler](INFO): Cycle time is:0.003913
2019-02-07T23:46:03.679Z,1549583163.679 [logger ThreadHandler](INFO): Cycle time is:0.004052
2019-02-07T23:46:03.781Z,1549583163.781 [logger ThreadHandler](INFO): Cycle time is:0.001504
2019-02-07T23:46:03.794Z,1549583163.794 [controlThread ThreadHandler](INFO): Cycle time is:0.003538
2019-02-07T23:46:03.882Z,1549583163.882 [logger ThreadHandler](INFO): Cycle time is:0.001196
2019-02-07T23:46:03.984Z,1549583163.984 [logger ThreadHandler](INFO): Cycle time is:0.001232
2019-02-07T23:46:04.087Z,1549583164.087 [logger ThreadHandler](INFO): Cycle time is:0.002542
2019-02-07T23:46:04.189Z,1549583164.189 [logger ThreadHandler](INFO): Cycle time is:0.001249
2019-02-07T23:46:04.292Z,1549583164.292 [logger ThreadHandler](INFO): Cycle time is:0.003258
2019-02-07T23:46:04.395Z,1549583164.395 [logger ThreadHandler](INFO): Cycle time is:0.002663
2019-02-07T23:46:04.453Z,1549583164.453 [controlThread ThreadHandler](INFO): Cycle time is:0.258095
2019-02-07T23:46:04.497Z,1549583164.497 [logger ThreadHandler](INFO): Cycle time is:0.000949
2019-02-07T23:46:04.612Z,1549583164.612 [NavChartDb ThreadHandler](INFO): Cycle time is:4.845375
2019-02-07T23:46:04.626Z,1549583164.626 [logger ThreadHandler](INFO): Cycle time is:0.029355
2019-02-07T23:46:04.732Z,1549583164.732 [logger ThreadHandler](INFO): Cycle time is:0.005438
2019-02-07T23:46:04.835Z,1549583164.835 [logger ThreadHandler](INFO): Cycle time is:0.002666
2019-02-07T23:46:04.854Z,1549583164.854 [controlThread ThreadHandler](INFO): Cycle time is:0.001253
2019-02-07T23:46:04.942Z,1549583164.942 [logger ThreadHandler](INFO): Cycle time is:0.007044
2019-02-07T23:46:05.047Z,1549583165.047 [logger ThreadHandler](INFO): Cycle time is:0.004113
2019-02-07T23:46:05.155Z,1549583165.155 [logger ThreadHandler](INFO): Cycle time is:0.007209
2019-02-07T23:46:05.259Z,1549583165.259 [controlThread ThreadHandler](INFO): Cycle time is:0.003961
2019-02-07T23:46:05.316Z,1549583165.316 [logger ThreadHandler](INFO): Cycle time is:0.060542
2019-02-07T23:46:05.419Z,1549583165.419 [logger ThreadHandler](INFO): Cycle time is:0.003385
2019-02-07T23:46:05.530Z,1549583165.530 [logger ThreadHandler](INFO): Cycle time is:0.010572
2019-02-07T23:46:05.632Z,1549583165.632 [logger ThreadHandler](INFO): Cycle time is:0.001483
2019-02-07T23:46:05.662Z,1549583165.662 [controlThread ThreadHandler](INFO): Cycle time is:0.003238
2019-02-07T23:46:05.739Z,1549583165.739 [logger ThreadHandler](INFO): Cycle time is:0.006077
2019-02-07T23:46:05.854Z,1549583165.854 [logger ThreadHandler](INFO): Cycle time is:0.015233
2019-02-07T23:46:05.956Z,1549583165.956 [logger ThreadHandler](INFO): Cycle time is:0.001125
2019-02-07T23:46:06.057Z,1549583166.057 [logger ThreadHandler](INFO): Cycle time is:0.000787
2019-02-07T23:46:06.066Z,1549583166.066 [controlThread ThreadHandler](INFO): Cycle time is:0.003739
2019-02-07T23:46:06.159Z,1549583166.159 [logger ThreadHandler](INFO): Cycle time is:0.001848
2019-02-07T23:46:06.283Z,1549583166.283 [logger ThreadHandler](INFO): Cycle time is:0.023485
2019-02-07T23:46:06.394Z,1549583166.394 [logger ThreadHandler](INFO): Cycle time is:0.010474
2019-02-07T23:46:06.470Z,1549583166.470 [controlThread ThreadHandler](INFO): Cycle time is:0.003647
2019-02-07T23:46:06.538Z,1549583166.538 [logger ThreadHandler](INFO): Cycle time is:0.043857
2019-02-07T23:46:06.639Z,1549583166.639 [logger ThreadHandler](INFO): Cycle time is:0.001174
2019-02-07T23:46:06.743Z,1549583166.743 [logger ThreadHandler](INFO): Cycle time is:0.003263
2019-02-07T23:46:06.847Z,1549583166.847 [logger ThreadHandler](INFO): Cycle time is:0.003600
2019-02-07T23:46:06.874Z,1549583166.874 [controlThread ThreadHandler](INFO): Cycle time is:0.003646
2019-02-07T23:46:06.959Z,1549583166.959 [logger ThreadHandler](INFO): Cycle time is:0.011671
2019-02-07T23:46:07.061Z,1549583167.061 [logger ThreadHandler](INFO): Cycle time is:0.001688
2019-02-07T23:46:07.166Z,1549583167.166 [logger ThreadHandler](INFO): Cycle time is:0.005152
2019-02-07T23:46:07.274Z,1549583167.274 [logger ThreadHandler](INFO): Cycle time is:0.006843
2019-02-07T23:46:07.279Z,1549583167.279 [controlThread ThreadHandler](INFO): Cycle time is:0.003765
2019-02-07T23:46:07.383Z,1549583167.383 [logger ThreadHandler](INFO): Cycle time is:0.009084
2019-02-07T23:46:07.499Z,1549583167.499 [logger ThreadHandler](INFO): Cycle time is:0.015422
2019-02-07T23:46:07.601Z,1549583167.601 [logger ThreadHandler](INFO): Cycle time is:0.001584
2019-02-07T23:46:07.673Z,1549583167.673 [NavChartDb ThreadHandler](INFO): Cycle time is:2.959946
2019-02-07T23:46:07.682Z,1549583167.682 [controlThread ThreadHandler](INFO): Cycle time is:0.002960
2019-02-07T23:46:07.702Z,1549583167.702 [logger ThreadHandler](INFO): Cycle time is:0.000875
2019-02-07T23:46:07.803Z,1549583167.803 [logger ThreadHandler](INFO): Cycle time is:0.001053
2019-02-07T23:46:07.905Z,1549583167.905 [logger ThreadHandler](INFO): Cycle time is:0.001182
2019-02-07T23:46:08.006Z,1549583168.006 [logger ThreadHandler](INFO): Cycle time is:0.000805
2019-02-07T23:46:08.086Z,1549583168.086 [controlThread ThreadHandler](INFO): Cycle time is:0.003456
2019-02-07T23:46:08.115Z,1549583168.115 [logger ThreadHandler](INFO): Cycle time is:0.008148
2019-02-07T23:46:08.254Z,1549583168.254 [logger ThreadHandler](INFO): Cycle time is:0.039358
2019-02-07T23:46:08.363Z,1549583168.363 [logger ThreadHandler](INFO): Cycle time is:0.007921
2019-02-07T23:46:08.467Z,1549583168.467 [logger ThreadHandler](INFO): Cycle time is:0.003927
2019-02-07T23:46:08.490Z,1549583168.490 [controlThread ThreadHandler](INFO): Cycle time is:0.003809
2019-02-07T23:46:08.568Z,1549583168.568 [logger ThreadHandler](INFO): Cycle time is:0.000588
2019-02-07T23:46:08.670Z,1549583168.670 [logger ThreadHandler](INFO): Cycle time is:0.001470
2019-02-07T23:46:08.779Z,1549583168.779 [logger ThreadHandler](INFO): Cycle time is:0.009059
2019-02-07T23:46:08.882Z,1549583168.882 [logger ThreadHandler](INFO): Cycle time is:0.002308
2019-02-07T23:46:08.894Z,1549583168.894 [controlThread ThreadHandler](INFO): Cycle time is:0.003668
2019-02-07T23:46:08.984Z,1549583168.984 [logger ThreadHandler](INFO): Cycle time is:0.002528
2019-02-07T23:46:09.103Z,1549583169.103 [logger ThreadHandler](INFO): Cycle time is:0.018608
2019-02-07T23:46:09.206Z,1549583169.206 [logger ThreadHandler](INFO): Cycle time is:0.002055
2019-02-07T23:46:09.299Z,1549583169.299 [controlThread ThreadHandler](INFO): Cycle time is:0.003706
2019-02-07T23:46:09.352Z,1549583169.352 [logger ThreadHandler](INFO): Cycle time is:0.045236
2019-02-07T23:46:09.470Z,1549583169.470 [logger ThreadHandler](INFO): Cycle time is:0.017298
2019-02-07T23:46:09.574Z,1549583169.574 [logger ThreadHandler](INFO): Cycle time is:0.003869
2019-02-07T23:46:09.679Z,1549583169.679 [logger ThreadHandler](INFO): Cycle time is:0.004178
2019-02-07T23:46:09.702Z,1549583169.702 [controlThread ThreadHandler](INFO): Cycle time is:0.003104
2019-02-07T23:46:09.793Z,1549583169.793 [logger ThreadHandler](INFO): Cycle time is:0.014275
2019-02-07T23:46:09.900Z,1549583169.900 [logger ThreadHandler](INFO): Cycle time is:0.005883
2019-02-07T23:46:10.010Z,1549583170.010 [logger ThreadHandler](INFO): Cycle time is:0.010245
2019-02-07T23:46:10.106Z,1549583170.106 [controlThread ThreadHandler](INFO): Cycle time is:0.003699
2019-02-07T23:46:10.166Z,1549583170.166 [logger ThreadHandler](INFO): Cycle time is:0.054992
2019-02-07T23:46:10.295Z,1549583170.295 [logger ThreadHandler](INFO): Cycle time is:0.029399
2019-02-07T23:46:10.397Z,1549583170.397 [logger ThreadHandler](INFO): Cycle time is:0.001843
2019-02-07T23:46:10.501Z,1549583170.501 [NavChartDb ThreadHandler](INFO): Cycle time is:2.727927
2019-02-07T23:46:10.503Z,1549583170.503 [logger ThreadHandler](INFO): Cycle time is:0.005232
2019-02-07T23:46:10.510Z,1549583170.510 [controlThread ThreadHandler](INFO): Cycle time is:0.003744
2019-02-07T23:46:10.617Z,1549583170.617 [logger ThreadHandler](INFO): Cycle time is:0.012858
2019-02-07T23:46:10.732Z,1549583170.732 [logger ThreadHandler](INFO): Cycle time is:0.014115
2019-02-07T23:46:10.839Z,1549583170.839 [logger ThreadHandler](INFO): Cycle time is:0.007194
2019-02-07T23:46:10.914Z,1549583170.914 [controlThread ThreadHandler](INFO): Cycle time is:0.003469
2019-02-07T23:46:10.947Z,1549583170.947 [logger ThreadHandler](INFO): Cycle time is:0.007467
2019-02-07T23:46:11.055Z,1549583171.055 [logger ThreadHandler](INFO): Cycle time is:0.008086
2019-02-07T23:46:11.157Z,1549583171.157 [logger ThreadHandler](INFO): Cycle time is:0.000933
2019-02-07T23:46:11.265Z,1549583171.265 [logger ThreadHandler](INFO): Cycle time is:0.008143
2019-02-07T23:46:11.319Z,1549583171.319 [controlThread ThreadHandler](INFO): Cycle time is:0.004587
2019-02-07T23:46:11.386Z,1549583171.386 [logger ThreadHandler](INFO): Cycle time is:0.020816
2019-02-07T23:46:11.488Z,1549583171.488 [logger ThreadHandler](INFO): Cycle time is:0.000700
2019-02-07T23:46:11.589Z,1549583171.589 [logger ThreadHandler](INFO): Cycle time is:0.001293
2019-02-07T23:46:11.694Z,1549583171.694 [logger ThreadHandler](INFO): Cycle time is:0.004865
2019-02-07T23:46:11.722Z,1549583171.722 [controlThread ThreadHandler](INFO): Cycle time is:0.002379
2019-02-07T23:46:11.799Z,1549583171.799 [logger ThreadHandler](INFO): Cycle time is:0.004001
2019-02-07T23:46:11.903Z,1549583171.903 [logger ThreadHandler](INFO): Cycle time is:0.004526
2019-02-07T23:46:12.009Z,1549583172.009 [logger ThreadHandler](INFO): Cycle time is:0.004966
2019-02-07T23:46:12.110Z,1549583172.110 [logger ThreadHandler](INFO): Cycle time is:0.000821
2019-02-07T23:46:12.126Z,1549583172.126 [controlThread ThreadHandler](INFO): Cycle time is:0.003621
2019-02-07T23:46:12.219Z,1549583172.219 [logger ThreadHandler](INFO): Cycle time is:0.008811
2019-02-07T23:46:12.299Z,1549583172.299 [NavChartDb ThreadHandler](INFO): Cycle time is:1.697895
2019-02-07T23:46:12.322Z,1549583172.322 [logger ThreadHandler](INFO): Cycle time is:0.001426
2019-02-07T23:46:12.435Z,1549583172.435 [logger ThreadHandler](INFO): Cycle time is:0.013173
2019-02-07T23:46:12.530Z,1549583172.530 [controlThread ThreadHandler](INFO): Cycle time is:0.003710
2019-02-07T23:46:12.573Z,1549583172.573 [logger ThreadHandler](INFO): Cycle time is:0.036750
2019-02-07T23:46:12.676Z,1549583172.676 [logger ThreadHandler](INFO): Cycle time is:0.002422
2019-02-07T23:46:12.779Z,1549583172.779 [logger ThreadHandler](INFO): Cycle time is:0.002694
2019-02-07T23:46:12.883Z,1549583172.883 [logger ThreadHandler](INFO): Cycle time is:0.003627
2019-02-07T23:46:12.934Z,1549583172.934 [controlThread ThreadHandler](INFO): Cycle time is:0.003681
2019-02-07T23:46:12.984Z,1549583172.984 [logger ThreadHandler](INFO): Cycle time is:0.000756
2019-02-07T23:46:13.087Z,1549583173.087 [logger ThreadHandler](INFO): Cycle time is:0.002884
2019-02-07T23:46:13.195Z,1549583173.195 [logger ThreadHandler](INFO): Cycle time is:0.007708
2019-02-07T23:46:13.298Z,1549583173.298 [logger ThreadHandler](INFO): Cycle time is:0.001950
2019-02-07T23:46:13.339Z,1549583173.339 [controlThread ThreadHandler](INFO): Cycle time is:0.003654
2019-02-07T23:46:13.403Z,1549583173.403 [logger ThreadHandler](INFO): Cycle time is:0.004797
2019-02-07T23:46:13.512Z,1549583173.512 [logger ThreadHandler](INFO): Cycle time is:0.008703
2019-02-07T23:46:13.615Z,1549583173.615 [logger ThreadHandler](INFO): Cycle time is:0.002838
2019-02-07T23:46:13.718Z,1549583173.718 [logger ThreadHandler](INFO): Cycle time is:0.001859
2019-02-07T23:46:13.742Z,1549583173.742 [controlThread ThreadHandler](INFO): Cycle time is:0.003258
2019-02-07T23:46:13.822Z,1549583173.822 [logger ThreadHandler](INFO): Cycle time is:0.004443
2019-02-07T23:46:13.934Z,1549583173.934 [logger ThreadHandler](INFO): Cycle time is:0.011558
2019-02-07T23:46:14.036Z,1549583174.036 [logger ThreadHandler](INFO): Cycle time is:0.000970
2019-02-07T23:46:14.136Z,1549583174.136 [logger ThreadHandler](INFO): Cycle time is:0.000635
2019-02-07T23:46:14.146Z,1549583174.146 [controlThread ThreadHandler](INFO): Cycle time is:0.003685
2019-02-07T23:46:14.249Z,1549583174.249 [logger ThreadHandler](INFO): Cycle time is:0.012155
2019-02-07T23:46:14.362Z,1549583174.362 [logger ThreadHandler](INFO): Cycle time is:0.012067
2019-02-07T23:46:14.463Z,1549583174.463 [logger ThreadHandler](INFO): Cycle time is:0.000683
2019-02-07T23:46:14.550Z,1549583174.550 [controlThread ThreadHandler](INFO): Cycle time is:0.003579
2019-02-07T23:46:14.590Z,1549583174.590 [logger ThreadHandler](INFO): Cycle time is:0.027056
2019-02-07T23:46:14.692Z,1549583174.692 [logger ThreadHandler](INFO): Cycle time is:0.001231
2019-02-07T23:46:14.797Z,1549583174.797 [logger ThreadHandler](INFO): Cycle time is:0.004364
2019-02-07T23:46:14.898Z,1549583174.898 [logger ThreadHandler](INFO): Cycle time is:0.000750
2019-02-07T23:46:14.954Z,1549583174.954 [controlThread ThreadHandler](INFO): Cycle time is:0.003746
2019-02-07T23:46:15.003Z,1549583175.003 [logger ThreadHandler](INFO): Cycle time is:0.004599
2019-02-07T23:46:15.131Z,1549583175.131 [logger ThreadHandler](INFO): Cycle time is:0.027775
2019-02-07T23:46:15.238Z,1549583175.238 [logger ThreadHandler](INFO): Cycle time is:0.006442
2019-02-07T23:46:15.358Z,1549583175.358 [controlThread ThreadHandler](INFO): Cycle time is:0.003717
2019-02-07T23:46:15.399Z,1549583175.399 [logger ThreadHandler](INFO): Cycle time is:0.061279
2019-02-07T23:46:15.504Z,1549583175.504 [logger ThreadHandler](INFO): Cycle time is:0.004895
2019-02-07T23:46:15.597Z,1549583175.597 [NavChartDb ThreadHandler](INFO): Cycle time is:3.197917
2019-02-07T23:46:15.615Z,1549583175.615 [logger ThreadHandler](INFO): Cycle time is:0.010495
2019-02-07T23:46:15.719Z,1549583175.719 [logger ThreadHandler](INFO): Cycle time is:0.003621
2019-02-07T23:46:15.762Z,1549583175.762 [controlThread ThreadHandler](INFO): Cycle time is:0.003696
2019-02-07T23:46:15.835Z,1549583175.835 [logger ThreadHandler](INFO): Cycle time is:0.015230
2019-02-07T23:46:15.943Z,1549583175.943 [logger ThreadHandler](INFO): Cycle time is:0.008471
2019-02-07T23:46:16.047Z,1549583176.047 [logger ThreadHandler](INFO): Cycle time is:0.002928
2019-02-07T23:46:16.148Z,1549583176.148 [logger ThreadHandler](INFO): Cycle time is:0.000931
2019-02-07T23:46:16.166Z,1549583176.166 [controlThread ThreadHandler](INFO): Cycle time is:0.003549
2019-02-07T23:46:16.251Z,1549583176.251 [logger ThreadHandler](INFO): Cycle time is:0.002796
2019-02-07T23:46:16.356Z,1549583176.356 [logger ThreadHandler](INFO): Cycle time is:0.003736
2019-02-07T23:46:16.459Z,1549583176.459 [logger ThreadHandler](INFO): Cycle time is:0.003142
2019-02-07T23:46:16.570Z,1549583176.570 [controlThread ThreadHandler](INFO): Cycle time is:0.003494
2019-02-07T23:46:16.584Z,1549583176.584 [logger ThreadHandler](INFO): Cycle time is:0.024339
2019-02-07T23:46:16.685Z,1549583176.685 [logger ThreadHandler](INFO): Cycle time is:0.000829
2019-02-07T23:46:16.787Z,1549583176.787 [logger ThreadHandler](INFO): Cycle time is:0.001396
2019-02-07T23:46:16.899Z,1549583176.899 [logger ThreadHandler](INFO): Cycle time is:0.011399
2019-02-07T23:46:16.974Z,1549583176.974 [controlThread ThreadHandler](INFO): Cycle time is:0.003766
2019-02-07T23:46:17.008Z,1549583177.008 [logger ThreadHandler](INFO): Cycle time is:0.008509
2019-02-07T23:46:17.122Z,1549583177.122 [logger ThreadHandler](INFO): Cycle time is:0.013553
2019-02-07T23:46:17.250Z,1549583177.250 [logger ThreadHandler](INFO): Cycle time is:0.026591
2019-02-07T23:46:17.354Z,1549583177.354 [logger ThreadHandler](INFO): Cycle time is:0.003740
2019-02-07T23:46:17.378Z,1549583177.378 [controlThread ThreadHandler](INFO): Cycle time is:0.003708
2019-02-07T23:46:17.463Z,1549583177.463 [logger ThreadHandler](INFO): Cycle time is:0.008244
2019-02-07T23:46:17.564Z,1549583177.564 [logger ThreadHandler](INFO): Cycle time is:0.000633
2019-02-07T23:46:17.671Z,1549583177.671 [logger ThreadHandler](INFO): Cycle time is:0.006773
2019-02-07T23:46:17.777Z,1549583177.777 [logger ThreadHandler](INFO): Cycle time is:0.005941
2019-02-07T23:46:17.782Z,1549583177.782 [controlThread ThreadHandler](INFO): Cycle time is:0.003634
2019-02-07T23:46:17.886Z,1549583177.886 [logger ThreadHandler](INFO): Cycle time is:0.008128
2019-02-07T23:46:17.991Z,1549583177.991 [logger ThreadHandler](INFO): Cycle time is:0.004300
2019-02-07T23:46:18.095Z,1549583178.095 [logger ThreadHandler](INFO): Cycle time is:0.003375
2019-02-07T23:46:18.186Z,1549583178.186 [controlThread ThreadHandler](INFO): Cycle time is:0.003630
2019-02-07T23:46:18.264Z,1549583178.264 [logger ThreadHandler](INFO): Cycle time is:0.069337
2019-02-07T23:46:18.370Z,1549583178.370 [logger ThreadHandler](INFO): Cycle time is:0.004667
2019-02-07T23:46:18.487Z,1549583178.487 [logger ThreadHandler](INFO): Cycle time is:0.016405
2019-02-07T23:46:18.590Z,1549583178.590 [controlThread ThreadHandler](INFO): Cycle time is:0.003723
2019-02-07T23:46:18.626Z,1549583178.626 [logger ThreadHandler](INFO): Cycle time is:0.038600
2019-02-07T23:46:18.739Z,1549583178.739 [logger ThreadHandler](INFO): Cycle time is:0.012858
2019-02-07T23:46:18.844Z,1549583178.844 [logger ThreadHandler](INFO): Cycle time is:0.005054
2019-02-07T23:46:18.951Z,1549583178.951 [logger ThreadHandler](INFO): Cycle time is:0.007002
2019-02-07T23:46:18.994Z,1549583178.994 [controlThread ThreadHandler](INFO): Cycle time is:0.003659
2019-02-07T23:46:19.063Z,1549583179.063 [logger ThreadHandler](INFO): Cycle time is:0.011041
2019-02-07T23:46:19.174Z,1549583179.174 [logger ThreadHandler](INFO): Cycle time is:0.011114
2019-02-07T23:46:19.308Z,1549583179.308 [logger ThreadHandler](INFO): Cycle time is:0.033620
2019-02-07T23:46:19.398Z,1549583179.398 [controlThread ThreadHandler](INFO): Cycle time is:0.003680
2019-02-07T23:46:19.439Z,1549583179.439 [logger ThreadHandler](INFO): Cycle time is:0.030277
2019-02-07T23:46:19.544Z,1549583179.544 [logger ThreadHandler](INFO): Cycle time is:0.004452
2019-02-07T23:46:19.647Z,1549583179.647 [logger ThreadHandler](INFO): Cycle time is:0.002954
2019-02-07T23:46:19.754Z,1549583179.754 [logger ThreadHandler](INFO): Cycle time is:0.007114
2019-02-07T23:46:19.802Z,1549583179.802 [controlThread ThreadHandler](INFO): Cycle time is:0.003655
2019-02-07T23:46:19.855Z,1549583179.855 [logger ThreadHandler](INFO): Cycle time is:0.000602
2019-02-07T23:46:19.961Z,1549583179.961 [logger ThreadHandler](INFO): Cycle time is:0.005855
2019-02-07T23:46:20.067Z,1549583180.067 [logger ThreadHandler](INFO): Cycle time is:0.005188
2019-02-07T23:46:20.168Z,1549583180.168 [logger ThreadHandler](INFO): Cycle time is:0.000682
2019-02-07T23:46:20.207Z,1549583180.207 [controlThread ThreadHandler](INFO): Cycle time is:0.003801
2019-02-07T23:46:20.278Z,1549583180.278 [logger ThreadHandler](INFO): Cycle time is:0.009277
2019-02-07T23:46:20.408Z,1549583180.408 [NavChartDb ThreadHandler](INFO): Cycle time is:4.710208
2019-02-07T23:46:20.421Z,1549583180.421 [logger ThreadHandler](INFO): Cycle time is:0.042189
2019-02-07T23:46:20.532Z,1549583180.532 [logger ThreadHandler](INFO): Cycle time is:0.010726
2019-02-07T23:46:20.610Z,1549583180.610 [controlThread ThreadHandler](INFO): Cycle time is:0.003090
2019-02-07T23:46:20.645Z,1549583180.645 [logger ThreadHandler](INFO): Cycle time is:0.013428
2019-02-07T23:46:20.747Z,1549583180.747 [logger ThreadHandler](INFO): Cycle time is:0.001825
2019-02-07T23:46:20.851Z,1549583180.851 [logger ThreadHandler](INFO): Cycle time is:0.002999
2019-02-07T23:46:20.952Z,1549583180.952 [logger ThreadHandler](INFO): Cycle time is:0.000605
2019-02-07T23:46:21.014Z,1549583181.014 [controlThread ThreadHandler](INFO): Cycle time is:0.003859
2019-02-07T23:46:21.059Z,1549583181.059 [logger ThreadHandler](INFO): Cycle time is:0.007380
2019-02-07T23:46:21.064Z,1549583181.064 [NavChartDb ThreadHandler](INFO): Cycle time is:0.556398
2019-02-07T23:46:21.161Z,1549583181.161 [logger ThreadHandler](INFO): Cycle time is:0.001111
2019-02-07T23:46:21.282Z,1549583181.282 [logger ThreadHandler](INFO): Cycle time is:0.020391
2019-02-07T23:46:21.392Z,1549583181.392 [logger ThreadHandler](INFO): Cycle time is:0.010356
2019-02-07T23:46:21.418Z,1549583181.418 [controlThread ThreadHandler](INFO): Cycle time is:0.003422
2019-02-07T23:46:21.499Z,1549583181.499 [logger ThreadHandler](INFO): Cycle time is:0.006247
2019-02-07T23:46:21.600Z,1549583181.600 [logger ThreadHandler](INFO): Cycle time is:0.000722
2019-02-07T23:46:21.711Z,1549583181.711 [logger ThreadHandler](INFO): Cycle time is:0.011095
2019-02-07T23:46:21.821Z,1549583181.821 [logger ThreadHandler](INFO): Cycle time is:0.008892
2019-02-07T23:46:21.822Z,1549583181.822 [controlThread ThreadHandler](INFO): Cycle time is:0.003747
2019-02-07T23:46:21.940Z,1549583181.940 [logger ThreadHandler](INFO): Cycle time is:0.018692
2019-02-07T23:46:22.060Z,1549583182.060 [logger ThreadHandler](INFO): Cycle time is:0.019705
2019-02-07T23:46:22.168Z,1549583182.168 [logger ThreadHandler](INFO): Cycle time is:0.007619
2019-02-07T23:46:22.227Z,1549583182.227 [controlThread ThreadHandler](INFO): Cycle time is:0.004116
2019-02-07T23:46:22.297Z,1549583182.297 [logger ThreadHandler](INFO): Cycle time is:0.028917
2019-02-07T23:46:22.411Z,1549583182.411 [logger ThreadHandler](INFO): Cycle time is:0.013517
2019-02-07T23:46:22.515Z,1549583182.515 [logger ThreadHandler](INFO): Cycle time is:0.003329
2019-02-07T23:46:22.619Z,1549583182.619 [logger ThreadHandler](INFO): Cycle time is:0.004219
2019-02-07T23:46:22.630Z,1549583182.630 [controlThread ThreadHandler](INFO): Cycle time is:0.003038
2019-02-07T23:46:22.731Z,1549583182.731 [logger ThreadHandler](INFO): Cycle time is:0.011423
2019-02-07T23:46:22.832Z,1549583182.832 [logger ThreadHandler](INFO): Cycle time is:0.000948
2019-02-07T23:46:22.933Z,1549583182.933 [logger ThreadHandler](INFO): Cycle time is:0.000689
2019-02-07T23:46:23.034Z,1549583183.034 [controlThread ThreadHandler](INFO): Cycle time is:0.003744
2019-02-07T23:46:23.071Z,1549583183.071 [logger ThreadHandler](INFO): Cycle time is:0.037130
2019-02-07T23:46:23.172Z,1549583183.172 [logger ThreadHandler](INFO): Cycle time is:0.000610
2019-02-07T23:46:23.280Z,1549583183.280 [logger ThreadHandler](INFO): Cycle time is:0.007448
2019-02-07T23:46:23.383Z,1549583183.383 [logger ThreadHandler](INFO): Cycle time is:0.002162
2019-02-07T23:46:23.438Z,1549583183.438 [controlThread ThreadHandler](INFO): Cycle time is:0.003600
2019-02-07T23:46:23.486Z,1549583183.486 [logger ThreadHandler](INFO): Cycle time is:0.003477
2019-02-07T23:46:23.591Z,1549583183.591 [logger ThreadHandler](INFO): Cycle time is:0.004044
2019-02-07T23:46:23.675Z,1549583183.675 [CommandLine](IMPORTANT): got command quit
2019-02-07T23:46:23.694Z,1549583183.694 [logger ThreadHandler](INFO): Cycle time is:0.003117
2019-02-07T23:46:23.764Z,1549583183.764 [NavChartDb ThreadHandler](INFO): Cycle time is:2.599025
2019-02-07T23:46:23.798Z,1549583183.798 [logger ThreadHandler](INFO): Cycle time is:0.003534
2019-02-07T23:46:23.842Z,1549583183.842 [controlThread ThreadHandler](INFO): Cycle time is:0.003624
2019-02-07T23:46:23.903Z,1549583183.903 [logger ThreadHandler](INFO): Cycle time is:0.003854
2019-02-07T23:46:24.006Z,1549583184.006 [logger ThreadHandler](INFO): Cycle time is:0.002911
2019-02-07T23:46:24.110Z,1549583184.110 [logger ThreadHandler](INFO): Cycle time is:0.003651
2019-02-07T23:46:24.134Z,1549583184.134 [NavChartDb ThreadHandler](INFO): Cycle time is:0.270011
2019-02-07T23:46:24.215Z,1549583184.215 [logger ThreadHandler](INFO): Cycle time is:0.004112
2019-02-07T23:46:24.246Z,1549583184.246 [controlThread ThreadHandler](INFO): Cycle time is:0.003856
2019-02-07T23:46:24.319Z,1549583184.319 [logger ThreadHandler](INFO): Cycle time is:0.003107
2019-02-07T23:46:24.422Z,1549583184.422 [logger ThreadHandler](INFO): Cycle time is:0.003285
2019-02-07T23:46:24.526Z,1549583184.526 [logger ThreadHandler](INFO): Cycle time is:0.003590
2019-02-07T23:46:24.630Z,1549583184.630 [logger ThreadHandler](INFO): Cycle time is:0.003645
2019-02-07T23:46:24.650Z,1549583184.650 [controlThread ThreadHandler](INFO): Cycle time is:0.003450
2019-02-07T23:46:24.686Z,1549583184.686 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:24.687Z,1549583184.687 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:24.734Z,1549583184.734 [logger ThreadHandler](INFO): Cycle time is:0.003724
2019-02-07T23:46:24.782Z,1549583184.782 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-07T23:46:24.782Z,1549583184.782 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:24.783Z,1549583184.783 [CommandLine](INFO): Join timeout helper Thread ID is 1279
2019-02-07T23:46:24.794Z,1549583184.794 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-07T23:46:24.794Z,1549583184.794 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:24.795Z,1549583184.795 [NavChartDb](INFO): Join timeout helper Thread ID is 1280
2019-02-07T23:46:24.836Z,1549583184.836 [logger ThreadHandler](INFO): Cycle time is:0.001058
2019-02-07T23:46:24.938Z,1549583184.938 [logger ThreadHandler](INFO): Cycle time is:0.002176
2019-02-07T23:46:25.042Z,1549583185.042 [logger ThreadHandler](INFO): Cycle time is:0.003672
2019-02-07T23:46:25.054Z,1549583185.054 [controlThread ThreadHandler](INFO): Cycle time is:0.003794
2019-02-07T23:46:25.146Z,1549583185.146 [logger ThreadHandler](INFO): Cycle time is:0.003598
2019-02-07T23:46:25.250Z,1549583185.250 [logger ThreadHandler](INFO): Cycle time is:0.003977
2019-02-07T23:46:25.355Z,1549583185.355 [logger ThreadHandler](INFO): Cycle time is:0.003387
2019-02-07T23:46:25.458Z,1549583185.458 [logger ThreadHandler](INFO): Cycle time is:0.003102
2019-02-07T23:46:25.461Z,1549583185.461 [controlThread ThreadHandler](INFO): Cycle time is:0.006224
2019-02-07T23:46:25.563Z,1549583185.563 [logger ThreadHandler](INFO): Cycle time is:0.004569
2019-02-07T23:46:25.666Z,1549583185.666 [logger ThreadHandler](INFO): Cycle time is:0.003121
2019-02-07T23:46:25.755Z,1549583185.755 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:25.756Z,1549583185.756 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:25.770Z,1549583185.770 [logger ThreadHandler](INFO): Cycle time is:0.003272
2019-02-07T23:46:25.776Z,1549583185.776 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-02-07T23:46:25.776Z,1549583185.776 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:25.776Z,1549583185.776 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1281
2019-02-07T23:46:25.862Z,1549583185.862 [controlThread ThreadHandler](INFO): Cycle time is:0.000964
2019-02-07T23:46:25.874Z,1549583185.874 [logger ThreadHandler](INFO): Cycle time is:0.003684
2019-02-07T23:46:25.878Z,1549583185.878 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:25.878Z,1549583185.878 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-07T23:46:25.978Z,1549583185.978 [logger ThreadHandler](INFO): Cycle time is:0.003920
2019-02-07T23:46:26.066Z,1549583186.066 [Rowe_600LCM](INFO): Powering down
2019-02-07T23:46:26.067Z,1549583186.067 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.068Z,1549583186.068 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-07T23:46:26.068Z,1549583186.068 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.068Z,1549583186.068 [Radio_Surface](INFO): Join timeout helper Thread ID is 1283
2019-02-07T23:46:26.082Z,1549583186.082 [logger ThreadHandler](INFO): Cycle time is:0.003329
2019-02-07T23:46:26.186Z,1549583186.186 [logger ThreadHandler](INFO): Cycle time is:0.003663
2019-02-07T23:46:26.266Z,1549583186.266 [controlThread ThreadHandler](INFO): Cycle time is:0.003675
2019-02-07T23:46:26.291Z,1549583186.291 [logger ThreadHandler](INFO): Cycle time is:0.004690
2019-02-07T23:46:26.370Z,1549583186.370 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:26.371Z,1549583186.371 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.378Z,1549583186.378 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-02-07T23:46:26.378Z,1549583186.378 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.378Z,1549583186.378 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1284
2019-02-07T23:46:26.394Z,1549583186.394 [logger ThreadHandler](INFO): Cycle time is:0.002618
2019-02-07T23:46:26.498Z,1549583186.498 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:26.498Z,1549583186.498 [WetLabsBB2FL](INFO): Powering down
2019-02-07T23:46:26.499Z,1549583186.499 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.500Z,1549583186.500 [logger ThreadHandler](INFO): Cycle time is:0.005586
2019-02-07T23:46:26.511Z,1549583186.511 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-02-07T23:46:26.511Z,1549583186.511 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.512Z,1549583186.512 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1285
2019-02-07T23:46:26.602Z,1549583186.602 [logger ThreadHandler](INFO): Cycle time is:0.001753
2019-02-07T23:46:26.670Z,1549583186.670 [controlThread ThreadHandler](INFO): Cycle time is:0.003676
2019-02-07T23:46:26.707Z,1549583186.707 [logger ThreadHandler](INFO): Cycle time is:0.003778
2019-02-07T23:46:26.810Z,1549583186.810 [logger ThreadHandler](INFO): Cycle time is:0.002535
2019-02-07T23:46:26.814Z,1549583186.814 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:26.815Z,1549583186.815 [CTD_Seabird](INFO): Powering down
2019-02-07T23:46:26.826Z,1549583186.826 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.835Z,1549583186.835 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-07T23:46:26.835Z,1549583186.835 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.835Z,1549583186.835 [logger](INFO): Join timeout helper Thread ID is 1286
2019-02-07T23:46:26.849Z,1549583186.849 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:26.849Z,1549583186.849 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.863Z,1549583186.863 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-07T23:46:26.863Z,1549583186.863 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.863Z,1549583186.863 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-07T23:46:26.863Z,1549583186.863 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:26.864Z,1549583186.864 [controlThread](INFO): Join timeout helper Thread ID is 1287
2019-02-07T23:46:27.074Z,1549583187.074 [controlThread ThreadHandler](INFO): Cycle time is:0.003699
2019-02-07T23:46:27.075Z,1549583187.075 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:46:27.075Z,1549583187.075 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-07T23:46:27.075Z,1549583187.075 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:46:27.078Z,1549583187.078 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-02-07T23:46:27.199Z,1549583187.199 [NAL9602](INFO): Powering down
2019-02-07T23:46:27.200Z,1549583187.200 [PNI_TCM](INFO): Powering down
2019-02-07T23:46:27.287Z,1549583187.287 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-07T23:46:27.288Z,1549583187.288 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-07T23:46:27.289Z,1549583187.289 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-07T23:46:27.289Z,1549583187.289 [MissionManager](INFO): Uninitializing Mission Default
2019-02-07T23:46:27.292Z,1549583187.292 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-07T23:46:27.294Z,1549583187.294 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-07T23:46:27.294Z,1549583187.294 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-07T23:46:27.294Z,1549583187.294 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-07T23:46:27.294Z,1549583187.294 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-07T23:46:27.295Z,1549583187.295 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-07T23:46:27.295Z,1549583187.295 [BuoyancyServo](INFO): Powering down
2019-02-07T23:46:27.310Z,1549583187.310 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-07T23:46:27.310Z,1549583187.310 [ElevatorServo](INFO): Powering down
2019-02-07T23:46:27.311Z,1549583187.311 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-07T23:46:27.311Z,1549583187.311 [MassServo](INFO): Powering down
2019-02-07T23:46:27.312Z,1549583187.312 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-07T23:46:27.312Z,1549583187.312 [RudderServo](INFO): Powering down
2019-02-07T23:46:27.313Z,1549583187.313 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-07T23:46:27.313Z,1549583187.313 [ThrusterServo](INFO): Powering down
2019-02-07T23:46:27.314Z,1549583187.314 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-07T23:46:27.315Z,1549583187.315 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-07T23:46:27.315Z,1549583187.315 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-07T23:46:27.315Z,1549583187.315 [CBIT](DEBUG): Powering off loads.
2019-02-07T23:46:27.326Z,1549583187.326 [CBIT](DEBUG): Disabling WDT.
2019-02-07T23:46:27.338Z,1549583187.338 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-07T23:46:27.339Z,1549583187.339 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:27.383Z,1549583187.383 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:27.388Z,1549583187.388 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:27.469Z,1549583187.469 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:27.474Z,1549583187.474 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:27.524Z,1549583187.524 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:46:27.581Z,1549583187.581 [logger ThreadHandler](INFO): Thread cancelled.