2017-09-12T20:38:57.455Z,1505248737.455 [Supervisor](DEBUG): Initializing supervisor.
2017-09-12T20:38:57.460Z,1505248737.460 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-09-12T20:38:57.460Z,1505248737.460 [SyncHandler](INFO): Protected caller Thread ID is 5914
2017-09-12T20:38:57.461Z,1505248737.461 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-09-12T20:38:57.463Z,1505248737.463 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-09-12T20:38:57.463Z,1505248737.463 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5915
2017-09-12T20:38:57.466Z,1505248737.466 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-09-12T20:38:57.481Z,1505248737.481 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-09-12T20:38:57.482Z,1505248737.482 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-09-12T20:38:57.482Z,1505248737.482 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5916
2017-09-12T20:38:57.483Z,1505248737.483 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-09-12T20:38:57.484Z,1505248737.484 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-09-12T20:38:57.485Z,1505248737.485 [logger ThreadHandler](INFO): Protected caller Thread ID is 5917
2017-09-12T20:38:57.488Z,1505248737.488 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-09-12T20:38:57.488Z,1505248737.488 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-09-12T20:38:57.490Z,1505248737.490 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-09-12T20:38:57.692Z,1505248737.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-09-12T20:38:57.692Z,1505248737.692 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-09-12T20:38:57.833Z,1505248737.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-09-12T20:38:57.833Z,1505248737.833 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-09-12T20:38:58.216Z,1505248738.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-09-12T20:38:58.217Z,1505248738.217 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-09-12T20:38:58.368Z,1505248738.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-09-12T20:38:58.369Z,1505248738.369 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-09-12T20:38:58.453Z,1505248738.453 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-09-12T20:38:58.675Z,1505248738.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-09-12T20:38:58.676Z,1505248738.676 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-09-12T20:38:58.784Z,1505248738.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-09-12T20:38:58.785Z,1505248738.785 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-09-12T20:38:59.117Z,1505248739.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-09-12T20:38:59.118Z,1505248739.118 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-09-12T20:38:59.547Z,1505248739.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-09-12T20:38:59.548Z,1505248739.548 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-09-12T20:38:59.902Z,1505248739.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-09-12T20:38:59.903Z,1505248739.903 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-09-12T20:39:00.581Z,1505248740.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-09-12T20:39:00.581Z,1505248740.581 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-09-12T20:39:00.793Z,1505248740.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-09-12T20:39:00.794Z,1505248740.794 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-09-12T20:39:00.901Z,1505248740.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-09-12T20:39:00.901Z,1505248740.901 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-09-12T20:39:01.454Z,1505248741.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-09-12T20:39:01.454Z,1505248741.454 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-09-12T20:39:01.571Z,1505248741.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-09-12T20:39:01.573Z,1505248741.573 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-09-12T20:39:01.574Z,1505248741.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-09-12T20:39:01.873Z,1505248741.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-09-12T20:39:01.873Z,1505248741.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-09-12T20:39:01.981Z,1505248741.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-09-12T20:39:02.076Z,1505248742.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-09-12T20:39:02.185Z,1505248742.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-09-12T20:39:02.275Z,1505248742.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-09-12T20:39:02.413Z,1505248742.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-09-12T20:39:02.583Z,1505248742.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-09-12T20:39:02.684Z,1505248742.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-09-12T20:39:02.781Z,1505248742.781 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-09-12T20:39:02.869Z,1505248742.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-09-12T20:39:03.067Z,1505248743.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-09-12T20:39:03.148Z,1505248743.148 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2017-09-12T20:39:03.148Z,1505248743.148 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-09-12T20:39:03.156Z,1505248743.156 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-09-12T20:39:03.281Z,1505248743.281 [SBIT](DEBUG): Construct Startup Built In Test.
2017-09-12T20:39:03.317Z,1505248743.317 [SBIT] Loaded
2017-09-12T20:39:03.318Z,1505248743.318 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-09-12T20:39:03.319Z,1505248743.319 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-09-12T20:39:03.353Z,1505248743.353 [IBIT] Loaded
2017-09-12T20:39:03.354Z,1505248743.354 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-09-12T20:39:03.358Z,1505248743.358 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-09-12T20:39:03.553Z,1505248743.553 [CBIT] Loaded
2017-09-12T20:39:03.553Z,1505248743.553 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-09-12T20:39:03.554Z,1505248743.554 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-09-12T20:39:03.554Z,1505248743.554 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-09-12T20:39:03.621Z,1505248743.621 [VerticalControl](DEBUG): Construct VerticalControl.
2017-09-12T20:39:03.741Z,1505248743.741 [VerticalControl] Loaded
2017-09-12T20:39:03.741Z,1505248743.741 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-09-12T20:39:03.742Z,1505248743.742 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-09-12T20:39:03.819Z,1505248743.819 [HorizontalControl] Loaded
2017-09-12T20:39:03.820Z,1505248743.820 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-09-12T20:39:03.820Z,1505248743.820 [SpeedControl](DEBUG): Construct SpeedControl.
2017-09-12T20:39:03.827Z,1505248743.827 [SpeedControl] Loaded
2017-09-12T20:39:03.828Z,1505248743.828 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-09-12T20:39:03.829Z,1505248743.829 [LoopControl](DEBUG): Construct LoopControl.
2017-09-12T20:39:03.829Z,1505248743.829 [LoopControl] Loaded
2017-09-12T20:39:03.830Z,1505248743.830 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-09-12T20:39:03.830Z,1505248743.830 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-09-12T20:39:03.831Z,1505248743.831 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-09-12T20:39:03.857Z,1505248743.857 [DepthRateCalculator] Loaded
2017-09-12T20:39:03.857Z,1505248743.857 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-09-12T20:39:03.863Z,1505248743.863 [PitchRateCalculator] Loaded
2017-09-12T20:39:03.864Z,1505248743.864 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-09-12T20:39:03.883Z,1505248743.883 [SpeedCalculator] Loaded
2017-09-12T20:39:03.883Z,1505248743.883 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-09-12T20:39:03.909Z,1505248743.909 [TempGradientCalculator] Loaded
2017-09-12T20:39:03.909Z,1505248743.909 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-09-12T20:39:03.928Z,1505248743.928 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-09-12T20:39:03.928Z,1505248743.928 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-09-12T20:39:03.934Z,1505248743.934 [YawRateCalculator] Loaded
2017-09-12T20:39:03.935Z,1505248743.935 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-09-12T20:39:03.935Z,1505248743.935 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-09-12T20:39:03.936Z,1505248743.936 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-09-12T20:39:04.021Z,1505248744.021 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-09-12T20:39:04.022Z,1505248744.022 [StratificationFrontDetector](DEBUG): (re)initializing
2017-09-12T20:39:04.022Z,1505248744.022 [StratificationFrontDetector] Loaded
2017-09-12T20:39:04.022Z,1505248744.022 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-09-12T20:39:04.023Z,1505248744.023 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-09-12T20:39:04.024Z,1505248744.024 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-09-12T20:39:04.251Z,1505248744.251 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-09-12T20:39:04.252Z,1505248744.252 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-09-12T20:39:04.410Z,1505248744.410 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-09-12T20:39:04.410Z,1505248744.410 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-09-12T20:39:04.478Z,1505248744.478 [DeadReckonUsingSpeedCalculator] Loaded
2017-09-12T20:39:04.479Z,1505248744.479 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-09-12T20:39:04.500Z,1505248744.500 [NavChart] Loaded
2017-09-12T20:39:04.500Z,1505248744.500 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-09-12T20:39:04.505Z,1505248744.505 [UniversalFixResidualReporter] Loaded
2017-09-12T20:39:04.506Z,1505248744.506 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-09-12T20:39:04.506Z,1505248744.506 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-09-12T20:39:04.507Z,1505248744.507 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-09-12T20:39:04.520Z,1505248744.520 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-09-12T20:39:04.521Z,1505248744.521 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-09-12T20:39:04.670Z,1505248744.670 [Aanderaa_O2] Loaded
2017-09-12T20:39:04.670Z,1505248744.670 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-09-12T20:39:04.689Z,1505248744.689 [PAR_Licor] Loaded
2017-09-12T20:39:04.689Z,1505248744.689 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-09-12T20:39:04.690Z,1505248744.690 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-09-12T20:39:04.691Z,1505248744.691 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-09-12T20:39:04.962Z,1505248744.962 [DataOverHttps] Loaded
2017-09-12T20:39:04.963Z,1505248744.963 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-09-12T20:39:04.980Z,1505248744.980 [Depth_Keller] Loaded
2017-09-12T20:39:04.980Z,1505248744.980 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-09-12T20:39:04.986Z,1505248744.986 [DropWeight] Loaded
2017-09-12T20:39:04.987Z,1505248744.987 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-09-12T20:39:05.101Z,1505248745.101 [NAL9602] Loaded
2017-09-12T20:39:05.101Z,1505248745.101 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-09-12T20:39:05.113Z,1505248745.113 [Onboard] Loaded
2017-09-12T20:39:05.113Z,1505248745.113 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-09-12T20:39:05.122Z,1505248745.122 [Radio_Surface] Loaded
2017-09-12T20:39:05.122Z,1505248745.122 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-09-12T20:39:05.124Z,1505248745.124 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A674E0
2017-09-12T20:39:05.124Z,1505248745.124 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5999
2017-09-12T20:39:05.295Z,1505248745.295 [DAT] Loaded
2017-09-12T20:39:05.296Z,1505248745.296 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-09-12T20:39:05.354Z,1505248745.354 [PNI_TCM] Loaded
2017-09-12T20:39:05.354Z,1505248745.354 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-09-12T20:39:05.428Z,1505248745.428 [Rowe_600LCM] Loaded
2017-09-12T20:39:05.429Z,1505248745.429 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-09-12T20:39:05.430Z,1505248745.430 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A974E0
2017-09-12T20:39:05.430Z,1505248745.430 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6000
2017-09-12T20:39:07.930Z,1505248747.930 [BPC1] Loaded
2017-09-12T20:39:07.930Z,1505248747.930 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-09-12T20:39:07.931Z,1505248747.931 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-09-12T20:39:07.932Z,1505248747.932 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-09-12T20:39:08.032Z,1505248748.032 [BuoyancyServo] Loaded
2017-09-12T20:39:08.033Z,1505248748.033 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-09-12T20:39:08.048Z,1505248748.048 [ElevatorServo] Loaded
2017-09-12T20:39:08.048Z,1505248748.048 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-09-12T20:39:08.063Z,1505248748.063 [MassServo] Loaded
2017-09-12T20:39:08.063Z,1505248748.063 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-09-12T20:39:08.078Z,1505248748.078 [RudderServo] Loaded
2017-09-12T20:39:08.078Z,1505248748.078 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-09-12T20:39:08.092Z,1505248748.092 [ThrusterServo] Loaded
2017-09-12T20:39:08.093Z,1505248748.093 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-09-12T20:39:08.093Z,1505248748.093 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-09-12T20:39:08.094Z,1505248748.094 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-09-12T20:39:08.192Z,1505248748.192 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-09-12T20:39:08.193Z,1505248748.193 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-09-12T20:39:08.218Z,1505248748.218 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-09-12T20:39:08.224Z,1505248748.224 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-09-12T20:39:08.225Z,1505248748.225 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-09-12T20:39:08.235Z,1505248748.235 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-09-12T20:39:08.236Z,1505248748.236 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6B4E0
2017-09-12T20:39:08.237Z,1505248748.237 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6001
2017-09-12T20:39:08.243Z,1505248748.243 [Supervisor](INFO): Main Thread ID is 5675
2017-09-12T20:39:08.244Z,1505248748.244 [Supervisor](DEBUG): Running supervisor.
2017-09-12T20:39:08.244Z,1505248748.244 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6002
2017-09-12T20:39:08.248Z,1505248748.248 [controlThread ThreadHandler](INFO): Handler Thread ID is 6003
2017-09-12T20:39:08.248Z,1505248748.248 [controlThread](DEBUG): Initializing ControlThread
2017-09-12T20:39:08.249Z,1505248748.249 [SBIT](INFO): Initialize SBIT Component.
2017-09-12T20:39:08.250Z,1505248748.250 [SBIT](IMPORTANT): git: 2017-08-02-103-g54acef0
2017-09-12T20:39:08.250Z,1505248748.250 [SBIT](INFO): git hash: 54acef0e8867e4ebce852e89742b80c54cea63d9
2017-09-12T20:39:08.252Z,1505248748.252 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-09-12T20:39:08.253Z,1505248748.253 [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
2017-09-12T20:39:08.254Z,1505248748.254 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2017-09-12T20:39:08.254Z,1505248748.254 [IBIT](INFO): Initialize IBIT Component.
2017-09-12T20:39:08.255Z,1505248748.255 [CBIT](DEBUG): Initialize CBIT Component.
2017-09-12T20:39:08.256Z,1505248748.256 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-09-12T20:39:08.257Z,1505248748.257 [logger ThreadHandler](INFO): Handler Thread ID is 6004
2017-09-12T20:39:08.284Z,1505248748.284 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6005
2017-09-12T20:39:08.300Z,1505248748.300 [Radio_Surface](INFO): Powering up
2017-09-12T20:39:08.301Z,1505248748.301 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6006
2017-09-12T20:39:08.301Z,1505248748.301 [Rowe_600LCM](INFO): Initializing
2017-09-12T20:39:08.302Z,1505248748.302 [Rowe_600LCM](INFO): Checking LCM
2017-09-12T20:39:08.310Z,1505248748.310 [Rowe_600LCM](INFO): LCM OK
2017-09-12T20:39:08.311Z,1505248748.311 [Rowe_600LCM](INFO): Powering up
2017-09-12T20:39:08.316Z,1505248748.316 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6007
2017-09-12T20:39:08.320Z,1505248748.320 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-09-12T20:39:08.320Z,1505248748.320 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-09-12T20:39:08.321Z,1505248748.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-09-12T20:39:08.321Z,1505248748.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-09-12T20:39:08.321Z,1505248748.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-09-12T20:39:08.321Z,1505248748.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-09-12T20:39:08.321Z,1505248748.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-09-12T20:39:08.322Z,1505248748.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-09-12T20:39:08.322Z,1505248748.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-09-12T20:39:08.322Z,1505248748.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-09-12T20:39:08.322Z,1505248748.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-09-12T20:39:08.322Z,1505248748.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-09-12T20:39:08.323Z,1505248748.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-09-12T20:39:08.323Z,1505248748.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-09-12T20:39:08.323Z,1505248748.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-09-12T20:39:08.324Z,1505248748.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-09-12T20:39:08.340Z,1505248748.340 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-09-12T20:39:08.342Z,1505248748.342 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-09-12T20:39:08.343Z,1505248748.343 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-09-12T20:39:08.343Z,1505248748.343 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-09-12T20:39:08.344Z,1505248748.344 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-12T20:39:08.345Z,1505248748.345 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-09-12T20:39:08.345Z,1505248748.345 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-09-12T20:39:08.345Z,1505248748.345 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-09-12T20:39:08.346Z,1505248748.346 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-09-12T20:39:08.347Z,1505248748.347 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-09-12T20:39:08.348Z,1505248748.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-12T20:39:08.349Z,1505248748.349 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-12T20:39:08.349Z,1505248748.349 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-12T20:39:08.349Z,1505248748.349 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-12T20:39:08.350Z,1505248748.350 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-12T20:39:08.350Z,1505248748.350 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-12T20:39:08.351Z,1505248748.351 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-09-12T20:39:08.351Z,1505248748.351 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-09-12T20:39:08.383Z,1505248748.383 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-09-12T20:39:08.471Z,1505248748.471 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-12T20:39:08.556Z,1505248748.556 [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>


2017-09-12T20:39:08.557Z,1505248748.557 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-09-12T20:39:08.642Z,1505248748.642 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-09-12T20:39:08.643Z,1505248748.643 [Default:A.Wait](DEBUG): Construct Wait.
2017-09-12T20:39:08.662Z,1505248748.662 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-12T20:39:08.690Z,1505248748.690 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-09-12T20:39:08.702Z,1505248748.702 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-09-12T20:39:08.734Z,1505248748.734 [Default:E.Execute](DEBUG): Construct Execute.
2017-09-12T20:39:08.738Z,1505248748.738 [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>


2017-09-12T20:39:08.749Z,1505248748.749 [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,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-09-12T20:39:08.924Z,1505248748.924 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-09-12T20:39:08.964Z,1505248748.964 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-09-12T20:39:09.054Z,1505248749.054 [DAT](INFO): Powering up
2017-09-12T20:39:09.054Z,1505248749.054 [DAT](DEBUG): Initializing DAT.
2017-09-12T20:39:09.273Z,1505248749.273 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-09-12T20:39:09.372Z,1505248749.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:09.413Z,1505248749.413 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:09.582Z,1505248749.582 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:39:09.590Z,1505248749.590 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-12T20:39:09.598Z,1505248749.598 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:39:09.606Z,1505248749.606 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-09-12T20:39:09.621Z,1505248749.621 [MassServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:39:09.630Z,1505248749.630 [MassServo](DEBUG): Initializing MassServo.
2017-09-12T20:39:09.653Z,1505248749.653 [RudderServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:39:09.666Z,1505248749.666 [RudderServo](DEBUG): Initializing RudderServo.
2017-09-12T20:39:09.692Z,1505248749.692 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:39:09.698Z,1505248749.698 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-09-12T20:39:10.083Z,1505248750.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:10.084Z,1505248750.084 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:10.431Z,1505248750.431 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-09-12T20:39:10.431Z,1505248750.431 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-09-12T20:39:10.431Z,1505248750.431 [BuoyancyServo] Communications Fault, FailCount= 1
2017-09-12T20:39:10.431Z,1505248750.431 [BuoyancyServo](ERROR): Communications Fault
2017-09-12T20:39:10.656Z,1505248750.656 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-09-12T20:39:10.671Z,1505248750.671 [NAL9602](INFO): Powering up NAL9602
2017-09-12T20:39:10.730Z,1505248750.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:10.748Z,1505248750.748 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:10.755Z,1505248750.755 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-12T20:39:10.771Z,1505248750.771 [BuoyancyServo](INFO): Powering down
2017-09-12T20:39:11.101Z,1505248751.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:11.102Z,1505248751.102 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:11.511Z,1505248751.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:11.514Z,1505248751.514 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:11.985Z,1505248751.985 [Aanderaa_O2](INFO): Powering down
2017-09-12T20:39:12.159Z,1505248752.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:12.160Z,1505248752.160 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:12.388Z,1505248752.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:12.402Z,1505248752.402 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:39:12.603Z,1505248752.603 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-09-12T20:39:12.729Z,1505248752.729 [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 &
2017-09-12T20:39:12.793Z,1505248752.793 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-09-12T20:39:12.812Z,1505248752.812 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-09-12T20:39:12.813Z,1505248752.813 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-09-12T20:39:12.913Z,1505248752.913 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-09-12T20:39:12.913Z,1505248752.913 [BuoyancyServo] No Fault, FailCount= 1
2017-09-12T20:39:13.234Z,1505248753.234 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:39:13.234Z,1505248753.234 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-12T20:39:21.495Z,1505248761.495 [NAL9602](INFO): NAL9602 initialized
2017-09-12T20:39:59.104Z,1505248799.104 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 06:33:06 
Warning battery low
CONNECT
2017-09-12T20:39:59.104Z,1505248799.104 [DAT](INFO): Got CONNECT
2017-09-12T20:39:59.116Z,1505248799.116 [DAT](INFO): entering command mode
2017-09-12T20:39:59.499Z,1505248799.499 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:39:59.921Z,1505248799.921 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:40:00.297Z,1505248800.297 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:40:00.697Z,1505248800.697 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:40:00.698Z,1505248800.698 [DAT](INFO): Found user prompt
2017-09-12T20:40:00.698Z,1505248800.698 [DAT](DEBUG): read user prompt 1: user:1>
2017-09-12T20:40:00.698Z,1505248800.698 [DAT](INFO): command mode acknowledged
2017-09-12T20:40:01.098Z,1505248801.098 [DAT](INFO): setting local address to 2
2017-09-12T20:40:01.517Z,1505248801.517 [DAT](DEBUG): checking for local address setting acknowledgment
2017-09-12T20:40:01.518Z,1505248801.518 [DAT](INFO): set local address to 2
2017-09-12T20:40:01.923Z,1505248801.923 [DAT](INFO): Found user prompt
2017-09-12T20:40:01.924Z,1505248801.924 [DAT](DEBUG): read user prompt 2: user:2>
2017-09-12T20:40:19.985Z,1505248819.985 [SBIT](IMPORTANT): Beginning Startup BIT
2017-09-12T20:40:19.999Z,1505248819.999 [CBIT](IMPORTANT): Beginning ground fault scan
2017-09-12T20:40:30.580Z,1505248830.580 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027892
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.011647
CHAN B0 (3.3V): 4.766929
CHAN B1 (3.15aV): 0.002421
CHAN B2 (3.15bV): -0.000195
CHAN B3 (GND): 0.000303
OPEN: -0.000477
Full Scale Calc: 4.765 mA, -1.589 mA
2017-09-12T20:40:53.764Z,1505248853.764 [CommandLine](IMPORTANT): got command show variable rowe_600lcm
2017-09-12T20:40:54.342Z,1505248854.342 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup (bool)
2017-09-12T20:40:54.342Z,1505248854.342 [CommandLine](IMPORTANT): Rowe_600LCM.simulateHardware (bool)
2017-09-12T20:40:54.363Z,1505248854.363 [CommandLine](IMPORTANT): Rowe_600LCM.bottomTrackVelocityAccuracy (meter_per_second)
2017-09-12T20:40:54.364Z,1505248854.364 [CommandLine](IMPORTANT): Rowe_600LCM.waterTrackVelocityAccuracy (meter_per_second)
2017-09-12T20:40:54.364Z,1505248854.364 [CommandLine](IMPORTANT): Rowe_600LCM.altitudeAccuracy (meter)
2017-09-12T20:40:54.479Z,1505248854.479 [CommandLine](IMPORTANT): Rowe_600LCM.loadControl (none)
2017-09-12T20:40:54.480Z,1505248854.480 [CommandLine](IMPORTANT): Rowe_600LCM.uart (none)
2017-09-12T20:40:54.480Z,1505248854.480 [CommandLine](IMPORTANT): Rowe_600LCM.baud (bit_per_second)
2017-09-12T20:40:54.481Z,1505248854.481 [CommandLine](IMPORTANT): Rowe_600LCM.maxSpeed (meter_per_second)
2017-09-12T20:40:54.481Z,1505248854.481 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelBottom (none)
2017-09-12T20:40:54.481Z,1505248854.481 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelWater (none)
2017-09-12T20:40:54.482Z,1505248854.482 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelDVL (none)
2017-09-12T20:40:54.482Z,1505248854.482 [CommandLine](IMPORTANT): Rowe_600LCM.lcmApplication (none)
2017-09-12T20:40:54.561Z,1505248854.561 [CommandLine](IMPORTANT): Rowe_600LCM.height_above_sea_floor (meter)
2017-09-12T20:40:54.562Z,1505248854.562 [CommandLine](IMPORTANT): Rowe_600LCM.platform_x_velocity_wrt_ground (meter_per_second)
2017-09-12T20:40:54.562Z,1505248854.562 [CommandLine](IMPORTANT): Rowe_600LCM.platform_y_velocity_wrt_ground (meter_per_second)
2017-09-12T20:40:54.562Z,1505248854.562 [CommandLine](IMPORTANT): Rowe_600LCM.platform_z_velocity_wrt_ground (meter_per_second)
2017-09-12T20:40:54.563Z,1505248854.563 [CommandLine](IMPORTANT): Rowe_600LCM.platform_x_velocity_wrt_sea_water (meter_per_second)
2017-09-12T20:40:54.563Z,1505248854.563 [CommandLine](IMPORTANT): Rowe_600LCM.platform_y_velocity_wrt_sea_water (meter_per_second)
2017-09-12T20:40:54.564Z,1505248854.564 [CommandLine](IMPORTANT): Rowe_600LCM.platform_z_velocity_wrt_sea_water (meter_per_second)
2017-09-12T20:40:54.564Z,1505248854.564 [CommandLine](IMPORTANT): Rowe_600LCM.platform_velocity_wrt_ground (meter_per_second)
2017-09-12T20:40:54.565Z,1505248854.565 [CommandLine](IMPORTANT): Rowe_600LCM.platform_velocity_wrt_sea_water (meter_per_second)
2017-09-12T20:40:54.565Z,1505248854.565 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude1 (meter)
2017-09-12T20:40:54.566Z,1505248854.566 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude2 (meter)
2017-09-12T20:40:54.566Z,1505248854.566 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude3 (meter)
2017-09-12T20:40:54.566Z,1505248854.566 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude4 (meter)
2017-09-12T20:40:54.599Z,1505248854.599 [CommandLine](IMPORTANT): Rowe_600LCM.durationOfLastRun (second)
2017-09-12T20:40:54.614Z,1505248854.614 [CommandLine](IMPORTANT): Rowe_600LCM.component_voltage (volt)
2017-09-12T20:40:54.614Z,1505248854.614 [CommandLine](IMPORTANT): Rowe_600LCM.component_avgVoltage (volt)
2017-09-12T20:40:54.615Z,1505248854.615 [CommandLine](IMPORTANT): Rowe_600LCM.component_current (milliampere)
2017-09-12T20:40:54.620Z,1505248854.620 [CommandLine](IMPORTANT): Rowe_600LCM.component_avgCurrent (milliampere)
2017-09-12T20:41:13.776Z,1505248873.776 [SBIT](IMPORTANT): SBIT PASSED
2017-09-12T20:41:14.092Z,1505248874.092 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004006
2017-09-12T20:41:14.180Z,1505248874.180 [MissionManager](IMPORTANT): Started mission Startup
2017-09-12T20:41:14.181Z,1505248874.181 [Startup] Running Loop=1
2017-09-12T20:41:14.181Z,1505248874.181 [Startup](DEBUG): Aggregate::initialize Startup
2017-09-12T20:41:14.181Z,1505248874.181 [Startup:A.GoToSurface] Running Loop=1
2017-09-12T20:41:14.181Z,1505248874.181 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-12T20:41:14.182Z,1505248874.182 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-12T20:41:14.182Z,1505248874.182 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-12T20:41:14.191Z,1505248874.191 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-12T20:41:14.191Z,1505248874.191 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-12T20:41:14.192Z,1505248874.192 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-12T20:41:14.227Z,1505248874.227 [Startup:StartupSatComms] Running Loop=1
2017-09-12T20:41:14.227Z,1505248874.227 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-09-12T20:41:14.227Z,1505248874.227 [Startup:StartupSatComms:A] Running Loop=1
2017-09-12T20:41:14.562Z,1505248874.562 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-09-12T20:41:37.533Z,1505248897.533 [CommandLine](IMPORTANT): got command restart application
2017-09-12T20:41:38.541Z,1505248898.541 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-09-12T20:41:38.547Z,1505248898.547 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:41:38.547Z,1505248898.547 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:38.549Z,1505248898.549 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-09-12T20:41:38.549Z,1505248898.549 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:38.550Z,1505248898.550 [CommandLine](INFO): Join timeout helper Thread ID is 6033
2017-09-12T20:41:38.565Z,1505248898.565 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-09-12T20:41:38.566Z,1505248898.566 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:38.566Z,1505248898.566 [NavChartDb](INFO): Join timeout helper Thread ID is 6034
2017-09-12T20:41:38.958Z,1505248898.958 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:41:38.958Z,1505248898.958 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:38.974Z,1505248898.974 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-09-12T20:41:38.974Z,1505248898.974 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:38.974Z,1505248898.974 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6035
2017-09-12T20:41:39.158Z,1505248899.158 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:41:39.159Z,1505248899.159 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-09-12T20:41:39.264Z,1505248899.264 [Rowe_600LCM](INFO): Powering down
2017-09-12T20:41:39.266Z,1505248899.266 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.270Z,1505248899.270 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-09-12T20:41:39.271Z,1505248899.271 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.271Z,1505248899.271 [Radio_Surface](INFO): Join timeout helper Thread ID is 6037
2017-09-12T20:41:39.355Z,1505248899.355 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:41:39.355Z,1505248899.355 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.370Z,1505248899.370 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-09-12T20:41:39.371Z,1505248899.371 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.371Z,1505248899.371 [logger](INFO): Join timeout helper Thread ID is 6038
2017-09-12T20:41:39.402Z,1505248899.402 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:41:39.403Z,1505248899.403 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.411Z,1505248899.411 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-09-12T20:41:39.412Z,1505248899.412 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.412Z,1505248899.412 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-09-12T20:41:39.412Z,1505248899.412 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:39.412Z,1505248899.412 [controlThread](INFO): Join timeout helper Thread ID is 6039
2017-09-12T20:41:39.417Z,1505248899.417 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:41:39.417Z,1505248899.417 [controlThread](DEBUG): Uninitializing ControlThread
2017-09-12T20:41:39.418Z,1505248899.418 [Aanderaa_O2](INFO): Powering down
2017-09-12T20:41:39.420Z,1505248899.420 [NAL9602](INFO): Powering down
2017-09-12T20:41:39.421Z,1505248899.421 [DAT](INFO): Powering down
2017-09-12T20:41:39.539Z,1505248899.539 [PNI_TCM](INFO): Powering down
2017-09-12T20:41:39.628Z,1505248899.628 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-12T20:41:39.630Z,1505248899.630 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-09-12T20:41:39.630Z,1505248899.630 [MissionManager](INFO): Uninitializing Mission Default
2017-09-12T20:41:39.633Z,1505248899.633 [MissionManager](INFO): Uninitializing Mission Startup
2017-09-12T20:41:39.634Z,1505248899.634 [Startup] Stopped
2017-09-12T20:41:39.634Z,1505248899.634 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-09-12T20:41:39.634Z,1505248899.634 [Startup:A.GoToSurface] Stopped
2017-09-12T20:41:39.634Z,1505248899.634 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-12T20:41:39.634Z,1505248899.634 [Startup:StartupSatComms] Stopped
2017-09-12T20:41:39.634Z,1505248899.634 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-09-12T20:41:39.634Z,1505248899.634 [Startup:StartupSatComms:A] Stopped
2017-09-12T20:41:39.636Z,1505248899.636 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-09-12T20:41:39.636Z,1505248899.636 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-09-12T20:41:39.637Z,1505248899.637 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-09-12T20:41:39.637Z,1505248899.637 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-09-12T20:41:39.637Z,1505248899.637 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-12T20:41:39.638Z,1505248899.638 [BuoyancyServo](INFO): Powering down
2017-09-12T20:41:39.651Z,1505248899.651 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-09-12T20:41:39.651Z,1505248899.651 [ElevatorServo](INFO): Powering down
2017-09-12T20:41:39.652Z,1505248899.652 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-09-12T20:41:39.652Z,1505248899.652 [MassServo](INFO): Powering down
2017-09-12T20:41:39.653Z,1505248899.653 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-09-12T20:41:39.653Z,1505248899.653 [RudderServo](INFO): Powering down
2017-09-12T20:41:39.654Z,1505248899.654 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-09-12T20:41:39.654Z,1505248899.654 [ThrusterServo](INFO): Powering down
2017-09-12T20:41:39.655Z,1505248899.655 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-09-12T20:41:39.656Z,1505248899.656 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-09-12T20:41:39.656Z,1505248899.656 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-09-12T20:41:39.657Z,1505248899.657 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:42.855Z,1505248902.855 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:42.871Z,1505248902.871 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:42.922Z,1505248902.922 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:41:43.008Z,1505248903.008 [logger ThreadHandler](INFO): Thread cancelled.