2017-09-12T20:41:44.117Z,1505248904.117 [Supervisor](DEBUG): Initializing supervisor.
2017-09-12T20:41:44.122Z,1505248904.122 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-09-12T20:41:44.122Z,1505248904.122 [SyncHandler](INFO): Protected caller Thread ID is 6040
2017-09-12T20:41:44.123Z,1505248904.123 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-09-12T20:41:44.125Z,1505248904.125 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-09-12T20:41:44.125Z,1505248904.125 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6041
2017-09-12T20:41:44.129Z,1505248904.129 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-09-12T20:41:44.143Z,1505248904.143 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-09-12T20:41:44.144Z,1505248904.144 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-09-12T20:41:44.145Z,1505248904.145 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6042
2017-09-12T20:41:44.146Z,1505248904.146 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-09-12T20:41:44.147Z,1505248904.147 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-09-12T20:41:44.147Z,1505248904.147 [logger ThreadHandler](INFO): Protected caller Thread ID is 6043
2017-09-12T20:41:44.150Z,1505248904.150 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-09-12T20:41:44.150Z,1505248904.150 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-09-12T20:41:44.152Z,1505248904.152 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-09-12T20:41:44.356Z,1505248904.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-09-12T20:41:44.357Z,1505248904.357 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-09-12T20:41:44.499Z,1505248904.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-09-12T20:41:44.500Z,1505248904.500 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-09-12T20:41:44.918Z,1505248904.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-09-12T20:41:44.919Z,1505248904.919 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-09-12T20:41:45.071Z,1505248905.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-09-12T20:41:45.072Z,1505248905.072 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-09-12T20:41:45.156Z,1505248905.156 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-09-12T20:41:45.380Z,1505248905.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-09-12T20:41:45.380Z,1505248905.380 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-09-12T20:41:45.489Z,1505248905.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-09-12T20:41:45.490Z,1505248905.490 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-09-12T20:41:45.826Z,1505248905.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-09-12T20:41:45.826Z,1505248905.826 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-09-12T20:41:46.255Z,1505248906.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-09-12T20:41:46.256Z,1505248906.256 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-09-12T20:41:46.611Z,1505248906.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-09-12T20:41:46.612Z,1505248906.612 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-09-12T20:41:47.250Z,1505248907.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-09-12T20:41:47.250Z,1505248907.250 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-09-12T20:41:47.464Z,1505248907.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-09-12T20:41:47.465Z,1505248907.465 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-09-12T20:41:47.572Z,1505248907.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-09-12T20:41:47.573Z,1505248907.573 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-09-12T20:41:48.129Z,1505248908.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-09-12T20:41:48.130Z,1505248908.130 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-09-12T20:41:48.246Z,1505248908.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-09-12T20:41:48.249Z,1505248908.249 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-09-12T20:41:48.249Z,1505248908.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-09-12T20:41:48.551Z,1505248908.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-09-12T20:41:48.552Z,1505248908.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-09-12T20:41:48.659Z,1505248908.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-09-12T20:41:48.755Z,1505248908.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-09-12T20:41:48.864Z,1505248908.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-09-12T20:41:48.955Z,1505248908.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-09-12T20:41:49.094Z,1505248909.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-09-12T20:41:49.265Z,1505248909.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-09-12T20:41:49.368Z,1505248909.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-09-12T20:41:49.465Z,1505248909.465 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-09-12T20:41:49.554Z,1505248909.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-09-12T20:41:49.753Z,1505248909.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-09-12T20:41:49.834Z,1505248909.834 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2017-09-12T20:41:49.834Z,1505248909.834 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-09-12T20:41:49.842Z,1505248909.842 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-09-12T20:41:49.973Z,1505248909.973 [SBIT](DEBUG): Construct Startup Built In Test.
2017-09-12T20:41:50.010Z,1505248910.010 [SBIT] Loaded
2017-09-12T20:41:50.010Z,1505248910.010 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-09-12T20:41:50.011Z,1505248910.011 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-09-12T20:41:50.047Z,1505248910.047 [IBIT] Loaded
2017-09-12T20:41:50.047Z,1505248910.047 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-09-12T20:41:50.052Z,1505248910.052 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-09-12T20:41:50.252Z,1505248910.252 [CBIT] Loaded
2017-09-12T20:41:50.252Z,1505248910.252 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-09-12T20:41:50.252Z,1505248910.252 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-09-12T20:41:50.253Z,1505248910.253 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-09-12T20:41:50.353Z,1505248910.353 [VerticalControl](DEBUG): Construct VerticalControl.
2017-09-12T20:41:50.476Z,1505248910.476 [VerticalControl] Loaded
2017-09-12T20:41:50.477Z,1505248910.477 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-09-12T20:41:50.478Z,1505248910.478 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-09-12T20:41:50.557Z,1505248910.557 [HorizontalControl] Loaded
2017-09-12T20:41:50.557Z,1505248910.557 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-09-12T20:41:50.558Z,1505248910.558 [SpeedControl](DEBUG): Construct SpeedControl.
2017-09-12T20:41:50.565Z,1505248910.565 [SpeedControl] Loaded
2017-09-12T20:41:50.566Z,1505248910.566 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-09-12T20:41:50.566Z,1505248910.566 [LoopControl](DEBUG): Construct LoopControl.
2017-09-12T20:41:50.567Z,1505248910.567 [LoopControl] Loaded
2017-09-12T20:41:50.567Z,1505248910.567 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-09-12T20:41:50.568Z,1505248910.568 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-09-12T20:41:50.569Z,1505248910.569 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-09-12T20:41:50.595Z,1505248910.595 [DepthRateCalculator] Loaded
2017-09-12T20:41:50.596Z,1505248910.596 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-09-12T20:41:50.602Z,1505248910.602 [PitchRateCalculator] Loaded
2017-09-12T20:41:50.602Z,1505248910.602 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-09-12T20:41:50.622Z,1505248910.622 [SpeedCalculator] Loaded
2017-09-12T20:41:50.622Z,1505248910.622 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-09-12T20:41:50.649Z,1505248910.649 [TempGradientCalculator] Loaded
2017-09-12T20:41:50.649Z,1505248910.649 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-09-12T20:41:50.668Z,1505248910.668 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-09-12T20:41:50.669Z,1505248910.669 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-09-12T20:41:50.675Z,1505248910.675 [YawRateCalculator] Loaded
2017-09-12T20:41:50.675Z,1505248910.675 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-09-12T20:41:50.676Z,1505248910.676 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-09-12T20:41:50.677Z,1505248910.677 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-09-12T20:41:50.765Z,1505248910.765 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-09-12T20:41:50.765Z,1505248910.765 [StratificationFrontDetector](DEBUG): (re)initializing
2017-09-12T20:41:50.765Z,1505248910.765 [StratificationFrontDetector] Loaded
2017-09-12T20:41:50.765Z,1505248910.765 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-09-12T20:41:50.766Z,1505248910.766 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-09-12T20:41:50.767Z,1505248910.767 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-09-12T20:41:51.006Z,1505248911.006 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-09-12T20:41:51.007Z,1505248911.007 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-09-12T20:41:51.123Z,1505248911.123 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-09-12T20:41:51.123Z,1505248911.123 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-09-12T20:41:51.287Z,1505248911.287 [DeadReckonUsingSpeedCalculator] Loaded
2017-09-12T20:41:51.287Z,1505248911.287 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-09-12T20:41:51.341Z,1505248911.341 [NavChart] Loaded
2017-09-12T20:41:51.341Z,1505248911.341 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-09-12T20:41:51.347Z,1505248911.347 [UniversalFixResidualReporter] Loaded
2017-09-12T20:41:51.347Z,1505248911.347 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-09-12T20:41:51.348Z,1505248911.348 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-09-12T20:41:51.348Z,1505248911.348 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-09-12T20:41:51.362Z,1505248911.362 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-09-12T20:41:51.363Z,1505248911.363 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-09-12T20:41:51.518Z,1505248911.518 [Aanderaa_O2] Loaded
2017-09-12T20:41:51.518Z,1505248911.518 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-09-12T20:41:51.538Z,1505248911.538 [PAR_Licor] Loaded
2017-09-12T20:41:51.538Z,1505248911.538 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-09-12T20:41:51.539Z,1505248911.539 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-09-12T20:41:51.540Z,1505248911.540 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-09-12T20:41:51.818Z,1505248911.818 [DataOverHttps] Loaded
2017-09-12T20:41:51.818Z,1505248911.818 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-09-12T20:41:51.836Z,1505248911.836 [Depth_Keller] Loaded
2017-09-12T20:41:51.836Z,1505248911.836 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-09-12T20:41:51.842Z,1505248911.842 [DropWeight] Loaded
2017-09-12T20:41:51.843Z,1505248911.843 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-09-12T20:41:51.960Z,1505248911.960 [NAL9602] Loaded
2017-09-12T20:41:51.961Z,1505248911.961 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-09-12T20:41:51.972Z,1505248911.972 [Onboard] Loaded
2017-09-12T20:41:51.973Z,1505248911.973 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-09-12T20:41:51.982Z,1505248911.982 [Radio_Surface] Loaded
2017-09-12T20:41:51.982Z,1505248911.982 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-09-12T20:41:51.983Z,1505248911.983 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A674E0
2017-09-12T20:41:51.984Z,1505248911.984 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6125
2017-09-12T20:41:52.159Z,1505248912.159 [DAT] Loaded
2017-09-12T20:41:52.160Z,1505248912.160 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-09-12T20:41:52.219Z,1505248912.219 [PNI_TCM] Loaded
2017-09-12T20:41:52.220Z,1505248912.220 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-09-12T20:41:52.296Z,1505248912.296 [Rowe_600LCM] Loaded
2017-09-12T20:41:52.297Z,1505248912.297 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-09-12T20:41:52.298Z,1505248912.298 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A974E0
2017-09-12T20:41:52.298Z,1505248912.298 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6126
2017-09-12T20:41:54.795Z,1505248914.795 [BPC1] Loaded
2017-09-12T20:41:54.796Z,1505248914.796 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-09-12T20:41:54.796Z,1505248914.796 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-09-12T20:41:54.797Z,1505248914.797 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-09-12T20:41:54.902Z,1505248914.902 [BuoyancyServo] Loaded
2017-09-12T20:41:54.902Z,1505248914.902 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-09-12T20:41:54.918Z,1505248914.918 [ElevatorServo] Loaded
2017-09-12T20:41:54.918Z,1505248914.918 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-09-12T20:41:54.933Z,1505248914.933 [MassServo] Loaded
2017-09-12T20:41:54.933Z,1505248914.933 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-09-12T20:41:54.948Z,1505248914.948 [RudderServo] Loaded
2017-09-12T20:41:54.949Z,1505248914.949 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-09-12T20:41:54.963Z,1505248914.963 [ThrusterServo] Loaded
2017-09-12T20:41:54.964Z,1505248914.964 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-09-12T20:41:54.964Z,1505248914.964 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-09-12T20:41:54.965Z,1505248914.965 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-09-12T20:41:55.068Z,1505248915.068 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-09-12T20:41:55.068Z,1505248915.068 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-09-12T20:41:55.095Z,1505248915.095 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-09-12T20:41:55.100Z,1505248915.100 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-09-12T20:41:55.101Z,1505248915.101 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-09-12T20:41:55.111Z,1505248915.111 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-09-12T20:41:55.113Z,1505248915.113 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6B4E0
2017-09-12T20:41:55.113Z,1505248915.113 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6127
2017-09-12T20:41:55.119Z,1505248915.119 [Supervisor](INFO): Main Thread ID is 5675
2017-09-12T20:41:55.120Z,1505248915.120 [Supervisor](DEBUG): Running supervisor.
2017-09-12T20:41:55.120Z,1505248915.120 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6128
2017-09-12T20:41:55.124Z,1505248915.124 [controlThread ThreadHandler](INFO): Handler Thread ID is 6129
2017-09-12T20:41:55.124Z,1505248915.124 [controlThread](DEBUG): Initializing ControlThread
2017-09-12T20:41:55.125Z,1505248915.125 [SBIT](INFO): Initialize SBIT Component.
2017-09-12T20:41:55.126Z,1505248915.126 [SBIT](IMPORTANT): git: 2017-08-02-103-g54acef0
2017-09-12T20:41:55.126Z,1505248915.126 [SBIT](INFO): git hash: 54acef0e8867e4ebce852e89742b80c54cea63d9
2017-09-12T20:41:55.128Z,1505248915.128 [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:41:55.129Z,1505248915.129 [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:41:55.130Z,1505248915.130 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2017-09-12T20:41:55.130Z,1505248915.130 [IBIT](INFO): Initialize IBIT Component.
2017-09-12T20:41:55.131Z,1505248915.131 [CBIT](DEBUG): Initialize CBIT Component.
2017-09-12T20:41:55.132Z,1505248915.132 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-09-12T20:41:55.133Z,1505248915.133 [logger ThreadHandler](INFO): Handler Thread ID is 6130
2017-09-12T20:41:55.160Z,1505248915.160 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6131
2017-09-12T20:41:55.167Z,1505248915.167 [Radio_Surface](INFO): Powering up
2017-09-12T20:41:55.176Z,1505248915.176 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6132
2017-09-12T20:41:55.176Z,1505248915.176 [Rowe_600LCM](INFO): Initializing
2017-09-12T20:41:55.177Z,1505248915.177 [Rowe_600LCM](INFO): Checking LCM
2017-09-12T20:41:55.185Z,1505248915.185 [Rowe_600LCM](INFO): LCM OK
2017-09-12T20:41:55.186Z,1505248915.186 [Rowe_600LCM](INFO): Powering up
2017-09-12T20:41:55.204Z,1505248915.204 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6133
2017-09-12T20:41:55.208Z,1505248915.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-09-12T20:41:55.208Z,1505248915.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-09-12T20:41:55.208Z,1505248915.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-09-12T20:41:55.208Z,1505248915.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-09-12T20:41:55.209Z,1505248915.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-09-12T20:41:55.209Z,1505248915.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-09-12T20:41:55.209Z,1505248915.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-09-12T20:41:55.209Z,1505248915.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-09-12T20:41:55.210Z,1505248915.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-09-12T20:41:55.210Z,1505248915.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-09-12T20:41:55.210Z,1505248915.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-09-12T20:41:55.210Z,1505248915.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-09-12T20:41:55.210Z,1505248915.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-09-12T20:41:55.211Z,1505248915.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-09-12T20:41:55.211Z,1505248915.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-09-12T20:41:55.211Z,1505248915.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-09-12T20:41:55.216Z,1505248915.216 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-09-12T20:41:55.218Z,1505248915.218 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-09-12T20:41:55.219Z,1505248915.219 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-09-12T20:41:55.220Z,1505248915.220 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-09-12T20:41:55.220Z,1505248915.220 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-12T20:41:55.220Z,1505248915.220 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-09-12T20:41:55.221Z,1505248915.221 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-09-12T20:41:55.221Z,1505248915.221 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-09-12T20:41:55.222Z,1505248915.222 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-09-12T20:41:55.223Z,1505248915.223 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-09-12T20:41:55.224Z,1505248915.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-12T20:41:55.225Z,1505248915.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-12T20:41:55.225Z,1505248915.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-12T20:41:55.226Z,1505248915.226 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-12T20:41:55.226Z,1505248915.226 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-12T20:41:55.227Z,1505248915.227 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-12T20:41:55.227Z,1505248915.227 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-09-12T20:41:55.228Z,1505248915.228 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-09-12T20:41:55.256Z,1505248915.256 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-09-12T20:41:55.283Z,1505248915.283 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-12T20:41:55.332Z,1505248915.332 [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:41:55.333Z,1505248915.333 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-09-12T20:41:55.404Z,1505248915.404 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-09-12T20:41:55.405Z,1505248915.405 [Default:A.Wait](DEBUG): Construct Wait.
2017-09-12T20:41:55.407Z,1505248915.407 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-12T20:41:55.452Z,1505248915.452 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-09-12T20:41:55.455Z,1505248915.455 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-09-12T20:41:55.479Z,1505248915.479 [Default:E.Execute](DEBUG): Construct Execute.
2017-09-12T20:41:55.483Z,1505248915.483 [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:41:55.490Z,1505248915.490 [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:41:55.684Z,1505248915.684 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-09-12T20:41:55.708Z,1505248915.708 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-09-12T20:41:55.822Z,1505248915.822 [DAT](INFO): Powering up
2017-09-12T20:41:55.823Z,1505248915.823 [DAT](DEBUG): Initializing DAT.
2017-09-12T20:41:56.034Z,1505248916.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-09-12T20:41:56.144Z,1505248916.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:56.189Z,1505248916.189 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:56.322Z,1505248916.322 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:41:56.330Z,1505248916.330 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-12T20:41:56.338Z,1505248916.338 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:41:56.346Z,1505248916.346 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-09-12T20:41:56.353Z,1505248916.353 [MassServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:41:56.365Z,1505248916.365 [MassServo](DEBUG): Initializing MassServo.
2017-09-12T20:41:56.373Z,1505248916.373 [RudderServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:41:56.401Z,1505248916.401 [RudderServo](DEBUG): Initializing RudderServo.
2017-09-12T20:41:56.416Z,1505248916.416 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:41:56.422Z,1505248916.422 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-09-12T20:41:56.828Z,1505248916.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:56.830Z,1505248916.830 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:57.179Z,1505248917.179 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-09-12T20:41:57.179Z,1505248917.179 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-09-12T20:41:57.179Z,1505248917.179 [BuoyancyServo] Communications Fault, FailCount= 1
2017-09-12T20:41:57.179Z,1505248917.179 [BuoyancyServo](ERROR): Communications Fault
2017-09-12T20:41:57.403Z,1505248917.403 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-09-12T20:41:57.427Z,1505248917.427 [NAL9602](INFO): Powering up NAL9602
2017-09-12T20:41:57.500Z,1505248917.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:57.501Z,1505248917.501 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:57.536Z,1505248917.536 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-12T20:41:57.536Z,1505248917.536 [BuoyancyServo](INFO): Powering down
2017-09-12T20:41:57.868Z,1505248917.868 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:57.869Z,1505248917.869 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:58.277Z,1505248918.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:58.278Z,1505248918.278 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:58.733Z,1505248918.733 [Aanderaa_O2](INFO): Powering down
2017-09-12T20:41:58.870Z,1505248918.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:58.872Z,1505248918.872 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:59.090Z,1505248919.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:59.094Z,1505248919.094 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-12T20:41:59.452Z,1505248919.452 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-09-12T20:41:59.650Z,1505248919.650 [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:41:59.711Z,1505248919.711 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-09-12T20:41:59.712Z,1505248919.712 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-09-12T20:41:59.713Z,1505248919.713 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-09-12T20:42:00.092Z,1505248920.092 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-09-12T20:42:00.092Z,1505248920.092 [BuoyancyServo] No Fault, FailCount= 1
2017-09-12T20:42:00.418Z,1505248920.418 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-12T20:42:00.418Z,1505248920.418 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-12T20:42:08.623Z,1505248928.623 [NAL9602](INFO): NAL9602 initialized
2017-09-12T20:42:22.663Z,1505248942.663 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-12T20:42:22.663Z,1505248942.663 [BPC1] Data Fault, FailCount= 1
2017-09-12T20:42:22.664Z,1505248942.664 [BPC1](ERROR): Data Fault
2017-09-12T20:42:22.794Z,1505248942.794 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-12T20:42:23.951Z,1505248943.951 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-12T20:42:23.951Z,1505248943.951 [BPC1] No Fault, FailCount= 1
2017-09-12T20:42:36.275Z,1505248956.275 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-12T20:42:36.276Z,1505248956.276 [BPC1] Data Fault, FailCount= 2
2017-09-12T20:42:36.276Z,1505248956.276 [BPC1](ERROR): Data Fault
2017-09-12T20:42:36.396Z,1505248956.396 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-12T20:42:37.583Z,1505248957.583 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-12T20:42:37.583Z,1505248957.583 [BPC1] No Fault, FailCount= 2
2017-09-12T20:42:45.443Z,1505248965.443 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 06:35:53 
Warning battery low
CONNECT
2017-09-12T20:42:45.443Z,1505248965.443 [DAT](INFO): Got CONNECT
2017-09-12T20:42:45.455Z,1505248965.455 [DAT](INFO): entering command mode
2017-09-12T20:42:45.838Z,1505248965.838 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:42:46.270Z,1505248966.270 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:42:46.640Z,1505248966.640 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:42:47.051Z,1505248967.051 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-12T20:42:47.051Z,1505248967.051 [DAT](INFO): Found user prompt
2017-09-12T20:42:47.052Z,1505248967.052 [DAT](DEBUG): read user prompt 1: user:1>
2017-09-12T20:42:47.052Z,1505248967.052 [DAT](INFO): command mode acknowledged
2017-09-12T20:42:47.451Z,1505248967.451 [DAT](INFO): setting local address to 2
2017-09-12T20:42:47.852Z,1505248967.852 [DAT](DEBUG): checking for local address setting acknowledgment
2017-09-12T20:42:47.853Z,1505248967.853 [DAT](INFO): set local address to 2
2017-09-12T20:42:48.238Z,1505248968.238 [DAT](INFO): Found user prompt
2017-09-12T20:42:48.239Z,1505248968.239 [DAT](DEBUG): read user prompt 2: user:2>
2017-09-12T20:43:06.770Z,1505248986.770 [SBIT](IMPORTANT): Beginning Startup BIT
2017-09-12T20:43:06.790Z,1505248986.790 [CBIT](IMPORTANT): Beginning ground fault scan
2017-09-12T20:43:17.312Z,1505248997.312 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027650
CHAN A2 (12V): -0.002183
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001374
CHAN B1 (3.15aV): 0.000004
CHAN B2 (3.15bV): 4.767929
CHAN B3 (GND): 0.027785
OPEN: -0.000415
Full Scale Calc: 4.765 mA, -1.589 mA
2017-09-12T20:43:28.709Z,1505249008.709 [PNI_TCM](ERROR): CRC does not match. Expected:0x42684 got:0x16769
2017-09-12T20:43:31.134Z,1505249011.134 [PNI_TCM](ERROR): CRC does not match. Expected:0x8231 got:0x44313
2017-09-12T20:43:36.707Z,1505249016.707 [PNI_TCM](ERROR): CRC does not match. Expected:0x31769 got:0x23580
2017-09-12T20:43:41.118Z,1505249021.118 [PNI_TCM](ERROR): CRC does not match. Expected:0x22005 got:0x49427
2017-09-12T20:44:00.263Z,1505249040.263 [SBIT](IMPORTANT): SBIT PASSED
2017-09-12T20:44:00.644Z,1505249040.644 [MissionManager](IMPORTANT): Started mission Startup
2017-09-12T20:44:00.645Z,1505249040.645 [Startup] Running Loop=1
2017-09-12T20:44:00.645Z,1505249040.645 [Startup](DEBUG): Aggregate::initialize Startup
2017-09-12T20:44:00.645Z,1505249040.645 [Startup:A.GoToSurface] Running Loop=1
2017-09-12T20:44:00.645Z,1505249040.645 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-12T20:44:00.650Z,1505249040.650 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-12T20:44:00.650Z,1505249040.650 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-12T20:44:00.651Z,1505249040.651 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-12T20:44:00.651Z,1505249040.651 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-12T20:44:00.651Z,1505249040.651 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-12T20:44:00.676Z,1505249040.676 [Startup:StartupSatComms] Running Loop=1
2017-09-12T20:44:00.676Z,1505249040.676 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-09-12T20:44:00.677Z,1505249040.677 [Startup:StartupSatComms:A] Running Loop=1
2017-09-12T20:44:00.972Z,1505249040.972 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003706
2017-09-12T20:44:01.066Z,1505249041.066 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-09-12T20:44:05.804Z,1505249045.804 [BPC1](ERROR): Failed to receive battery data
2017-09-12T20:44:05.804Z,1505249045.804 [BPC1] Communications Fault, FailCount= 1
2017-09-12T20:44:05.804Z,1505249045.804 [BPC1](ERROR): Communications Fault
2017-09-12T20:44:05.863Z,1505249045.863 [CBIT](ERROR): Communications Fault in component: BPC1
2017-09-12T20:44:07.056Z,1505249047.056 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-12T20:44:07.056Z,1505249047.056 [BPC1] No Fault, FailCount= 1
2017-09-12T20:44:25.928Z,1505249065.928 [CommandLine](IMPORTANT): got command restart application
2017-09-12T20:44:26.935Z,1505249066.935 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-09-12T20:44:26.941Z,1505249066.941 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:44:26.942Z,1505249066.942 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.051Z,1505249067.051 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-09-12T20:44:27.052Z,1505249067.052 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.052Z,1505249067.052 [CommandLine](INFO): Join timeout helper Thread ID is 6147
2017-09-12T20:44:27.053Z,1505249067.053 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-09-12T20:44:27.053Z,1505249067.053 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.054Z,1505249067.054 [NavChartDb](INFO): Join timeout helper Thread ID is 6148
2017-09-12T20:44:27.420Z,1505249067.420 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:44:27.421Z,1505249067.421 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.428Z,1505249067.428 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-09-12T20:44:27.428Z,1505249067.428 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.429Z,1505249067.429 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6149
2017-09-12T20:44:27.625Z,1505249067.625 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:44:27.625Z,1505249067.625 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-09-12T20:44:27.725Z,1505249067.725 [Rowe_600LCM](INFO): Powering down
2017-09-12T20:44:27.727Z,1505249067.727 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.737Z,1505249067.737 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-09-12T20:44:27.737Z,1505249067.737 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.737Z,1505249067.737 [Radio_Surface](INFO): Join timeout helper Thread ID is 6151
2017-09-12T20:44:27.785Z,1505249067.785 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:44:27.785Z,1505249067.785 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.797Z,1505249067.797 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-09-12T20:44:27.797Z,1505249067.797 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.798Z,1505249067.798 [logger](INFO): Join timeout helper Thread ID is 6152
2017-09-12T20:44:27.843Z,1505249067.843 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:44:27.843Z,1505249067.843 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.858Z,1505249067.858 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-09-12T20:44:27.858Z,1505249067.858 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.858Z,1505249067.858 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-09-12T20:44:27.858Z,1505249067.858 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:27.859Z,1505249067.859 [controlThread](INFO): Join timeout helper Thread ID is 6153
2017-09-12T20:44:27.867Z,1505249067.867 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-12T20:44:27.867Z,1505249067.867 [controlThread](DEBUG): Uninitializing ControlThread
2017-09-12T20:44:27.868Z,1505249067.868 [Aanderaa_O2](INFO): Powering down
2017-09-12T20:44:27.870Z,1505249067.870 [NAL9602](INFO): Powering down
2017-09-12T20:44:27.871Z,1505249067.871 [DAT](INFO): Powering down
2017-09-12T20:44:27.989Z,1505249067.989 [PNI_TCM](INFO): Powering down
2017-09-12T20:44:28.078Z,1505249068.078 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-12T20:44:28.080Z,1505249068.080 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-09-12T20:44:28.081Z,1505249068.081 [MissionManager](INFO): Uninitializing Mission Default
2017-09-12T20:44:28.084Z,1505249068.084 [MissionManager](INFO): Uninitializing Mission Startup
2017-09-12T20:44:28.084Z,1505249068.084 [Startup] Stopped
2017-09-12T20:44:28.084Z,1505249068.084 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-09-12T20:44:28.084Z,1505249068.084 [Startup:A.GoToSurface] Stopped
2017-09-12T20:44:28.084Z,1505249068.084 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-12T20:44:28.084Z,1505249068.084 [Startup:StartupSatComms] Stopped
2017-09-12T20:44:28.084Z,1505249068.084 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-09-12T20:44:28.085Z,1505249068.085 [Startup:StartupSatComms:A] Stopped
2017-09-12T20:44:28.087Z,1505249068.087 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-09-12T20:44:28.087Z,1505249068.087 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-09-12T20:44:28.087Z,1505249068.087 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-09-12T20:44:28.088Z,1505249068.088 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-09-12T20:44:28.088Z,1505249068.088 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-12T20:44:28.088Z,1505249068.088 [BuoyancyServo](INFO): Powering down
2017-09-12T20:44:28.101Z,1505249068.101 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-09-12T20:44:28.101Z,1505249068.101 [ElevatorServo](INFO): Powering down
2017-09-12T20:44:28.102Z,1505249068.102 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-09-12T20:44:28.102Z,1505249068.102 [MassServo](INFO): Powering down
2017-09-12T20:44:28.103Z,1505249068.103 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-09-12T20:44:28.104Z,1505249068.104 [RudderServo](INFO): Powering down
2017-09-12T20:44:28.104Z,1505249068.104 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-09-12T20:44:28.105Z,1505249068.105 [ThrusterServo](INFO): Powering down
2017-09-12T20:44:28.106Z,1505249068.106 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-09-12T20:44:28.106Z,1505249068.106 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-09-12T20:44:28.106Z,1505249068.106 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-09-12T20:44:28.108Z,1505249068.108 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:31.306Z,1505249071.306 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:31.326Z,1505249071.326 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:31.377Z,1505249071.377 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-12T20:44:31.457Z,1505249071.457 [logger ThreadHandler](INFO): Thread cancelled.