2018-01-09T22:03:42.360Z,1515535422.360 [Supervisor](DEBUG): Initializing supervisor.
2018-01-09T22:03:42.363Z,1515535422.363 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2018-01-09T22:03:42.364Z,1515535422.364 [SyncHandler](INFO): Protected caller Thread ID is 770
2018-01-09T22:03:42.364Z,1515535422.364 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-01-09T22:03:42.365Z,1515535422.365 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2018-01-09T22:03:42.366Z,1515535422.366 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 771
2018-01-09T22:03:42.368Z,1515535422.368 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-01-09T22:03:42.380Z,1515535422.380 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-01-09T22:03:42.381Z,1515535422.381 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2018-01-09T22:03:42.382Z,1515535422.382 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 772
2018-01-09T22:03:42.382Z,1515535422.382 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-01-09T22:03:42.383Z,1515535422.383 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2018-01-09T22:03:42.384Z,1515535422.384 [logger ThreadHandler](INFO): Protected caller Thread ID is 773
2018-01-09T22:03:42.386Z,1515535422.386 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-01-09T22:03:42.386Z,1515535422.386 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-01-09T22:03:42.389Z,1515535422.389 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-01-09T22:03:42.570Z,1515535422.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-01-09T22:03:42.570Z,1515535422.570 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-01-09T22:03:42.713Z,1515535422.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-01-09T22:03:42.714Z,1515535422.714 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-01-09T22:03:43.030Z,1515535423.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-01-09T22:03:43.031Z,1515535423.031 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-01-09T22:03:43.169Z,1515535423.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-01-09T22:03:43.171Z,1515535423.171 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-01-09T22:03:43.264Z,1515535423.264 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-01-09T22:03:43.456Z,1515535423.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-01-09T22:03:43.458Z,1515535423.458 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-01-09T22:03:43.559Z,1515535423.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-01-09T22:03:43.560Z,1515535423.560 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-01-09T22:03:43.874Z,1515535423.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-01-09T22:03:43.875Z,1515535423.875 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-01-09T22:03:44.216Z,1515535424.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-01-09T22:03:44.216Z,1515535424.216 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-01-09T22:03:44.506Z,1515535424.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-01-09T22:03:44.507Z,1515535424.507 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-01-09T22:03:44.991Z,1515535424.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-01-09T22:03:44.992Z,1515535424.992 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-01-09T22:03:49.884Z,1515535429.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-01-09T22:03:49.884Z,1515535429.884 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-01-09T22:03:49.986Z,1515535429.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-01-09T22:03:49.987Z,1515535429.987 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-01-09T22:03:50.439Z,1515535430.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-01-09T22:03:52.629Z,1515535432.629 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-01-09T22:03:52.742Z,1515535432.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-01-09T22:03:52.744Z,1515535432.744 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2018-01-09T22:03:52.747Z,1515535432.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2018-01-09T22:03:52.974Z,1515535432.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-01-09T22:03:52.975Z,1515535432.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2018-01-09T22:03:53.076Z,1515535433.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2018-01-09T22:03:53.184Z,1515535433.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2018-01-09T22:03:53.301Z,1515535433.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2018-01-09T22:03:54.332Z,1515535434.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2018-01-09T22:03:54.481Z,1515535434.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2018-01-09T22:03:54.651Z,1515535434.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2018-01-09T22:03:54.762Z,1515535434.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2018-01-09T22:03:54.862Z,1515535434.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2018-01-09T22:03:54.951Z,1515535434.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2018-01-09T22:03:55.116Z,1515535435.116 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-01-09T22:03:55.119Z,1515535435.119 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-01-09T22:03:55.274Z,1515535435.274 [SBIT](DEBUG): Construct Startup Built In Test.
2018-01-09T22:03:55.305Z,1515535435.305 [SBIT] Loaded
2018-01-09T22:03:55.305Z,1515535435.305 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-01-09T22:03:55.306Z,1515535435.306 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-01-09T22:03:55.334Z,1515535435.334 [IBIT] Loaded
2018-01-09T22:03:55.335Z,1515535435.335 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-01-09T22:03:55.338Z,1515535435.338 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-01-09T22:03:55.498Z,1515535435.498 [CBIT] Loaded
2018-01-09T22:03:55.498Z,1515535435.498 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-01-09T22:03:55.498Z,1515535435.498 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-01-09T22:03:55.500Z,1515535435.500 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-01-09T22:03:55.667Z,1515535435.667 [VerticalControl](DEBUG): Construct VerticalControl.
2018-01-09T22:03:55.769Z,1515535435.769 [VerticalControl] Loaded
2018-01-09T22:03:55.769Z,1515535435.769 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-01-09T22:03:55.770Z,1515535435.770 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-01-09T22:03:55.833Z,1515535435.833 [HorizontalControl] Loaded
2018-01-09T22:03:55.833Z,1515535435.833 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-01-09T22:03:55.834Z,1515535435.834 [SpeedControl](DEBUG): Construct SpeedControl.
2018-01-09T22:03:55.839Z,1515535435.839 [SpeedControl] Loaded
2018-01-09T22:03:55.840Z,1515535435.840 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-01-09T22:03:55.840Z,1515535435.840 [LoopControl](DEBUG): Construct LoopControl.
2018-01-09T22:03:55.841Z,1515535435.841 [LoopControl] Loaded
2018-01-09T22:03:55.841Z,1515535435.841 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-01-09T22:03:55.842Z,1515535435.842 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-01-09T22:03:55.842Z,1515535435.842 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-01-09T22:03:55.899Z,1515535435.899 [DepthRateCalculator] Loaded
2018-01-09T22:03:55.899Z,1515535435.899 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-01-09T22:03:55.905Z,1515535435.905 [PitchRateCalculator] Loaded
2018-01-09T22:03:55.905Z,1515535435.905 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-01-09T22:03:55.921Z,1515535435.921 [SpeedCalculator] Loaded
2018-01-09T22:03:55.921Z,1515535435.921 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-01-09T22:03:55.944Z,1515535435.944 [TempGradientCalculator] Loaded
2018-01-09T22:03:55.944Z,1515535435.944 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-01-09T22:03:55.960Z,1515535435.960 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-01-09T22:03:55.960Z,1515535435.960 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-01-09T22:03:55.965Z,1515535435.965 [YawRateCalculator] Loaded
2018-01-09T22:03:55.966Z,1515535435.966 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-01-09T22:03:55.966Z,1515535435.966 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-01-09T22:03:55.967Z,1515535435.967 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-01-09T22:03:56.092Z,1515535436.092 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-01-09T22:03:56.092Z,1515535436.092 [StratificationFrontDetector](DEBUG): (re)initializing
2018-01-09T22:03:56.092Z,1515535436.092 [StratificationFrontDetector] Loaded
2018-01-09T22:03:56.093Z,1515535436.093 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-01-09T22:03:56.093Z,1515535436.093 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-01-09T22:03:56.094Z,1515535436.094 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-01-09T22:03:56.366Z,1515535436.366 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-01-09T22:03:56.368Z,1515535436.368 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-01-09T22:03:56.484Z,1515535436.484 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-01-09T22:03:56.485Z,1515535436.485 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-01-09T22:03:56.542Z,1515535436.542 [DeadReckonUsingSpeedCalculator] Loaded
2018-01-09T22:03:56.542Z,1515535436.542 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-01-09T22:03:56.597Z,1515535436.597 [DeadReckonWithRespectToSeafloor] Loaded
2018-01-09T22:03:56.598Z,1515535436.598 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-01-09T22:03:56.614Z,1515535436.614 [NavChart] Loaded
2018-01-09T22:03:56.615Z,1515535436.615 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-01-09T22:03:56.619Z,1515535436.619 [UniversalFixResidualReporter] Loaded
2018-01-09T22:03:56.619Z,1515535436.619 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-01-09T22:03:56.620Z,1515535436.620 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-01-09T22:03:56.622Z,1515535436.622 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-01-09T22:03:56.647Z,1515535436.647 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-01-09T22:03:56.648Z,1515535436.648 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-01-09T22:03:56.846Z,1515535436.846 [Aanderaa_O2] Loaded
2018-01-09T22:03:56.847Z,1515535436.847 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-01-09T22:03:56.882Z,1515535436.882 [CTD_Seabird] Loaded
2018-01-09T22:03:56.883Z,1515535436.883 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-01-09T22:03:56.884Z,1515535436.884 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4079B4E0
2018-01-09T22:03:56.884Z,1515535436.884 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852
2018-01-09T22:03:56.899Z,1515535436.899 [PAR_Licor] Loaded
2018-01-09T22:03:56.900Z,1515535436.900 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-01-09T22:03:56.950Z,1515535436.950 [WetLabsBB2FL] Loaded
2018-01-09T22:03:56.951Z,1515535436.951 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-01-09T22:03:56.952Z,1515535436.952 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407CB4E0
2018-01-09T22:03:56.952Z,1515535436.952 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853
2018-01-09T22:03:56.997Z,1515535436.997 [WetLabsSeaOWL_UV_A] Loaded
2018-01-09T22:03:56.997Z,1515535436.997 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-01-09T22:03:56.998Z,1515535436.998 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407FB4E0
2018-01-09T22:03:56.999Z,1515535436.999 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 854
2018-01-09T22:03:56.999Z,1515535436.999 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-01-09T22:03:57.000Z,1515535437.000 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-01-09T22:03:57.616Z,1515535437.616 [DataOverHttps] Loaded
2018-01-09T22:03:57.616Z,1515535437.616 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-01-09T22:03:57.630Z,1515535437.630 [Depth_Keller] Loaded
2018-01-09T22:03:57.630Z,1515535437.630 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-01-09T22:03:57.635Z,1515535437.635 [DropWeight] Loaded
2018-01-09T22:03:57.636Z,1515535437.636 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-01-09T22:03:57.728Z,1515535437.728 [NAL9602] Loaded
2018-01-09T22:03:57.729Z,1515535437.729 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-01-09T22:03:57.734Z,1515535437.734 [Onboard] Loaded
2018-01-09T22:03:57.734Z,1515535437.734 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-01-09T22:03:57.742Z,1515535437.742 [Radio_Surface] Loaded
2018-01-09T22:03:57.742Z,1515535437.742 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-01-09T22:03:57.743Z,1515535437.743 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A434E0
2018-01-09T22:03:57.744Z,1515535437.744 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 855
2018-01-09T22:03:57.868Z,1515535437.868 [DAT] Loaded
2018-01-09T22:03:57.868Z,1515535437.868 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2018-01-09T22:03:58.061Z,1515535438.061 [PNI_TCM] Loaded
2018-01-09T22:03:58.061Z,1515535438.061 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-01-09T22:03:58.124Z,1515535438.124 [Rowe_600LCM] Loaded
2018-01-09T22:03:58.124Z,1515535438.124 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-01-09T22:03:58.125Z,1515535438.125 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A734E0
2018-01-09T22:03:58.126Z,1515535438.126 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 856
2018-01-09T22:03:59.968Z,1515535439.968 [BPC1] Loaded
2018-01-09T22:03:59.969Z,1515535439.969 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-01-09T22:03:59.969Z,1515535439.969 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-01-09T22:03:59.970Z,1515535439.970 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-01-09T22:04:00.090Z,1515535440.090 [BuoyancyServo] Loaded
2018-01-09T22:04:00.090Z,1515535440.090 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-01-09T22:04:00.102Z,1515535440.102 [ElevatorServo] Loaded
2018-01-09T22:04:00.103Z,1515535440.103 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-01-09T22:04:00.114Z,1515535440.114 [MassServo] Loaded
2018-01-09T22:04:00.115Z,1515535440.115 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-01-09T22:04:00.126Z,1515535440.126 [RudderServo] Loaded
2018-01-09T22:04:00.127Z,1515535440.127 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-01-09T22:04:00.138Z,1515535440.138 [ThrusterServo] Loaded
2018-01-09T22:04:00.139Z,1515535440.139 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-01-09T22:04:00.139Z,1515535440.139 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-01-09T22:04:00.140Z,1515535440.140 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-01-09T22:04:00.267Z,1515535440.267 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-01-09T22:04:00.268Z,1515535440.268 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-01-09T22:04:00.306Z,1515535440.306 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-01-09T22:04:00.310Z,1515535440.310 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-01-09T22:04:00.311Z,1515535440.311 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-01-09T22:04:00.318Z,1515535440.318 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-01-09T22:04:00.320Z,1515535440.320 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B1A4E0
2018-01-09T22:04:00.320Z,1515535440.320 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 857
2018-01-09T22:04:00.324Z,1515535440.324 [Supervisor](INFO): Main Thread ID is 765
2018-01-09T22:04:00.325Z,1515535440.325 [Supervisor](DEBUG): Running supervisor.
2018-01-09T22:04:00.325Z,1515535440.325 [CommandLine ThreadHandler](INFO): Handler Thread ID is 858
2018-01-09T22:04:00.328Z,1515535440.328 [controlThread ThreadHandler](INFO): Handler Thread ID is 859
2018-01-09T22:04:00.329Z,1515535440.329 [controlThread](DEBUG): Initializing ControlThread
2018-01-09T22:04:00.329Z,1515535440.329 [SBIT](INFO): Initialize SBIT Component.
2018-01-09T22:04:00.330Z,1515535440.330 [SBIT](IMPORTANT): git: 2017-12-12-6-g934df05
2018-01-09T22:04:00.330Z,1515535440.330 [SBIT](INFO): git hash: 934df0507d0d0c68d703bd95bfaa4c08b3d83462
2018-01-09T22:04:00.332Z,1515535440.332 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2018-01-09T22:04:00.332Z,1515535440.332 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2018-01-09T22:04:00.333Z,1515535440.333 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2018-01-09T22:04:00.334Z,1515535440.334 [IBIT](INFO): Initialize IBIT Component.
2018-01-09T22:04:00.335Z,1515535440.335 [CBIT](DEBUG): Initialize CBIT Component.
2018-01-09T22:04:00.335Z,1515535440.335 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-01-09T22:04:00.335Z,1515535440.335 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-01-09T22:04:00.336Z,1515535440.336 [logger ThreadHandler](INFO): Handler Thread ID is 860
2018-01-09T22:04:00.365Z,1515535440.365 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 861
2018-01-09T22:04:00.365Z,1515535440.365 [CTD_Seabird](INFO): Initializing
2018-01-09T22:04:00.405Z,1515535440.405 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 863
2018-01-09T22:04:00.407Z,1515535440.407 [WetLabsBB2FL](INFO): Powering down
2018-01-09T22:04:00.417Z,1515535440.417 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-01-09T22:04:00.419Z,1515535440.419 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-01-09T22:04:00.420Z,1515535440.420 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-01-09T22:04:00.421Z,1515535440.421 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-01-09T22:04:00.421Z,1515535440.421 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-01-09T22:04:00.421Z,1515535440.421 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-01-09T22:04:00.422Z,1515535440.422 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-01-09T22:04:00.422Z,1515535440.422 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-01-09T22:04:00.423Z,1515535440.423 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-01-09T22:04:00.424Z,1515535440.424 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-01-09T22:04:00.429Z,1515535440.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-01-09T22:04:00.430Z,1515535440.430 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-01-09T22:04:00.430Z,1515535440.430 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-01-09T22:04:00.431Z,1515535440.431 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-01-09T22:04:00.431Z,1515535440.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-01-09T22:04:00.431Z,1515535440.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-01-09T22:04:00.432Z,1515535440.432 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-01-09T22:04:00.437Z,1515535440.437 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-01-09T22:04:00.437Z,1515535440.437 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-01-09T22:04:00.438Z,1515535440.438 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-01-09T22:04:00.438Z,1515535440.438 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-01-09T22:04:00.457Z,1515535440.457 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 864
2018-01-09T22:04:00.477Z,1515535440.477 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 865
2018-01-09T22:04:00.483Z,1515535440.483 [Radio_Surface](INFO): Powering up
2018-01-09T22:04:00.486Z,1515535440.486 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-01-09T22:04:00.498Z,1515535440.498 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 866
2018-01-09T22:04:00.500Z,1515535440.500 [Rowe_600LCM](INFO): Initializing
2018-01-09T22:04:00.501Z,1515535440.501 [Rowe_600LCM](INFO): Checking LCM
2018-01-09T22:04:00.599Z,1515535440.599 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 867
2018-01-09T22:04:00.610Z,1515535440.610 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-01-09T22:04:00.646Z,1515535440.646 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-01-09T22:04:00.646Z,1515535440.646 [CTD_Seabird](INFO): Powering down
2018-01-09T22:04:00.719Z,1515535440.719 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-01-09T22:04:00.719Z,1515535440.719 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-01-09T22:04:00.720Z,1515535440.720 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-01-09T22:04:00.720Z,1515535440.720 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-01-09T22:04:00.720Z,1515535440.720 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-01-09T22:04:00.720Z,1515535440.720 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-01-09T22:04:00.720Z,1515535440.720 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-01-09T22:04:00.729Z,1515535440.729 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-01-09T22:04:00.729Z,1515535440.729 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-01-09T22:04:00.729Z,1515535440.729 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-01-09T22:04:00.730Z,1515535440.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-01-09T22:04:00.730Z,1515535440.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-01-09T22:04:00.730Z,1515535440.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-01-09T22:04:00.730Z,1515535440.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-01-09T22:04:00.730Z,1515535440.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-01-09T22:04:00.731Z,1515535440.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-01-09T22:04:00.752Z,1515535440.752 [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>


2018-01-09T22:04:00.769Z,1515535440.769 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-01-09T22:04:00.916Z,1515535440.916 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-01-09T22:04:00.917Z,1515535440.917 [Default:A.Wait](DEBUG): Construct Wait.
2018-01-09T22:04:00.919Z,1515535440.919 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-01-09T22:04:00.961Z,1515535440.961 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-01-09T22:04:00.964Z,1515535440.964 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-01-09T22:04:00.987Z,1515535440.987 [Default:E.Execute](DEBUG): Construct Execute.
2018-01-09T22:04:01.077Z,1515535441.077 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2018-01-09T22:04:01.082Z,1515535441.082 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-01-09T22:04:01.127Z,1515535441.127 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-01-09T22:04:01.261Z,1515535441.261 [Rowe_600LCM](INFO): LCM OK
2018-01-09T22:04:01.261Z,1515535441.261 [Rowe_600LCM](INFO): Powering up
2018-01-09T22:04:01.345Z,1515535441.345 [DAT](INFO): Powering up
2018-01-09T22:04:01.346Z,1515535441.346 [DAT](DEBUG): Initializing DAT.
2018-01-09T22:04:01.536Z,1515535441.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:01.536Z,1515535441.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:01.643Z,1515535441.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:01.649Z,1515535441.649 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:01.667Z,1515535441.667 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:01.818Z,1515535441.818 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-01-09T22:04:01.823Z,1515535441.823 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-01-09T22:04:01.845Z,1515535441.845 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-01-09T22:04:01.851Z,1515535441.851 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-01-09T22:04:01.865Z,1515535441.865 [MassServo](DEBUG): Initializing EZServoServo.
2018-01-09T22:04:01.871Z,1515535441.871 [MassServo](DEBUG): Initializing MassServo.
2018-01-09T22:04:01.885Z,1515535441.885 [RudderServo](DEBUG): Initializing EZServoServo.
2018-01-09T22:04:01.891Z,1515535441.891 [RudderServo](DEBUG): Initializing RudderServo.
2018-01-09T22:04:01.913Z,1515535441.913 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-01-09T22:04:01.920Z,1515535441.920 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-01-09T22:04:02.170Z,1515535442.170 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-01-09T22:04:02.170Z,1515535442.170 [DropWeight] Hardware Fault, FailCount= 1
2018-01-09T22:04:02.170Z,1515535442.170 [DropWeight](ERROR): Hardware Fault
2018-01-09T22:04:02.224Z,1515535442.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.225Z,1515535442.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:02.226Z,1515535442.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:02.226Z,1515535442.226 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.227Z,1515535442.227 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.253Z,1515535442.253 [CommandLine](FAULT): Scheduling is paused
2018-01-09T22:04:02.254Z,1515535442.254 [CBIT](INFO): Critical error at 20180109T220402
2018-01-09T22:04:02.254Z,1515535442.254 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-01-09T22:04:02.256Z,1515535442.256 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-01-09T22:04:02.257Z,1515535442.257 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-01-09T22:04:02.472Z,1515535442.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.472Z,1515535442.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:02.473Z,1515535442.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:02.474Z,1515535442.474 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.474Z,1515535442.474 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.894Z,1515535442.894 [CBIT](INFO): Critical error at 20180109T220402
2018-01-09T22:04:02.965Z,1515535442.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.965Z,1515535442.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:02.966Z,1515535442.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:02.967Z,1515535442.967 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:02.968Z,1515535442.968 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:03.362Z,1515535443.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:03.362Z,1515535443.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:03.363Z,1515535443.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:03.364Z,1515535443.364 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:03.377Z,1515535443.377 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:03.741Z,1515535443.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:03.741Z,1515535443.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:03.742Z,1515535443.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:03.758Z,1515535443.758 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:03.759Z,1515535443.759 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:04.162Z,1515535444.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:04.162Z,1515535444.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:04.163Z,1515535444.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:04.164Z,1515535444.164 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:04.165Z,1515535444.165 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:04.627Z,1515535444.627 [Aanderaa_O2](INFO): Powering down
2018-01-09T22:04:04.812Z,1515535444.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:04.812Z,1515535444.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:04.813Z,1515535444.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:04.814Z,1515535444.814 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:04.815Z,1515535444.815 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-01-09T22:04:05.041Z,1515535445.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:05.042Z,1515535445.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:05.268Z,1515535445.268 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-01-09T22:04:05.391Z,1515535445.391 [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 38400 >& /dev/null &
2018-01-09T22:04:05.436Z,1515535445.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:05.445Z,1515535445.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:05.815Z,1515535445.815 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2018-01-09T22:04:05.816Z,1515535445.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:06.319Z,1515535446.319 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-01-09T22:04:06.320Z,1515535446.320 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-01-09T22:04:06.320Z,1515535446.320 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-01-09T22:04:06.335Z,1515535446.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:06.787Z,1515535446.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:07.118Z,1515535447.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:07.466Z,1515535447.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:07.858Z,1515535447.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:08.274Z,1515535448.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:08.650Z,1515535448.650 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:09.067Z,1515535449.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:09.469Z,1515535449.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:09.905Z,1515535449.905 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:10.259Z,1515535450.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:10.663Z,1515535450.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:11.052Z,1515535451.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:11.463Z,1515535451.463 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:11.897Z,1515535451.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:12.271Z,1515535452.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:12.662Z,1515535452.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:13.074Z,1515535453.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:13.422Z,1515535453.422 [DAT](DEBUG): 
Teledyne Benthos DAT-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.10.9
Jan  9 2018 22:00:53 
WARNING: battery low
Features enabled [Bearing]
CONNECT
2018-01-09T22:04:13.422Z,1515535453.422 [DAT](INFO): Got CONNECT
2018-01-09T22:04:13.433Z,1515535453.433 [DAT](INFO): entering command mode
2018-01-09T22:04:13.477Z,1515535453.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:13.857Z,1515535453.857 [DAT](DEBUG): checking for command mode acknowledgment
2018-01-09T22:04:13.903Z,1515535453.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:14.214Z,1515535454.214 [DAT](DEBUG): checking for command mode acknowledgment
2018-01-09T22:04:14.268Z,1515535454.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:14.640Z,1515535454.640 [CommandLine](IMPORTANT): got command quit
2018-01-09T22:04:14.657Z,1515535454.657 [DAT](DEBUG): checking for command mode acknowledgment
2018-01-09T22:04:14.658Z,1515535454.658 [DAT](DEBUG): read user prompt 1: user:1>
2018-01-09T22:04:14.658Z,1515535454.658 [DAT](INFO): command mode acknowledged
2018-01-09T22:04:14.693Z,1515535454.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:15.017Z,1515535455.017 [DAT](INFO): setting local address to 1
2018-01-09T22:04:15.108Z,1515535455.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:15.417Z,1515535455.417 [DAT](DEBUG): checking for local address setting acknowledgment
2018-01-09T22:04:15.418Z,1515535455.418 [DAT](INFO): set local address to 1
2018-01-09T22:04:15.447Z,1515535455.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:15.647Z,1515535455.647 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:15.648Z,1515535455.648 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:15.752Z,1515535455.752 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-01-09T22:04:15.752Z,1515535455.752 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:15.753Z,1515535455.753 [CommandLine](INFO): Join timeout helper Thread ID is 883
2018-01-09T22:04:15.768Z,1515535455.768 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-01-09T22:04:15.768Z,1515535455.768 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:15.769Z,1515535455.769 [NavChartDb](INFO): Join timeout helper Thread ID is 884
2018-01-09T22:04:15.884Z,1515535455.884 [DAT](DEBUG): read user prompt 2: user:2>
2018-01-09T22:04:15.937Z,1515535455.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:16.278Z,1515535456.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:16.691Z,1515535456.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:17.066Z,1515535457.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:17.458Z,1515535457.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:17.953Z,1515535457.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:18.287Z,1515535458.287 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:18.680Z,1515535458.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:19.065Z,1515535459.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:19.435Z,1515535459.435 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:19.435Z,1515535459.435 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:19.442Z,1515535459.442 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-01-09T22:04:19.442Z,1515535459.442 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:19.443Z,1515535459.443 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 885
2018-01-09T22:04:19.451Z,1515535459.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:19.842Z,1515535459.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:19.911Z,1515535459.911 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:19.911Z,1515535459.911 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-01-09T22:04:20.130Z,1515535460.130 [Rowe_600LCM](INFO): Powering down
2018-01-09T22:04:20.132Z,1515535460.132 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.143Z,1515535460.143 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-01-09T22:04:20.143Z,1515535460.143 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.144Z,1515535460.144 [Radio_Surface](INFO): Join timeout helper Thread ID is 887
2018-01-09T22:04:20.261Z,1515535460.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:20.341Z,1515535460.341 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:20.341Z,1515535460.341 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.343Z,1515535460.343 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-01-09T22:04:20.343Z,1515535460.343 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.344Z,1515535460.344 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 888
2018-01-09T22:04:20.404Z,1515535460.404 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:20.405Z,1515535460.405 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.417Z,1515535460.417 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-01-09T22:04:20.417Z,1515535460.417 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.417Z,1515535460.417 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 889
2018-01-09T22:04:20.652Z,1515535460.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:20.744Z,1515535460.744 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:20.745Z,1515535460.745 [WetLabsBB2FL](INFO): Powering down
2018-01-09T22:04:20.746Z,1515535460.746 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.749Z,1515535460.749 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-01-09T22:04:20.749Z,1515535460.749 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:20.750Z,1515535460.750 [CTD_Seabird](INFO): Join timeout helper Thread ID is 890
2018-01-09T22:04:20.897Z,1515535460.897 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:21.059Z,1515535461.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-01-09T22:04:21.138Z,1515535461.138 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-01-09T22:04:21.138Z,1515535461.138 [CTD_Seabird](INFO): Powering down
2018-01-09T22:04:21.139Z,1515535461.139 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.158Z,1515535461.158 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-01-09T22:04:21.158Z,1515535461.158 [logger ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.158Z,1515535461.158 [logger](INFO): Join timeout helper Thread ID is 892
2018-01-09T22:04:21.194Z,1515535461.194 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:21.194Z,1515535461.194 [logger ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.210Z,1515535461.210 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-01-09T22:04:21.210Z,1515535461.210 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.210Z,1515535461.210 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-01-09T22:04:21.210Z,1515535461.210 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.210Z,1515535461.210 [controlThread](INFO): Join timeout helper Thread ID is 893
2018-01-09T22:04:21.385Z,1515535461.385 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-01-09T22:04:21.385Z,1515535461.385 [controlThread](DEBUG): Uninitializing ControlThread
2018-01-09T22:04:21.386Z,1515535461.386 [Aanderaa_O2](INFO): Powering down
2018-01-09T22:04:21.388Z,1515535461.388 [NAL9602](INFO): Powering down
2018-01-09T22:04:21.410Z,1515535461.410 [DAT](INFO): Powering down
2018-01-09T22:04:21.530Z,1515535461.530 [PNI_TCM](INFO): Powering down
2018-01-09T22:04:21.619Z,1515535461.619 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-01-09T22:04:21.620Z,1515535461.620 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-01-09T22:04:21.621Z,1515535461.621 [MissionManager](INFO): Uninitializing Mission Default
2018-01-09T22:04:21.624Z,1515535461.624 [MissionManager](INFO): Uninitializing Mission Startup
2018-01-09T22:04:21.625Z,1515535461.625 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-01-09T22:04:21.625Z,1515535461.625 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-01-09T22:04:21.626Z,1515535461.626 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-01-09T22:04:21.626Z,1515535461.626 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-01-09T22:04:21.626Z,1515535461.626 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-01-09T22:04:21.627Z,1515535461.627 [BuoyancyServo](INFO): Powering down
2018-01-09T22:04:21.642Z,1515535461.642 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-01-09T22:04:21.642Z,1515535461.642 [ElevatorServo](INFO): Powering down
2018-01-09T22:04:21.661Z,1515535461.661 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-01-09T22:04:21.662Z,1515535461.662 [MassServo](INFO): Powering down
2018-01-09T22:04:21.681Z,1515535461.681 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-01-09T22:04:21.681Z,1515535461.681 [RudderServo](INFO): Powering down
2018-01-09T22:04:21.682Z,1515535461.682 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-01-09T22:04:21.682Z,1515535461.682 [ThrusterServo](INFO): Powering down
2018-01-09T22:04:21.683Z,1515535461.683 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-01-09T22:04:21.683Z,1515535461.683 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-01-09T22:04:21.684Z,1515535461.684 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-01-09T22:04:21.685Z,1515535461.685 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.779Z,1515535461.779 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.782Z,1515535461.782 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.786Z,1515535461.786 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.843Z,1515535461.843 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:21.860Z,1515535461.860 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:22.140Z,1515535462.140 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-01-09T22:04:22.189Z,1515535462.189 [logger ThreadHandler](INFO): Thread cancelled.