2015-09-10T23:16:58.353Z,1441927018.353 [Supervisor](DEBUG): Initializing supervisor.
2015-09-10T23:16:58.357Z,1441927018.357 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-09-10T23:16:58.357Z,1441927018.357 [SyncHandler](INFO): Protected caller Thread ID is 1109
2015-09-10T23:16:58.358Z,1441927018.358 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-09-10T23:16:58.359Z,1441927018.359 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-09-10T23:16:58.360Z,1441927018.360 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1110
2015-09-10T23:16:58.363Z,1441927018.363 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-09-10T23:16:58.375Z,1441927018.375 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-09-10T23:16:58.376Z,1441927018.376 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-09-10T23:16:58.377Z,1441927018.377 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1111
2015-09-10T23:16:58.377Z,1441927018.377 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-09-10T23:16:58.378Z,1441927018.378 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-09-10T23:16:58.379Z,1441927018.379 [logger ThreadHandler](INFO): Protected caller Thread ID is 1112
2015-09-10T23:16:58.381Z,1441927018.381 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-09-10T23:16:58.381Z,1441927018.381 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-09-10T23:16:58.386Z,1441927018.386 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-09-10T23:16:58.931Z,1441927018.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-09-10T23:16:58.932Z,1441927018.932 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-09-10T23:16:59.108Z,1441927019.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-09-10T23:16:59.109Z,1441927019.109 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-09-10T23:16:59.242Z,1441927019.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-09-10T23:16:59.243Z,1441927019.243 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-09-10T23:16:59.379Z,1441927019.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-09-10T23:16:59.380Z,1441927019.380 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-09-10T23:16:59.469Z,1441927019.469 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-09-10T23:16:59.668Z,1441927019.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-09-10T23:16:59.669Z,1441927019.669 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-09-10T23:16:59.758Z,1441927019.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-09-10T23:16:59.759Z,1441927019.759 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-09-10T23:17:00.043Z,1441927020.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-09-10T23:17:00.044Z,1441927020.044 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-09-10T23:17:00.406Z,1441927020.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-09-10T23:17:00.406Z,1441927020.406 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-09-10T23:17:00.726Z,1441927020.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-09-10T23:17:00.728Z,1441927020.728 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-09-10T23:17:01.265Z,1441927021.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-09-10T23:17:01.266Z,1441927021.266 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-09-10T23:17:01.495Z,1441927021.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-09-10T23:17:01.496Z,1441927021.496 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-09-10T23:17:01.603Z,1441927021.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-09-10T23:17:01.604Z,1441927021.604 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-09-10T23:17:02.083Z,1441927022.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-09-10T23:17:02.084Z,1441927022.084 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-09-10T23:17:02.229Z,1441927022.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-09-10T23:17:02.231Z,1441927022.231 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-09-10T23:17:02.233Z,1441927022.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-09-10T23:17:02.480Z,1441927022.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-09-10T23:17:02.481Z,1441927022.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-09-10T23:17:02.604Z,1441927022.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-09-10T23:17:02.704Z,1441927022.704 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-09-10T23:17:02.806Z,1441927022.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-09-10T23:17:02.958Z,1441927022.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-09-10T23:17:03.104Z,1441927023.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-09-10T23:17:03.210Z,1441927023.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-09-10T23:17:03.304Z,1441927023.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-09-10T23:17:03.390Z,1441927023.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-09-10T23:17:03.491Z,1441927023.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-09-10T23:17:03.677Z,1441927023.677 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2015-09-10T23:17:03.678Z,1441927023.678 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2015-09-10T23:17:03.681Z,1441927023.681 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-09-10T23:17:03.744Z,1441927023.744 [VerticalControl](DEBUG): Construct VerticalControl.
2015-09-10T23:17:03.866Z,1441927023.866 [VerticalControl] Loaded
2015-09-10T23:17:03.866Z,1441927023.866 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-09-10T23:17:03.867Z,1441927023.867 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-09-10T23:17:03.939Z,1441927023.939 [HorizontalControl] Loaded
2015-09-10T23:17:03.940Z,1441927023.940 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-09-10T23:17:03.940Z,1441927023.940 [SpeedControl](DEBUG): Construct SpeedControl.
2015-09-10T23:17:03.946Z,1441927023.946 [SpeedControl] Loaded
2015-09-10T23:17:03.947Z,1441927023.947 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-09-10T23:17:03.947Z,1441927023.947 [LoopControl](DEBUG): Construct LoopControl.
2015-09-10T23:17:03.948Z,1441927023.948 [LoopControl] Loaded
2015-09-10T23:17:03.948Z,1441927023.948 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-09-10T23:17:03.949Z,1441927023.949 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-09-10T23:17:03.949Z,1441927023.949 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-09-10T23:17:04.090Z,1441927024.090 [SBIT](DEBUG): Construct Startup Built In Test.
2015-09-10T23:17:04.102Z,1441927024.102 [SBIT] Loaded
2015-09-10T23:17:04.102Z,1441927024.102 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-09-10T23:17:04.103Z,1441927024.103 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-09-10T23:17:04.134Z,1441927024.134 [IBIT] Loaded
2015-09-10T23:17:04.134Z,1441927024.134 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-09-10T23:17:04.136Z,1441927024.136 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-09-10T23:17:04.278Z,1441927024.278 [CBIT] Loaded
2015-09-10T23:17:04.279Z,1441927024.279 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-09-10T23:17:04.279Z,1441927024.279 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-09-10T23:17:04.280Z,1441927024.280 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-09-10T23:17:04.314Z,1441927024.314 [DepthRateCalculator] Loaded
2015-09-10T23:17:04.314Z,1441927024.314 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-09-10T23:17:04.320Z,1441927024.320 [PitchRateCalculator] Loaded
2015-09-10T23:17:04.320Z,1441927024.320 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-09-10T23:17:04.332Z,1441927024.332 [SpeedCalculator] Loaded
2015-09-10T23:17:04.332Z,1441927024.332 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-09-10T23:17:04.349Z,1441927024.349 [TempGradientCalculator] Loaded
2015-09-10T23:17:04.349Z,1441927024.349 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-09-10T23:17:04.366Z,1441927024.366 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2015-09-10T23:17:04.366Z,1441927024.366 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2015-09-10T23:17:04.372Z,1441927024.372 [YawRateCalculator] Loaded
2015-09-10T23:17:04.372Z,1441927024.372 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-09-10T23:17:04.373Z,1441927024.373 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-09-10T23:17:04.373Z,1441927024.373 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-09-10T23:17:04.479Z,1441927024.479 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2015-09-10T23:17:04.479Z,1441927024.479 [StratificationFrontDetector](DEBUG): (re)initializing
2015-09-10T23:17:04.479Z,1441927024.479 [StratificationFrontDetector] Loaded
2015-09-10T23:17:04.479Z,1441927024.479 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2015-09-10T23:17:04.480Z,1441927024.480 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-09-10T23:17:04.480Z,1441927024.480 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-09-10T23:17:04.732Z,1441927024.732 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-09-10T23:17:04.733Z,1441927024.733 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-09-10T23:17:04.843Z,1441927024.843 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-09-10T23:17:04.844Z,1441927024.844 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-09-10T23:17:04.900Z,1441927024.900 [DeadReckonUsingSpeedCalculator] Loaded
2015-09-10T23:17:04.900Z,1441927024.900 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-09-10T23:17:04.918Z,1441927024.918 [NavChart] Loaded
2015-09-10T23:17:04.918Z,1441927024.918 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-09-10T23:17:04.922Z,1441927024.922 [UniversalFixResidualReporter] Loaded
2015-09-10T23:17:04.923Z,1441927024.923 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-09-10T23:17:04.923Z,1441927024.923 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-09-10T23:17:04.924Z,1441927024.924 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-09-10T23:17:04.936Z,1441927024.936 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-09-10T23:17:04.936Z,1441927024.936 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-09-10T23:17:05.155Z,1441927025.155 [Aanderaa_O2] Loaded
2015-09-10T23:17:05.156Z,1441927025.156 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-09-10T23:17:05.236Z,1441927025.236 [CTD_NeilBrown] Loaded
2015-09-10T23:17:05.236Z,1441927025.236 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-09-10T23:17:05.237Z,1441927025.237 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059B4E0
2015-09-10T23:17:05.238Z,1441927025.238 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1191
2015-09-10T23:17:05.256Z,1441927025.256 [ESPComponent] Loaded
2015-09-10T23:17:05.256Z,1441927025.256 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2015-09-10T23:17:05.271Z,1441927025.271 [PAR_Licor] Loaded
2015-09-10T23:17:05.271Z,1441927025.271 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-09-10T23:17:05.323Z,1441927025.323 [WetLabsBB2FL] Loaded
2015-09-10T23:17:05.323Z,1441927025.323 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-09-10T23:17:05.324Z,1441927025.324 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CB4E0
2015-09-10T23:17:05.324Z,1441927025.324 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1192
2015-09-10T23:17:05.325Z,1441927025.325 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-09-10T23:17:05.325Z,1441927025.325 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-09-10T23:17:05.670Z,1441927025.670 [AcousticModem_Benthos_ATM900] Loaded
2015-09-10T23:17:05.670Z,1441927025.670 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2015-09-10T23:17:05.744Z,1441927025.744 [DataOverHttps] Loaded
2015-09-10T23:17:05.745Z,1441927025.745 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-09-10T23:17:05.759Z,1441927025.759 [Depth_Keller] Loaded
2015-09-10T23:17:05.759Z,1441927025.759 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-09-10T23:17:05.765Z,1441927025.765 [DropWeight] Loaded
2015-09-10T23:17:05.765Z,1441927025.765 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-09-10T23:17:05.862Z,1441927025.862 [NAL9602] Loaded
2015-09-10T23:17:05.863Z,1441927025.863 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-09-10T23:17:05.905Z,1441927025.905 [Onboard] Loaded
2015-09-10T23:17:05.906Z,1441927025.906 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-09-10T23:17:05.913Z,1441927025.913 [Radio_Surface] Loaded
2015-09-10T23:17:05.913Z,1441927025.913 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-09-10T23:17:05.914Z,1441927025.914 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4080F4E0
2015-09-10T23:17:05.914Z,1441927025.914 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1193
2015-09-10T23:17:05.963Z,1441927025.963 [PNI_TCM] Loaded
2015-09-10T23:17:05.964Z,1441927025.964 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-09-10T23:17:06.151Z,1441927026.151 [Rowe_600] Loaded
2015-09-10T23:17:06.151Z,1441927026.151 [ComponentRegistry](DEBUG): Component "Rowe_600" handled in its own thread.
2015-09-10T23:17:06.152Z,1441927026.152 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 4083F4E0
2015-09-10T23:17:06.153Z,1441927026.153 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 1194
2015-09-10T23:17:08.070Z,1441927028.070 [BPC1] Loaded
2015-09-10T23:17:08.070Z,1441927028.070 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-09-10T23:17:08.070Z,1441927028.070 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-09-10T23:17:08.071Z,1441927028.071 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-09-10T23:17:08.187Z,1441927028.187 [BuoyancyServo] Loaded
2015-09-10T23:17:08.187Z,1441927028.187 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-09-10T23:17:08.200Z,1441927028.200 [ElevatorServo] Loaded
2015-09-10T23:17:08.201Z,1441927028.201 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-09-10T23:17:08.213Z,1441927028.213 [MassServo] Loaded
2015-09-10T23:17:08.213Z,1441927028.213 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-09-10T23:17:08.225Z,1441927028.225 [RudderServo] Loaded
2015-09-10T23:17:08.226Z,1441927028.226 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-09-10T23:17:08.237Z,1441927028.237 [ThrusterServo] Loaded
2015-09-10T23:17:08.238Z,1441927028.238 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-09-10T23:17:08.238Z,1441927028.238 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-09-10T23:17:08.239Z,1441927028.239 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-09-10T23:17:08.363Z,1441927028.363 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-09-10T23:17:08.364Z,1441927028.364 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-09-10T23:17:08.399Z,1441927028.399 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-09-10T23:17:08.403Z,1441927028.403 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-09-10T23:17:08.404Z,1441927028.404 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-09-10T23:17:08.411Z,1441927028.411 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-09-10T23:17:08.412Z,1441927028.412 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2015-09-10T23:17:08.412Z,1441927028.412 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1195
2015-09-10T23:17:08.417Z,1441927028.417 [Supervisor](INFO): Main Thread ID is 764
2015-09-10T23:17:08.418Z,1441927028.418 [Supervisor](DEBUG): Running supervisor.
2015-09-10T23:17:08.418Z,1441927028.418 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1196
2015-09-10T23:17:08.421Z,1441927028.421 [controlThread ThreadHandler](INFO): Handler Thread ID is 1197
2015-09-10T23:17:08.422Z,1441927028.422 [controlThread](DEBUG): Initializing ControlThread
2015-09-10T23:17:08.423Z,1441927028.423 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-09-10T23:17:08.424Z,1441927028.424 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-09-10T23:17:08.425Z,1441927028.425 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-09-10T23:17:08.426Z,1441927028.426 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-09-10T23:17:08.426Z,1441927028.426 [SBIT](INFO): Initialize SBIT Component.
2015-09-10T23:17:08.427Z,1441927028.427 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12608
2015-09-10T23:17:08.427Z,1441927028.427 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-09-10T23:17:08.427Z,1441927028.427 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-09-10T23:17:08.428Z,1441927028.428 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2015-09-10T23:17:08.429Z,1441927028.429 [IBIT](INFO): Initialize IBIT Component.
2015-09-10T23:17:08.429Z,1441927028.429 [CBIT](DEBUG): Initialize CBIT Component.
2015-09-10T23:17:08.430Z,1441927028.430 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-09-10T23:17:08.431Z,1441927028.431 [logger ThreadHandler](INFO): Handler Thread ID is 1198
2015-09-10T23:17:08.450Z,1441927028.450 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1199
2015-09-10T23:17:08.450Z,1441927028.450 [CTD_NeilBrown](INFO): Powering down
2015-09-10T23:17:08.453Z,1441927028.453 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-09-10T23:17:08.454Z,1441927028.454 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-09-10T23:17:08.454Z,1441927028.454 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-09-10T23:17:08.454Z,1441927028.454 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-09-10T23:17:08.455Z,1441927028.455 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2015-09-10T23:17:08.455Z,1441927028.455 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-09-10T23:17:08.456Z,1441927028.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-09-10T23:17:08.456Z,1441927028.456 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:17:08.457Z,1441927028.457 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:17:08.458Z,1441927028.458 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-09-10T23:17:08.458Z,1441927028.458 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:17:08.459Z,1441927028.459 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:17:08.459Z,1441927028.459 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-09-10T23:17:08.459Z,1441927028.459 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-09-10T23:17:08.481Z,1441927028.481 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-09-10T23:17:08.506Z,1441927028.506 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1200
2015-09-10T23:17:08.507Z,1441927028.507 [WetLabsBB2FL](INFO): Powering down
2015-09-10T23:17:08.529Z,1441927028.529 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-09-10T23:17:08.538Z,1441927028.538 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1201
2015-09-10T23:17:08.554Z,1441927028.554 [Radio_Surface](INFO): Powering up
2015-09-10T23:17:08.560Z,1441927028.560 [MissionManager](DEBUG):
2015-09-10T23:17:08.561Z,1441927028.561 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-09-10T23:17:08.566Z,1441927028.566 [Rowe_600 ThreadHandler](INFO): Handler Thread ID is 1202
2015-09-10T23:17:08.578Z,1441927028.578 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1203
2015-09-10T23:17:08.583Z,1441927028.583 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-09-10T23:17:08.586Z,1441927028.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-09-10T23:17:08.586Z,1441927028.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-09-10T23:17:08.586Z,1441927028.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-09-10T23:17:08.586Z,1441927028.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-09-10T23:17:08.587Z,1441927028.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-09-10T23:17:08.587Z,1441927028.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-09-10T23:17:08.587Z,1441927028.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-09-10T23:17:08.588Z,1441927028.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-09-10T23:17:08.637Z,1441927028.637 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-09-10T23:17:08.662Z,1441927028.662 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-09-10T23:17:08.671Z,1441927028.671 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-09-10T23:17:08.690Z,1441927028.690 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2015-09-10T23:17:08.696Z,1441927028.696 [Default:D.Execute](DEBUG): Construct Execute.
2015-09-10T23:17:08.716Z,1441927028.716 [MissionManager](DEBUG):
0
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2015-09-10T23:17:08.722Z,1441927028.722 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-09-10T23:17:08.818Z,1441927028.818 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-09-10T23:17:08.818Z,1441927028.818 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-09-10T23:17:08.834Z,1441927028.834 [Rowe_600](INFO): Powering down
2015-09-10T23:17:09.091Z,1441927029.091 [Rowe_600](INFO): Initializing
2015-09-10T23:17:09.094Z,1441927029.094 [Rowe_600](INFO): Powering up
2015-09-10T23:17:09.411Z,1441927029.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:09.427Z,1441927029.427 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:09.536Z,1441927029.536 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-09-10T23:17:09.543Z,1441927029.543 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-09-10T23:17:09.565Z,1441927029.565 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-09-10T23:17:09.571Z,1441927029.571 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-09-10T23:17:09.585Z,1441927029.585 [MassServo](DEBUG): Initializing EZServoServo.
2015-09-10T23:17:09.591Z,1441927029.591 [MassServo](DEBUG): Initializing MassServo.
2015-09-10T23:17:09.609Z,1441927029.609 [RudderServo](DEBUG): Initializing EZServoServo.
2015-09-10T23:17:09.615Z,1441927029.615 [RudderServo](DEBUG): Initializing RudderServo.
2015-09-10T23:17:09.629Z,1441927029.629 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-09-10T23:17:09.636Z,1441927029.636 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-09-10T23:17:09.907Z,1441927029.907 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:09.909Z,1441927029.909 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:10.185Z,1441927030.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:10.187Z,1441927030.187 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:10.516Z,1441927030.516 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2015-09-10T23:17:10.517Z,1441927030.517 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2015-09-10T23:17:10.517Z,1441927030.517 [BuoyancyServo] Communications Fault, FailCount= 1
2015-09-10T23:17:10.517Z,1441927030.517 [BuoyancyServo](ERROR): Communications Fault
2015-09-10T23:17:10.723Z,1441927030.723 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2015-09-10T23:17:10.812Z,1441927030.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:10.825Z,1441927030.825 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:10.835Z,1441927030.835 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-09-10T23:17:10.836Z,1441927030.836 [BuoyancyServo](INFO): Powering down
2015-09-10T23:17:11.159Z,1441927031.159 [NAL9602](INFO): Powering up NAL9602
2015-09-10T23:17:11.234Z,1441927031.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:11.235Z,1441927031.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:11.765Z,1441927031.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:11.770Z,1441927031.770 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:11.999Z,1441927031.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:12.011Z,1441927032.011 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-10T23:17:12.971Z,1441927032.971 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2015-09-10T23:17:12.971Z,1441927032.971 [BuoyancyServo] No Fault, FailCount= 1
2015-09-10T23:17:13.270Z,1441927033.270 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-09-10T23:17:13.390Z,1441927033.390 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-09-10T23:17:16.110Z,1441927036.110 [AcousticModem_Benthos_ATM900](DEBUG):
2015-09-10T23:17:17.309Z,1441927037.309 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2015-09-10T23:17:17.310Z,1441927037.310 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-09-10T23:17:17.717Z,1441927037.717 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2015-09-10T23:17:18.110Z,1441927038.110 [AcousticModem_Benthos_ATM900](DEBUG): Sep 10 2015 23:19:31
2015-09-10T23:17:18.905Z,1441927038.905 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:17:18.905Z,1441927038.905 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-09-10T23:17:18.905Z,1441927038.905 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:17:18.910Z,1441927038.910 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-09-10T23:17:19.037Z,1441927039.037 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:17:19.422Z,1441927039.422 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:17:19.488Z,1441927039.488 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-09-10T23:17:19.489Z,1441927039.489 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2015-09-10T23:17:19.489Z,1441927039.489 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2015-09-10T23:17:19.708Z,1441927039.708 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2015-09-10T23:17:20.108Z,1441927040.108 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2015-09-10T23:17:20.109Z,1441927040.109 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2015-09-10T23:17:20.235Z,1441927040.235 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:17:20.235Z,1441927040.235 [Aanderaa_O2] No Fault, FailCount= 1
2015-09-10T23:17:20.522Z,1441927040.522 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2015-09-10T23:17:22.112Z,1441927042.112 [NAL9602](INFO): NAL9602 initialized
2015-09-10T23:17:30.903Z,1441927050.903 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:17:30.903Z,1441927050.903 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-09-10T23:17:30.904Z,1441927050.904 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:17:31.020Z,1441927051.020 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:17:31.421Z,1441927051.421 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:17:32.289Z,1441927052.289 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:17:32.289Z,1441927052.289 [Aanderaa_O2] No Fault, FailCount= 2
2015-09-10T23:17:39.773Z,1441927059.773 [BPC1](ERROR): Failed to receive battery data
2015-09-10T23:17:39.773Z,1441927059.773 [BPC1] Communications Fault, FailCount= 1
2015-09-10T23:17:39.773Z,1441927059.773 [BPC1](ERROR): Communications Fault
2015-09-10T23:17:39.912Z,1441927059.912 [CBIT](ERROR): Communications Fault in component: BPC1
2015-09-10T23:17:41.063Z,1441927061.063 [CBIT](INFO): Clearing failed state for component BPC1
2015-09-10T23:17:41.064Z,1441927061.064 [BPC1] No Fault, FailCount= 1
2015-09-10T23:17:42.902Z,1441927062.902 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:17:42.902Z,1441927062.902 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-09-10T23:17:42.902Z,1441927062.902 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:17:43.099Z,1441927063.099 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:17:43.099Z,1441927063.099 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-09-10T23:17:43.420Z,1441927063.420 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:17:53.232Z,1441927073.232 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 3.000000 centimeter
2015-09-10T23:17:53.233Z,1441927073.233 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2015-09-10T23:17:53.429Z,1441927073.429 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-09-10T23:18:11.874Z,1441927091.874 [SBIT](IMPORTANT): Beginning Startup BIT
2015-09-10T23:18:11.877Z,1441927091.877 [CBIT](IMPORTANT): Beginning GF scan
2015-09-10T23:18:25.083Z,1441927105.083 [SBIT](FAULT): Mass: EXPECTED:0.017000 ACTUAL:0.029999
2015-09-10T23:18:25.083Z,1441927105.083 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.773485
2015-09-10T23:18:38.226Z,1441927118.226 [CBIT](IMPORTANT): No ground fault detected
2015-09-10T23:18:51.829Z,1441927131.829 [SBIT](FAULT): Mass: EXPECTED:0.008400 ACTUAL:0.029999
2015-09-10T23:18:51.829Z,1441927131.829 [SBIT](FAULT): Control surface position failure.
2015-09-10T23:19:05.462Z,1441927145.462 [SBIT](FAULT): Mass: EXPECTED:0.011000 ACTUAL:0.029999
2015-09-10T23:19:05.462Z,1441927145.462 [SBIT](FAULT): Control surface position failure.
2015-09-10T23:19:05.821Z,1441927145.821 [SBIT](CRITICAL): SBIT FAILED
2015-09-10T23:19:05.822Z,1441927145.822 [CommandLine](FAULT): Scheduling is paused
2015-09-10T23:19:06.214Z,1441927146.214 [MissionManager](IMPORTANT): Started mission Startup
2015-09-10T23:19:06.215Z,1441927146.215 [Startup] Running Loop=1
2015-09-10T23:19:06.215Z,1441927146.215 [Startup](DEBUG): Aggregate::initialize Startup
2015-09-10T23:19:06.215Z,1441927146.215 [Startup:A.GoToSurface] Running Loop=1
2015-09-10T23:19:06.215Z,1441927146.215 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-09-10T23:19:06.216Z,1441927146.216 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-09-10T23:19:06.216Z,1441927146.216 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-09-10T23:19:06.217Z,1441927146.217 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-09-10T23:19:06.223Z,1441927146.223 [Startup:StartupSatComms] Running Loop=1
2015-09-10T23:19:06.223Z,1441927146.223 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2015-09-10T23:19:06.223Z,1441927146.223 [Startup:StartupSatComms:A] Running Loop=1
2015-09-10T23:19:06.614Z,1441927146.614 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-09-10T23:20:06.239Z,1441927206.239 [Startup:StartupSatComms:A](INFO): Timed out from 2015-09-10T23:19:06.2Z
2015-09-10T23:20:06.239Z,1441927206.239 [Startup:StartupSatComms:A] Stopped
2015-09-10T23:20:06.239Z,1441927206.239 [Startup:StartupSatComms:B] Running Loop=1
2015-09-10T23:20:06.641Z,1441927206.641 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-09-10T23:20:08.605Z,1441927208.605 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-09-10T23:20:08.606Z,1441927208.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-09-10T23:20:08.606Z,1441927208.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-09-10T23:20:08.607Z,1441927208.607 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-09-10T23:20:08.608Z,1441927208.608 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2015-09-10T23:20:08.608Z,1441927208.608 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-09-10T23:20:08.632Z,1441927208.632 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-09-10T23:20:08.632Z,1441927208.632 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-09-10T23:20:09.012Z,1441927209.012 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-09-10T23:20:09.013Z,1441927209.013 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-09-10T23:20:09.013Z,1441927209.013 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-09-10T23:20:09.013Z,1441927209.013 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2015-09-10T23:20:09.403Z,1441927209.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-09-10T23:20:09.403Z,1441927209.403 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:20:09.404Z,1441927209.404 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:20:09.404Z,1441927209.404 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-09-10T23:20:09.404Z,1441927209.404 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:20:09.405Z,1441927209.405 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:20:09.802Z,1441927209.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2015-09-10T23:20:10.210Z,1441927210.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2015-09-10T23:20:10.604Z,1441927210.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2015-09-10T23:20:11.005Z,1441927211.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2015-09-10T23:20:11.421Z,1441927211.421 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2015-09-10T23:20:11.820Z,1441927211.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2015-09-10T23:20:12.222Z,1441927212.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2015-09-10T23:20:12.622Z,1441927212.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2015-09-10T23:20:13.004Z,1441927213.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2015-09-10T23:20:13.017Z,1441927213.017 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:20:13.017Z,1441927213.017 [Aanderaa_O2] No Fault, FailCount= 3
2015-09-10T23:20:13.407Z,1441927213.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2015-09-10T23:20:13.806Z,1441927213.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2015-09-10T23:20:14.207Z,1441927214.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2015-09-10T23:20:14.608Z,1441927214.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2015-09-10T23:20:15.009Z,1441927215.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2015-09-10T23:20:15.477Z,1441927215.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2015-09-10T23:20:15.819Z,1441927215.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2015-09-10T23:20:16.213Z,1441927216.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2015-09-10T23:20:16.654Z,1441927216.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2015-09-10T23:20:17.025Z,1441927217.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2015-09-10T23:20:17.461Z,1441927217.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2015-09-10T23:20:17.854Z,1441927217.854 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2015-09-10T23:20:18.216Z,1441927218.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2015-09-10T23:20:18.616Z,1441927218.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2015-09-10T23:20:19.009Z,1441927219.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2015-09-10T23:20:19.419Z,1441927219.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2015-09-10T23:20:19.802Z,1441927219.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2015-09-10T23:20:20.202Z,1441927220.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2015-09-10T23:20:20.599Z,1441927220.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2015-09-10T23:20:20.999Z,1441927220.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2015-09-10T23:20:21.399Z,1441927221.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2015-09-10T23:20:21.835Z,1441927221.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2015-09-10T23:20:22.228Z,1441927222.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2015-09-10T23:20:22.636Z,1441927222.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2015-09-10T23:20:23.031Z,1441927223.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2015-09-10T23:20:23.399Z,1441927223.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2015-09-10T23:20:23.765Z,1441927223.765 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:20:23.765Z,1441927223.765 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-09-10T23:20:23.765Z,1441927223.765 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:20:23.802Z,1441927223.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2015-09-10T23:20:23.843Z,1441927223.843 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:20:24.286Z,1441927224.286 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:20:24.313Z,1441927224.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2015-09-10T23:20:24.603Z,1441927224.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2015-09-10T23:20:25.007Z,1441927225.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2015-09-10T23:20:25.037Z,1441927225.037 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:20:25.037Z,1441927225.037 [Aanderaa_O2] No Fault, FailCount= 1
2015-09-10T23:20:25.404Z,1441927225.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2015-09-10T23:20:25.807Z,1441927225.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2015-09-10T23:20:26.202Z,1441927226.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2015-09-10T23:20:26.606Z,1441927226.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2015-09-10T23:20:27.045Z,1441927227.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2015-09-10T23:20:27.420Z,1441927227.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2015-09-10T23:20:27.822Z,1441927227.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2015-09-10T23:20:28.226Z,1441927228.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2015-09-10T23:20:28.603Z,1441927228.603 [BPC1](ERROR): Failed to parse bank A battery data
2015-09-10T23:20:28.603Z,1441927228.603 [BPC1] Data Fault, FailCount= 1
2015-09-10T23:20:28.603Z,1441927228.603 [BPC1](ERROR): Data Fault
2015-09-10T23:20:28.623Z,1441927228.623 [CBIT](ERROR): Data Fault in component: BPC1
2015-09-10T23:20:29.823Z,1441927229.823 [CBIT](INFO): Clearing failed state for component BPC1
2015-09-10T23:20:29.824Z,1441927229.824 [BPC1] No Fault, FailCount= 1
2015-09-10T23:20:35.767Z,1441927235.767 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:20:35.767Z,1441927235.767 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-09-10T23:20:35.767Z,1441927235.767 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:20:35.838Z,1441927235.838 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:20:36.281Z,1441927236.281 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:20:37.041Z,1441927237.041 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:20:37.046Z,1441927237.046 [Aanderaa_O2] No Fault, FailCount= 2
2015-09-10T23:20:47.766Z,1441927247.766 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:20:47.766Z,1441927247.766 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-09-10T23:20:47.766Z,1441927247.766 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:20:47.852Z,1441927247.852 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:20:47.852Z,1441927247.852 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-09-10T23:20:48.283Z,1441927248.283 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:21:06.609Z,1441927266.609 [Startup:StartupSatComms:B](INFO): Timed out from 2015-09-10T23:20:06.2Z
2015-09-10T23:21:06.609Z,1441927266.609 [Startup:StartupSatComms:B] Stopped
2015-09-10T23:21:06.610Z,1441927266.610 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-09-10T23:21:06.610Z,1441927266.610 [Startup:StartupSatComms] Stopped
2015-09-10T23:21:06.610Z,1441927266.610 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2015-09-10T23:21:06.611Z,1441927266.611 [Startup](INFO): Completed Startup
2015-09-10T23:21:06.611Z,1441927266.611 [Startup] Stopped
2015-09-10T23:21:06.611Z,1441927266.611 [Startup](DEBUG): Aggregate::uninitialize Startup
2015-09-10T23:21:06.611Z,1441927266.611 [Startup:A.GoToSurface] Stopped
2015-09-10T23:21:06.611Z,1441927266.611 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-09-10T23:21:07.010Z,1441927267.010 [MissionManager](IMPORTANT): Started mission Default
2015-09-10T23:21:07.011Z,1441927267.011 [Default] Running Loop=1
2015-09-10T23:21:07.011Z,1441927267.011 [Default](DEBUG): Aggregate::initialize Default
2015-09-10T23:21:07.011Z,1441927267.011 [Default:A.GoToSurface] Running Loop=1
2015-09-10T23:21:07.011Z,1441927267.011 [Default:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-09-10T23:21:07.011Z,1441927267.011 [Default:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-09-10T23:21:07.012Z,1441927267.012 [Default:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-09-10T23:21:07.012Z,1441927267.012 [Default:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-09-10T23:21:07.019Z,1441927267.019 [Default:CheckIn] Running Loop=1
2015-09-10T23:21:07.019Z,1441927267.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2015-09-10T23:21:07.019Z,1441927267.019 [Default:CheckIn:Read_GPS] Running Loop=1
2015-09-10T23:21:07.423Z,1441927267.423 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2015-09-10T23:21:15.003Z,1441927275.003 [BPC1](ERROR): Failed to receive battery data
2015-09-10T23:21:15.003Z,1441927275.003 [BPC1] Communications Fault, FailCount= 1
2015-09-10T23:21:15.003Z,1441927275.003 [BPC1](ERROR): Communications Fault
2015-09-10T23:21:15.059Z,1441927275.059 [CBIT](ERROR): Communications Fault in component: BPC1
2015-09-10T23:21:16.226Z,1441927276.226 [CBIT](INFO): Clearing failed state for component BPC1
2015-09-10T23:21:16.226Z,1441927276.226 [BPC1] No Fault, FailCount= 1
2015-09-10T23:21:52.227Z,1441927312.227 [CBIT](CRITICAL): Environmental Failure. Press:14.982194 PSI. Humidity:59%. Temp:21 C. ABORTING MISSION
2015-09-10T23:22:25.384Z,1441927345.384 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-09-10T23:23:09.446Z,1441927389.446 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-09-10T23:23:09.446Z,1441927389.446 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2015-09-10T23:23:09.446Z,1441927389.446 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-09-10T23:23:09.448Z,1441927389.448 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-09-10T23:23:09.448Z,1441927389.448 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2015-09-10T23:23:09.448Z,1441927389.448 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-09-10T23:23:09.524Z,1441927389.524 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-09-10T23:23:09.524Z,1441927389.524 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-09-10T23:23:09.925Z,1441927389.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-09-10T23:23:09.925Z,1441927389.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2015-09-10T23:23:09.926Z,1441927389.926 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-09-10T23:23:09.926Z,1441927389.926 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2015-09-10T23:23:10.289Z,1441927390.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-09-10T23:23:10.290Z,1441927390.290 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:23:10.290Z,1441927390.290 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:23:10.290Z,1441927390.290 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-09-10T23:23:10.291Z,1441927390.291 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:23:10.291Z,1441927390.291 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:23:10.670Z,1441927390.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2015-09-10T23:23:11.060Z,1441927391.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2015-09-10T23:23:11.456Z,1441927391.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2015-09-10T23:23:11.849Z,1441927391.849 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2015-09-10T23:23:12.260Z,1441927392.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2015-09-10T23:23:12.663Z,1441927392.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2015-09-10T23:23:13.058Z,1441927393.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2015-09-10T23:23:13.469Z,1441927393.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2015-09-10T23:23:13.822Z,1441927393.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2015-09-10T23:23:14.276Z,1441927394.276 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2015-09-10T23:23:14.658Z,1441927394.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2015-09-10T23:23:15.030Z,1441927395.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2015-09-10T23:23:15.463Z,1441927395.463 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2015-09-10T23:23:15.858Z,1441927395.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2015-09-10T23:23:16.245Z,1441927396.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2015-09-10T23:23:16.668Z,1441927396.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2015-09-10T23:23:17.068Z,1441927397.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2015-09-10T23:23:17.472Z,1441927397.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2015-09-10T23:23:17.913Z,1441927397.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2015-09-10T23:23:17.934Z,1441927397.934 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:23:17.934Z,1441927397.934 [Aanderaa_O2] No Fault, FailCount= 3
2015-09-10T23:23:18.238Z,1441927398.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2015-09-10T23:23:18.666Z,1441927398.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2015-09-10T23:23:19.131Z,1441927399.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2015-09-10T23:23:19.475Z,1441927399.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2015-09-10T23:23:19.845Z,1441927399.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2015-09-10T23:23:20.277Z,1441927400.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2015-09-10T23:23:20.652Z,1441927400.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2015-09-10T23:23:21.107Z,1441927401.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2015-09-10T23:23:21.464Z,1441927401.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2015-09-10T23:23:21.856Z,1441927401.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2015-09-10T23:23:22.281Z,1441927402.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2015-09-10T23:23:22.666Z,1441927402.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2015-09-10T23:23:23.109Z,1441927403.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2015-09-10T23:23:23.461Z,1441927403.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2015-09-10T23:23:23.867Z,1441927403.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2015-09-10T23:23:24.233Z,1441927404.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2015-09-10T23:23:24.677Z,1441927404.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2015-09-10T23:23:25.076Z,1441927405.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2015-09-10T23:23:25.475Z,1441927405.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2015-09-10T23:23:25.851Z,1441927405.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2015-09-10T23:23:26.246Z,1441927406.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2015-09-10T23:23:26.654Z,1441927406.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2015-09-10T23:23:27.047Z,1441927407.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2015-09-10T23:23:27.451Z,1441927407.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2015-09-10T23:23:27.852Z,1441927407.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2015-09-10T23:23:28.167Z,1441927408.167 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:23:28.167Z,1441927408.167 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-09-10T23:23:28.167Z,1441927408.167 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:23:28.265Z,1441927408.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2015-09-10T23:23:28.307Z,1441927408.307 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:23:28.681Z,1441927408.681 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:23:28.787Z,1441927408.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2015-09-10T23:23:29.048Z,1441927409.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2015-09-10T23:23:29.503Z,1441927409.503 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:23:29.503Z,1441927409.503 [Aanderaa_O2] No Fault, FailCount= 1
2015-09-10T23:23:40.166Z,1441927420.166 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:23:40.166Z,1441927420.166 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-09-10T23:23:40.166Z,1441927420.166 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:23:40.299Z,1441927420.299 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:23:40.688Z,1441927420.688 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:23:41.504Z,1441927421.504 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:23:41.504Z,1441927421.504 [Aanderaa_O2] No Fault, FailCount= 2
2015-09-10T23:23:47.092Z,1441927427.092 [CBIT](CRITICAL): Environmental Failure. Press:13.920176 PSI. Humidity:58%. Temp:21 C. ABORTING MISSION
2015-09-10T23:23:52.165Z,1441927432.165 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:23:52.165Z,1441927432.165 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-09-10T23:23:52.165Z,1441927432.165 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:23:52.299Z,1441927432.299 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:23:52.299Z,1441927432.299 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-09-10T23:23:52.682Z,1441927432.682 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:24:31.054Z,1441927471.054 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:55%. Temp:21 C. ABORTING MISSION
2015-09-10T23:24:42.220Z,1441927482.220 [CBIT](CRITICAL): Environmental Failure. Press:13.920176 PSI. Humidity:59%. Temp:21 C. ABORTING MISSION
2015-09-10T23:26:07.032Z,1441927567.032 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-09-10T23:21:07.0Z
2015-09-10T23:26:07.032Z,1441927567.032 [Default:CheckIn:Read_GPS] Stopped
2015-09-10T23:26:07.032Z,1441927567.032 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-09-10T23:26:07.437Z,1441927567.437 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-09-10T23:26:10.600Z,1441927570.600 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-09-10T23:26:10.600Z,1441927570.600 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2015-09-10T23:26:10.600Z,1441927570.600 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-09-10T23:26:10.602Z,1441927570.602 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-09-10T23:26:10.602Z,1441927570.602 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2015-09-10T23:26:10.602Z,1441927570.602 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-09-10T23:26:10.639Z,1441927570.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-09-10T23:26:10.639Z,1441927570.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-09-10T23:26:11.011Z,1441927571.011 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-09-10T23:26:11.011Z,1441927571.011 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2015-09-10T23:26:11.011Z,1441927571.011 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-09-10T23:26:11.011Z,1441927571.011 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2015-09-10T23:26:11.398Z,1441927571.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-09-10T23:26:11.399Z,1441927571.399 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:26:11.399Z,1441927571.399 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:26:11.399Z,1441927571.399 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-09-10T23:26:11.400Z,1441927571.400 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-09-10T23:26:11.400Z,1441927571.400 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-09-10T23:26:11.806Z,1441927571.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2015-09-10T23:26:12.206Z,1441927572.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2015-09-10T23:26:12.610Z,1441927572.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2015-09-10T23:26:13.001Z,1441927573.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2015-09-10T23:26:13.405Z,1441927573.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2015-09-10T23:26:13.805Z,1441927573.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2015-09-10T23:26:14.208Z,1441927574.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2015-09-10T23:26:14.607Z,1441927574.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2015-09-10T23:26:15.007Z,1441927575.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2015-09-10T23:26:15.408Z,1441927575.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2015-09-10T23:26:15.807Z,1441927575.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2015-09-10T23:26:16.259Z,1441927576.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2015-09-10T23:26:16.606Z,1441927576.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2015-09-10T23:26:17.047Z,1441927577.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2015-09-10T23:26:17.406Z,1441927577.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2015-09-10T23:26:17.838Z,1441927577.838 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2015-09-10T23:26:18.245Z,1441927578.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2015-09-10T23:26:18.607Z,1441927578.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2015-09-10T23:26:19.017Z,1441927579.017 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2015-09-10T23:26:19.409Z,1441927579.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2015-09-10T23:26:19.806Z,1441927579.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2015-09-10T23:26:20.207Z,1441927580.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2015-09-10T23:26:20.608Z,1441927580.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2015-09-10T23:26:21.007Z,1441927581.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2015-09-10T23:26:21.407Z,1441927581.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2015-09-10T23:26:21.805Z,1441927581.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2015-09-10T23:26:22.209Z,1441927582.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2015-09-10T23:26:22.222Z,1441927582.222 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:26:22.222Z,1441927582.222 [Aanderaa_O2] No Fault, FailCount= 3
2015-09-10T23:26:22.607Z,1441927582.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2015-09-10T23:26:23.008Z,1441927583.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2015-09-10T23:26:23.408Z,1441927583.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2015-09-10T23:26:23.810Z,1441927583.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2015-09-10T23:26:24.209Z,1441927584.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2015-09-10T23:26:24.612Z,1441927584.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2015-09-10T23:26:25.007Z,1441927585.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2015-09-10T23:26:25.409Z,1441927585.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2015-09-10T23:26:25.808Z,1441927585.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2015-09-10T23:26:26.211Z,1441927586.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2015-09-10T23:26:26.608Z,1441927586.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2015-09-10T23:26:27.012Z,1441927587.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2015-09-10T23:26:27.407Z,1441927587.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2015-09-10T23:26:27.807Z,1441927587.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2015-09-10T23:26:28.210Z,1441927588.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2015-09-10T23:26:28.615Z,1441927588.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2015-09-10T23:26:29.009Z,1441927589.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2015-09-10T23:26:29.412Z,1441927589.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2015-09-10T23:26:29.811Z,1441927589.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2015-09-10T23:26:30.266Z,1441927590.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2015-09-10T23:26:32.966Z,1441927592.966 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:26:32.967Z,1441927592.967 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-09-10T23:26:32.967Z,1441927592.967 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:26:33.053Z,1441927593.053 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:26:33.488Z,1441927593.488 [Aanderaa_O2](INFO): Powering down
2015-09-10T23:26:34.224Z,1441927594.224 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-09-10T23:26:34.224Z,1441927594.224 [Aanderaa_O2] No Fault, FailCount= 1
2015-09-10T23:26:37.785Z,1441927597.785 [NAL9602](FAULT): Queried for signal strength and failed to receive proper response. no error
2015-09-10T23:26:37.859Z,1441927597.859 [NAL9602](ERROR): received:
+CSQ:0
OK
2015-09-10T23:26:37.859Z,1441927597.859 [NAL9602] Data Fault, FailCount= 1
2015-09-10T23:26:37.859Z,1441927597.859 [NAL9602](ERROR): Data Fault
2015-09-10T23:26:37.933Z,1441927597.933 [CBIT](ERROR): Data Fault in component: NAL9602
2015-09-10T23:26:38.180Z,1441927598.180 [NAL9602](INFO): Powering down
2015-09-10T23:26:39.023Z,1441927599.023 [CBIT](INFO): Clearing failed state for component NAL9602
2015-09-10T23:26:39.023Z,1441927599.023 [NAL9602] No Fault, FailCount= 1
2015-09-10T23:26:43.377Z,1441927603.377 [NAL9602](INFO): Powering up NAL9602
2015-09-10T23:26:44.965Z,1441927604.965 [Aanderaa_O2](FAULT): Timed out starting
2015-09-10T23:26:44.965Z,1441927604.965 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-09-10T23:26:44.965Z,1441927604.965 [Aanderaa_O2](ERROR): Communications Fault
2015-09-10T23:26:45.045Z,1441927605.045 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-09-10T23:26:45.487Z,1441927605.487 [Aanderaa_O2](INFO): Powering down
2015