2018-11-10T15:41:04.893Z,1541864464.893 [Supervisor](DEBUG): Initializing supervisor.
2018-11-10T15:41:04.895Z,1541864464.895 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-10T15:41:04.896Z,1541864464.896 [SyncHandler](INFO): Protected caller Thread ID is 9186
2018-11-10T15:41:04.896Z,1541864464.896 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-10T15:41:04.897Z,1541864464.897 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-10T15:41:04.897Z,1541864464.897 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9187
2018-11-10T15:41:04.900Z,1541864464.900 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-10T15:41:04.912Z,1541864464.912 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-10T15:41:04.912Z,1541864464.912 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-10T15:41:04.913Z,1541864464.913 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9188
2018-11-10T15:41:04.914Z,1541864464.914 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-10T15:41:04.915Z,1541864464.915 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-10T15:41:04.915Z,1541864464.915 [logger ThreadHandler](INFO): Protected caller Thread ID is 9189
2018-11-10T15:41:04.917Z,1541864464.917 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-10T15:41:04.917Z,1541864464.917 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-10T15:41:04.921Z,1541864464.921 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-10T15:41:05.324Z,1541864465.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-10T15:41:05.325Z,1541864465.325 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-10T15:41:05.530Z,1541864465.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-10T15:41:05.530Z,1541864465.530 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-10T15:41:05.841Z,1541864465.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-10T15:41:05.842Z,1541864465.842 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-10T15:41:05.989Z,1541864465.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-10T15:41:05.991Z,1541864465.991 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-10T15:41:06.091Z,1541864466.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-10T15:41:06.092Z,1541864466.092 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-10T15:41:06.568Z,1541864466.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-10T15:41:06.569Z,1541864466.569 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-10T15:41:07.042Z,1541864467.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-10T15:41:07.045Z,1541864467.045 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-10T15:41:07.596Z,1541864467.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-10T15:41:07.598Z,1541864467.598 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-10T15:41:07.702Z,1541864467.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-10T15:41:07.703Z,1541864467.703 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-10T15:41:07.800Z,1541864467.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-10T15:41:07.802Z,1541864467.802 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-10T15:41:08.022Z,1541864468.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-10T15:41:08.024Z,1541864468.024 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-10T15:41:08.232Z,1541864468.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-10T15:41:08.233Z,1541864468.233 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-10T15:41:08.590Z,1541864468.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-10T15:41:08.591Z,1541864468.591 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-10T15:41:08.738Z,1541864468.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-10T15:41:08.739Z,1541864468.739 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-10T15:41:08.821Z,1541864468.821 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-11-10T15:41:08.825Z,1541864468.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-11-10T15:41:08.929Z,1541864468.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-11-10T15:41:09.048Z,1541864469.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-11-10T15:41:09.145Z,1541864469.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-11-10T15:41:09.248Z,1541864469.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-11-10T15:41:09.787Z,1541864469.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-11-10T15:41:09.971Z,1541864469.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-11-10T15:41:10.049Z,1541864470.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-11-10T15:41:10.280Z,1541864470.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-10T15:41:10.281Z,1541864470.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-11-10T15:41:10.364Z,1541864470.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-11-10T15:41:10.456Z,1541864470.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-11-10T15:41:10.559Z,1541864470.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-11-10T15:41:10.688Z,1541864470.688 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-11-10T15:41:10.689Z,1541864470.689 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-10T15:41:10.705Z,1541864470.705 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-10T15:41:10.976Z,1541864470.976 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-10T15:41:10.976Z,1541864470.976 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-10T15:41:11.028Z,1541864471.028 [DepthRateCalculator] Loaded
2018-11-10T15:41:11.029Z,1541864471.029 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-10T15:41:11.035Z,1541864471.035 [PitchRateCalculator] Loaded
2018-11-10T15:41:11.035Z,1541864471.035 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-10T15:41:11.051Z,1541864471.051 [SpeedCalculator] Loaded
2018-11-10T15:41:11.051Z,1541864471.051 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-10T15:41:11.072Z,1541864471.072 [TempGradientCalculator] Loaded
2018-11-10T15:41:11.072Z,1541864471.072 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-10T15:41:11.078Z,1541864471.078 [YawRateCalculator] Loaded
2018-11-10T15:41:11.078Z,1541864471.078 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-10T15:41:11.117Z,1541864471.117 [ElevatorOffsetCalculator] Loaded
2018-11-10T15:41:11.117Z,1541864471.117 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-10T15:41:11.118Z,1541864471.118 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-10T15:41:11.118Z,1541864471.118 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-10T15:41:11.215Z,1541864471.215 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-10T15:41:11.215Z,1541864471.215 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-10T15:41:11.345Z,1541864471.345 [BuoyancyServo] Loaded
2018-11-10T15:41:11.345Z,1541864471.345 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-10T15:41:11.359Z,1541864471.359 [ElevatorServo] Loaded
2018-11-10T15:41:11.360Z,1541864471.360 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-10T15:41:11.374Z,1541864471.374 [MassServo] Loaded
2018-11-10T15:41:11.374Z,1541864471.374 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-10T15:41:11.389Z,1541864471.389 [RudderServo] Loaded
2018-11-10T15:41:11.390Z,1541864471.390 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-10T15:41:11.403Z,1541864471.403 [ThrusterServo] Loaded
2018-11-10T15:41:11.404Z,1541864471.404 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-10T15:41:11.404Z,1541864471.404 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-10T15:41:11.405Z,1541864471.405 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-10T15:41:11.426Z,1541864471.426 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-10T15:41:11.426Z,1541864471.426 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-10T15:41:11.546Z,1541864471.546 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-10T15:41:11.546Z,1541864471.546 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-10T15:41:11.923Z,1541864471.923 [DataOverHttps] Loaded
2018-11-10T15:41:11.923Z,1541864471.923 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-10T15:41:11.937Z,1541864471.937 [Depth_Keller] Loaded
2018-11-10T15:41:11.937Z,1541864471.937 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-10T15:41:11.942Z,1541864471.942 [DropWeight] Loaded
2018-11-10T15:41:11.942Z,1541864471.942 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-10T15:41:12.038Z,1541864472.038 [NAL9602] Loaded
2018-11-10T15:41:12.038Z,1541864472.038 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-10T15:41:12.054Z,1541864472.054 [Onboard] Loaded
2018-11-10T15:41:12.054Z,1541864472.054 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-10T15:41:12.065Z,1541864472.065 [Radio_Surface] Loaded
2018-11-10T15:41:12.065Z,1541864472.065 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-10T15:41:12.066Z,1541864472.066 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-11-10T15:41:12.066Z,1541864472.066 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9272
2018-11-10T15:41:12.112Z,1541864472.112 [PNI_TCM] Loaded
2018-11-10T15:41:12.113Z,1541864472.113 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-10T15:41:12.172Z,1541864472.172 [Rowe_600LCM] Loaded
2018-11-10T15:41:12.172Z,1541864472.172 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-10T15:41:12.173Z,1541864472.173 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-11-10T15:41:12.174Z,1541864472.174 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 9273
2018-11-10T15:41:12.174Z,1541864472.174 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-10T15:41:12.175Z,1541864472.175 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-10T15:41:12.320Z,1541864472.320 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-10T15:41:12.341Z,1541864472.341 [SBIT] Loaded
2018-11-10T15:41:12.342Z,1541864472.342 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-10T15:41:12.342Z,1541864472.342 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-10T15:41:12.354Z,1541864472.354 [IBIT] Loaded
2018-11-10T15:41:12.355Z,1541864472.355 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-10T15:41:12.358Z,1541864472.358 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-10T15:41:12.504Z,1541864472.504 [CBIT] Loaded
2018-11-10T15:41:12.504Z,1541864472.504 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-10T15:41:12.504Z,1541864472.504 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-10T15:41:12.505Z,1541864472.505 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-10T15:41:12.754Z,1541864472.754 [CTD_NeilBrown] Loaded
2018-11-10T15:41:12.755Z,1541864472.755 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-10T15:41:12.756Z,1541864472.756 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2018-11-10T15:41:12.756Z,1541864472.756 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9274
2018-11-10T15:41:12.770Z,1541864472.770 [PAR_Licor] Loaded
2018-11-10T15:41:12.770Z,1541864472.770 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-10T15:41:12.818Z,1541864472.818 [WetLabsBB2FL] Loaded
2018-11-10T15:41:12.819Z,1541864472.819 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-10T15:41:12.820Z,1541864472.820 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2018-11-10T15:41:12.820Z,1541864472.820 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9275
2018-11-10T15:41:12.821Z,1541864472.821 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-10T15:41:12.821Z,1541864472.821 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-10T15:41:12.904Z,1541864472.904 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-10T15:41:12.986Z,1541864472.986 [VerticalControl] Loaded
2018-11-10T15:41:12.986Z,1541864472.986 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-10T15:41:12.987Z,1541864472.987 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-10T15:41:13.042Z,1541864473.042 [HorizontalControl] Loaded
2018-11-10T15:41:13.042Z,1541864473.042 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-10T15:41:13.043Z,1541864473.043 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-10T15:41:13.045Z,1541864473.045 [SpeedControl] Loaded
2018-11-10T15:41:13.045Z,1541864473.045 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-10T15:41:13.046Z,1541864473.046 [LoopControl](DEBUG): Construct LoopControl.
2018-11-10T15:41:13.046Z,1541864473.046 [LoopControl] Loaded
2018-11-10T15:41:13.046Z,1541864473.046 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-10T15:41:13.047Z,1541864473.047 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-10T15:41:13.047Z,1541864473.047 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-10T15:41:13.081Z,1541864473.081 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-10T15:41:13.081Z,1541864473.081 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-10T15:41:13.182Z,1541864473.182 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-10T15:41:13.183Z,1541864473.183 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-10T15:41:13.198Z,1541864473.198 [NavChart] Loaded
2018-11-10T15:41:13.199Z,1541864473.199 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-10T15:41:13.203Z,1541864473.203 [UniversalFixResidualReporter] Loaded
2018-11-10T15:41:13.203Z,1541864473.203 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-10T15:41:13.203Z,1541864473.203 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-10T15:41:13.207Z,1541864473.207 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-10T15:41:13.208Z,1541864473.208 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-10T15:41:13.214Z,1541864473.214 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-10T15:41:13.216Z,1541864473.216 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0
2018-11-10T15:41:13.216Z,1541864473.216 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9276
2018-11-10T15:41:13.221Z,1541864473.221 [Supervisor](INFO): Main Thread ID is 9185
2018-11-10T15:41:13.221Z,1541864473.221 [Supervisor](DEBUG): Running supervisor.
2018-11-10T15:41:13.221Z,1541864473.221 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9277
2018-11-10T15:41:13.224Z,1541864473.224 [controlThread ThreadHandler](INFO): Handler Thread ID is 9278
2018-11-10T15:41:13.224Z,1541864473.224 [controlThread](DEBUG): Initializing ControlThread
2018-11-10T15:41:13.225Z,1541864473.225 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-10T15:41:13.226Z,1541864473.226 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-10T15:41:13.226Z,1541864473.226 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-10T15:41:13.226Z,1541864473.226 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-10T15:41:13.227Z,1541864473.227 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-10T15:41:13.227Z,1541864473.227 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-10T15:41:13.234Z,1541864473.234 [SBIT](INFO): Initialize SBIT Component.
2018-11-10T15:41:13.234Z,1541864473.234 [SBIT](IMPORTANT): git: 2018-10-09
2018-11-10T15:41:13.234Z,1541864473.234 [SBIT](INFO): git hash: e647870a3e94d94bcb418e3013bcadd95d09d9aa
2018-11-10T15:41:13.235Z,1541864473.235 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-10T15:41:13.235Z,1541864473.235 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-10T15:41:13.236Z,1541864473.236 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2018-11-10T15:41:13.237Z,1541864473.237 [IBIT](INFO): Initialize IBIT Component.
2018-11-10T15:41:13.237Z,1541864473.237 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-10T15:41:13.238Z,1541864473.238 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-10T15:41:13.239Z,1541864473.239 [logger ThreadHandler](INFO): Handler Thread ID is 9279
2018-11-10T15:41:13.256Z,1541864473.256 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9280
2018-11-10T15:41:13.261Z,1541864473.261 [Radio_Surface](INFO): Powering up
2018-11-10T15:41:13.268Z,1541864473.268 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 9281
2018-11-10T15:41:13.268Z,1541864473.268 [Rowe_600LCM](INFO): Initializing
2018-11-10T15:41:13.269Z,1541864473.269 [Rowe_600LCM](INFO): Checking LCM
2018-11-10T15:41:13.278Z,1541864473.278 [Rowe_600LCM](INFO): LCM OK
2018-11-10T15:41:13.278Z,1541864473.278 [Rowe_600LCM](INFO): Powering up
2018-11-10T15:41:13.284Z,1541864473.284 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9282
2018-11-10T15:41:13.285Z,1541864473.285 [CTD_NeilBrown](INFO): Powering down
2018-11-10T15:41:13.312Z,1541864473.312 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9283
2018-11-10T15:41:13.313Z,1541864473.313 [WetLabsBB2FL](INFO): Powering down
2018-11-10T15:41:13.340Z,1541864473.340 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9284
2018-11-10T15:41:13.348Z,1541864473.348 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-10T15:41:13.350Z,1541864473.350 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-10T15:41:13.350Z,1541864473.350 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-10T15:41:13.351Z,1541864473.351 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-10T15:41:13.352Z,1541864473.352 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-10T15:41:13.352Z,1541864473.352 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-10T15:41:13.353Z,1541864473.353 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-10T15:41:13.353Z,1541864473.353 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-10T15:41:13.353Z,1541864473.353 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-10T15:41:13.354Z,1541864473.354 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-10T15:41:13.370Z,1541864473.370 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-10T15:41:13.376Z,1541864473.376 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-10T15:41:13.376Z,1541864473.376 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-10T15:41:13.376Z,1541864473.376 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-10T15:41:13.376Z,1541864473.376 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-10T15:41:13.376Z,1541864473.376 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-10T15:41:13.377Z,1541864473.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-10T15:41:13.377Z,1541864473.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-10T15:41:13.377Z,1541864473.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-10T15:41:13.377Z,1541864473.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-10T15:41:13.377Z,1541864473.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-10T15:41:13.378Z,1541864473.378 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-10T15:41:13.378Z,1541864473.378 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-10T15:41:13.378Z,1541864473.378 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-10T15:41:13.378Z,1541864473.378 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-10T15:41:13.378Z,1541864473.378 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-10T15:41:13.379Z,1541864473.379 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-10T15:41:13.430Z,1541864473.430 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-11-10T15:41:13.430Z,1541864473.430 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-10T15:41:13.502Z,1541864473.502 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-10T15:41:13.520Z,1541864473.520 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-10T15:41:13.521Z,1541864473.521 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-10T15:41:13.541Z,1541864473.541 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-10T15:41:13.567Z,1541864473.567 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-10T15:41:13.573Z,1541864473.573 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-10T15:41:13.593Z,1541864473.593 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-11-10T15:41:13.597Z,1541864473.597 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-10T15:41:13.622Z,1541864473.622 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-10T15:41:13.922Z,1541864473.922 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:14.096Z,1541864474.096 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:41:14.104Z,1541864474.104 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-10T15:41:14.110Z,1541864474.110 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:41:14.116Z,1541864474.116 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-10T15:41:14.138Z,1541864474.138 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:41:14.144Z,1541864474.144 [MassServo](DEBUG): Initializing MassServo.
2018-11-10T15:41:14.166Z,1541864474.166 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:41:14.172Z,1541864474.172 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-10T15:41:14.178Z,1541864474.178 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:41:14.184Z,1541864474.184 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-10T15:41:14.582Z,1541864474.582 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:17.596Z,1541864477.596 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-10T15:41:17.730Z,1541864477.730 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-10T15:41:17.796Z,1541864477.796 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-10T15:41:17.797Z,1541864477.797 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-10T15:41:17.797Z,1541864477.797 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-10T15:41:24.098Z,1541864484.098 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:24.487Z,1541864484.487 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:24.867Z,1541864484.867 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:25.214Z,1541864485.214 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004384
2018-11-10T15:41:25.264Z,1541864485.264 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:25.647Z,1541864485.647 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:26.076Z,1541864486.076 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-10T15:41:42.414Z,1541864502.414 [NAL9602](INFO): Powering up NAL9602
2018-11-10T15:41:42.426Z,1541864502.426 [CommandLine](IMPORTANT): got command failComponent
2018-11-10T15:41:42.426Z,1541864502.426 [CommandLine](IMPORTANT): Failed components:
2018-11-10T15:41:42.426Z,1541864502.426 [CommandLine](IMPORTANT): No failed Components.
2018-11-10T15:41:49.707Z,1541864509.707 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-10T15:41:49.728Z,1541864509.728 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-10T15:41:53.525Z,1541864513.525 [NAL9602](INFO): NAL9602 initialized
2018-11-10T15:42:00.857Z,1541864520.857 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.005128
CHAN A1 (24V): -0.008639
CHAN A2 (12V): -0.001997
CHAN A3 (5V): -0.001663
CHAN B0 (3.3V): 4.766929
CHAN B1 (3.15aV): -0.000540
CHAN B2 (3.15bV): -0.000378
CHAN B3 (GND): 0.000483
OPEN: -0.000498
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-10T15:42:06.078Z,1541864526.078 [CommandLine](IMPORTANT): got command configSet Rowe_600LCM.loadAtStartup 0.000000 bool
2018-11-10T15:42:06.078Z,1541864526.078 [CommandLine](FAULT): configSet Rowe_600LCM.loadAtStartup without persist will have no effect.
2018-11-10T15:42:06.624Z,1541864526.624 [Rowe_600LCM](IMPORTANT): Stopping now due to load at startup. No immediate restart required.
2018-11-10T15:42:06.625Z,1541864526.625 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-10T15:42:06.944Z,1541864526.944 [Rowe_600LCM](INFO): Powering down
2018-11-10T15:42:26.511Z,1541864546.511 [CommandLine](IMPORTANT): got command failComponent
2018-11-10T15:42:26.511Z,1541864546.511 [CommandLine](IMPORTANT): Failed components:
2018-11-10T15:42:26.512Z,1541864546.512 [CommandLine](IMPORTANT): No failed Components.
2018-11-10T15:42:43.692Z,1541864563.692 [SBIT](IMPORTANT): SBIT PASSED
2018-11-10T15:42:43.728Z,1541864563.728 [CommandLine](IMPORTANT): got command configSet list
2018-11-10T15:42:43.728Z,1541864563.728 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-10T15:42:43.729Z,1541864563.729 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature 0.150000 kelvin;

