2015-10-09T15:08:48.413Z,1444403328.413 [Supervisor](DEBUG): Initializing supervisor.
2015-10-09T15:08:48.415Z,1444403328.415 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-10-09T15:08:48.416Z,1444403328.416 [SyncHandler](INFO): Protected caller Thread ID is 3763
2015-10-09T15:08:48.416Z,1444403328.416 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-10-09T15:08:48.417Z,1444403328.417 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-10-09T15:08:48.418Z,1444403328.418 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3764
2015-10-09T15:08:48.421Z,1444403328.421 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-10-09T15:08:48.433Z,1444403328.433 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-10-09T15:08:48.434Z,1444403328.434 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-10-09T15:08:48.434Z,1444403328.434 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3765
2015-10-09T15:08:48.435Z,1444403328.435 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-10-09T15:08:48.436Z,1444403328.436 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-10-09T15:08:48.436Z,1444403328.436 [logger ThreadHandler](INFO): Protected caller Thread ID is 3766
2015-10-09T15:08:48.438Z,1444403328.438 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-10-09T15:08:48.439Z,1444403328.439 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-10-09T15:08:48.444Z,1444403328.444 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-10-09T15:08:48.949Z,1444403328.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-10-09T15:08:48.951Z,1444403328.951 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-10-09T15:08:49.122Z,1444403329.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-10-09T15:08:49.124Z,1444403329.124 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-10-09T15:08:49.255Z,1444403329.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-10-09T15:08:49.257Z,1444403329.257 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-10-09T15:08:49.392Z,1444403329.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-10-09T15:08:49.392Z,1444403329.392 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-10-09T15:08:49.480Z,1444403329.480 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-10-09T15:08:49.682Z,1444403329.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-10-09T15:08:49.683Z,1444403329.683 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-10-09T15:08:49.876Z,1444403329.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-10-09T15:08:49.877Z,1444403329.877 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-10-09T15:08:50.156Z,1444403330.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-10-09T15:08:50.157Z,1444403330.157 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-10-09T15:08:50.512Z,1444403330.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-10-09T15:08:50.514Z,1444403330.514 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-10-09T15:08:50.828Z,1444403330.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-10-09T15:08:50.829Z,1444403330.829 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-10-09T15:08:51.353Z,1444403331.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-10-09T15:08:51.354Z,1444403331.354 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-10-09T15:08:51.561Z,1444403331.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-10-09T15:08:51.563Z,1444403331.563 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-10-09T15:08:51.669Z,1444403331.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-10-09T15:08:51.670Z,1444403331.670 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-10-09T15:08:52.123Z,1444403332.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-10-09T15:08:52.124Z,1444403332.124 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-10-09T15:08:52.238Z,1444403332.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-10-09T15:08:52.240Z,1444403332.240 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-tethys/
2015-10-09T15:08:52.244Z,1444403332.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Control.cfg
2015-10-09T15:08:52.348Z,1444403332.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/BIT.cfg
2015-10-09T15:08:52.465Z,1444403332.465 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Sensor.cfg
2015-10-09T15:08:52.609Z,1444403332.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Navigation.cfg
2015-10-09T15:08:52.708Z,1444403332.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Science.cfg
2015-10-09T15:08:52.852Z,1444403332.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Servo.cfg
2015-10-09T15:08:52.956Z,1444403332.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Simulator.cfg
2015-10-09T15:08:53.050Z,1444403333.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/logger.cfg
2015-10-09T15:08:53.137Z,1444403333.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/secure.cfg
2015-10-09T15:08:53.235Z,1444403333.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/vehicle.cfg
2015-10-09T15:08:53.341Z,1444403333.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/workSite.cfg
2015-10-09T15:08:53.441Z,1444403333.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Battery.cfg
2015-10-09T15:08:53.683Z,1444403333.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-10-09T15:08:53.684Z,1444403333.684 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2015-10-09T15:08:53.693Z,1444403333.693 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-10-09T15:08:53.753Z,1444403333.753 [VerticalControl](DEBUG): Construct VerticalControl.
2015-10-09T15:08:53.874Z,1444403333.874 [VerticalControl] Loaded
2015-10-09T15:08:53.874Z,1444403333.874 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-10-09T15:08:53.875Z,1444403333.875 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-10-09T15:08:53.946Z,1444403333.946 [HorizontalControl] Loaded
2015-10-09T15:08:53.947Z,1444403333.947 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-10-09T15:08:53.947Z,1444403333.947 [SpeedControl](DEBUG): Construct SpeedControl.
2015-10-09T15:08:53.953Z,1444403333.953 [SpeedControl] Loaded
2015-10-09T15:08:53.953Z,1444403333.953 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-10-09T15:08:53.954Z,1444403333.954 [LoopControl](DEBUG): Construct LoopControl.
2015-10-09T15:08:53.955Z,1444403333.955 [LoopControl] Loaded
2015-10-09T15:08:53.955Z,1444403333.955 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-10-09T15:08:53.955Z,1444403333.955 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-10-09T15:08:53.956Z,1444403333.956 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-10-09T15:08:54.094Z,1444403334.094 [SBIT](DEBUG): Construct Startup Built In Test.
2015-10-09T15:08:54.106Z,1444403334.106 [SBIT] Loaded
2015-10-09T15:08:54.106Z,1444403334.106 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-10-09T15:08:54.106Z,1444403334.106 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-10-09T15:08:54.135Z,1444403334.135 [IBIT] Loaded
2015-10-09T15:08:54.136Z,1444403334.136 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-10-09T15:08:54.138Z,1444403334.138 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-10-09T15:08:54.276Z,1444403334.276 [CBIT] Loaded
2015-10-09T15:08:54.276Z,1444403334.276 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-10-09T15:08:54.277Z,1444403334.277 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-10-09T15:08:54.277Z,1444403334.277 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-10-09T15:08:54.311Z,1444403334.311 [DepthRateCalculator] Loaded
2015-10-09T15:08:54.311Z,1444403334.311 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-10-09T15:08:54.317Z,1444403334.317 [PitchRateCalculator] Loaded
2015-10-09T15:08:54.317Z,1444403334.317 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-10-09T15:08:54.328Z,1444403334.328 [SpeedCalculator] Loaded
2015-10-09T15:08:54.329Z,1444403334.329 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-10-09T15:08:54.345Z,1444403334.345 [TempGradientCalculator] Loaded
2015-10-09T15:08:54.345Z,1444403334.345 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-10-09T15:08:54.362Z,1444403334.362 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2015-10-09T15:08:54.362Z,1444403334.362 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2015-10-09T15:08:54.368Z,1444403334.368 [YawRateCalculator] Loaded
2015-10-09T15:08:54.368Z,1444403334.368 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-10-09T15:08:54.369Z,1444403334.369 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-10-09T15:08:54.369Z,1444403334.369 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-10-09T15:08:54.475Z,1444403334.475 [StratificationFrontDetector](INFO): threshold set to: 0.300012 degC
2015-10-09T15:08:54.475Z,1444403334.475 [StratificationFrontDetector](DEBUG): (re)initializing
2015-10-09T15:08:54.475Z,1444403334.475 [StratificationFrontDetector] Loaded
2015-10-09T15:08:54.476Z,1444403334.476 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2015-10-09T15:08:54.476Z,1444403334.476 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-10-09T15:08:54.477Z,1444403334.477 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-10-09T15:08:54.727Z,1444403334.727 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-10-09T15:08:54.728Z,1444403334.728 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-10-09T15:08:54.871Z,1444403334.871 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-10-09T15:08:54.872Z,1444403334.872 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-10-09T15:08:54.926Z,1444403334.926 [DeadReckonUsingSpeedCalculator] Loaded
2015-10-09T15:08:54.927Z,1444403334.927 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-10-09T15:08:54.944Z,1444403334.944 [NavChart] Loaded
2015-10-09T15:08:54.944Z,1444403334.944 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-10-09T15:08:54.948Z,1444403334.948 [UniversalFixResidualReporter] Loaded
2015-10-09T15:08:54.948Z,1444403334.948 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-10-09T15:08:54.949Z,1444403334.949 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-10-09T15:08:54.949Z,1444403334.949 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-10-09T15:08:54.961Z,1444403334.961 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-10-09T15:08:54.962Z,1444403334.962 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-10-09T15:08:55.177Z,1444403335.177 [Aanderaa_O2] Loaded
2015-10-09T15:08:55.178Z,1444403335.178 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-10-09T15:08:55.189Z,1444403335.189 [CANONSampler] Loaded
2015-10-09T15:08:55.190Z,1444403335.190 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2015-10-09T15:08:55.267Z,1444403335.267 [CTD_NeilBrown] Loaded
2015-10-09T15:08:55.267Z,1444403335.267 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-10-09T15:08:55.269Z,1444403335.269 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059D4E0
2015-10-09T15:08:55.269Z,1444403335.269 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3848
2015-10-09T15:08:55.284Z,1444403335.284 [PAR_Licor] Loaded
2015-10-09T15:08:55.284Z,1444403335.284 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-10-09T15:08:55.302Z,1444403335.302 [VemcoVR2C] Loaded
2015-10-09T15:08:55.303Z,1444403335.303 [ComponentRegistry](DEBUG): SyncComponent "VemcoVR2C" handled in the control thread.
2015-10-09T15:08:55.352Z,1444403335.352 [WetLabsBB2FL] Loaded
2015-10-09T15:08:55.352Z,1444403335.352 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-10-09T15:08:55.354Z,1444403335.354 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CD4E0
2015-10-09T15:08:55.354Z,1444403335.354 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3849
2015-10-09T15:08:55.355Z,1444403335.355 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-10-09T15:08:55.355Z,1444403335.355 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-10-09T15:08:55.723Z,1444403335.723 [AHRS_sp3003D] Loaded
2015-10-09T15:08:55.723Z,1444403335.723 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2015-10-09T15:08:55.770Z,1444403335.770 [AcousticModem_Benthos_ATM900] Loaded
2015-10-09T15:08:55.771Z,1444403335.771 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2015-10-09T15:08:55.847Z,1444403335.847 [DataOverHttps] Loaded
2015-10-09T15:08:55.847Z,1444403335.847 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-10-09T15:08:55.861Z,1444403335.861 [Depth_Keller] Loaded
2015-10-09T15:08:55.861Z,1444403335.861 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-10-09T15:08:55.866Z,1444403335.866 [DropWeight] Loaded
2015-10-09T15:08:55.867Z,1444403335.867 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-10-09T15:08:55.000Z,1444403336.000 [DVL_micro] Loaded
2015-10-09T15:08:55.000Z,1444403336.000 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2015-10-09T15:08:56.094Z,1444403336.094 [NAL9602] Loaded
2015-10-09T15:08:56.094Z,1444403336.094 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-10-09T15:08:56.136Z,1444403336.136 [Onboard] Loaded
2015-10-09T15:08:56.136Z,1444403336.136 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-10-09T15:08:56.143Z,1444403336.143 [Radio_Surface] Loaded
2015-10-09T15:08:56.143Z,1444403336.143 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-10-09T15:08:56.145Z,1444403336.145 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408124E0
2015-10-09T15:08:56.145Z,1444403336.145 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3850
2015-10-09T15:08:58.019Z,1444403338.019 [BPC1] Loaded
2015-10-09T15:08:58.019Z,1444403338.019 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-10-09T15:08:58.020Z,1444403338.020 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-10-09T15:08:58.020Z,1444403338.020 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-10-09T15:08:58.138Z,1444403338.138 [BuoyancyServo] Loaded
2015-10-09T15:08:58.138Z,1444403338.138 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-10-09T15:08:58.151Z,1444403338.151 [ElevatorServo] Loaded
2015-10-09T15:08:58.151Z,1444403338.151 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-10-09T15:08:58.163Z,1444403338.163 [MassServo] Loaded
2015-10-09T15:08:58.163Z,1444403338.163 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-10-09T15:08:58.175Z,1444403338.175 [RudderServo] Loaded
2015-10-09T15:08:58.176Z,1444403338.176 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-10-09T15:08:58.187Z,1444403338.187 [ThrusterServo] Loaded
2015-10-09T15:08:58.188Z,1444403338.188 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-10-09T15:08:58.188Z,1444403338.188 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-10-09T15:08:58.189Z,1444403338.189 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-10-09T15:08:58.312Z,1444403338.312 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-10-09T15:08:58.313Z,1444403338.313 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-10-09T15:08:58.347Z,1444403338.347 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-10-09T15:08:58.351Z,1444403338.351 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-10-09T15:08:58.352Z,1444403338.352 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-10-09T15:08:58.359Z,1444403338.359 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-10-09T15:08:58.360Z,1444403338.360 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408C04E0
2015-10-09T15:08:58.360Z,1444403338.360 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3851
2015-10-09T15:08:58.365Z,1444403338.365 [Supervisor](INFO): Main Thread ID is 769
2015-10-09T15:08:58.365Z,1444403338.365 [Supervisor](DEBUG): Running supervisor.
2015-10-09T15:08:58.366Z,1444403338.366 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3852
2015-10-09T15:08:58.369Z,1444403338.369 [controlThread ThreadHandler](INFO): Handler Thread ID is 3853
2015-10-09T15:08:58.369Z,1444403338.369 [controlThread](DEBUG): Initializing ControlThread
2015-10-09T15:08:58.370Z,1444403338.370 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-10-09T15:08:58.372Z,1444403338.372 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-10-09T15:08:58.373Z,1444403338.373 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-10-09T15:08:58.373Z,1444403338.373 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-10-09T15:08:58.373Z,1444403338.373 [SBIT](INFO): Initialize SBIT Component.
2015-10-09T15:08:58.374Z,1444403338.374 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12656
2015-10-09T15:08:58.374Z,1444403338.374 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-10-09T15:08:58.375Z,1444403338.375 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-10-09T15:08:58.376Z,1444403338.376 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2015-10-09T15:08:58.376Z,1444403338.376 [IBIT](INFO): Initialize IBIT Component.
2015-10-09T15:08:58.377Z,1444403338.377 [CBIT](DEBUG): Initialize CBIT Component.
2015-10-09T15:08:58.377Z,1444403338.377 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-10-09T15:08:58.378Z,1444403338.378 [logger ThreadHandler](INFO): Handler Thread ID is 3854
2015-10-09T15:08:58.399Z,1444403338.399 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3855
2015-10-09T15:08:58.400Z,1444403338.400 [CTD_NeilBrown](INFO): Powering down
2015-10-09T15:08:58.403Z,1444403338.403 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-10-09T15:08:58.403Z,1444403338.403 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-10-09T15:08:58.404Z,1444403338.404 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-10-09T15:08:58.404Z,1444403338.404 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-10-09T15:08:58.404Z,1444403338.404 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2015-10-09T15:08:58.405Z,1444403338.405 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-10-09T15:08:58.406Z,1444403338.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-10-09T15:08:58.406Z,1444403338.406 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2015-10-09T15:08:58.407Z,1444403338.407 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2015-10-09T15:08:58.407Z,1444403338.407 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-10-09T15:08:58.408Z,1444403338.408 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2015-10-09T15:08:58.408Z,1444403338.408 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2015-10-09T15:08:58.408Z,1444403338.408 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-10-09T15:08:58.409Z,1444403338.409 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-10-09T15:08:58.431Z,1444403338.431 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-10-09T15:08:58.455Z,1444403338.455 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3856
2015-10-09T15:08:58.457Z,1444403338.457 [WetLabsBB2FL](INFO): Powering down
2015-10-09T15:08:58.487Z,1444403338.487 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-10-09T15:08:58.491Z,1444403338.491 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3857
2015-10-09T15:08:58.498Z,1444403338.498 [Radio_Surface](INFO): Powering up
2015-10-09T15:08:58.508Z,1444403338.508 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3858
2015-10-09T15:08:58.513Z,1444403338.513 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-10-09T15:08:58.515Z,1444403338.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-10-09T15:08:58.515Z,1444403338.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-10-09T15:08:58.515Z,1444403338.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-10-09T15:08:58.516Z,1444403338.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-10-09T15:08:58.516Z,1444403338.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-10-09T15:08:58.516Z,1444403338.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-10-09T15:08:58.516Z,1444403338.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-10-09T15:08:58.517Z,1444403338.517 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-10-09T15:08:58.540Z,1444403338.540 [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>


2015-10-09T15:08:58.541Z,1444403338.541 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-10-09T15:08:58.599Z,1444403338.599 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-10-09T15:08:58.600Z,1444403338.600 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-10-09T15:08:58.634Z,1444403338.634 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-10-09T15:08:58.653Z,1444403338.653 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2015-10-09T15:08:58.659Z,1444403338.659 [Default:D.Execute](DEBUG): Construct Execute.
2015-10-09T15:08:58.671Z,1444403338.671 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2015-10-09T15:08:58.692Z,1444403338.692 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,VemcoVR2C,AHRS_sp3003D,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,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-10-09T15:08:58.738Z,1444403338.738 [CANONSampler](INFO): Powering down
2015-10-09T15:08:58.924Z,1444403338.924 [VemcoVR2C](INFO): Powering up
2015-10-09T15:08:58.930Z,1444403338.930 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2015-10-09T15:08:58.955Z,1444403338.955 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-10-09T15:08:58.955Z,1444403338.955 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-10-09T15:08:58.993Z,1444403338.993 [Depth_Keller](ERROR): Pressure reading out of range: 2102.507080 decibar
2015-10-09T15:08:59.040Z,1444403339.040 [DVL_micro](INFO): Initializing
2015-10-09T15:08:59.419Z,1444403339.419 [DepthRateCalculator](ERROR): Depth measurement is not active
2015-10-09T15:08:59.521Z,1444403339.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-10-09T15:08:59.545Z,1444403339.545 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-10-09T15:08:59.656Z,1444403339.656 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-10-09T15:08:59.661Z,1444403339.661 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-10-09T15:08:59.679Z,1444403339.679 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-10-09T15:08:59.685Z,1444403339.685 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-10-09T15:08:59.703Z,1444403339.703 [MassServo](DEBUG): Initializing EZServoServo.
2015-10-09T15:08:59.706Z,1444403339.706 [MassServo](DEBUG): Initializing MassServo.
2015-10-09T15:08:59.731Z,1444403339.731 [RudderServo](DEBUG): Initializing EZServoServo.
2015-10-09T15:08:59.737Z,1444403339.737 [RudderServo](DEBUG): Initializing RudderServo.
2015-10-09T15:08:59.743Z,1444403339.743 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-10-09T15:08:59.749Z,1444403339.749 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-10-09T15:09:00.136Z,1444403340.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-10-09T15:09:00.146Z,1444403340.146 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-10-09T15:09:01.360Z,1444403341.360 [NAL9602](INFO): Powering up NAL9602
2015-10-09T15:09:01.712Z,1444403341.712 [Aanderaa_O2](INFO): Powering down
2015-10-09T15:09:07.407Z,1444403347.407 [AcousticModem_Benthos_ATM900](DEBUG): 
2015-10-09T15:09:08.725Z,1444403348.726 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series
2015-10-09T15:09:08.726Z,1444403348.726 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-10-09T15:09:08.727Z,1444403348.727 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.8.9
2015-10-09T15:09:09.114Z,1444403349.114 [AcousticModem_Benthos_ATM900](DEBUG): Oct  9 2015 15:07:17 
2015-10-09T15:09:09.963Z,1444403349.963 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-10-09T15:09:10.363Z,1444403350.363 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-10-09T15:09:10.364Z,1444403350.364 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2015-10-09T15:09:10.364Z,1444403350.364 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2015-10-09T15:09:10.756Z,1444403350.756 [AcousticModem_Benthos_ATM900](INFO): setting local address to 1
2015-10-09T15:09:11.156Z,1444403351.156 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2015-10-09T15:09:11.157Z,1444403351.157 [AcousticModem_Benthos_ATM900](INFO): set local address to 1
2015-10-09T15:09:11.562Z,1444403351.562 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2015-10-09T15:09:12.388Z,1444403352.388 [NAL9602](INFO): NAL9602 initialized
2015-10-09T15:09:26.990Z,1444403366.990 [SBIT](IMPORTANT): Beginning Startup BIT
2015-10-09T15:09:26.993Z,1444403366.993 [CBIT](IMPORTANT): Beginning GF scan
2015-10-09T15:09:37.308Z,1444403377.308 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:09:37.308Z,1444403377.308 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:09:37.308Z,1444403377.308 [BPC1](ERROR): Communications Fault
2015-10-09T15:09:37.373Z,1444403377.373 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:09:38.562Z,1444403378.562 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:09:38.562Z,1444403378.562 [BPC1] No Fault, FailCount= 1
2015-10-09T15:09:53.472Z,1444403393.472 [CBIT](IMPORTANT): No ground fault detected
2015-10-09T15:10:11.704Z,1444403411.704 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:10:11.704Z,1444403411.704 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:10:20.610Z,1444403420.610 [SBIT](IMPORTANT): SBIT PASSED
2015-10-09T15:10:21.004Z,1444403421.004 [MissionManager](IMPORTANT): Started mission Startup
2015-10-09T15:10:21.005Z,1444403421.005 [Startup] Running Loop=1
2015-10-09T15:10:21.005Z,1444403421.005 [Startup](DEBUG): Aggregate::initialize Startup
2015-10-09T15:10:21.005Z,1444403421.005 [Startup:A.GoToSurface] Running Loop=1
2015-10-09T15:10:21.005Z,1444403421.005 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-10-09T15:10:21.005Z,1444403421.005 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-10-09T15:10:21.006Z,1444403421.006 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-10-09T15:10:21.006Z,1444403421.006 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2015-10-09T15:10:21.007Z,1444403421.007 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2015-10-09T15:10:21.007Z,1444403421.007 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2015-10-09T15:10:21.013Z,1444403421.013 [Startup:StartupSatComms] Running Loop=1
2015-10-09T15:10:21.013Z,1444403421.013 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2015-10-09T15:10:21.014Z,1444403421.014 [Startup:StartupSatComms:A] Running Loop=1
2015-10-09T15:10:21.478Z,1444403421.478 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-10-09T15:10:54.188Z,1444403454.188 [BPC1](ERROR): Failed to parse message.
2015-10-09T15:10:54.188Z,1444403454.188 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:10:54.188Z,1444403454.188 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:10:54.188Z,1444403454.188 [BPC1](ERROR): Data Fault
2015-10-09T15:10:54.208Z,1444403454.208 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:10:55.414Z,1444403455.414 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:10:55.414Z,1444403455.414 [BPC1] No Fault, FailCount= 1
2015-10-09T15:11:11.790Z,1444403471.790 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:11:11.790Z,1444403471.790 [BPC1] Communications Fault, FailCount= 2
2015-10-09T15:11:11.790Z,1444403471.790 [BPC1](ERROR): Communications Fault
2015-10-09T15:11:11.810Z,1444403471.810 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:11:13.018Z,1444403473.018 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:11:13.018Z,1444403473.018 [BPC1] No Fault, FailCount= 2
2015-10-09T15:11:21.399Z,1444403481.399 [Startup:StartupSatComms:A](INFO): Timed out from 2015-10-09T15:10:21.0Z
2015-10-09T15:11:21.399Z,1444403481.399 [Startup:StartupSatComms:A] Stopped
2015-10-09T15:11:21.399Z,1444403481.399 [Startup:StartupSatComms:B] Running Loop=1
2015-10-09T15:11:21.799Z,1444403481.799 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-10-09T15:11:58.606Z,1444403518.606 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-10-09T15:11:58.606Z,1444403518.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-10-09T15:11:58.606Z,1444403518.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-10-09T15:11:58.613Z,1444403518.613 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-10-09T15:11:58.613Z,1444403518.613 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2015-10-09T15:11:58.613Z,1444403518.613 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-10-09T15:11:58.633Z,1444403518.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-10-09T15:11:58.633Z,1444403518.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-10-09T15:11:59.031Z,1444403519.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-10-09T15:11:59.031Z,1444403519.031 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-10-09T15:11:59.032Z,1444403519.032 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-10-09T15:11:59.032Z,1444403519.032 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2015-10-09T15:11:59.411Z,1444403519.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-10-09T15:11:59.411Z,1444403519.411 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2015-10-09T15:11:59.412Z,1444403519.412 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2015-10-09T15:11:59.412Z,1444403519.412 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-10-09T15:11:59.413Z,1444403519.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2015-10-09T15:11:59.413Z,1444403519.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2015-10-09T15:11:59.855Z,1444403519.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2015-10-09T15:12:00.212Z,1444403520.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2015-10-09T15:12:00.672Z,1444403520.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2015-10-09T15:12:00.996Z,1444403520.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2015-10-09T15:12:01.458Z,1444403521.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2015-10-09T15:12:01.825Z,1444403521.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2015-10-09T15:12:02.267Z,1444403522.267 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2015-10-09T15:12:02.614Z,1444403522.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2015-10-09T15:12:02.996Z,1444403522.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2015-10-09T15:12:03.393Z,1444403523.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2015-10-09T15:12:03.828Z,1444403523.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2015-10-09T15:12:04.211Z,1444403524.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2015-10-09T15:12:04.594Z,1444403524.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2015-10-09T15:12:04.996Z,1444403524.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2015-10-09T15:12:05.439Z,1444403525.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2015-10-09T15:12:05.823Z,1444403525.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2015-10-09T15:12:06.228Z,1444403526.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2015-10-09T15:12:06.625Z,1444403526.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2015-10-09T15:12:07.041Z,1444403527.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2015-10-09T15:12:07.413Z,1444403527.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2015-10-09T15:12:07.813Z,1444403527.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2015-10-09T15:12:08.216Z,1444403528.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2015-10-09T15:12:08.624Z,1444403528.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2015-10-09T15:12:09.001Z,1444403529.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2015-10-09T15:12:09.399Z,1444403529.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2015-10-09T15:12:09.818Z,1444403529.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2015-10-09T15:12:10.228Z,1444403530.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2015-10-09T15:12:10.624Z,1444403530.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2015-10-09T15:12:11.028Z,1444403531.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2015-10-09T15:12:11.424Z,1444403531.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2015-10-09T15:12:11.895Z,1444403531.895 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2015-10-09T15:12:12.216Z,1444403532.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2015-10-09T15:12:12.627Z,1444403532.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2015-10-09T15:12:12.991Z,1444403532.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2015-10-09T15:12:13.460Z,1444403533.460 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2015-10-09T15:12:13.837Z,1444403533.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2015-10-09T15:12:14.197Z,1444403534.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2015-10-09T15:12:14.599Z,1444403534.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2015-10-09T15:12:15.042Z,1444403535.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2015-10-09T15:12:15.402Z,1444403535.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2015-10-09T15:12:15.827Z,1444403535.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2015-10-09T15:12:16.227Z,1444403536.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2015-10-09T15:12:16.652Z,1444403536.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2015-10-09T15:12:17.024Z,1444403537.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2015-10-09T15:12:17.398Z,1444403537.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2015-10-09T15:12:17.795Z,1444403537.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2015-10-09T15:12:18.234Z,1444403538.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2015-10-09T15:12:18.603Z,1444403538.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2015-10-09T15:12:21.458Z,1444403541.458 [Startup:StartupSatComms:B](INFO): Timed out from 2015-10-09T15:11:21.4Z
2015-10-09T15:12:21.458Z,1444403541.458 [Startup:StartupSatComms:B] Stopped
2015-10-09T15:12:21.458Z,1444403541.458 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-10-09T15:12:21.459Z,1444403541.459 [Startup:StartupSatComms] Stopped
2015-10-09T15:12:21.459Z,1444403541.459 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2015-10-09T15:12:21.460Z,1444403541.460 [Startup](INFO): Completed Startup
2015-10-09T15:12:21.460Z,1444403541.460 [Startup] Stopped
2015-10-09T15:12:21.460Z,1444403541.460 [Startup](DEBUG): Aggregate::uninitialize Startup
2015-10-09T15:12:21.460Z,1444403541.460 [Startup:A.GoToSurface] Stopped
2015-10-09T15:12:21.460Z,1444403541.460 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-10-09T15:12:21.835Z,1444403541.835 [MissionManager](IMPORTANT): Started mission Default
2015-10-09T15:12:21.835Z,1444403541.835 [Default] Running Loop=1
2015-10-09T15:12:21.835Z,1444403541.835 [Default](DEBUG): Aggregate::initialize Default
2015-10-09T15:12:21.835Z,1444403541.835 [Default:A.GoToSurface] Running Loop=1
2015-10-09T15:12:21.835Z,1444403541.835 [Default:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-10-09T15:12:21.835Z,1444403541.835 [Default:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-10-09T15:12:21.836Z,1444403541.836 [Default:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-10-09T15:12:21.836Z,1444403541.836 [Default:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2015-10-09T15:12:21.836Z,1444403541.836 [Default:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2015-10-09T15:12:21.837Z,1444403541.837 [Default:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2015-10-09T15:12:21.842Z,1444403541.842 [Default:CheckIn] Running Loop=1
2015-10-09T15:12:21.842Z,1444403541.842 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2015-10-09T15:12:21.842Z,1444403541.842 [Default:CheckIn:Read_GPS] Running Loop=1
2015-10-09T15:12:22.209Z,1444403542.209 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2015-10-09T15:14:05.267Z,1444403645.267 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:14:06.867Z,1444403646.867 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:14:06.868Z,1444403646.868 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:14:06.868Z,1444403646.868 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:14:06.872Z,1444403646.872 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004706
2015-10-09T15:14:16.072Z,1444403656.072 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-10-09T15:14:20.478Z,1444403660.478 [NAL9602](INFO): GPS fix at 20151009T151253: (36.731484, -122.091775)
2015-10-09T15:14:20.497Z,1444403660.497 [Default:CheckIn:Read_GPS] Stopped
2015-10-09T15:14:20.497Z,1444403660.497 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-10-09T15:14:20.993Z,1444403660.993 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-10-09T15:14:27.689Z,1444403667.689 [NAL9602](INFO): Powering down
2015-10-09T15:14:28.463Z,1444403668.463 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20151007T183224/Courier0168.lzma
2015-10-09T15:14:28.465Z,1444403668.465 [DataOverHttps](INFO): dataWriting with SSL WROTE 389 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 222

imei=300234011866620&imeiPassword=e7u64%5e&filename=Logs/20151007T183224/Courier0168.lzma&fileSize=45&file=%5d%00%00%04%00+%00%00%00%00%00%00%00%00%15%1d%80%03%0cVi%c0N%c1%93%c8%03%d8%8f%7bvn_H%19%7f%9a%fc%bc%918%ecl%08%00


2015-10-09T15:14:30.059Z,1444403670.059 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=true&momsn=3787485&filename=Logs%2F20151007T183224%2FCourier0168.lzma, 1
2015-10-09T15:14:30.060Z,1444403670.060 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3787485&filename=Logs%2F20151007T183224%2FCourier0168.lzma, key = 6, value = tethys
2015-10-09T15:14:30.060Z,1444403670.060 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3787485&filename=Logs%2F20151007T183224%2FCourier0168.lzma, key = 0, value = true
2015-10-09T15:14:30.061Z,1444403670.061 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151007T183224%2FCourier0168.lzma, key = 4, value = 3787485
2015-10-09T15:14:30.061Z,1444403670.061 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151007T183224%2FCourier0168.lzma
2015-10-09T15:14:30.073Z,1444403670.073 [DataOverHttps](INFO): Moved sent file to Logs/20151007T183224/Courier0168.lzma.bak
2015-10-09T15:14:30.074Z,1444403670.074 [DataOverHttps](INFO): SBD MOMSN=3787485
2015-10-09T15:14:44.965Z,1444403684.965 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:14:46.568Z,1444403686.568 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:14:46.569Z,1444403686.569 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:14:46.569Z,1444403686.569 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:14:53.691Z,1444403693.691 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20151009T150848/Courier0004.lzma
2015-10-09T15:14:53.693Z,1444403693.693 [DataOverHttps](INFO): dataWriting with SSL WROTE 798 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 631

imei=300234011866620&imeiPassword=e7u64%5e&filename=Logs/20151009T150848/Courier0004.lzma&fileSize=213&file=%5d%00%00%04%00%db%01%00%00%00%00%00%00%00%15%1d%80%03%0cVi%bf1H%e2%fa%22%18%8bj1s%e6%3a%f8P%b3%d0%f3%fa%f9%d4%5d%adF%a0%baV%e7d%22%21%9a%2b%c5%9e%ca%a1%12%7fT%969I%ddZ%00%e6%5e%15F%3f%0c%85%bcB%d9%b2T%d4C%eb%bf%00%fd%23%a1hB%e10%f6HDn%93%89%8fI%96%004%90%18%a8%90%98%3c%8bX%16%ab%9e%ef%25%90%987%96%f4%ba%dfc%ae%bc6V%f4%85%cf%c0k%18b0%d6%a9E2M%22ns%9d%c0%b1%f4g%ea%5b%13X%3dl%baI%bd%fa%bc%fb7L%e9%40%ccG%95C0o%95%dag%00%a9x%03%18%d6%fd%98%f2%2b%d4%ff%96%2c%b2%3e%c9%ca%8b%7ds%d8%9c%fe%08%c2hS%d1%fa%d6n%cb0%841%bb%cd%e0%00


2015-10-09T15:14:56.072Z,1444403696.072 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=true&momsn=3787487&filename=Logs%2F20151009T150848%2FCourier0004.lzma, 1
2015-10-09T15:14:56.072Z,1444403696.072 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3787487&filename=Logs%2F20151009T150848%2FCourier0004.lzma, key = 6, value = tethys
2015-10-09T15:14:56.073Z,1444403696.073 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3787487&filename=Logs%2F20151009T150848%2FCourier0004.lzma, key = 0, value = true
2015-10-09T15:14:56.074Z,1444403696.074 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151009T150848%2FCourier0004.lzma, key = 4, value = 3787487
2015-10-09T15:14:56.074Z,1444403696.074 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151009T150848%2FCourier0004.lzma
2015-10-09T15:14:56.075Z,1444403696.075 [DataOverHttps](INFO): Moved sent file to Logs/20151009T150848/Courier0004.lzma.bak
2015-10-09T15:14:56.075Z,1444403696.075 [DataOverHttps](INFO): SBD MOMSN=3787487
2015-10-09T15:15:02.865Z,1444403702.865 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:15:04.477Z,1444403704.477 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:15:04.477Z,1444403704.477 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:15:04.478Z,1444403704.478 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:15:11.572Z,1444403711.572 [DataOverHttps](INFO): Sending 922 bytes from file Logs/20151007T183224/Express0169.lzma
2015-10-09T15:15:11.577Z,1444403711.577 [DataOverHttps](INFO): dataWriting with SSL WROTE 2596 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 2428

imei=300234011866620&imeiPassword=e7u64%5e&filename=Logs/20151007T183224/Express0169.lzma&fileSize=922&file=%5d%00%00%04%00J%08%00%00%00%00%00%00%00%15%1d%80%03%0cVi%c0%93%8b%e4%a8d4%7b%b1B%e0%9d%97%11%d5%d4%897%ac%81%05%bf8%8d%dc0%1fq%bb%21%ddQ%b5%96%23R%b4k%c5%b50+-%1c%d1l%adG%ca%bf%ed%f2%85+%ed%a0%b38%ec%8c%9b%d4%a7%c0%0eK%83%94%23%14_1G%1c%a1%01%e8%e5c%14%f6%c2%96%3dl%0ef%f0%85%f4%92%1d%04%ec%ae.%0f6%ef%b1%9bz%e6%8dG%0fE%c0%a7%efHtq%db%99%df%8f%95%16%d4%ab%d6%93%c8%dc%c8%ed%a1l%1d%10%88%98%eab%fdoj%7f%c2%f1%cb%8a%f4%c3%26%fbD%ae%1eQazY%81%86%1c%cf%ecC%5cC%d8m%e6%10%0b%b5%3f%d6M%f2%d3%a7%fb%5c%60%ae%c6%ba%29%1a%ffo%05%cb%d1%b9%85+L%c8%db%07%24%b6%04%1a%8dL%f4t%c3%1c%e3%9d%00%a2%7d%e0%99l%b4%d2%c9%ff%ce%f5%99%14%85g%a6i%82%bf%a88%c8%fchE%aa%c0%ab%bcK%97%9c%1e%a8%5bw%e2%3f%eec%0a%eb%c1%12%8e%05%a3%ef%3c%b1%28%d6%e0QH21%1d%d4%f9%e2%7br%d3j%9f%94%fa%a5nh%85%a7%f7%3d%ab%0cw%0fV1%0c%f5%c9%17o7%86%5bs%9c%0dL%5b%16gI_%0e%60%a34%f3%b8l%06G%b5%c2%5d%f3%b7SWx%2fp%02%9a%28%9b%d7%c3%de%b9%28Y%d2%90%e7%27%1c%f2%0d%f3%e6r%ea%db%28%17%c2%b7%f48%f5%cb%d1%1fa%21%feW5%c6%1b%f4%24xM%bb%3f%da7%15%ff%c4X%13%e1%84%92%1b%90%19r%db%bd%db%7cw%2c%c1%87%c8%08%ed%11%e0V%7f%c9%ed%93u%25%2f%ad%f7%3c%c3%90M.%86VZ%91n%8c%e5%01%a4%cf%cc%9bs%a9%b4W%c5%08f%fc%3c%ff%99%87%9aW%40%1c%b6U%1a%08%d3%2cf%a9%dd%f0%de%84.%3d%8d%d0%a1%ea%99%94%8b%e07%95%02%87%a89%3cf%a8Qs%ac%a9%a2%08I9v%e9%84k%92%09%5d%988%f2%f5%df%9b%b5J%d2%ca.e%c9%d5%a7g%5c%dd%f2%9f.+J~A%e8%8a%d4%5e%b7%1b%c7%e6%f0%bb%a8%3d%7cm%82%5b%bd%04o%1eh%1c%2f%27%5d%15h%d2%ce%f3q%94%5d%f7%c7%16%96v%10%ec%40%c6%f2%f6%9b%b0%85%cf%ce%5d%3c%11%bcL%05~Xz%c2A%90o%04%00H-%b1d%17%7f%0a%8cF%fc4%1c%c6%2a%e9%1d%9b%2b%2c%b4%b4%86%f6%cf%40D%c7%ac%f3%be7%e6%2b%09%91%2c%27%cd%d8%17o%1e%b6%feG%fe%17%ad%c6%86%23%00bW%8b%e025%7f%df%fd%0fd%e0f%fa%02AD%e8%5d%11%05%b9y%a0%8c%9f.%5ew%c9%da%e1%b7O%d4%f9%82%943%22%28_%f7%cc3%84%a0%b6%c6-%f5%88%c3%ceJ%aa%1fL%9d%f57%27l%b9%ca%84%f7%f78%f8%f6%d1t%d0%bem%a2%c4%99S1%28%9f%f4%18%b7%17%a1%15%e7%f6f%99%f6%85Y%c1nw%e9%1a%cc%2c%97b%0e%04%d2R%f6%88F%14%9f%97%ae%1b%80p%1b%d7%21%dc%03%bdF%f7%ea%f0%40%08%e7%01%03%e3%1cq%87%aft%27%08%00P%fc%dd%e6%bb.W%b0%d0%12%d3%3dC%a7pl%88%5eN%a6%f2VQ%0d%ef%b6%23i%2aH%03%a1%5c%fdS%e4%a7%5c%b5z4%22%cb%9f%cc%9b%2b%fd%f4%1cT%85Y%a2RzV%fc%fe%fe%c6%a8%ca%89%ba%e0%40%a8%0e%fb%dap%ea%d4%ad%f9dA0%08%d2%ee%da%9d%ac%b1%ec%99%f3%1d%3d%21%1cmi%eexeQ%b2%7bNy%dc%a8%0b%9f%28z%a0%c4%7bMQ%b6%b5%e0%0f%eb%94%23%1b%fa%bf%8a%d9g%23%e6%ef%f3%5e


2015-10-09T15:15:15.962Z,1444403715.962 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=true&momsn=3787492&filename=Logs%2F20151007T183224%2FExpress0169.lzma, 1
2015-10-09T15:15:15.963Z,1444403715.963 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3787492&filename=Logs%2F20151007T183224%2FExpress0169.lzma, key = 6, value = tethys
2015-10-09T15:15:15.963Z,1444403715.963 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3787492&filename=Logs%2F20151007T183224%2FExpress0169.lzma, key = 0, value = true
2015-10-09T15:15:15.964Z,1444403715.964 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151007T183224%2FExpress0169.lzma, key = 4, value = 3787492
2015-10-09T15:15:15.964Z,1444403715.964 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151007T183224%2FExpress0169.lzma
2015-10-09T15:15:15.966Z,1444403715.966 [DataOverHttps](INFO): Moved sent file to Logs/20151007T183224/Express0169.lzma.bak
2015-10-09T15:15:15.966Z,1444403715.966 [DataOverHttps](INFO): SBD MOMSN=3787492
2015-10-09T15:15:24.482Z,1444403724.482 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:15:26.475Z,1444403726.475 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:15:26.475Z,1444403726.475 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:15:26.476Z,1444403726.476 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:15:33.574Z,1444403733.574 [DataOverHttps](INFO): Sending 449 bytes from file Logs/20151009T150848/Express0001.lzma
2015-10-09T15:15:33.578Z,1444403733.578 [DataOverHttps](INFO): dataWriting with SSL WROTE 1425 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 1257

imei=300234011866620&imeiPassword=e7u64%5e&filename=Logs/20151009T150848/Express0001.lzma&fileSize=449&file=%5d%00%00%04%00%7b%02%00%00%00%00%00%00%00%15%1d%80%03%0cVi%bf1U%5e%ae%14%09%a7%08%d5%087%cd%96c%2a%3bG%19%86%a3%83%d0%f4%df%ccd%a6%87%9bd%3a%be%f9%84%c9M%ba%b8%8f4m%08%99%f6%bcn%e6%9e%05P%f2%a5%0d%e1%b7%9d%de%fe%05%15%98%f2%b5%0a+%bf%ef%a9%16-%28%5b%b1%8b~%cayh%e7R%89%d6C%e8%d5M%60%96%c9%03%09%7d%3cg%09%e0%b7%ed%3eh%2b%a1%12%8c%02%24%b9%f8%21%f0%e8F%005%03%8a%3b%89%06BQ%3b%f0%11%b4%e6%dd%fd-%b3%15%13Yr%ba%ec%dd%f8%bf%b5%0b%06e%ed%c6U%d6%f0F%ecH%b5%b7%98%e3%14%b0%1f3t%f2n%15%b7%d7%ee%91%0c%ec%7f%82%bf%f8%da%ac%94%1cA%94%a0%c6%bd%c0%03%a8%3f%bc%e0%eb7%2f-%ef%99%1a%5c%a2%00%ed6E%27q%7d%f7%d1%9e%cd%a7_%90%13%ff%07%c8%0c%94f%99%2a%88%24%8bT%e4%8d%f8Pg%a4%9b%ea%cc%b9%27%60%c0Q%a9%3f%d9%9b%83Y%b9%8c%bd%9b%7f%81%f6%85%0c%d3%84%7c%0e%7cq0%9cO%e1H%86%b0%f6%ab%b667%c7%089%2b%f8%ca%c0%b0tCP%97%db%e0%3a%9b%cap%18E%cb%d2%7dp%3f%a8%15%90Ll%09%e1%97%e4%1a%2fh%a6%81%5b%a4%8b%9d%05%0d%245%22%e6%a4%8c%08u%b9%21%0f%22C%d2%0bx%ed%04%b8v%bd%c3VaS%bf%fb%5d%f4%96%a2%40t%7c%27%84%a9%be%d5%d1%cen%abfS%8d%fcn%c2%a0%cd%81%14G%bd%96%d8%ad%aco%df%ce%bb%ec%2b%e8%a0%8fqE%7dyO%aceR%e3%ba%07%8d%d2%fb%5b%88%82%b3np%b2UC%bdo%17%1aK%1c%2f%ff0%84%a9EF%3f.%97%cdx%dd%00


2015-10-09T15:15:37.969Z,1444403737.969 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=true&momsn=3787522&filename=Logs%2F20151009T150848%2FExpress0001.lzma, 1
2015-10-09T15:15:37.969Z,1444403737.969 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3787522&filename=Logs%2F20151009T150848%2FExpress0001.lzma, key = 6, value = tethys
2015-10-09T15:15:37.970Z,1444403737.970 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3787522&filename=Logs%2F20151009T150848%2FExpress0001.lzma, key = 0, value = true
2015-10-09T15:15:37.970Z,1444403737.970 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151009T150848%2FExpress0001.lzma, key = 4, value = 3787522
2015-10-09T15:15:37.971Z,1444403737.971 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151009T150848%2FExpress0001.lzma
2015-10-09T15:15:37.972Z,1444403737.972 [DataOverHttps](INFO): Moved sent file to Logs/20151009T150848/Express0001.lzma.bak
2015-10-09T15:15:37.972Z,1444403737.972 [DataOverHttps](INFO): SBD MOMSN=3787522
2015-10-09T15:15:49.790Z,1444403749.790 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:15:58.553Z,1444403758.553 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:15:58.553Z,1444403758.553 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:15:58.554Z,1444403758.554 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:16:22.604Z,1444403782.604 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:16:22.605Z,1444403782.605 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:16:22.605Z,1444403782.605 [BPC1](ERROR): Data Fault
2015-10-09T15:16:22.628Z,1444403782.628 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:16:23.825Z,1444403783.825 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:16:23.825Z,1444403783.825 [BPC1] No Fault, FailCount= 1
2015-10-09T15:16:44.496Z,1444403804.496 [DataOverHttps](INFO): Sending 477 bytes from file Logs/20151009T150848/Express0005.lzma
2015-10-09T15:16:44.499Z,1444403804.499 [DataOverHttps](INFO): dataWriting with SSL WROTE 1457 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 1289

imei=300234011866620&imeiPassword=e7u64%5e&filename=Logs/20151009T150848/Express0005.lzma&fileSize=477&file=%5d%00%00%04%00%de%03%00%00%00%00%00%00%00%15%1d%80%03%0cVi%bf1U%5e%ae%14%09%a7%08%ce%24%89%b4%98%3dG%c9%87%d2%0f%3a+%2b%18x%e15%0c0%ac%d3%e0%27%8fS%02%b6%fc%96%89rC%c1Q%e2T%1c%bd%a0%2as%aaa%ab%00%b1%f2%04R~%92%ce%0a%07%05%85%21%bb%fdX%3chu%c7%f4%c2sn%d3%97hz%3fD%3b%1f%88.%7f%cf%08%d0%daS%c2%fb%eaCV%fa%24%d4%18%60%1b%16%89%a3%d7+%d8%3f%3aTV%b2%e4%94b%7f%a6o%e68c%cb%fe-%5c%e6%24%ae%25%85K%3f%a3%f7%09r%85%12N%02%07%1f%88%cct%c0W%02%a8%9a2%81%0e%1b%d1%f6L%98%b2%5e%23%c3%9e%8ay%9e%9d%c0%d7%7c%bbEJV%b5%10%e5%11%5c%0cg%8a%04%25n%bc%f7V%9d%82xh%af%b1%d3C9%0ew%db%60%a9B%e8%b1K%8aR%c2%81+Q%eb%83%9a%5d%ed%90%d2%3e0%a4%01%e1%8940%14h%1b%95%bb%e9%16%e4%bbM%f13%fe%9f%f76G%feF%ef%e6%daTVn4%93%e4%f0%d3%c5%f0%85%3a%1fd%0csq%9dXV%03B%c5%5d%cc%7bS%c5~%d4%94G%3b%f7%19%e96%02%ba%e3%08%a5%82%c4%9b%ce%db%7d%a3Ws%07%5c3%a8%0f%91%fa%c7%fc%3a%7d%9b%d4%e1%91%07%ca%24%e0%c5%c7%fb%93%05%b6%c3%5e%3fyy%0e%9a%04%a2uC%e9%0e%3b%15%05%b6%db%21%fdt%bb%a6%fb%1f%b0%baZ%3c%3bm%8179-%0f%de_U%a1D%dd%bcEIW%c4%e9%3e%c0%80%d9%faQ%aeq%f3%e7%7d%a1H%2f%1fdA%3e2%baQw%1a%c3%ba%b1%02%21%0b4X%0a%1ax%c3%f9A%d6%f7%adM%06%af%0f%2cf0%ad%f0%26%1d%8d%ebq%04%0f%d5%a4%d9%feQ4K%f9%13cS%12%ca%8a%ec%a6%88%60%8e%cd%7b%fc%08


2015-10-09T15:16:48.091Z,1444403808.091 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=true&momsn=3787549&filename=Logs%2F20151009T150848%2FExpress0005.lzma, 1
2015-10-09T15:16:48.092Z,1444403808.092 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3787549&filename=Logs%2F20151009T150848%2FExpress0005.lzma, key = 6, value = tethys
2015-10-09T15:16:48.092Z,1444403808.092 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3787549&filename=Logs%2F20151009T150848%2FExpress0005.lzma, key = 0, value = true
2015-10-09T15:16:48.093Z,1444403808.093 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151009T150848%2FExpress0005.lzma, key = 4, value = 3787549
2015-10-09T15:16:48.093Z,1444403808.093 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151009T150848%2FExpress0005.lzma
2015-10-09T15:16:48.094Z,1444403808.094 [DataOverHttps](INFO): Moved sent file to Logs/20151009T150848/Express0005.lzma.bak
2015-10-09T15:16:48.094Z,1444403808.094 [DataOverHttps](INFO): SBD MOMSN=3787549
2015-10-09T15:16:48.865Z,1444403808.865 [Default:CheckIn:Read_Iridium] Stopped
2015-10-09T15:16:48.865Z,1444403808.865 [Default:CheckIn:C.Wait] Running Loop=1
2015-10-09T15:16:48.865Z,1444403808.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2015-10-09T15:17:02.735Z,1444403822.735 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:17:07.537Z,1444403827.537 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:17:07.537Z,1444403827.537 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:17:07.538Z,1444403827.538 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:17:10.233Z,1444403830.233 [CommandLine](IMPORTANT): got command report mod NAL9602.sigQuality
2015-10-09T15:17:10.486Z,1444403830.486 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:18:07.828Z,1444403887.828 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:18:11.015Z,1444403891.015 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:18:11.015Z,1444403891.015 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:18:11.016Z,1444403891.016 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:18:18.666Z,1444403898.666 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:18:18.670Z,1444403898.670 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:18:18.670Z,1444403898.670 [BPC1](ERROR): Communications Fault
2015-10-09T15:18:18.718Z,1444403898.718 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:18:19.868Z,1444403899.868 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:18:19.868Z,1444403899.868 [BPC1] No Fault, FailCount= 1
2015-10-09T15:18:58.774Z,1444403938.774 [CBIT](DEBUG): EFC running - data check-sum false
2015-10-09T15:19:05.362Z,1444403945.362 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:19:06.954Z,1444403946.954 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:19:06.954Z,1444403946.954 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:19:06.955Z,1444403946.955 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:19:13.818Z,1444403953.818 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:19:13.818Z,1444403953.818 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:19:13.818Z,1444403953.818 [BPC1](ERROR): Data Fault
2015-10-09T15:19:13.900Z,1444403953.900 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:19:14.995Z,1444403954.995 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:19:14.995Z,1444403954.995 [BPC1] No Fault, FailCount= 1
2015-10-09T15:19:49.382Z,1444403989.382 [DVL_micro](ERROR): only read 45 of 46 data items
2015-10-09T15:19:49.382Z,1444403989.382 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 50 -12 5 14 13 13 24.0 68.1 39.1 2 -38.5 61.1 39.1 2 24 68 39 2 -38 61 39 2 -3.54 -1.57 51.6 -1.0 16.5 0.005 35.0 1489 102
2015-10-09T15:19:49.397Z,1444403989.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:19:49.793Z,1444403989.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:19:50.185Z,1444403990.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.2 s old, using for 20.0 s.
2015-10-09T15:19:50.567Z,1444403990.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.6 s old, using for 20.0 s.
2015-10-09T15:20:48.596Z,1444404048.596 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:20:48.596Z,1444404048.596 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:20:48.596Z,1444404048.596 [BPC1](ERROR): Communications Fault
2015-10-09T15:20:48.639Z,1444404048.639 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:20:49.797Z,1444404049.797 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:20:49.797Z,1444404049.797 [BPC1] No Fault, FailCount= 1
2015-10-09T15:21:49.427Z,1444404109.427 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2015-10-09T15:21:49.427Z,1444404109.427 [Default:CheckIn:C.Wait] Stopped
2015-10-09T15:21:49.427Z,1444404109.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2015-10-09T15:21:49.427Z,1444404109.427 [Default:CheckIn:D] Running Loop=1
2015-10-09T15:21:49.806Z,1444404109.806 [Default:CheckIn:D] Stopped
2015-10-09T15:21:49.807Z,1444404109.807 [Default:CheckIn:E] Running Loop=1
2015-10-09T15:21:50.223Z,1444404110.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.466162 min 
2015-10-09T15:21:50.225Z,1444404110.225 [Default:CheckIn:E] Stopped
2015-10-09T15:21:50.225Z,1444404110.225 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-10-09T15:21:50.225Z,1444404110.225 [Default:CheckIn] Stopped
2015-10-09T15:21:50.225Z,1444404110.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2015-10-09T15:21:50.225Z,1444404110.225 [Default:CheckIn](INFO): Running loop #2
2015-10-09T15:21:50.226Z,1444404110.226 [Default:CheckIn] Running Loop=2
2015-10-09T15:21:50.226Z,1444404110.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2015-10-09T15:21:50.226Z,1444404110.226 [Default:CheckIn:Read_GPS] Running Loop=1
2015-10-09T15:21:51.002Z,1444404111.002 [NAL9602](INFO): Powering up
2015-10-09T15:21:51.082Z,1444404111.082 [Reporter](INFO): NAL9602.sigQuality no_value
2015-10-09T15:22:01.449Z,1444404121.449 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:22:02.205Z,1444404122.205 [NAL9602](INFO): NAL9602 initialized
2015-10-09T15:22:07.362Z,1444404127.362 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.408432
2015-10-09T15:22:34.197Z,1444404154.197 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-10-09T15:22:34.717Z,1444404154.717 [NAL9602](FAULT): received: 
2015-10-09T15:22:34.717Z,1444404154.717 [NAL9602] Data Fault, FailCount= 1
2015-10-09T15:22:34.717Z,1444404154.717 [NAL9602](ERROR): Data Fault
2015-10-09T15:22:34.764Z,1444404154.764 [CBIT](ERROR): Data Fault in component: NAL9602
2015-10-09T15:22:34.766Z,1444404154.766 [Reporter](INFO): NAL9602.sigQuality no_value
2015-10-09T15:22:35.013Z,1444404155.013 [NAL9602](INFO): Powering down
2015-10-09T15:22:35.816Z,1444404155.816 [CBIT](INFO): Clearing failed state for component NAL9602
2015-10-09T15:22:35.816Z,1444404155.816 [NAL9602] No Fault, FailCount= 1
2015-10-09T15:22:40.163Z,1444404160.163 [NAL9602](INFO): Powering up NAL9602
2015-10-09T15:22:50.631Z,1444404170.631 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:22:51.365Z,1444404171.365 [NAL9602](INFO): NAL9602 initialized
2015-10-09T15:23:20.568Z,1444404200.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:23:30.163Z,1444404210.163 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:23:30.163Z,1444404210.163 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:23:30.163Z,1444404210.163 [BPC1](ERROR): Communications Fault
2015-10-09T15:23:30.185Z,1444404210.185 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:23:31.384Z,1444404211.384 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:23:31.384Z,1444404211.384 [BPC1] No Fault, FailCount= 1
2015-10-09T15:24:05.705Z,1444404245.705 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:24:07.302Z,1444404247.302 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false&mtmsn=20151009T152239&fileSize=228&file=%94%E6%FE8%CBnZ%E0%FBk%DC%EF6%B6%FA%C78f%16j1%BA%D3%F9%F8%9F+%5CA%E0q%26%E9%5E%97%26%9F2%D3%EA%A71%D9%8Br%2F%CB%9E%09%D3%A8%83%12%BE+K6%A4O%DC%3D%14%CF%9Fh%26%AA%08%B4%C1%3A%9A%BB%16%1ER%B5%DDZ%C8%EAx%84i%E2%B0%BE7W%97%D1t%01%93%15%03%97g%2B%C2%11%9A_1%CC%AB%AD%14%C0%BBg%AD%C9y%F73%A4%5C%DE%88%12m%3A%E0%95K%15%08Epy%F9%A7Z%23gz%BE%EB%00%FB%29%285%F2%99%00%E2T%26-Y%1E%12%08%E3V%F7%06%BC%D4%9DXX%EF%FA%D8C%8E%0A%16%BC%09%A1%D2%E1%F4%22%92gD%ED%BC%D3w%80%B1%F0%CFXN%D6%F1ix%2C%A4%0CV%13l%28%BB%96%EF%B0g%A9%0C%97%EDZ%E9%8F%15%9B%7E%1C%AD%28%28g%D7Y%CB%5D%F2E, 1
2015-10-09T15:24:07.303Z,1444404247.303 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false&mtmsn=20151009T152239&fileSize=228&file=%94%E6%FE8%CBnZ%E0%FBk%DC%EF6%B6%FA%C78f%16j1%BA%D3%F9%F8%9F+%5CA%E0q%26%E9%5E%97%26%9F2%D3%EA%A71%D9%8Br%2F%CB%9E%09%D3%A8%83%12%BE+K6%A4O%DC%3D%14%CF%9Fh%26%AA%08%B4%C1%3A%9A%BB%16%1ER%B5%DDZ%C8%EAx%84i%E2%B0%BE7W%97%D1t%01%93%15%03%97g%2B%C2%11%9A_1%CC%AB%AD%14%C0%BBg%AD%C9y%F73%A4%5C%DE%88%12m%3A%E0%95K%15%08Epy%F9%A7Z%23gz%BE%EB%00%FB%29%285%F2%99%00%E2T%26-Y%1E%12%08%E3V%F7%06%BC%D4%9DXX%EF%FA%D8C%8E%0A%16%BC%09%A1%D2%E1%F4%22%92gD%ED%BC%D3w%80%B1%F0%CFXN%D6%F1ix%2C%A4%0CV%13l%28%BB%96%EF%B0g%A9%0C%97%EDZ%E9%8F%15%9B%7E%1C%AD%28%28g%D7Y%CB%5D%F2E, key = 6, value = tethys
2015-10-09T15:24:07.303Z,1444404247.303 [DataOverHttps](DEBUG): ParseDataRead( data = mtmsn=20151009T152239&fileSize=228&file=%94%E6%FE8%CBnZ%E0%FBk%DC%EF6%B6%FA%C78f%16j1%BA%D3%F9%F8%9F+%5CA%E0q%26%E9%5E%97%26%9F2%D3%EA%A71%D9%8Br%2F%CB%9E%09%D3%A8%83%12%BE+K6%A4O%DC%3D%14%CF%9Fh%26%AA%08%B4%C1%3A%9A%BB%16%1ER%B5%DDZ%C8%EAx%84i%E2%B0%BE7W%97%D1t%01%93%15%03%97g%2B%C2%11%9A_1%CC%AB%AD%14%C0%BBg%AD%C9y%F73%A4%5C%DE%88%12m%3A%E0%95K%15%08Epy%F9%A7Z%23gz%BE%EB%00%FB%29%285%F2%99%00%E2T%26-Y%1E%12%08%E3V%F7%06%BC%D4%9DXX%EF%FA%D8C%8E%0A%16%BC%09%A1%D2%E1%F4%22%92gD%ED%BC%D3w%80%B1%F0%CFXN%D6%F1ix%2C%A4%0CV%13l%28%BB%96%EF%B0g%A9%0C%97%EDZ%E9%8F%15%9B%7E%1C%AD%28%28g%D7Y%CB%5D%F2E, key = 0, value = false
2015-10-09T15:24:07.304Z,1444404247.304 [DataOverHttps](DEBUG): ParseDataRead( data = fileSize=228&file=%94%E6%FE8%CBnZ%E0%FBk%DC%EF6%B6%FA%C78f%16j1%BA%D3%F9%F8%9F+%5CA%E0q%26%E9%5E%97%26%9F2%D3%EA%A71%D9%8Br%2F%CB%9E%09%D3%A8%83%12%BE+K6%A4O%DC%3D%14%CF%9Fh%26%AA%08%B4%C1%3A%9A%BB%16%1ER%B5%DDZ%C8%EAx%84i%E2%B0%BE7W%97%D1t%01%93%15%03%97g%2B%C2%11%9A_1%CC%AB%AD%14%C0%BBg%AD%C9y%F73%A4%5C%DE%88%12m%3A%E0%95K%15%08Epy%F9%A7Z%23gz%BE%EB%00%FB%29%285%F2%99%00%E2T%26-Y%1E%12%08%E3V%F7%06%BC%D4%9DXX%EF%FA%D8C%8E%0A%16%BC%09%A1%D2%E1%F4%22%92gD%ED%BC%D3w%80%B1%F0%CFXN%D6%F1ix%2C%A4%0CV%13l%28%BB%96%EF%B0g%A9%0C%97%EDZ%E9%8F%15%9B%7E%1C%AD%28%28g%D7Y%CB%5D%F2E, key = 5, value = 20151009T152239
2015-10-09T15:24:07.305Z,1444404247.305 [DataOverHttps](DEBUG): ParseDataRead( data = file=%94%E6%FE8%CBnZ%E0%FBk%DC%EF6%B6%FA%C78f%16j1%BA%D3%F9%F8%9F+%5CA%E0q%26%E9%5E%97%26%9F2%D3%EA%A71%D9%8Br%2F%CB%9E%09%D3%A8%83%12%BE+K6%A4O%DC%3D%14%CF%9Fh%26%AA%08%B4%C1%3A%9A%BB%16%1ER%B5%DDZ%C8%EAx%84i%E2%B0%BE7W%97%D1t%01%93%15%03%97g%2B%C2%11%9A_1%CC%AB%AD%14%C0%BBg%AD%C9y%F73%A4%5C%DE%88%12m%3A%E0%95K%15%08Epy%F9%A7Z%23gz%BE%EB%00%FB%29%285%F2%99%00%E2T%26-Y%1E%12%08%E3V%F7%06%BC%D4%9DXX%EF%FA%D8C%8E%0A%16%BC%09%A1%D2%E1%F4%22%92gD%ED%BC%D3w%80%B1%F0%CFXN%D6%F1ix%2C%A4%0CV%13l%28%BB%96%EF%B0g%A9%0C%97%EDZ%E9%8F%15%9B%7E%1C%AD%28%28g%D7Y%CB%5D%F2E, key = 3, value = 228
2015-10-09T15:24:07.305Z,1444404247.305 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 1, value = %94%E6%FE8%CBnZ%E0%FBk%DC%EF6%B6%FA%C78f%16j1%BA%D3%F9%F8%9F+%5CA%E0q%26%E9%5E%97%26%9F2%D3%EA%A71%D9%8Br%2F%CB%9E%09%D3%A8%83%12%BE+K6%A4O%DC%3D%14%CF%9Fh%26%AA%08%B4%C1%3A%9A%BB%16%1ER%B5%DDZ%C8%EAx%84i%E2%B0%BE7W%97%D1t%01%93%15%03%97g%2B%C2%11%9A_1%CC%AB%AD%14%C0%BBg%AD%C9y%F73%A4%5C%DE%88%12m%3A%E0%95K%15%08Epy%F9%A7Z%23gz%BE%EB%00%FB%29%285%F2%99%00%E2T%26-Y%1E%12%08%E3V%F7%06%BC%D4%9DXX%EF%FA%D8C%8E%0A%16%BC%09%A1%D2%E1%F4%22%92gD%ED%BC%D3w%80%B1%F0%CFXN%D6%F1ix%2C%A4%0CV%13l%28%BB%96%EF%B0g%A9%0C%97%EDZ%E9%8F%15%9B%7E%1C%AD%28%28g%D7Y%CB%5D%F2E
2015-10-09T15:24:07.306Z,1444404247.306 [DataOverHttps](IMPORTANT): SBD MTMSN=20151009T152239
2015-10-09T15:24:07.308Z,1444404247.308 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006307
2015-10-09T15:24:15.589Z,1444404255.589 [DataOverHttps](INFO): dataWriting with SSL WROTE 230 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 64

imei=300234011866620&imeiPassword=e7u64%5e&mtmsn=20151009T152239


2015-10-09T15:24:15.591Z,1444404255.591 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.NeedCommsTime 30 minute;set profile_station.Lat 36.750 degree;set profile_station.Lon -122.022 degree;set profile_station.YoYoMaxDepth 100 meter" wnxs 1 2
2015-10-09T15:24:15.687Z,1444404255.687 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.NeedCommsTime 30 minute;set profile_station.Lat 36.750 degree;set profile_station.Lon -122.022 degree;set profile_station.YoYoMaxDepth 100 meter" wnxs 1 2.000000
2015-10-09T15:24:15.687Z,1444404255.687 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=wnxs
2015-10-09T15:24:15.688Z,1444404255.688 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='wnxs'): "load Science/profile_station.xml;set profile_station.NeedCommsTime 30 minute;set profile_station.Lat 36.750 degree;set profile_station.Lon -122.022 degree;set profile_station.YoYoMaxDepth 100 meter" ASAP
2015-10-09T15:24:17.581Z,1444404257.581 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false&mtmsn=20151009T152249&fileSize=84&file=%25%CA%CC%C6%5C8%B8n%3C%C4%BB%02%C4%3E%2C%CD%05%E9E%BD%0F%0D%F0%C2%5D%40%10%87%5D%98%FA%C1%3B%8D%273%BE%03%08%14%84%FF%E7%B7%9F3%96%F7%85%EC%06%5D%F8%3C%8Fw%DA%A9%F4%87%E5U%DFu%F2w%13%17%21%A9%A3%1C%955%3B%88%03%824.B%83%E0%E6, 1
2015-10-09T15:24:17.582Z,1444404257.582 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false&mtmsn=20151009T152249&fileSize=84&file=%25%CA%CC%C6%5C8%B8n%3C%C4%BB%02%C4%3E%2C%CD%05%E9E%BD%0F%0D%F0%C2%5D%40%10%87%5D%98%FA%C1%3B%8D%273%BE%03%08%14%84%FF%E7%B7%9F3%96%F7%85%EC%06%5D%F8%3C%8Fw%DA%A9%F4%87%E5U%DFu%F2w%13%17%21%A9%A3%1C%955%3B%88%03%824.B%83%E0%E6, key = 6, value = tethys
2015-10-09T15:24:17.582Z,1444404257.582 [DataOverHttps](DEBUG): ParseDataRead( data = mtmsn=20151009T152249&fileSize=84&file=%25%CA%CC%C6%5C8%B8n%3C%C4%BB%02%C4%3E%2C%CD%05%E9E%BD%0F%0D%F0%C2%5D%40%10%87%5D%98%FA%C1%3B%8D%273%BE%03%08%14%84%FF%E7%B7%9F3%96%F7%85%EC%06%5D%F8%3C%8Fw%DA%A9%F4%87%E5U%DFu%F2w%13%17%21%A9%A3%1C%955%3B%88%03%824.B%83%E0%E6, key = 0, value = false
2015-10-09T15:24:17.583Z,1444404257.583 [DataOverHttps](DEBUG): ParseDataRead( data = fileSize=84&file=%25%CA%CC%C6%5C8%B8n%3C%C4%BB%02%C4%3E%2C%CD%05%E9E%BD%0F%0D%F0%C2%5D%40%10%87%5D%98%FA%C1%3B%8D%273%BE%03%08%14%84%FF%E7%B7%9F3%96%F7%85%EC%06%5D%F8%3C%8Fw%DA%A9%F4%87%E5U%DFu%F2w%13%17%21%A9%A3%1C%955%3B%88%03%824.B%83%E0%E6, key = 5, value = 20151009T152249
2015-10-09T15:24:17.584Z,1444404257.584 [DataOverHttps](DEBUG): ParseDataRead( data = file=%25%CA%CC%C6%5C8%B8n%3C%C4%BB%02%C4%3E%2C%CD%05%E9E%BD%0F%0D%F0%C2%5D%40%10%87%5D%98%FA%C1%3B%8D%273%BE%03%08%14%84%FF%E7%B7%9F3%96%F7%85%EC%06%5D%F8%3C%8Fw%DA%A9%F4%87%E5U%DFu%F2w%13%17%21%A9%A3%1C%955%3B%88%03%824.B%83%E0%E6, key = 3, value = 84
2015-10-09T15:24:17.584Z,1444404257.584 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 1, value = %25%CA%CC%C6%5C8%B8n%3C%C4%BB%02%C4%3E%2C%CD%05%E9E%BD%0F%0D%F0%C2%5D%40%10%87%5D%98%FA%C1%3B%8D%273%BE%03%08%14%84%FF%E7%B7%9F3%96%F7%85%EC%06%5D%F8%3C%8Fw%DA%A9%F4%87%E5U%DFu%F2w%13%17%21%A9%A3%1C%955%3B%88%03%824.B%83%E0%E6
2015-10-09T15:24:17.584Z,1444404257.584 [DataOverHttps](IMPORTANT): SBD MTMSN=20151009T152249
2015-10-09T15:24:25.159Z,1444404265.159 [DataOverHttps](INFO): dataWriting with SSL WROTE 230 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 64

imei=300234011866620&imeiPassword=e7u64%5e&mtmsn=20151009T152249


2015-10-09T15:24:25.160Z,1444404265.160 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMinAltitude 14 meter;run " wnxs 2 2
2015-10-09T15:24:25.213Z,1444404265.213 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMinAltitude 14 meter;run " wnxs 2 2.000000
2015-10-09T15:24:25.214Z,1444404265.214 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=wnxs
2015-10-09T15:24:25.215Z,1444404265.215 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='wnxs'): "set profile_station.YoYoMinAltitude 14 meter;run " ASAP
2015-10-09T15:24:25.264Z,1444404265.264 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2015-10-09T15:24:25.273Z,1444404265.273 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2015-10-09T15:24:25.471Z,1444404265.471 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2015-10-09T15:24:25.474Z,1444404265.474 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 1.000000 h
2015-10-09T15:24:25.486Z,1444404265.486 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2015-10-09T15:24:25.491Z,1444404265.491 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2015-10-09T15:24:25.502Z,1444404265.502 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2015-10-09T15:24:25.509Z,1444404265.509 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2015-10-09T15:24:25.512Z,1444404265.512 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2015-10-09T15:24:25.520Z,1444404265.520 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2015-10-09T15:24:25.524Z,1444404265.524 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2015-10-09T15:24:25.536Z,1444404265.536 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2015-10-09T15:24:25.540Z,1444404265.540 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2015-10-09T15:24:25.551Z,1444404265.551 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2015-10-09T15:24:25.559Z,1444404265.559 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2015-10-09T15:24:25.562Z,1444404265.562 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2015-10-09T15:24:25.573Z,1444404265.573 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2015-10-09T15:24:25.577Z,1444404265.577 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2015-10-09T15:24:25.601Z,1444404265.601 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2015-10-09T15:24:25.794Z,1444404265.794 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2015-10-09T15:24:25.797Z,1444404265.797 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2015-10-09T15:24:25.808Z,1444404265.808 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2015-10-09T15:24:25.811Z,1444404265.811 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2015-10-09T15:24:25.815Z,1444404265.815 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2015-10-09T15:24:25.834Z,1444404265.834 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2015-10-09T15:24:25.840Z,1444404265.840 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 1.000000 bool
2015-10-09T15:24:25.844Z,1444404265.844 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 1.000000 bool
2015-10-09T15:24:25.848Z,1444404265.848 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2015-10-09T15:24:25.864Z,1444404265.864 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2015-10-09T15:24:25.872Z,1444404265.872 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2015-10-09T15:24:25.876Z,1444404265.876 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2015-10-09T15:24:25.887Z,1444404265.887 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2015-10-09T15:24:25.894Z,1444404265.894 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2015-10-09T15:24:25.902Z,1444404265.902 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2015-10-09T15:24:25.905Z,1444404265.905 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2015-10-09T15:24:25.912Z,1444404265.912 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2015-10-09T15:24:25.917Z,1444404265.917 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2015-10-09T15:24:25.939Z,1444404265.939 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2015-10-09T15:24:25.947Z,1444404265.947 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2015-10-09T15:24:25.966Z,1444404265.966 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2015-10-09T15:24:26.016Z,1444404266.016 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2015-10-09T15:24:26.072Z,1444404266.072 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2015-10-09T15:24:26.103Z,1444404266.103 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2015-10-09T15:24:26.105Z,1444404266.105 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2015-10-09T15:24:26.105Z,1444404266.105 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2015-10-09T15:24:26.211Z,1444404266.211 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2015-10-09T15:24:26.227Z,1444404266.227 [MissionManager](INFO): DefineArg profile_station:NeedComms.AcousticTimeout = 96.000000 h
2015-10-09T15:24:26.239Z,1444404266.239 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2015-10-09T15:24:26.246Z,1444404266.246 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2015-10-09T15:24:26.249Z,1444404266.249 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2015-10-09T15:24:26.257Z,1444404266.257 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2015-10-09T15:24:26.260Z,1444404266.260 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2015-10-09T15:24:26.264Z,1444404266.264 [MissionManager](INFO): DefineArg profile_station:NeedComms.BuoyancyTimeout = 3.000000 min
2015-10-09T15:24:26.280Z,1444404266.280 [profile_station:NeedComms:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-10-09T15:24:26.314Z,1444404266.314 [profile_station:NeedComms:E.Buoyancy](DEBUG): Construct Buoyancy.
2015-10-09T15:24:26.331Z,1444404266.331 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2015-10-09T15:24:26.394Z,1444404266.394 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2015-10-09T15:24:26.398Z,1444404266.398 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2015-10-09T15:24:26.409Z,1444404266.409 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2015-10-09T15:24:26.410Z,1444404266.410 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2015-10-09T15:24:26.435Z,1444404266.435 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2015-10-09T15:24:26.457Z,1444404266.457 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2015-10-09T15:24:26.547Z,1444404266.547 [profile_station:I.Pitch](DEBUG): Construct.
2015-10-09T15:24:26.554Z,1444404266.554 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2015-10-09T15:24:26.562Z,1444404266.562 [profile_station:K.SetSpeed](DEBUG): Construct.
2015-10-09T15:24:26.571Z,1444404266.571 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2015-10-09T15:24:26.620Z,1444404266.620 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2015-10-09T15:24:26.634Z,1444404266.634 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2015-10-09T15:24:26.683Z,1444404266.683 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2015-10-09T15:24:26.732Z,1444404266.732 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       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/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:hour/><Value>1</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2015-10-09T15:24:26.733Z,1444404266.733 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2015-10-09T15:24:26.766Z,1444404266.766 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:24:26.767Z,1444404266.767 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:24:26.768Z,1444404266.768 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:24:35.533Z,1444404275.533 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 30.000000 minute
2015-10-09T15:24:35.533Z,1444404275.533 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.750000 degree
2015-10-09T15:24:35.534Z,1444404275.534 [CommandLine](IMPORTANT): got command set profile_station.Lon -122.022003 degree
2015-10-09T15:24:35.535Z,1444404275.535 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 100.000000 meter
2015-10-09T15:24:37.948Z,1444404277.948 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinAltitude 14.000000 meter
2015-10-09T15:24:37.961Z,1444404277.961 [CommandLine](IMPORTANT): got command run 
2015-10-09T15:24:37.964Z,1444404277.964 [CommandLine](IMPORTANT): Running 
2015-10-09T15:24:38.047Z,1444404278.047 [Default] Stopped
2015-10-09T15:24:38.047Z,1444404278.047 [Default](DEBUG): Aggregate::uninitialize Default
2015-10-09T15:24:38.047Z,1444404278.047 [Default:A.GoToSurface] Stopped
2015-10-09T15:24:38.047Z,1444404278.047 [Default:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-10-09T15:24:38.047Z,1444404278.047 [Default:CheckIn] Stopped
2015-10-09T15:24:38.047Z,1444404278.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2015-10-09T15:24:38.047Z,1444404278.047 [Default:CheckIn:Read_GPS] Stopped
2015-10-09T15:24:38.047Z,1444404278.047 [MissionManager](IMPORTANT): Started mission profile_station
2015-10-09T15:24:38.048Z,1444404278.048 [profile_station] Running Loop=1
2015-10-09T15:24:38.048Z,1444404278.048 [profile_station](DEBUG): Aggregate::initialize profile_station
2015-10-09T15:24:38.048Z,1444404278.048 [profile_station:Science] Running Loop=1
2015-10-09T15:24:38.048Z,1444404278.048 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2015-10-09T15:24:38.048Z,1444404278.048 [profile_station:Science:A] Running Loop=1
2015-10-09T15:24:38.048Z,1444404278.048 [profile_station:Science:B] Running Loop=1
2015-10-09T15:24:38.052Z,1444404278.052 [profile_station:Science:C] Running Loop=1
2015-10-09T15:24:38.052Z,1444404278.052 [profile_station:Science:D] Running Loop=1
2015-10-09T15:24:38.053Z,1444404278.053 [profile_station:Science:E] Running Loop=1
2015-10-09T15:24:38.053Z,1444404278.053 [profile_station:Science:F] Running Loop=1
2015-10-09T15:24:38.053Z,1444404278.053 [profile_station:Science:PeakDetectChl] Running Loop=1
2015-10-09T15:24:38.053Z,1444404278.053 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2015-10-09T15:24:38.053Z,1444404278.053 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2015-10-09T15:24:38.053Z,1444404278.053 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2015-10-09T15:24:38.055Z,1444404278.055 [profile_station:Science:PeakDetectNO3] Running Loop=1
2015-10-09T15:24:38.055Z,1444404278.055 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2015-10-09T15:24:38.055Z,1444404278.055 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2015-10-09T15:24:38.055Z,1444404278.055 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2015-10-09T15:24:38.058Z,1444404278.058 [profile_station:StandardEnvelopes] Running Loop=1
2015-10-09T15:24:38.058Z,1444404278.058 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2015-10-09T15:24:38.058Z,1444404278.058 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2015-10-09T15:24:38.058Z,1444404278.058 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2015-10-09T15:24:38.058Z,1444404278.058 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2015-10-09T15:24:38.058Z,1444404278.058 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:I.Pitch] Running Loop=1
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:I.Pitch](DEBUG): Initialize.
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:J.Buoyancy] Running Loop=1
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:K.SetSpeed] Running Loop=1
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:K.SetSpeed](DEBUG): Initialize.
2015-10-09T15:24:38.059Z,1444404278.059 [profile_station:L.DepthEnvelope] Running Loop=1
2015-10-09T15:24:38.060Z,1444404278.060 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2015-10-09T15:24:38.060Z,1444404278.060 [profile_station:M.AltitudeEnvelope] Running Loop=1
2015-10-09T15:24:38.060Z,1444404278.060 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2015-10-09T15:24:38.060Z,1444404278.060 [profile_station:N.YoYo] Running Loop=1
2015-10-09T15:24:38.060Z,1444404278.060 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2015-10-09T15:24:38.069Z,1444404278.069 [profile_station:C] Running Loop=1
2015-10-09T15:24:38.070Z,1444404278.070 [profile_station:N.YoYo] Running Loop=1
2015-10-09T15:24:38.079Z,1444404278.079 [profile_station:M.AltitudeEnvelope] Running Loop=1
2015-10-09T15:24:38.093Z,1444404278.093 [profile_station:L.DepthEnvelope] Running Loop=1
2015-10-09T15:24:38.103Z,1444404278.103 [profile_station:K.SetSpeed] Running Loop=1
2015-10-09T15:24:38.112Z,1444404278.112 [profile_station:J.Buoyancy] Running Loop=1
2015-10-09T15:24:38.129Z,1444404278.129 [profile_station:I.Pitch] Running Loop=1
2015-10-09T15:24:38.135Z,1444404278.135 [profile_station:StandardEnvelopes] Running Loop=1
2015-10-09T15:24:38.135Z,1444404278.135 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2015-10-09T15:24:38.154Z,1444404278.154 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2015-10-09T15:24:38.159Z,1444404278.159 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2015-10-09T15:24:38.182Z,1444404278.182 [profile_station:C] Stopped
2015-10-09T15:24:38.182Z,1444404278.182 [profile_station:E] Running Loop=1
2015-10-09T15:24:38.182Z,1444404278.182 [profile_station:Science] Running Loop=1
2015-10-09T15:24:38.187Z,1444404278.187 [profile_station:Science:PeakDetectNO3] Stopped
2015-10-09T15:24:38.187Z,1444404278.187 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2015-10-09T15:24:38.187Z,1444404278.187 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2015-10-09T15:24:38.187Z,1444404278.187 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2015-10-09T15:24:38.187Z,1444404278.187 [profile_station:Science:PeakDetectChl] Running Loop=1
2015-10-09T15:24:38.200Z,1444404278.200 [profile_station:Science:F] Running Loop=1
2015-10-09T15:24:38.202Z,1444404278.202 [profile_station:Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2015-10-09T15:24:38.203Z,1444404278.203 [profile_station:Science:E] Stopped
2015-10-09T15:24:38.203Z,1444404278.203 [profile_station:Science:D] Stopped
2015-10-09T15:24:38.203Z,1444404278.203 [profile_station:Science:C] Running Loop=1
2015-10-09T15:24:38.204Z,1444404278.204 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2015-10-09T15:24:38.213Z,1444404278.213 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2015-10-09T15:24:38.215Z,1444404278.215 [profile_station:Science:B] Stopped
2015-10-09T15:24:38.215Z,1444404278.215 [profile_station:Science:A] Running Loop=1
2015-10-09T15:24:38.216Z,1444404278.216 [profile_station:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2015-10-09T15:24:38.342Z,1444404278.342 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2015-10-09T15:24:38.342Z,1444404278.342 [WetLabsBB2FL](INFO): Powering up
2015-10-09T15:24:38.490Z,1444404278.490 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-10-09T15:24:38.497Z,1444404278.497 [profile_station:E] Stopped
2015-10-09T15:24:38.497Z,1444404278.497 [profile_station:F] Running Loop=1
2015-10-09T15:24:38.506Z,1444404278.506 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-10-09T15:24:38.883Z,1444404278.883 [profile_station:F] Stopped
2015-10-09T15:24:38.883Z,1444404278.883 [profile_station:G] Running Loop=1
2015-10-09T15:24:38.922Z,1444404278.922 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:39.327Z,1444404279.327 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:39.412Z,1444404279.412 [profile_station:G] Stopped
2015-10-09T15:24:39.412Z,1444404279.412 [profile_station:StartingMission] Running Loop=1
2015-10-09T15:24:39.412Z,1444404279.412 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2015-10-09T15:24:39.735Z,1444404279.735 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:39.926Z,1444404279.926 [profile_station:NeedComms] Running Loop=1
2015-10-09T15:24:39.926Z,1444404279.926 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2015-10-09T15:24:39.926Z,1444404279.926 [profile_station:NeedComms:A.GoToSurface] Running Loop=1
2015-10-09T15:24:39.926Z,1444404279.926 [profile_station:NeedComms:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-10-09T15:24:39.927Z,1444404279.927 [profile_station:NeedComms:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-10-09T15:24:39.933Z,1444404279.933 [profile_station:NeedComms:A.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2015-10-09T15:24:39.933Z,1444404279.933 [profile_station:NeedComms:A.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2015-10-09T15:24:39.934Z,1444404279.934 [profile_station:NeedComms:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2015-10-09T15:24:39.934Z,1444404279.934 [profile_station:NeedComms:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2015-10-09T15:24:39.951Z,1444404279.951 [profile_station:NeedComms:B] Running Loop=1
2015-10-09T15:24:40.152Z,1444404280.152 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:40.324Z,1444404280.324 [profile_station:NeedComms:B](DEBUG): Initialize ReadDataComponent to sense time_fix
2015-10-09T15:24:40.574Z,1444404280.574 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:40.977Z,1444404280.977 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:41.410Z,1444404281.410 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:41.818Z,1444404281.818 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:42.223Z,1444404282.223 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:42.648Z,1444404282.648 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:43.056Z,1444404283.056 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:43.461Z,1444404283.461 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:43.890Z,1444404283.890 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:44.305Z,1444404284.305 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:44.719Z,1444404284.719 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:45.140Z,1444404285.140 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:45.548Z,1444404285.548 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:45.961Z,1444404285.961 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:46.378Z,1444404286.378 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:46.782Z,1444404286.782 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:47.195Z,1444404287.195 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:47.615Z,1444404287.615 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:48.025Z,1444404288.025 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:48.441Z,1444404288.441 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:48.865Z,1444404288.865 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:49.278Z,1444404289.278 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:49.684Z,1444404289.684 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:50.108Z,1444404290.108 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:50.516Z,1444404290.516 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:50.921Z,1444404290.921 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:51.345Z,1444404291.345 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:51.758Z,1444404291.758 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:52.167Z,1444404292.167 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:52.583Z,1444404292.583 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:52.988Z,1444404292.988 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:53.400Z,1444404293.400 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:53.821Z,1444404293.821 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:54.230Z,1444404294.230 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:54.640Z,1444404294.640 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:55.059Z,1444404295.059 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:55.472Z,1444404295.472 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:55.884Z,1444404295.884 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:56.309Z,1444404296.309 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:56.717Z,1444404296.717 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:57.131Z,1444404297.131 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:57.547Z,1444404297.547 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:57.952Z,1444404297.952 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:58.364Z,1444404298.364 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:58.781Z,1444404298.781 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:59.193Z,1444404299.193 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:24:59.598Z,1444404299.598 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:00.023Z,1444404300.023 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:00.427Z,1444404300.427 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:00.844Z,1444404300.844 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:01.264Z,1444404301.264 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:01.669Z,1444404301.669 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:02.078Z,1444404302.078 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:02.503Z,1444404302.503 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:02.916Z,1444404302.916 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:03.321Z,1444404303.321 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:03.736Z,1444404303.736 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:04.141Z,1444404304.141 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:04.550Z,1444404304.550 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:04.963Z,1444404304.963 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:05.371Z,1444404305.371 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:05.776Z,1444404305.776 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:06.192Z,1444404306.192 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:06.605Z,1444404306.605 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:07.017Z,1444404307.017 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:07.450Z,1444404307.450 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:07.863Z,1444404307.863 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:08.271Z,1444404308.271 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:08.704Z,1444404308.704 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:09.109Z,1444404309.109 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:09.521Z,1444404309.521 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:09.935Z,1444404309.935 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:10.347Z,1444404310.347 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:10.751Z,1444404310.751 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:11.228Z,1444404311.228 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:15.824Z,1444404315.824 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:20.206Z,1444404320.206 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:27.213Z,1444404327.213 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:34.283Z,1444404334.283 [DataOverHttps](INFO): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1
User-Agent: LRAUV
Host: 134.89.2.23:443
Accept: */*
Content-Type: application/x-www-form-urlencoded
Content-Length: 42

imei=300234011866620&imeiPassword=e7u64%5e


2015-10-09T15:25:36.844Z,1444404336.844 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:37.099Z,1444404337.099 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=tethys&busy=false, 1
2015-10-09T15:25:37.099Z,1444404337.099 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = tethys
2015-10-09T15:25:37.100Z,1444404337.100 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false
2015-10-09T15:25:47.625Z,1444404347.625 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:54.235Z,1444404354.235 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:25:59.703Z,1444404359.703 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:26:00.397Z,1444404360.397 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:01.462Z,1444404361.462 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:03.025Z,1444404363.025 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:07.207Z,1444404367.207 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:08.265Z,1444404368.265 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:09.827Z,1444404369.827 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:10.842Z,1444404370.842 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:26:10.842Z,1444404370.842 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:26:17.212Z,1444404377.212 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:26:17.212Z,1444404377.212 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:26:17.212Z,1444404377.212 [BPC1](ERROR): Data Fault
2015-10-09T15:26:17.261Z,1444404377.261 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:17.289Z,1444404377.289 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:26:18.454Z,1444404378.454 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:26:18.454Z,1444404378.454 [BPC1] No Fault, FailCount= 1
2015-10-09T15:26:19.054Z,1444404379.054 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:21.033Z,1444404381.033 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:22.391Z,1444404382.391 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:23.232Z,1444404383.232 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:26.269Z,1444404386.269 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:28.669Z,1444404388.669 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:32.003Z,1444404392.003 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:26:32.003Z,1444404392.003 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:26:32.863Z,1444404392.863 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:34.433Z,1444404394.433 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:35.484Z,1444404395.484 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:35.000Z,1444404396.000 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:36.544Z,1444404396.544 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:26:37.297Z,1444404397.297 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:26:46.052Z,1444404406.052 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:47.202Z,1444404407.202 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:48.377Z,1444404408.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:26:51.191Z,1444404411.191 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:26:51.191Z,1444404411.191 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:26:55.069Z,1444404415.069 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:26:55.754Z,1444404415.754 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:27:03.423Z,1444404423.423 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:05.601Z,1444404425.601 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-10-09T15:27:06.802Z,1444404426.802 [NAL9602](INFO): GPS fix at 20151009T152538: (36.732528, -122.093002)
2015-10-09T15:27:06.878Z,1444404426.878 [profile_station:NeedComms:B] Stopped
2015-10-09T15:27:06.878Z,1444404426.878 [profile_station:NeedComms:C] Running Loop=1
2015-10-09T15:27:07.289Z,1444404427.289 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-10-09T15:27:07.613Z,1444404427.613 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:08.667Z,1444404428.667 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:13.494Z,1444404433.494 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:13.936Z,1444404433.936 [NAL9602](INFO): Powering down
2015-10-09T15:27:18.598Z,1444404438.598 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:26.454Z,1444404446.454 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:29.075Z,1444404449.075 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:30.241Z,1444404450.241 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:30.453Z,1444404450.453 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:30.651Z,1444404450.651 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:31.799Z,1444404451.799 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:32.844Z,1444404452.844 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:34.839Z,1444404454.839 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:35.885Z,1444404455.885 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:38.605Z,1444404458.605 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:38.724Z,1444404458.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:27:39.459Z,1444404459.459 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:42.483Z,1444404462.483 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:44.474Z,1444404464.474 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:48.669Z,1444404468.669 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:53.905Z,1444404473.905 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:55.247Z,1444404475.247 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:27:58.363Z,1444404478.363 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:27:58.363Z,1444404478.363 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:27:58.363Z,1444404478.363 [BPC1](ERROR): Communications Fault
2015-10-09T15:27:58.392Z,1444404478.392 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:27:59.695Z,1444404479.695 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:27:59.695Z,1444404479.695 [BPC1] No Fault, FailCount= 1
2015-10-09T15:28:03.628Z,1444404483.628 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:04.339Z,1444404484.339 [DVL_micro](ERROR): only read 45 of 46 data items
2015-10-09T15:28:04.339Z,1444404484.339 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 504 362 363 459 9 9 29 10 188.2 -129.5 455.1 2 157.6 165.3 455.1 2 188 -129 455 2 157 165 455 2 -0.65 -0.57 80.9 -1.0 16.4 0.005 3 126
2015-10-09T15:28:04.354Z,1444404484.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:28:04.752Z,1444404484.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:28:05.161Z,1444404485.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.2 s old, using for 20.0 s.
2015-10-09T15:28:05.571Z,1444404485.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.6 s old, using for 20.0 s.
2015-10-09T15:28:09.134Z,1444404489.134 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:28:12.843Z,1444404492.843 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:14.409Z,1444404494.409 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:14.623Z,1444404494.623 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:14.832Z,1444404494.832 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:17.870Z,1444404497.870 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:25.611Z,1444404505.611 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:28.435Z,1444404508.435 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:29.480Z,1444404509.480 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:35.242Z,1444404515.242 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:37.129Z,1444404517.129 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.030960
2015-10-09T15:28:37.139Z,1444404517.139 [NAL9602](INFO): Powering up
2015-10-09T15:28:37.247Z,1444404517.247 [Reporter](INFO): NAL9602.sigQuality no_value
2015-10-09T15:28:46.023Z,1444404526.023 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:28:47.611Z,1444404527.611 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:28:48.335Z,1444404528.335 [NAL9602](INFO): NAL9602 initialized
2015-10-09T15:28:58.836Z,1444404538.836 [CBIT](DEBUG): EFC running - data check-sum false
2015-10-09T15:29:03.151Z,1444404543.151 [BPC1](ERROR): Failed to parse message.
2015-10-09T15:29:03.151Z,1444404543.151 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:29:03.151Z,1444404543.151 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:29:03.151Z,1444404543.151 [BPC1](ERROR): Data Fault
2015-10-09T15:29:03.231Z,1444404543.231 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:29:04.405Z,1444404544.405 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:29:04.405Z,1444404544.405 [BPC1] No Fault, FailCount= 1
2015-10-09T15:29:07.913Z,1444404547.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:29:09.916Z,1444404549.916 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:11.045Z,1444404551.045 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:12.095Z,1444404552.095 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:17.986Z,1444404557.986 [NAL9602](FAULT): Queried for signal strength and failed to receive proper response. no error
2015-10-09T15:29:18.072Z,1444404558.072 [NAL9602](ERROR): received: 
COMMAND MODE: Started

COMMAND MODE: Ready for Input

2015-10-09T15:29:18.072Z,1444404558.072 [NAL9602] Data Fault, FailCount= 2
2015-10-09T15:29:18.072Z,1444404558.072 [NAL9602](ERROR): Data Fault
2015-10-09T15:29:18.119Z,1444404558.119 [CBIT](ERROR): Data Fault in component: NAL9602
2015-10-09T15:29:18.155Z,1444404558.155 [Reporter](INFO): NAL9602.sigQuality no_value
2015-10-09T15:29:18.374Z,1444404558.374 [NAL9602](INFO): Powering down
2015-10-09T15:29:19.221Z,1444404559.221 [CBIT](INFO): Clearing failed state for component NAL9602
2015-10-09T15:29:19.221Z,1444404559.221 [NAL9602] No Fault, FailCount= 2
2015-10-09T15:29:23.560Z,1444404563.560 [NAL9602](INFO): Powering up NAL9602
2015-10-09T15:29:30.215Z,1444404570.215 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:34.104Z,1444404574.104 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:29:34.759Z,1444404574.759 [NAL9602](INFO): NAL9602 initialized
2015-10-09T15:29:35.187Z,1444404575.187 [BPC1](ERROR): Failed to parse message.
2015-10-09T15:29:35.188Z,1444404575.188 [BPC1](ERROR): Failed to parse bank A battery data
2015-10-09T15:29:35.188Z,1444404575.188 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:29:35.188Z,1444404575.188 [BPC1](ERROR): Data Fault
2015-10-09T15:29:35.218Z,1444404575.218 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:29:36.459Z,1444404576.459 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:29:36.459Z,1444404576.459 [BPC1] No Fault, FailCount= 1
2015-10-09T15:29:39.839Z,1444404579.839 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:42.458Z,1444404582.458 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:43.809Z,1444404583.809 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:47.902Z,1444404587.902 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:52.090Z,1444404592.090 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:53.663Z,1444404593.663 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:56.279Z,1444404596.279 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:29:59.101Z,1444404599.101 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:02.871Z,1444404602.871 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:07.609Z,1444404607.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:30:07.705Z,1444404607.705 [BPC1](ERROR): Failed to parse message.
2015-10-09T15:30:07.706Z,1444404607.706 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:30:07.706Z,1444404607.706 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:30:07.706Z,1444404607.706 [BPC1](ERROR): Data Fault
2015-10-09T15:30:07.787Z,1444404607.787 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:30:08.839Z,1444404608.839 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:30:08.843Z,1444404608.843 [BPC1] No Fault, FailCount= 1
2015-10-09T15:30:13.653Z,1444404613.653 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:20.040Z,1444404620.040 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:23.282Z,1444404623.282 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:23.706Z,1444404623.706 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:25.667Z,1444404625.667 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:30:25.667Z,1444404625.667 [BPC1] Communications Fault, FailCount= 2
2015-10-09T15:30:25.667Z,1444404625.667 [BPC1](ERROR): Communications Fault
2015-10-09T15:30:25.733Z,1444404625.733 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:30:25.901Z,1444404625.901 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:26.849Z,1444404626.849 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:30:26.849Z,1444404626.849 [BPC1] No Fault, FailCount= 2
2015-10-09T15:30:29.676Z,1444404629.676 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:36.682Z,1444404636.682 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:37.845Z,1444404637.845 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:39.310Z,1444404639.310 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:43.497Z,1444404643.497 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:53.224Z,1444404653.224 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:30:59.105Z,1444404659.105 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:00.422Z,1444404660.422 [BPC1](ERROR): Failed to parse bank B battery data
2015-10-09T15:31:00.422Z,1444404660.422 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:31:00.422Z,1444404660.422 [BPC1](ERROR): Data Fault
2015-10-09T15:31:00.502Z,1444404660.502 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:31:01.708Z,1444404661.708 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:31:01.708Z,1444404661.708 [BPC1] No Fault, FailCount= 1
2015-10-09T15:31:05.066Z,1444404665.066 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:08.141Z,1444404668.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:31:09.818Z,1444404669.818 [CTD_NeilBrown](ERROR): Bad response: 41.95419
2015-10-09T15:31:10.816Z,1444404670.816 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:13.084Z,1444404673.084 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-10-09T15:31:16.268Z,1444404676.268 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:23.279Z,1444404683.279 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:24.220Z,1444404684.220 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:24.653Z,1444404684.653 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:25.907Z,1444404685.907 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:29.028Z,1444404689.028 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:30.077Z,1444404690.077 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:31.450Z,1444404691.450 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:31:31.450Z,1444404691.450 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:31:35.906Z,1444404695.906 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:31:38.892Z,1444404698.892 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:31:49.239Z,1444404709.239 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:50.290Z,1444404710.290 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:51.864Z,1444404711.864 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:53.630Z,1444404713.630 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:31:57.785Z,1444404717.785 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:31:57.786Z,1444404717.786 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:31:59.920Z,1444404719.920 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:01.911Z,1444404721.911 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:02.232Z,1444404722.232 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:32:05.052Z,1444404725.052 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:08.001Z,1444404728.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:32:11.226Z,1444404731.226 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:20.440Z,1444404740.440 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:23.052Z,1444404743.052 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:29.651Z,1444404749.651 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:30.075Z,1444404750.075 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:30.995Z,1444404750.995 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:32:31.634Z,1444404751.634 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:36.874Z,1444404756.874 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:37.929Z,1444404757.929 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:38.235Z,1444404758.235 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:43.464Z,1444404763.464 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:43.744Z,1444404763.744 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:32:43.744Z,1444404763.744 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:32:48.187Z,1444404768.187 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:32:53.103Z,1444404773.103 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:53.829Z,1444404773.829 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:32:54.248Z,1444404774.248 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:01.259Z,1444404781.259 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:02.305Z,1444404782.305 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:03.877Z,1444404783.877 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:05.448Z,1444404785.448 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:08.277Z,1444404788.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:33:08.287Z,1444404788.287 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:08.297Z,1444404788.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:33:08.297Z,1444404788.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:33:12.880Z,1444404792.880 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:33:16.027Z,1444404796.027 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:21.467Z,1444404801.467 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:32.673Z,1444404812.673 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:33.542Z,1444404813.542 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:33:33.718Z,1444404813.718 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:39.479Z,1444404819.479 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:43.449Z,1444404823.449 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:47.524Z,1444404827.524 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:33:47.524Z,1444404827.524 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:33:51.309Z,1444404831.309 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:51.617Z,1444404831.617 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-10-09T15:33:57.270Z,1444404837.270 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:33:58.324Z,1444404838.324 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:08.497Z,1444404848.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:34:10.672Z,1444404850.672 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:12.242Z,1444404852.242 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:13.299Z,1444404853.299 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:13.315Z,1444404853.315 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:34:13.316Z,1444404853.316 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:34:14.866Z,1444404854.866 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:15.911Z,1444404855.911 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:17.482Z,1444404857.482 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:17.873Z,1444404857.873 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:34:19.064Z,1444404859.064 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:20.114Z,1444404860.114 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:26.068Z,1444404866.068 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:32.653Z,1444404872.653 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:34.861Z,1444404874.861 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:39.091Z,1444404879.091 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:34:39.092Z,1444404879.092 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:34:41.235Z,1444404881.235 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:43.101Z,1444404883.101 [DVL_micro](ERROR): only read 44 of 46 data items
2015-10-09T15:34:43.104Z,1444404883.104 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 -241 -209 -358 -380 10 17 32 11 156.2 228.2 -320.3 2 20.7 275.8 -320.3 2 156 228 -3275 -320 2 -5.87 -0.83 30.1 -1.0 16.4 0.005 35.0 1489 125
2015-10-09T15:34:43.148Z,1444404883.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:34:43.558Z,1444404883.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:34:43.625Z,1444404883.625 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:34:44.161Z,1444404884.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.4 s old, using for 20.0 s.
2015-10-09T15:34:46.925Z,1444404886.925 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:34:47.529Z,1444404887.529 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:50.245Z,1444404890.245 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:34:54.239Z,1444404894.239 [BPC1](ERROR): Failed to parse bank A battery data
2015-10-09T15:34:54.239Z,1444404894.239 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:34:54.239Z,1444404894.239 [BPC1](ERROR): Data Fault
2015-10-09T15:34:54.321Z,1444404894.321 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:34:55.479Z,1444404895.479 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:34:55.479Z,1444404895.479 [BPC1] No Fault, FailCount= 1
2015-10-09T15:35:02.496Z,1444404902.496 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:05.007Z,1444404905.007 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:35:05.007Z,1444404905.007 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:35:09.016Z,1444404909.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:35:09.532Z,1444404909.532 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:35:10.669Z,1444404910.669 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:13.077Z,1444404913.077 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:28.889Z,1444404928.889 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:30.868Z,1444404930.868 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:31.138Z,1444404931.138 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:35:31.138Z,1444404931.138 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:35:31.922Z,1444404931.922 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:32.438Z,1444404932.438 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:32.860Z,1444404932.860 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:33.909Z,1444404933.909 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:35.629Z,1444404935.629 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:35:35.700Z,1444404935.700 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:37.883Z,1444404937.883 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:38.929Z,1444404938.929 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:44.689Z,1444404944.689 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:48.885Z,1444404948.885 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:50.656Z,1444404950.656 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:53.904Z,1444404953.904 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:35:56.890Z,1444404956.890 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:35:56.891Z,1444404956.891 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:36:02.494Z,1444404962.494 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:06.676Z,1444404966.676 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:07.525Z,1444404967.525 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:08.988Z,1444404968.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:36:11.912Z,1444404971.912 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:13.682Z,1444404973.682 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:15.445Z,1444404975.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:36:15.446Z,1444404975.446 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:36:19.456Z,1444404979.456 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:19.886Z,1444404979.886 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:36:24.467Z,1444404984.467 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:24.893Z,1444404984.893 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:28.394Z,1444404988.394 [BPC1](ERROR): Failed to parse bank A battery data
2015-10-09T15:36:28.394Z,1444404988.394 [BPC1] Data Fault, FailCount= 1
2015-10-09T15:36:28.394Z,1444404988.394 [BPC1](ERROR): Data Fault
2015-10-09T15:36:28.470Z,1444404988.470 [CBIT](ERROR): Data Fault in component: BPC1
2015-10-09T15:36:29.499Z,1444404989.499 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:36:29.716Z,1444404989.716 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:36:29.716Z,1444404989.716 [BPC1] No Fault, FailCount= 1
2015-10-09T15:36:41.578Z,1444405001.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:36:41.578Z,1444405001.578 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:36:45.737Z,1444405005.737 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:36:49.067Z,1444405009.067 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:00.067Z,1444405020.067 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:00.700Z,1444405020.700 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:02.258Z,1444405022.258 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:03.320Z,1444405023.320 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:06.482Z,1444405026.482 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:37:06.661Z,1444405026.661 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:08.648Z,1444405028.648 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:09.650Z,1444405029.650 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:37:10.548Z,1444405030.548 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:11.285Z,1444405031.285 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:13.669Z,1444405033.669 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:17.338Z,1444405037.338 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:21.536Z,1444405041.536 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:22.057Z,1444405042.057 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:24.149Z,1444405044.149 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:24.869Z,1444405044.869 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:25.232Z,1444405045.232 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:37:25.232Z,1444405045.232 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:37:29.068Z,1444405049.068 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:29.723Z,1444405049.723 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:37:33.753Z,1444405053.753 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:37:33.754Z,1444405053.754 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:37:33.754Z,1444405053.754 [BPC1](ERROR): Communications Fault
2015-10-09T15:37:33.836Z,1444405053.836 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:37:34.096Z,1444405054.096 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:34.992Z,1444405054.992 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:37:34.993Z,1444405054.993 [BPC1] No Fault, FailCount= 1
2015-10-09T15:37:38.270Z,1444405058.270 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:42.567Z,1444405062.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:37:42.567Z,1444405062.567 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:37:43.516Z,1444405063.516 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:46.130Z,1444405066.130 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:47.099Z,1444405067.099 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:37:47.905Z,1444405067.905 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:52.100Z,1444405072.100 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:56.911Z,1444405076.911 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:37:58.922Z,1444405078.922 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:00.253Z,1444405080.253 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:00.688Z,1444405080.688 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:38:00.688Z,1444405080.688 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:38:00.885Z,1444405080.885 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:01.306Z,1444405081.306 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:02.300Z,1444405082.300 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:38:02.300Z,1444405082.300 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:38:02.300Z,1444405082.300 [BPC1](ERROR): Communications Fault
2015-10-09T15:38:02.366Z,1444405082.366 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:38:03.539Z,1444405083.539 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:38:03.539Z,1444405083.539 [BPC1] No Fault, FailCount= 1
2015-10-09T15:38:05.175Z,1444405085.175 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:38:05.493Z,1444405085.493 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:09.267Z,1444405089.267 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:09.621Z,1444405089.621 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:38:13.353Z,1444405093.353 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:16.077Z,1444405096.077 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:17.119Z,1444405097.119 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:18.477Z,1444405098.477 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:19.943Z,1444405099.943 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:20.820Z,1444405100.820 [DVL_micro](ERROR): only read 5 of 46 data items
2015-10-09T15:38:20.824Z,1444405100.824 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 - 0 34 -19 -103 -10 13 17 23 10 182.8 -12.0 -26.4 2 66.7 170.6 -26.4 2 182 -12 -26 2 66 170 -26 2 4.50 -0.55 72.4 -1.0 16.5 0.005 35.0 1489 78
2015-10-09T15:38:20.841Z,1444405100.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:38:21.246Z,1444405101.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:38:21.648Z,1444405101.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.2 s old, using for 20.0 s.
2015-10-09T15:38:22.009Z,1444405102.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.6 s old, using for 20.0 s.
2015-10-09T15:38:25.682Z,1444405105.682 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:38:25.682Z,1444405105.682 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:38:30.099Z,1444405110.099 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:30.574Z,1444405110.574 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:38:31.885Z,1444405111.885 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:38.001Z,1444405118.001 [DVL_micro](ERROR): only read 44 of 46 data items
2015-10-09T15:38:38.001Z,1444405118.001 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 70 114 70 0 9 14 12 13 0 152.1 68.5 2 -151.5 13.0 68.5 2 0 152 68 2 -151 13 68 2 2.88 -1.63 85.1 -1.0005 35.0 1489 123
2015-10-09T15:38:38.029Z,1444405118.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:38:38.055Z,1444405118.055 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:38.462Z,1444405118.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.9 s old, using for 20.0 s.
2015-10-09T15:38:38.764Z,1444405118.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.2 s old, using for 20.0 s.
2015-10-09T15:38:39.191Z,1444405119.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.6 s old, using for 20.0 s.
2015-10-09T15:38:45.070Z,1444405125.070 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:48.740Z,1444405128.740 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:50.951Z,1444405130.951 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:52.506Z,1444405132.506 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:52.934Z,1444405132.934 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:58.887Z,1444405138.887 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:38:58.990Z,1444405138.990 [CBIT](DEBUG): EFC running - data check-sum false
2015-10-09T15:38:59.521Z,1444405139.521 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:01.520Z,1444405141.520 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:03.707Z,1444405143.707 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:08.098Z,1444405148.098 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:09.746Z,1444405149.746 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:39:15.129Z,1444405155.129 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:18.879Z,1444405158.879 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:32.292Z,1444405172.292 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:32.913Z,1444405172.913 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:39.094Z,1444405179.094 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:47.675Z,1444405187.675 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:48.305Z,1444405188.305 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:49.883Z,1444405189.883 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:52.693Z,1444405192.693 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:53.753Z,1444405193.753 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:54.262Z,1444405194.262 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:55.732Z,1444405195.732 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:57.932Z,1444405197.932 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:39:59.513Z,1444405199.513 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:39:59.513Z,1444405199.513 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:39:59.513Z,1444405199.513 [BPC1](ERROR): Communications Fault
2015-10-09T15:39:59.544Z,1444405199.544 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:40:00.901Z,1444405200.901 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:40:00.901Z,1444405200.901 [BPC1] No Fault, FailCount= 1
2015-10-09T15:40:04.944Z,1444405204.944 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:07.671Z,1444405207.671 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:09.866Z,1444405209.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:40:17.931Z,1444405217.931 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:18.568Z,1444405218.568 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:18.873Z,1444405218.873 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:22.536Z,1444405222.536 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:26.514Z,1444405226.514 [DVL_micro](ERROR): checksum mismatch: reported77 , calculated:65
2015-10-09T15:40:26.514Z,1444405226.514 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 236 77 -178 3 11 12 30 12 552.5 98.8 37.2 2 187.9 .2 2 552 98 37 2 187 528 37 2 4.44 -1.07 60.3 -1.0 16.4 0.005 35.0 1489 77
2015-10-09T15:40:26.583Z,1444405226.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:40:26.956Z,1444405226.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:40:27.540Z,1444405227.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.4 s old, using for 20.0 s.
2015-10-09T15:40:27.879Z,1444405227.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.7 s old, using for 20.0 s.
2015-10-09T15:40:28.201Z,1444405228.201 [DVL_micro](ERROR): only read 40 of 46 data items
2015-10-09T15:40:28.201Z,1444405228.201 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 92 78 62 106 8 17 17 17 40.0 -37.4 91.1 2 52.2 16.4 91.1 2 40 -37 91 2 52 16 91 2 3.81 -1.80 60.5 - 0.005 35.0 1489 88
2015-10-09T15:40:28.225Z,1444405228.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 2.1 s old, using for 20.0 s.
2015-10-09T15:40:28.636Z,1444405228.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 2.5 s old, using for 20.0 s.
2015-10-09T15:40:28.979Z,1444405228.979 [BPC1](ERROR): Failed to receive battery data
2015-10-09T15:40:28.979Z,1444405228.979 [BPC1] Communications Fault, FailCount= 1
2015-10-09T15:40:28.980Z,1444405228.980 [BPC1](ERROR): Communications Fault
2015-10-09T15:40:28.990Z,1444405228.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 2.8 s old, using for 20.0 s.
2015-10-09T15:40:29.055Z,1444405229.055 [CBIT](ERROR): Communications Fault in component: BPC1
2015-10-09T15:40:29.426Z,1444405229.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 3.3 s old, using for 20.0 s.
2015-10-09T15:40:29.803Z,1444405229.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 3.6 s old, using for 20.0 s.
2015-10-09T15:40:30.204Z,1444405230.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 4.1 s old, using for 20.0 s.
2015-10-09T15:40:30.273Z,1444405230.273 [CBIT](INFO): Clearing failed state for component BPC1
2015-10-09T15:40:30.273Z,1444405230.273 [BPC1] No Fault, FailCount= 1
2015-10-09T15:40:30.645Z,1444405230.645 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 4.5 s old, using for 20.0 s.
2015-10-09T15:40:30.695Z,1444405230.695 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:31.042Z,1444405231.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 4.9 s old, using for 20.0 s.
2015-10-09T15:40:38.134Z,1444405238.134 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:39.905Z,1444405239.905 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:45.158Z,1444405245.158 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:45.670Z,1444405245.670 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:50.498Z,1444405250.498 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:57.300Z,1444405257.300 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:57.716Z,1444405257.716 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:40:58.432Z,1444405258.432 [DVL_micro](ERROR): only read 45 of 46 data items
2015-10-09T15:40:58.433Z,1444405258.433 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 173 142 191 213 14 11 9 14 -24.0 -94.8 193.8 2 93.6 -28.4 193.8 2 193 2 93 -28 193 2 6.82 -1.99 87.3 -1.0 16.4 0.005 35.0 1489 106
2015-10-09T15:40:58.523Z,1444405258.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.5 s old, using for 20.0 s.
2015-10-09T15:40:58.846Z,1444405258.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:40:59.454Z,1444405259.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.4 s old, using for 20.0 s.
2015-10-09T15:40:59.754Z,1444405259.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.7 s old, using for 20.0 s.
2015-10-09T15:41:00.549Z,1444405260.549 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:01.686Z,1444405261.686 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:04.307Z,1444405264.307 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:09.747Z,1444405269.747 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:10.047Z,1444405270.047 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:41:17.291Z,1444405277.291 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:17.707Z,1444405277.707 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:19.899Z,1444405279.899 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:24.718Z,1444405284.718 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:25.143Z,1444405285.143 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:27.979Z,1444405287.979 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:28.689Z,1444405288.689 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:32.879Z,1444405292.879 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:34.349Z,1444405294.349 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:35.924Z,1444405295.924 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:36.148Z,1444405296.148 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:39.495Z,1444405299.495 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:41.160Z,1444405301.160 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:43.163Z,1444405303.163 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:47.550Z,1444405307.550 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:48.279Z,1444405308.279 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:48.483Z,1444405308.483 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:49.538Z,1444405309.538 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:50.370Z,1444405310.370 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:51.720Z,1444405311.720 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:41:53.712Z,1444405313.712 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:00.101Z,1444405320.101 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:04.296Z,1444405324.296 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:07.335Z,1444405327.335 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:09.123Z,1444405329.123 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:09.532Z,1444405329.532 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:10.406Z,1444405330.406 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:42:12.873Z,1444405332.873 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:13.502Z,1444405333.502 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:16.547Z,1444405336.547 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:19.164Z,1444405339.164 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:19.484Z,1444405339.484 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:22.088Z,1444405342.088 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:33.290Z,1444405353.290 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:33.410Z,1444405353.410 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:42:36.121Z,1444405356.121 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:36.955Z,1444405356.955 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:38.105Z,1444405358.105 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:38.531Z,1444405358.531 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:45.349Z,1444405365.349 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:45.985Z,1444405365.985 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:51.717Z,1444405371.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:42:51.717Z,1444405371.717 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:42:56.199Z,1444405376.199 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-10-09T15:42:57.059Z,1444405377.059 [DVL_micro](ERROR): checksum mismatch: reported100 , calculated:112
2015-10-09T15:42:57.059Z,1444405377.059 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 -274 -259 -219 -161 16 14 14 8 -73.4 -130.8 -246.2 2 116.9 -94.0 -246.2 2 -73 6 2 116 -94 -246 2 5.12 -2.52 80.5 -1.0 16.4 0.005 35.0 1489 100
2015-10-09T15:42:57.112Z,1444405377.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:42:57.166Z,1444405377.166 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:42:57.453Z,1444405377.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.7 s old, using for 20.0 s.
2015-10-09T15:42:57.876Z,1444405377.876 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.1 s old, using for 20.0 s.
2015-10-09T15:42:58.269Z,1444405378.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.5 s old, using for 20.0 s.
2015-10-09T15:42:59.146Z,1444405379.146 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-10-09T15:43:01.559Z,1444405381.559 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:02.711Z,1444405382.711 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:04.698Z,1444405384.698 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:04.912Z,1444405384.912 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:05.187Z,1444405385.187 [DVL_micro](ERROR): only read 44 of 46 data items
2015-10-09T15:43:05.187Z,1444405385.187 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 115 211 360 252 13 18 26 16 -4.7 252.9 2 -20.9 -330.9 252.9 2 -327 -54 252 2 -20 -330 252 2 6.00 -2.37 76.9 -1.0 16.4 0.005 35.0
2015-10-09T15:43:05.222Z,1444405385.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:43:05.673Z,1444405385.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.9 s old, using for 20.0 s.
2015-10-09T15:43:06.009Z,1444405386.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.2 s old, using for 20.0 s.
2015-10-09T15:43:06.398Z,1444405386.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.6 s old, using for 20.0 s.
2015-10-09T15:43:10.773Z,1444405390.773 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2015-10-09T15:43:13.496Z,1444405393.496 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:17.593Z,1444405397.593 [NAL9602](INFO): SBD MO Status=2, MOMSN=34848, MT Status=2, MTMSN=0
2015-10-09T15:43:17.594Z,1444405397.594 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-10-09T15:43:19.149Z,1444405399.149 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:22.111Z,1444405402.111 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-10-09T15:43:25.959Z,1444405405.959 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:28.469Z,1444405408.469 [DVL_micro](ERROR): only read 43 of 46 data items
2015-10-09T15:43:28.469Z,1444405408.469 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 -0.1 -0.1 -0.1 -0.1 0 0 0 0 -20 -6 -88 -209 8 1 2 -190.3 213.1 -87.1 2 90 270 -87 2 -190 213 -87 2 -3.19 -10.96 60.3 -1.0 16.4 0.005 35.0 1489 113
2015-10-09T15:43:28.512Z,1444405408.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.4 s old, using for 20.0 s.
2015-10-09T15:43:28.874Z,1444405408.874 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 0.8 s old, using for 20.0 s.
2015-10-09T15:43:28.888Z,1444405408.888 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:29.366Z,1444405409.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.3 s old, using for 20.0 s.
2015-10-09T15:43:29.684Z,1444405409.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): Water reference layer data is 1.6 s old, using for 20.0 s.
2015-10-09T15:43:39.273Z,1444405419.273 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=34848, MT Status=1, MTMSN=295
2015-10-09T15:43:39.332Z,1444405419.332 [NAL9602](INFO): Sent 228 bytes from file Logs/20151009T150848/Courier0008.lzma
2015-10-09T15:43:39.332Z,1444405419.332 [NAL9602](INFO): Packets left to send: 0
2015-10-09T15:43:39.335Z,1444405419.335 [NAL9602](DEBUG): Stored copy of sent data in Logs/20151009T150848/Courier0008.lzma.parts/0000.sbd
2015-10-09T15:43:39.335Z,1444405419.335 [NAL9602](DEBUG): Completed sending Logs/20151009T150848/Courier0008.lzma
2015-10-09T15:43:39.560Z,1444405419.560 [CTD_NeilBrown](DEBUG): serial timeout
2015-10-09T15:43:39.821Z,1444405419.821 [NAL9602](INFO): Received command:restart app
2015-10-09T15:43:39.914Z,1444405419.914 [CommandLine](IMPORTANT): got command restart application
2015-10-09T15:43:40.922Z,1444405420.922 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:40.922Z,1444405420.922 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.023Z,1444405421.023 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2015-10-09T15:43:41.023Z,1444405421.023 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.024Z,1444405421.024 [CommandLine](INFO): Join timeout helper Thread ID is 3927
2015-10-09T15:43:41.024Z,1444405421.024 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-10-09T15:43:41.024Z,1444405421.024 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.025Z,1444405421.025 [NavChartDb](INFO): Join timeout helper Thread ID is 3928
2015-10-09T15:43:41.077Z,1444405421.077 [profile_station] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:A] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:C] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:F] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:PeakDetectChl] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2015-10-09T15:43:41.078Z,1444405421.078 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2015-10-09T15:43:41.083Z,1444405421.083 [profile_station:NeedComms] Stopped
2015-10-09T15:43:41.084Z,1444405421.084 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2015-10-09T15:43:41.084Z,1444405421.084 [profile_station:NeedComms:A.GoToSurface] Stopped
2015-10-09T15:43:41.085Z,1444405421.085 [profile_station:NeedComms:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-10-09T15:43:41.085Z,1444405421.085 [profile_station:NeedComms:C] Stopped
2015-10-09T15:43:41.085Z,1444405421.085 [profile_station:StandardEnvelopes] Stopped
2015-10-09T15:43:41.086Z,1444405421.086 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2015-10-09T15:43:41.099Z,1444405421.099 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2015-10-09T15:43:41.099Z,1444405421.099 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2015-10-09T15:43:41.099Z,1444405421.099 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2015-10-09T15:43:41.099Z,1444405421.099 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:StartingMission] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:I.Pitch] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:J.Buoyancy] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:K.SetSpeed] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:L.DepthEnvelope] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:M.AltitudeEnvelope] Stopped
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2015-10-09T15:43:41.100Z,1444405421.100 [profile_station:N.YoYo] Stopped
2015-10-09T15:43:41.101Z,1444405421.101 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2015-10-09T15:43:41.191Z,1444405421.191 [WetLabsBB2FL](INFO): Powering down
2015-10-09T15:43:41.239Z,1444405421.239 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:41.239Z,1444405421.239 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.259Z,1444405421.259 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-10-09T15:43:41.259Z,1444405421.259 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.260Z,1444405421.260 [Radio_Surface](INFO): Join timeout helper Thread ID is 3929
2015-10-09T15:43:41.445Z,1444405421.445 [MissionManager](IMPORTANT): Started mission Default
2015-10-09T15:43:41.445Z,1444405421.445 [Default] Running Loop=1
2015-10-09T15:43:41.445Z,1444405421.445 [Default](DEBUG): Aggregate::initialize Default
2015-10-09T15:43:41.446Z,1444405421.446 [Default:A.GoToSurface] Running Loop=1
2015-10-09T15:43:41.446Z,1444405421.446 [Default:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-10-09T15:43:41.446Z,1444405421.446 [Default:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-10-09T15:43:41.446Z,1444405421.446 [Default:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-10-09T15:43:41.447Z,1444405421.447 [Default:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2015-10-09T15:43:41.451Z,1444405421.451 [Default:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2015-10-09T15:43:41.452Z,1444405421.452 [Default:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2015-10-09T15:43:41.453Z,1444405421.453 [Default:CheckIn] Running Loop=1
2015-10-09T15:43:41.453Z,1444405421.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2015-10-09T15:43:41.453Z,1444405421.453 [Default:CheckIn:Read_GPS] Running Loop=1
2015-10-09T15:43:41.456Z,1444405421.456 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:41.456Z,1444405421.456 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.475Z,1444405421.475 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2015-10-09T15:43:41.475Z,1444405421.475 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.476Z,1444405421.476 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3930
2015-10-09T15:43:41.596Z,1444405421.596 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:41.596Z,1444405421.596 [WetLabsBB2FL](INFO): Powering down
2015-10-09T15:43:41.597Z,1444405421.597 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.603Z,1444405421.603 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2015-10-09T15:43:41.603Z,1444405421.603 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.604Z,1444405421.604 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3931
2015-10-09T15:43:41.708Z,1444405421.708 [Aanderaa_O2](INFO): Powering down
2015-10-09T15:43:41.804Z,1444405421.804 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:41.804Z,1444405421.804 [CTD_NeilBrown](INFO): Powering down
2015-10-09T15:43:41.805Z,1444405421.805 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.809Z,1444405421.809 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-10-09T15:43:41.809Z,1444405421.809 [logger ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.809Z,1444405421.809 [logger](INFO): Join timeout helper Thread ID is 3932
2015-10-09T15:43:41.824Z,1444405421.824 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:41.824Z,1444405421.824 [logger ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.829Z,1444405421.829 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-10-09T15:43:41.829Z,1444405421.829 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.829Z,1444405421.829 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-10-09T15:43:41.829Z,1444405421.829 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:41.829Z,1444405421.829 [controlThread](INFO): Join timeout helper Thread ID is 3933
2015-10-09T15:43:41.958Z,1444405421.958 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-10-09T15:43:41.958Z,1444405421.958 [controlThread](DEBUG): Uninitializing ControlThread
2015-10-09T15:43:41.959Z,1444405421.959 [Aanderaa_O2](INFO): Powering down
2015-10-09T15:43:41.962Z,1444405421.962 [AHRS_sp3003D](INFO): Powering down
2015-10-09T15:43:42.049Z,1444405422.049 [AcousticModem_Benthos_ATM900](INFO): Powering down
2015-10-09T15:43:42.241Z,1444405422.241 [DVL_micro](INFO): uninitialize:Powering down
2015-10-09T15:43:42.241Z,1444405422.241 [NAL9602](INFO): Powering down
2015-10-09T15:43:42.243Z,1444405422.243 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-10-09T15:43:42.245Z,1444405422.245 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-10-09T15:43:42.246Z,1444405422.246 [Default] Stopped
2015-10-09T15:43:42.246Z,1444405422.246 [Default](DEBUG): Aggregate::uninitialize Default
2015-10-09T15:43:42.246Z,1444405422.246 [Default:A.GoToSurface] Stopped
2015-10-09T15:43:42.246Z,1444405422.246 [Default:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-10-09T15:43:42.246Z,1444405422.246 [Default:CheckIn] Stopped
2015-10-09T15:43:42.246Z,1444405422.246 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2015-10-09T15:43:42.246Z,1444405422.246 [Default:CheckIn:Read_GPS] Stopped
2015-10-09T15:43:42.249Z,1444405422.249 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-10-09T15:43:42.249Z,1444405422.249 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-10-09T15:43:42.249Z,1444405422.249 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-10-09T15:43:42.250Z,1444405422.250 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-10-09T15:43:42.250Z,1444405422.250 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-10-09T15:43:42.250Z,1444405422.250 [BuoyancyServo](INFO): Powering down
2015-10-09T15:43:42.265Z,1444405422.265 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-10-09T15:43:42.265Z,1444405422.265 [ElevatorServo](INFO): Powering down
2015-10-09T15:43:42.265Z,1444405422.265 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-10-09T15:43:42.266Z,1444405422.266 [MassServo](INFO): Powering down
2015-10-09T15:43:42.266Z,1444405422.266 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-10-09T15:43:42.266Z,1444405422.266 [RudderServo](INFO): Powering down
2015-10-09T15:43:42.267Z,1444405422.267 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-10-09T15:43:42.267Z,1444405422.267 [ThrusterServo](INFO): Powering down
2015-10-09T15:43:42.268Z,1444405422.268 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-10-09T15:43:42.269Z,1444405422.269 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-10-09T15:43:42.269Z,1444405422.269 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-10-09T15:43:42.301Z,1444405422.301 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:42.351Z,1444405422.351 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:42.356Z,1444405422.356 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:42.415Z,1444405422.415 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:42.464Z,1444405422.464 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-10-09T15:43:42.531Z,1444405422.531 [logger ThreadHandler](INFO): Thread cancelled.