2018-11-10T15:42:43.730Z,1541864563.730 [CommandLine](IMPORTANT): Express linearApproximation CTD_NeilBrown.bin_median_sea_water_salinity 0.020000 practical_salinity_unit;

2018-11-10T15:42:43.731Z,1541864563.731 [CommandLine](IMPORTANT): Express linearApproximation CTD_NeilBrown.bin_median_sea_water_temperature 0.150000 kelvin;

2018-11-10T15:42:43.731Z,1541864563.731 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-11-10T15:42:43.731Z,1541864563.731 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-11-10T15:42:43.731Z,1541864563.731 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-11-10T15:42:43.731Z,1541864563.731 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-11-10T15:42:43.731Z,1541864563.731 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-11-10T15:42:44.052Z,1541864564.052 [MissionManager](IMPORTANT): Started mission Startup
2018-11-10T15:42:44.053Z,1541864564.053 [Startup] Running Loop=1
2018-11-10T15:42:44.053Z,1541864564.053 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-10T15:42:44.053Z,1541864564.053 [Startup:A.GoToSurface] Running Loop=1
2018-11-10T15:42:44.053Z,1541864564.053 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-10T15:42:44.054Z,1541864564.054 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-10T15:42:44.054Z,1541864564.054 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-10T15:42:44.054Z,1541864564.054 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-10T15:42:44.055Z,1541864564.055 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-10T15:42:44.055Z,1541864564.055 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-10T15:42:44.062Z,1541864564.062 [Startup:StartupSatComms] Running Loop=1
2018-11-10T15:42:44.062Z,1541864564.062 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-10T15:42:44.062Z,1541864564.062 [Startup:StartupSatComms:A] Running Loop=1
2018-11-10T15:42:44.462Z,1541864564.462 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-10T15:43:14.948Z,1541864594.948 [Radio_Surface](INFO): Powering down
2018-11-10T15:43:28.713Z,1541864608.713 [Radio_Surface](INFO): Powering up
2018-11-10T15:43:29.404Z,1541864609.404 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-11-10T15:43:29.404Z,1541864609.404 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-11-10T15:43:29.404Z,1541864609.404 [ThrusterServo] Communications Fault, FailCount= 1
2018-11-10T15:43:29.404Z,1541864609.404 [ThrusterServo](ERROR): Communications Fault
2018-11-10T15:43:29.408Z,1541864609.408 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-10T15:43:29.994Z,1541864609.994 [MassServo](FAULT): Mass Shifter uart error: serial timeout
2018-11-10T15:43:29.994Z,1541864609.994 [MassServo] Communications Fault, FailCount= 1
2018-11-10T15:43:29.994Z,1541864609.994 [MassServo](ERROR): Communications Fault
2018-11-10T15:43:29.995Z,1541864609.995 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-10T15:43:29.995Z,1541864609.995 [ThrusterServo](INFO): Powering down
2018-11-10T15:43:30.001Z,1541864610.001 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-10T15:43:30.336Z,1541864610.336 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-10T15:43:30.336Z,1541864610.336 [MassServo](INFO): Powering down
2018-11-10T15:43:30.660Z,1541864610.660 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-10T15:43:30.660Z,1541864610.660 [ThrusterServo] No Fault, FailCount= 1
2018-11-10T15:43:31.087Z,1541864611.087 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:31.087Z,1541864611.087 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-10T15:43:31.124Z,1541864611.124 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-10T15:43:31.125Z,1541864611.125 [MassServo] No Fault, FailCount= 1
2018-11-10T15:43:31.444Z,1541864611.444 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:31.445Z,1541864611.445 [MassServo](DEBUG): Initializing MassServo.
2018-11-10T15:43:32.073Z,1541864612.073 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-10T15:43:32.136Z,1541864612.136 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-10T15:43:32.136Z,1541864612.136 [ThrusterServo] Communications Fault, FailCount= 2
2018-11-10T15:43:32.136Z,1541864612.136 [ThrusterServo](ERROR): Communications Fault
2018-11-10T15:43:32.140Z,1541864612.140 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-10T15:43:32.548Z,1541864612.548 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-10T15:43:32.548Z,1541864612.548 [ThrusterServo](INFO): Powering down
2018-11-10T15:43:33.108Z,1541864613.108 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-10T15:43:33.108Z,1541864613.108 [ThrusterServo] No Fault, FailCount= 2
2018-11-10T15:43:33.491Z,1541864613.491 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:33.496Z,1541864613.496 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-10T15:43:34.324Z,1541864614.324 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-10T15:43:34.388Z,1541864614.388 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-10T15:43:34.388Z,1541864614.388 [ThrusterServo] Communications Fault, FailCount= 3
2018-11-10T15:43:34.388Z,1541864614.388 [ThrusterServo](ERROR): Communications Fault
2018-11-10T15:43:34.399Z,1541864614.399 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-10T15:43:34.892Z,1541864614.892 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-10T15:43:34.892Z,1541864614.892 [ThrusterServo](INFO): Powering down
2018-11-10T15:43:35.753Z,1541864615.753 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-10T15:43:35.753Z,1541864615.753 [ThrusterServo] No Fault, FailCount= 3
2018-11-10T15:43:36.013Z,1541864616.013 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:36.013Z,1541864616.013 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-10T15:43:36.295Z,1541864616.295 [Depth_Keller](INFO): A/D timeout, 8 tries over 125 ms

2018-11-10T15:43:36.295Z,1541864616.295 [Depth_Keller] Data Fault, FailCount= 1
2018-11-10T15:43:36.295Z,1541864616.295 [Depth_Keller](ERROR): Data Fault
2018-11-10T15:43:36.437Z,1541864616.437 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-11-10T15:43:36.456Z,1541864616.456 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-11-10T15:43:36.844Z,1541864616.844 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-10T15:43:36.860Z,1541864616.860 [CTD_NeilBrown](DEBUG): serial timeout
2018-11-10T15:43:36.916Z,1541864616.916 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-10T15:43:36.916Z,1541864616.916 [ThrusterServo] Communications Fault, FailCount= 4
2018-11-10T15:43:36.916Z,1541864616.916 [ThrusterServo](ERROR): Communications Fault
2018-11-10T15:43:36.925Z,1541864616.925 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-10T15:43:36.925Z,1541864616.925 [CBIT](ERROR): Data Fault in component: Depth_Keller
2018-11-10T15:43:36.926Z,1541864616.926 [CBIT](INFO): Clearing failed state for component Depth_Keller
2018-11-10T15:43:36.926Z,1541864616.926 [Depth_Keller] No Fault, FailCount= 1
2018-11-10T15:43:37.177Z,1541864617.177 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-10T15:43:37.177Z,1541864617.177 [ThrusterServo](INFO): Powering down
2018-11-10T15:43:37.308Z,1541864617.308 [CTD_NeilBrown](DEBUG): serial timeout
2018-11-10T15:43:38.032Z,1541864618.032 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-10T15:43:38.033Z,1541864618.033 [ThrusterServo] No Fault, FailCount= 4
2018-11-10T15:43:38.333Z,1541864618.333 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:38.333Z,1541864618.333 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-10T15:43:39.163Z,1541864619.163 [MassServo](ERROR): isCommunicating uart error serial timeout
2018-11-10T15:43:39.164Z,1541864619.164 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout
2018-11-10T15:43:39.164Z,1541864619.164 [MassServo] Hardware Fault, FailCount= 2
2018-11-10T15:43:39.164Z,1541864619.164 [MassServo](ERROR): Hardware Fault
2018-11-10T15:43:39.616Z,1541864619.616 [CBIT](ERROR): Hardware Fault in component: MassServo
2018-11-10T15:43:39.822Z,1541864619.822 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-10T15:43:39.822Z,1541864619.822 [MassServo](INFO): Powering down
2018-11-10T15:43:40.719Z,1541864620.719 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:40.720Z,1541864620.720 [MassServo](DEBUG): Initializing MassServo.
2018-11-10T15:43:40.736Z,1541864620.736 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-10T15:43:40.736Z,1541864620.736 [MassServo] No Fault, FailCount= 2
2018-11-10T15:43:43.396Z,1541864623.396 [MassServo](FAULT): Invalid EZ Servo response:""
2018-11-10T15:43:43.396Z,1541864623.396 [MassServo] Communications Fault, FailCount= 3
2018-11-10T15:43:43.396Z,1541864623.396 [MassServo](ERROR): Communications Fault
2018-11-10T15:43:43.397Z,1541864623.397 [MassServo](FAULT): Mass initialization uart error:no error
2018-11-10T15:43:43.401Z,1541864623.401 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-10T15:43:43.719Z,1541864623.719 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-10T15:43:43.719Z,1541864623.719 [MassServo](INFO): Powering down
2018-11-10T15:43:44.458Z,1541864624.458 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-10T15:42:44.1Z
2018-11-10T15:43:44.458Z,1541864624.458 [Startup:StartupSatComms:A] Stopped
2018-11-10T15:43:44.458Z,1541864624.458 [Startup:StartupSatComms:B] Running Loop=1
2018-11-10T15:43:44.478Z,1541864624.478 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:44.478Z,1541864624.478 [MassServo](DEBUG): Initializing MassServo.
2018-11-10T15:43:44.508Z,1541864624.508 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-10T15:43:44.508Z,1541864624.508 [MassServo] No Fault, FailCount= 3
2018-11-10T15:43:44.680Z,1541864624.680 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-10T15:43:47.116Z,1541864627.116 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2018-11-10T15:43:47.116Z,1541864627.116 [MassServo] Communications Fault, FailCount= 4
2018-11-10T15:43:47.116Z,1541864627.116 [MassServo](ERROR): Communications Fault
2018-11-10T15:43:47.122Z,1541864627.122 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-10T15:43:47.336Z,1541864627.336 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-10T15:43:47.337Z,1541864627.337 [MassServo](INFO): Powering down
2018-11-10T15:43:48.085Z,1541864628.085 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:48.086Z,1541864628.086 [MassServo](DEBUG): Initializing MassServo.
2018-11-10T15:43:48.116Z,1541864628.116 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-10T15:43:48.116Z,1541864628.116 [MassServo] No Fault, FailCount= 4
2018-11-10T15:43:50.032Z,1541864630.032 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2018-11-10T15:43:50.032Z,1541864630.032 [MassServo] Communications Fault, FailCount= 5
2018-11-10T15:43:50.032Z,1541864630.032 [MassServo](ERROR): Communications Fault
2018-11-10T15:43:50.036Z,1541864630.036 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-10T15:43:50.037Z,1541864630.037 [CBIT](CRITICAL): Communications Fault in component: MassServo
2018-11-10T15:43:50.232Z,1541864630.232 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-10T15:43:50.232Z,1541864630.232 [MassServo](INFO): Powering down
2018-11-10T15:43:50.248Z,1541864630.248 [CommandLine](FAULT): Scheduling is paused
2018-11-10T15:43:50.248Z,1541864630.248 [CBIT](INFO): Critical error at 20181110T154350
2018-11-10T15:43:50.248Z,1541864630.248 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-10T15:43:51.130Z,1541864631.130 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-10T15:43:51.130Z,1541864631.130 [MassServo](DEBUG): Initializing MassServo.
2018-11-10T15:43:54.912Z,1541864634.912 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-11-10T15:43:54.912Z,1541864634.912 [PNI_TCM] Data Fault, FailCount= 1
2018-11-10T15:43:54.912Z,1541864634.912 [PNI_TCM](ERROR): Data Fault
2018-11-10T15:43:54.972Z,1541864634.972 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.344940 seconds old.
2018-11-10T15:43:55.006Z,1541864635.006 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-10T15:43:55.297Z,1541864635.297 [PNI_TCM](INFO): Powering down
2018-11-10T15:43:55.309Z,1541864635.309 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.681544 seconds old.
2018-11-10T15:43:55.747Z,1541864635.747 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.119355 seconds old.
2018-11-10T15:43:56.118Z,1541864636.118 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.490632 seconds old.
2018-11-10T15:43:56.620Z,1541864636.620 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.992427 seconds old.
2018-11-10T15:43:56.680Z,1541864636.680 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-10T15:43:56.680Z,1541864636.680 [PNI_TCM] No Fault, FailCount= 1
2018-11-10T15:43:56.943Z,1541864636.943 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.315385 seconds old.
2018-11-10T15:43:57.350Z,1541864637.350 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.722848 seconds old.
2018-11-10T15:43:57.750Z,1541864637.750 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.122512 seconds old.
2018-11-10T15:43:58.140Z,1541864638.140 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.513166 seconds old.
2018-11-10T15:43:58.618Z,1541864638.618 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.990350 seconds old.
2018-11-10T15:43:58.941Z,1541864638.941 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.313757 seconds old.
2018-11-10T15:43:59.334Z,1541864639.334 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.706753 seconds old.
2018-11-10T15:43:59.738Z,1541864639.738 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.110664 seconds old.
2018-11-10T15:44:00.112Z,1541864640.112 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.485225 seconds old.
2018-11-10T15:44:00.746Z,1541864640.746 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.118344 seconds old.
2018-11-10T15:44:01.074Z,1541864641.074 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.446631 seconds old.
2018-11-10T15:44:01.518Z,1541864641.518 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.890493 seconds old.
2018-11-10T15:44:01.791Z,1541864641.791 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.163615 seconds old.
2018-11-10T15:44:02.240Z,1541864642.240 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.613129 seconds old.
2018-11-10T15:44:02.606Z,1541864642.606 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.978682 seconds old.
2018-11-10T15:44:02.950Z,1541864642.950 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.322741 seconds old.
2018-11-10T15:44:03.368Z,1541864643.368 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.740692 seconds old.
2018-11-10T15:44:03.736Z,1541864643.736 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.108674 seconds old.
2018-11-10T15:44:09.340Z,1541864649.340 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-11-10T15:44:09.340Z,1541864649.340 [PNI_TCM] Data Fault, FailCount= 2
2018-11-10T15:44:09.340Z,1541864649.340 [PNI_TCM](ERROR): Data Fault
2018-11-10T15:44:09.400Z,1541864649.400 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.414420 seconds old.
2018-11-10T15:44:09.450Z,1541864649.450 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-10T15:44:09.701Z,1541864649.701 [PNI_TCM](INFO): Powering down
2018-11-10T15:44:09.768Z,1541864649.768 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.782429 seconds old.
2018-11-10T15:44:10.120Z,1541864650.120 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.134945 seconds old.
2018-11-10T15:44:10.530Z,1541864650.530 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.544569 seconds old.
2018-11-10T15:44:10.934Z,1541864650.934 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.948298 seconds old.
2018-11-10T15:44:10.996Z,1541864650.996 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-10T15:44:10.996Z,1541864650.996 [PNI_TCM] No Fault, FailCount= 2
2018-11-10T15:44:11.339Z,1541864651.339 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.353848 seconds old.
2018-11-10T15:44:11.721Z,1541864651.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.735333 seconds old.
2018-11-10T15:44:12.193Z,1541864652.193 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.207341 seconds old.
2018-11-10T15:44:12.553Z,1541864652.553 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.567751 seconds old.
2018-11-10T15:44:12.914Z,1541864652.914 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.928187 seconds old.
2018-11-10T15:44:13.368Z,1541864653.368 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.382301 seconds old.
2018-11-10T15:44:13.369Z,1541864653.369 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-10T15:44:13.369Z,1541864653.369 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-10T15:44:13.369Z,1541864653.369 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-10T15:44:13.444Z,1541864653.444 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-10T15:44:13.787Z,1541864653.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-10T15:44:13.787Z,1541864653.787 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-10T15:44:14.097Z,1541864654.097 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-10T15:44:14.098Z,1541864654.098 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-10T15:44:14.098Z,1541864654.098 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-10T15:44:15.651Z,1541864655.651 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-11-10T15:44:23.702Z,1541864663.702 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2018-11-10T15:44:24.228Z,1541864664.228 [NAL9602](FAULT): received: 
2018-11-10T15:44:24.228Z,1541864664.228 [NAL9602] Data Fault, FailCount= 1
2018-11-10T15:44:24.228Z,1541864664.228 [NAL9602](ERROR): Data Fault
2018-11-10T15:44:24.480Z,1541864664.480 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-10T15:44:24.621Z,1541864664.621 [NAL9602](INFO): Powering down
2018-11-10T15:44:25.529Z,1541864665.529 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-10T15:44:25.529Z,1541864665.529 [NAL9602] No Fault, FailCount= 1
2018-11-10T15:44:34.992Z,1541864674.992 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-11-10T15:44:34.992Z,1541864674.992 [PNI_TCM] Data Fault, FailCount= 1
2018-11-10T15:44:34.992Z,1541864674.992 [PNI_TCM](ERROR): Data Fault
2018-11-10T15:44:35.022Z,1541864675.022 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.450817 seconds old.
2018-11-10T15:44:35.075Z,1541864675.075 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-10T15:44:35.316Z,1541864675.316 [PNI_TCM](INFO): Powering down
2018-11-10T15:44:35.345Z,1541864675.345 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.773982 seconds old.
2018-11-10T15:44:35.762Z,1541864675.762 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.191297 seconds old.
2018-11-10T15:44:36.400Z,1541864676.400 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.829034 seconds old.
2018-11-10T15:44:36.657Z,1541864676.657 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.085983 seconds old.
2018-11-10T15:44:36.740Z,1541864676.740 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-10T15:44:36.740Z,1541864676.740 [PNI_TCM] No Fault, FailCount= 1
2018-11-10T15:44:36.942Z,1541864676.942 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.371268 seconds old.
2018-11-10T15:44:37.332Z,1541864677.332 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.761523 seconds old.
2018-11-10T15:44:37.777Z,1541864677.777 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.205765 seconds old.
2018-11-10T15:44:38.129Z,1541864678.129 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.557374 seconds old.
2018-11-10T15:44:38.614Z,1541864678.614 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.042982 seconds old.
2018-11-10T15:44:38.962Z,1541864678.962 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.391576 seconds old.
2018-11-10T15:44:39.399Z,1541864679.399 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.828236 seconds old.
2018-11-10T15:44:39.766Z,1541864679.766 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.195034 seconds old.
2018-11-10T15:44:40.185Z,1541864680.185 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.613709 seconds old.
2018-11-10T15:44:40.590Z,1541864680.590 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.019195 seconds old.
2018-11-10T15:44:40.950Z,1541864680.950 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.379480 seconds old.
2018-11-10T15:44:41.384Z,1541864681.384 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.804657 seconds old.
2018-11-10T15:44:41.741Z,1541864681.741 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.170319 seconds old.
2018-11-10T15:44:44.651Z,1541864684.651 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-10T15:43:44.5Z
2018-11-10T15:44:44.651Z,1541864684.651 [Startup:StartupSatComms:B] Stopped
2018-11-10T15:44:44.651Z,1541864684.651 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-10T15:44:44.651Z,1541864684.651 [Startup:StartupSatComms] Stopped
2018-11-10T15:44:44.663Z,1541864684.663 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-10T15:44:44.664Z,1541864684.664 [Startup](INFO): Completed Startup
2018-11-10T15:44:44.665Z,1541864684.665 [MissionManager](INFO): Startup is completed.
2018-11-10T15:44:44.665Z,1541864684.665 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-10T15:44:44.665Z,1541864684.665 [Startup] Stopped
2018-11-10T15:44:44.665Z,1541864684.665 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-10T15:44:44.665Z,1541864684.665 [Startup:A.GoToSurface] Stopped
2018-11-10T15:44:44.665Z,1541864684.665 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-10T15:44:44.996Z,1541864684.996 [MissionManager](IMPORTANT): Started mission Default
2018-11-10T15:44:44.997Z,1541864684.997 [Default] Running Loop=1
2018-11-10T15:44:44.997Z,1541864684.997 [Default](DEBUG): Aggregate::initialize Default
2018-11-10T15:44:44.997Z,1541864684.997 [Default:B.GoToSurface] Running Loop=1
2018-11-10T15:44:44.997Z,1541864684.997 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-10T15:44:44.997Z,1541864684.997 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-10T15:44:44.998Z,1541864684.998 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-10T15:44:44.998Z,1541864684.998 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-10T15:44:44.998Z,1541864684.998 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-10T15:44:44.999Z,1541864684.999 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-10T15:44:44.999Z,1541864684.999 [Default:A.Wait] Running Loop=1
2018-11-10T15:44:44.999Z,1541864684.999 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-10T15:44:46.089Z,1541864686.089 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-11-10T15:44:47.364Z,1541864687.364 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-11-10T15:44:47.364Z,1541864687.364 [PNI_TCM] Data Fault, FailCount= 2
2018-11-10T15:44:47.364Z,1541864687.364 [PNI_TCM](ERROR): Data Fault
2018-11-10T15:44:47.390Z,1541864687.390 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.306249 seconds old.
2018-11-10T15:44:47.477Z,1541864687.477 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-10T15:44:47.757Z,1541864687.757 [PNI_TCM](INFO): Powering down
2018-11-10T15:44:47.805Z,1541864687.805 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.720706 seconds old.
2018-11-10T15:44:48.158Z,1541864688.158 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.074029 seconds old.
2018-11-10T15:44:48.558Z,1541864688.558 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.473868 seconds old.
2018-11-10T15:44:49.004Z,1541864689.004 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.920453 seconds old.
2018-11-10T15:44:49.070Z,1541864689.070 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-10T15:44:49.070Z,1541864689.070 [PNI_TCM] No Fault, FailCount= 2
2018-11-10T15:44:49.362Z,1541864689.362 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.277946 seconds old.
2018-11-10T15:44:49.762Z,1541864689.762 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.677914 seconds old.
2018-11-10T15:44:50.309Z,1541864690.309 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.225545 seconds old.
2018-11-10T15:44:50.667Z,1541864690.667 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.582739 seconds old.
2018-11-10T15:44:50.966Z,1541864690.966 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.881855 seconds old.
2018-11-10T15:44:51.374Z,1541864691.374 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.289874 seconds old.
2018-11-10T15:44:51.766Z,1541864691.766 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.682001 seconds old.
2018-11-10T15:44:52.229Z,1541864692.229 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.119401 seconds old.
2018-11-10T15:44:54.276Z,1541864694.276 [CBIT](CRITICAL): run-time exception #7
2018-11-10T15:44:54.277Z,1541864694.277 [CBIT](CRITICAL): Bad address: 0x4041E008
2018-11-10T15:44:54.277Z,1541864694.277 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x169dc4]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x169960]

2018-11-10T15:44:54.277Z,1541864694.277 [CBIT] Software Fault, FailCount= 1
2018-11-10T15:44:54.277Z,1541864694.277 [CBIT](ERROR): Software Fault
2018-11-10T15:44:54.291Z,1541864694.291 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-10T15:44:54.292Z,1541864694.292 [SyncHandler](INFO): Protected caller Thread ID is 9442
2018-11-10T15:44:54.601Z,1541864694.601 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-10T15:44:54.636Z,1541864694.636 [CBIT](CRITICAL): run-time exception #7
2018-11-10T15:44:54.636Z,1541864694.636 [CBIT](CRITICAL): Bad address: 0x4041E004
2018-11-10T15:44:54.636Z,1541864694.636 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x169dc4]

2018-11-10T15:44:54.652Z,1541864694.652 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-10T15:44:54.652Z,1541864694.652 [SyncHandler](INFO): Protected caller Thread ID is 9443
2018-11-10T15:44:54.893Z,1541864694.893 [NAL9602](INFO): Powering up NAL9602
2018-11-10T15:44:58.171Z,1541864698.171 [Default:A.Wait](INFO): Done Waiting.
2018-11-10T15:44:58.176Z,1541864698.176 [Default:A.Wait] Stopped
2018-11-10T15:44:58.176Z,1541864698.176 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-10T15:44:58.580Z,1541864698.580 [Default:CheckIn] Running Loop=1
2018-11-10T15:44:58.580Z,1541864698.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-10T15:44:58.581Z,1541864698.581 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-10T15:44:58.972Z,1541864698.972 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-10T15:44:59.339Z,1541864699.339 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-11-10T15:44:59.340Z,1541864699.340 [PNI_TCM] Data Fault, FailCount= 1
2018-11-10T15:44:59.340Z,1541864699.340 [PNI_TCM](ERROR): Data Fault
2018-11-10T15:44:59.382Z,1541864699.382 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.411791 seconds old.
2018-11-10T15:44:59.718Z,1541864699.718 [PNI_TCM](INFO): Powering down
2018-11-10T15:44:59.810Z,1541864699.810 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.840568 seconds old.
2018-11-10T15:45:00.154Z,1541864700.154 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.184007 seconds old.
2018-11-10T15:45:00.566Z,1541864700.566 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.596239 seconds old.
2018-11-10T15:45:00.944Z,1541864700.944 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.974035 seconds old.
2018-11-10T15:45:01.356Z,1541864701.356 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.385901 seconds old.
2018-11-10T15:45:01.756Z,1541864701.756 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.785900 seconds old.
2018-11-10T15:45:02.172Z,1541864702.172 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.202134 seconds old.
2018-11-10T15:45:02.552Z,1541864702.552 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.581928 seconds old.
2018-11-10T15:45:02.933Z,1541864702.933 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.962705 seconds old.
2018-11-10T15:45:03.327Z,1541864703.327 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.356946 seconds old.
2018-11-10T15:45:03.727Z,1541864703.727 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.757300 seconds old.
2018-11-10T15:45:04.115Z,1541864704.115 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.145220 seconds old.
2018-11-10T15:45:04.539Z,1541864704.539 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.569323 seconds old.
2018-11-10T15:45:04.915Z,1541864704.915 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.945270 seconds old.
2018-11-10T15:45:05.375Z,1541864705.375 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.403938 seconds old.
2018-11-10T15:45:05.739Z,1541864705.739 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.768783 seconds old.
2018-11-10T15:45:06.116Z,1541864706.116 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.146415 seconds old.
2018-11-10T15:45:06.521Z,1541864706.521 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.551260 seconds old.
2018-11-10T15:45:06.921Z,1541864706.921 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.951254 seconds old.
2018-11-10T15:45:07.321Z,1541864707.321 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.350645 seconds old.
2018-11-10T15:45:07.721Z,1541864707.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.751266 seconds old.
2018-11-10T15:45:08.121Z,1541864708.121 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.151281 seconds old.
2018-11-10T15:45:08.521Z,1541864708.521 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.551519 seconds old.
2018-11-10T15:45:08.921Z,1541864708.921 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.951263 seconds old.
2018-11-10T15:45:09.320Z,1541864709.320 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.349932 seconds old.
2018-11-10T15:45:09.715Z,1541864709.715 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.744767 seconds old.
2018-11-10T15:45:10.116Z,1541864710.116 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.145862 seconds old.
2018-11-10T15:45:10.521Z,1541864710.521 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.551270 seconds old.
2018-11-10T15:45:10.921Z,1541864710.921 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.951546 seconds old.
2018-11-10T15:45:11.321Z,1541864711.321 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.351382 seconds old.
2018-11-10T15:45:11.721Z,1541864711.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.751232 seconds old.
2018-11-10T15:45:12.121Z,1541864712.121 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.151536 seconds old.
2018-11-10T15:45:12.521Z,1541864712.521 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.551248 seconds old.
2018-11-10T15:45:12.920Z,1541864712.920 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.950431 seconds old.
2018-11-10T15:45:13.321Z,1541864713.321 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.350646 seconds old.
2018-11-10T15:45:13.721Z,1541864713.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.751259 seconds old.
2018-11-10T15:45:14.120Z,1541864714.120 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.149873 seconds old.
2018-11-10T15:45:14.516Z,1541864714.516 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.545739 seconds old.
2018-11-10T15:45:14.921Z,1541864714.921 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.951243 seconds old.
2018-11-10T15:45:15.322Z,1541864715.322 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 16.352245 seconds old.
2018-11-10T15:45:15.721Z,1541864715.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 16.751528 seconds old.
2018-11-10T15:45:16.093Z,1541864716.093 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-10T15:45:16.094Z,1541864716.094 [NAL9602] Communications Fault, FailCount= 2
2018-11-10T15:45:16.094Z,1541864716.094 [NAL9602](ERROR): Communications Fault
2018-11-10T15:45:16.121Z,1541864716.121 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.151266 seconds old.
2018-11-10T15:45:16.497Z,1541864716.497 [NAL9602](INFO): Powering down
2018-11-10T15:45:16.526Z,1541864716.526 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.556377 seconds old.
2018-11-10T15:45:16.891Z,1541864716.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-11-10T15:45:16.897Z,1541864716.897 [NAL9602](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2018-11-10T15:45:16.897Z,1541864716.897 [NAL9602] Hardware Fault, FailCount= 2
2018-11-10T15:45:16.897Z,1541864716.897 [NAL9602](ERROR): Hardware Fault
2018-11-10T15:45:16.920Z,1541864716.920 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.950259 seconds old.
2018-11-10T15:45:17.325Z,1541864717.325 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 18.355264 seconds old.
2018-11-10T15:45:17.725Z,1541864717.725 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 18.755416 seconds old.
2018-11-10T15:45:18.123Z,1541864718.123 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.152967 seconds old.
2018-11-10T15:45:18.529Z,1541864718.529 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.559285 seconds old.
2018-11-10T15:45:18.929Z,1541864718.929 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.959501 seconds old.
2018-11-10T15:45:19.329Z,1541864719.329 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.359270 seconds old.
2018-11-10T15:45:19.722Z,1541864719.722 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.751999 seconds old.
2018-11-10T15:45:20.133Z,1541864720.133 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.162560 seconds old.
2018-11-10T15:45:20.528Z,1541864720.528 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.557511 seconds old.
2018-11-10T15:45:20.923Z,1541864720.923 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.953075 seconds old.
2018-11-10T15:45:21.289Z,1541864721.289 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.199615
2018-11-10T15:45:21.337Z,1541864721.337 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 22.367433 seconds old.
2018-11-10T15:45:21.723Z,1541864721.723 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 22.752646 seconds old.
2018-11-10T15:45:22.123Z,1541864722.123 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.153107 seconds old.
2018-11-10T15:45:22.534Z,1541864722.534 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.564080 seconds old.
2018-11-10T15:45:22.929Z,1541864722.929 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.959324 seconds old.
2018-11-10T15:45:23.329Z,1541864723.329 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 24.359266 seconds old.
2018-11-10T15:45:23.729Z,1541864723.729 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 24.759522 seconds old.
2018-11-10T15:45:24.129Z,1541864724.129 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.159262 seconds old.
2018-11-10T15:45:24.523Z,1541864724.523 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.552686 seconds old.
2018-11-10T15:45:24.929Z,1541864724.929 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.959011 seconds old.
2018-11-10T15:45:25.329Z,1541864725.329 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 26.359283 seconds old.
2018-11-10T15:45:25.723Z,1541864725.723 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 26.752669 seconds old.
2018-11-10T15:45:26.133Z,1541864726.133 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 27.162549 seconds old.
2018-11-10T15:45:26.529Z,1541864726.529 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 27.559252 seconds old.
2018-11-10T15:45:26.930Z,1541864726.930 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 27.959870 seconds old.
2018-11-10T15:45:27.330Z,1541864727.330 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.359578 seconds old.
2018-11-10T15:45:27.729Z,1541864727.729 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.759275 seconds old.
2018-11-10T15:45:28.124Z,1541864728.124 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 29.154145 seconds old.
2018-11-10T15:45:28.533Z,1541864728.533 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 29.563531 seconds old.
2018-11-10T15:45:28.929Z,1541864728.929 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 29.959249 seconds old.
2018-11-10T15:45:29.329Z,1541864729.329 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 30.359262 seconds old.
2018-11-10T15:45:29.729Z,1541864729.729 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 30.758812 seconds old.
2018-11-10T15:45:30.241Z,1541864730.241 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 31.271233 seconds old.
2018-11-10T15:45:30.529Z,1541864730.529 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 31.558680 seconds old.
2018-11-10T15:45:30.934Z,1541864730.934 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 31.964035 seconds old.
2018-11-10T15:45:31.340Z,1541864731.340 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 32.369764 seconds old.
2018-11-10T15:45:31.713Z,1541864731.713 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 32.743123 seconds old.
2018-11-10T15:45:32.138Z,1541864732.138 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 33.167549 seconds old.
2018-11-10T15:45:32.521Z,1541864732.521 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 33.551275 seconds old.
2018-11-10T15:45:32.916Z,1541864732.916 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 33.946243 seconds old.
2018-11-10T15:45:33.322Z,1541864733.322 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 34.351565 seconds old.
2018-11-10T15:45:33.721Z,1541864733.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 34.751258 seconds old.
2018-11-10T15:45:34.116Z,1541864734.116 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 35.146360 seconds old.
2018-11-10T15:45:34.516Z,1541864734.516 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 35.545520 seconds old.
2018-11-10T15:45:34.922Z,1541864734.922 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 35.952314 seconds old.
2018-11-10T15:45:35.325Z,1541864735.325 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 36.355261 seconds old.
2018-11-10T15:45:35.569Z,1541864735.569 [CommandLine](IMPORTANT): got command quit
2018-11-10T15:45:35.721Z,1541864735.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 36.751507 seconds old.
2018-11-10T15:45:36.122Z,1541864736.122 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 37.151841 seconds old.
2018-11-10T15:45:36.515Z,1541864736.515 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 37.544875 seconds old.
2018-11-10T15:45:36.578Z,1541864736.578 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:36.578Z,1541864736.578 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:36.639Z,1541864736.639 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-10T15:45:36.639Z,1541864736.639 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:36.640Z,1541864736.640 [CommandLine](INFO): Join timeout helper Thread ID is 9449
2018-11-10T15:45:36.640Z,1541864736.640 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-10T15:45:36.641Z,1541864736.641 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:36.641Z,1541864736.641 [NavChartDb](INFO): Join timeout helper Thread ID is 9450
2018-11-10T15:45:36.915Z,1541864736.915 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 37.944937 seconds old.
2018-11-10T15:45:37.012Z,1541864737.012 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:37.012Z,1541864737.012 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.035Z,1541864737.035 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-10T15:45:37.036Z,1541864737.036 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.036Z,1541864737.036 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9451
2018-11-10T15:45:37.192Z,1541864737.192 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:37.192Z,1541864737.192 [WetLabsBB2FL](INFO): Powering down
2018-11-10T15:45:37.193Z,1541864737.193 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.195Z,1541864737.195 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-10T15:45:37.196Z,1541864737.196 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.196Z,1541864737.196 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9452
2018-11-10T15:45:37.321Z,1541864737.321 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 38.351268 seconds old.
2018-11-10T15:45:37.500Z,1541864737.500 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:37.500Z,1541864737.500 [CTD_NeilBrown](INFO): Powering down
2018-11-10T15:45:37.501Z,1541864737.501 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.503Z,1541864737.503 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-11-10T15:45:37.504Z,1541864737.504 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.504Z,1541864737.504 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 9453
2018-11-10T15:45:37.721Z,1541864737.721 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 38.751249 seconds old.
2018-11-10T15:45:37.736Z,1541864737.736 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:37.736Z,1541864737.736 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-10T15:45:37.866Z,1541864737.866 [Rowe_600LCM](INFO): Powering down
2018-11-10T15:45:37.867Z,1541864737.867 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.869Z,1541864737.869 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-10T15:45:37.869Z,1541864737.869 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:37.869Z,1541864737.869 [Radio_Surface](INFO): Join timeout helper Thread ID is 9455
2018-11-10T15:45:38.130Z,1541864738.130 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 39.160307 seconds old.
2018-11-10T15:45:38.160Z,1541864738.160 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:38.160Z,1541864738.160 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.172Z,1541864738.172 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-10T15:45:38.172Z,1541864738.172 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.173Z,1541864738.173 [logger](INFO): Join timeout helper Thread ID is 9456
2018-11-10T15:45:38.196Z,1541864738.196 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:38.197Z,1541864738.197 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.204Z,1541864738.204 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-10T15:45:38.204Z,1541864738.204 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.204Z,1541864738.204 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-10T15:45:38.204Z,1541864738.204 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.205Z,1541864738.205 [controlThread](INFO): Join timeout helper Thread ID is 9457
2018-11-10T15:45:38.484Z,1541864738.484 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-10T15:45:38.484Z,1541864738.484 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-10T15:45:38.485Z,1541864738.485 [NAL9602](INFO): Powering down
2018-11-10T15:45:38.486Z,1541864738.486 [PNI_TCM](INFO): Powering down
2018-11-10T15:45:38.488Z,1541864738.488 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-10T15:45:38.488Z,1541864738.488 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-10T15:45:38.489Z,1541864738.489 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-10T15:45:38.489Z,1541864738.489 [MissionManager](INFO): Uninitializing Mission Default
2018-11-10T15:45:38.489Z,1541864738.489 [Default] Stopped
2018-11-10T15:45:38.489Z,1541864738.489 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-10T15:45:38.490Z,1541864738.490 [Default:B.GoToSurface] Stopped
2018-11-10T15:45:38.490Z,1541864738.490 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-10T15:45:38.490Z,1541864738.490 [Default:CheckIn] Stopped
2018-11-10T15:45:38.490Z,1541864738.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-10T15:45:38.490Z,1541864738.490 [Default:CheckIn:Read_GPS] Stopped
2018-11-10T15:45:38.493Z,1541864738.493 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-10T15:45:38.494Z,1541864738.494 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-10T15:45:38.494Z,1541864738.494 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-10T15:45:38.494Z,1541864738.494 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-10T15:45:38.494Z,1541864738.494 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-10T15:45:38.495Z,1541864738.495 [BuoyancyServo](INFO): Powering down
2018-11-10T15:45:38.508Z,1541864738.508 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-10T15:45:38.508Z,1541864738.508 [ElevatorServo](INFO): Powering down
2018-11-10T15:45:38.509Z,1541864738.509 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-10T15:45:38.509Z,1541864738.509 [MassServo](INFO): Powering down
2018-11-10T15:45:38.510Z,1541864738.510 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-10T15:45:38.510Z,1541864738.510 [RudderServo](INFO): Powering down
2018-11-10T15:45:38.511Z,1541864738.511 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-10T15:45:38.511Z,1541864738.511 [ThrusterServo](INFO): Powering down
2018-11-10T15:45:38.512Z,1541864738.512 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-10T15:45:38.512Z,1541864738.512 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-10T15:45:38.513Z,1541864738.513 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-10T15:45:38.526Z,1541864738.526 [CBIT](CRITICAL): run-time exception #7
2018-11-10T15:45:38.527Z,1541864738.527 [CBIT](CRITICAL): Bad address: 0x4041E004
2018-11-10T15:45:38.527Z,1541864738.527 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x169dc4]

2018-11-10T15:45:38.528Z,1541864738.528 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-10T15:45:38.529Z,1541864738.529 [SyncHandler](INFO): Protected caller Thread ID is 9458
2018-11-10T15:45:38.530Z,1541864738.530 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.709Z,1541864738.709 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.715Z,1541864738.715 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.752Z,1541864738.752 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.756Z,1541864738.756 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.782Z,1541864738.782 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-10T15:45:38.842Z,1541864738.842 [logger ThreadHandler](INFO): Thread cancelled.