2015-11-05T02:26:30.300Z,1446690390.300 [Supervisor](DEBUG): Initializing supervisor. 2015-11-05T02:26:30.303Z,1446690390.303 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2015-11-05T02:26:30.304Z,1446690390.304 [SyncHandler](INFO): Protected caller Thread ID is 765 2015-11-05T02:26:30.304Z,1446690390.304 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2015-11-05T02:26:30.307Z,1446690390.307 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2015-11-05T02:26:30.308Z,1446690390.307 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 766 2015-11-05T02:26:30.310Z,1446690390.310 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2015-11-05T02:26:30.323Z,1446690390.323 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2015-11-05T02:26:30.327Z,1446690390.327 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2015-11-05T02:26:30.327Z,1446690390.328 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 767 2015-11-05T02:26:30.328Z,1446690390.328 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2015-11-05T02:26:30.331Z,1446690390.331 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2015-11-05T02:26:30.331Z,1446690390.332 [logger ThreadHandler](INFO): Protected caller Thread ID is 768 2015-11-05T02:26:30.334Z,1446690390.334 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2015-11-05T02:26:30.334Z,1446690390.334 [Supervisor](INFO): Looking for Config files in directory: Config/ 2015-11-05T02:26:30.337Z,1446690390.337 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2015-11-05T02:26:30.771Z,1446690390.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2015-11-05T02:26:30.773Z,1446690390.773 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2015-11-05T02:26:30.968Z,1446690390.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2015-11-05T02:26:30.970Z,1446690390.970 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2015-11-05T02:26:31.120Z,1446690391.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2015-11-05T02:26:31.122Z,1446690391.122 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2015-11-05T02:26:31.280Z,1446690391.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2015-11-05T02:26:31.282Z,1446690391.282 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2015-11-05T02:26:31.380Z,1446690391.380 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2015-11-05T02:26:31.612Z,1446690391.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2015-11-05T02:26:31.613Z,1446690391.613 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2015-11-05T02:26:31.712Z,1446690391.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2015-11-05T02:26:31.713Z,1446690391.713 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2015-11-05T02:26:32.060Z,1446690392.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2015-11-05T02:26:32.061Z,1446690392.061 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2015-11-05T02:26:32.475Z,1446690392.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2015-11-05T02:26:32.476Z,1446690392.476 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2015-11-05T02:26:32.846Z,1446690392.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2015-11-05T02:26:32.847Z,1446690392.847 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2015-11-05T02:26:33.473Z,1446690393.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2015-11-05T02:26:33.474Z,1446690393.474 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2015-11-05T02:26:33.719Z,1446690393.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2015-11-05T02:26:33.719Z,1446690393.719 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2015-11-05T02:26:33.843Z,1446690393.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2015-11-05T02:26:33.844Z,1446690393.843 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2015-11-05T02:26:34.433Z,1446690394.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2015-11-05T02:26:34.433Z,1446690394.433 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2015-11-05T02:26:34.563Z,1446690394.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2015-11-05T02:26:34.565Z,1446690394.565 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2015-11-05T02:26:34.567Z,1446690394.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2015-11-05T02:26:34.856Z,1446690394.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2015-11-05T02:26:34.858Z,1446690394.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2015-11-05T02:26:34.997Z,1446690394.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2015-11-05T02:26:35.108Z,1446690395.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2015-11-05T02:26:35.222Z,1446690395.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2015-11-05T02:26:35.400Z,1446690395.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2015-11-05T02:26:35.568Z,1446690395.568 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2015-11-05T02:26:35.684Z,1446690395.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2015-11-05T02:26:35.789Z,1446690395.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2015-11-05T02:26:35.883Z,1446690395.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2015-11-05T02:26:35.989Z,1446690395.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2015-11-05T02:26:36.208Z,1446690396.208 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2015-11-05T02:26:36.212Z,1446690396.212 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2015-11-05T02:26:36.294Z,1446690396.294 [VerticalControl](DEBUG): Construct VerticalControl. 2015-11-05T02:26:36.439Z,1446690396.439 [VerticalControl] Loaded 2015-11-05T02:26:36.439Z,1446690396.439 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2015-11-05T02:26:36.440Z,1446690396.440 [HorizontalControl](DEBUG): Construct HorizontalControl. 2015-11-05T02:26:36.526Z,1446690396.526 [HorizontalControl] Loaded 2015-11-05T02:26:36.527Z,1446690396.527 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2015-11-05T02:26:36.527Z,1446690396.527 [SpeedControl](DEBUG): Construct SpeedControl. 2015-11-05T02:26:36.534Z,1446690396.534 [SpeedControl] Loaded 2015-11-05T02:26:36.534Z,1446690396.534 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2015-11-05T02:26:36.535Z,1446690396.535 [LoopControl](DEBUG): Construct LoopControl. 2015-11-05T02:26:36.536Z,1446690396.536 [LoopControl] Loaded 2015-11-05T02:26:36.536Z,1446690396.536 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2015-11-05T02:26:36.536Z,1446690396.536 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2015-11-05T02:26:36.537Z,1446690396.537 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2015-11-05T02:26:36.704Z,1446690396.704 [SBIT](DEBUG): Construct Startup Built In Test. 2015-11-05T02:26:36.717Z,1446690396.717 [SBIT] Loaded 2015-11-05T02:26:36.717Z,1446690396.717 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2015-11-05T02:26:36.718Z,1446690396.718 [IBIT](DEBUG): Construct Initiated Built In Test. 2015-11-05T02:26:36.754Z,1446690396.754 [IBIT] Loaded 2015-11-05T02:26:36.754Z,1446690396.754 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2015-11-05T02:26:36.756Z,1446690396.756 [CBIT](DEBUG): Construct Continuous Built In Test. 2015-11-05T02:26:36.923Z,1446690396.923 [CBIT] Loaded 2015-11-05T02:26:36.924Z,1446690396.924 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2015-11-05T02:26:36.924Z,1446690396.924 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2015-11-05T02:26:36.926Z,1446690396.926 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2015-11-05T02:26:36.964Z,1446690396.964 [DepthRateCalculator] Loaded 2015-11-05T02:26:36.965Z,1446690396.965 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2015-11-05T02:26:36.975Z,1446690396.974 [PitchRateCalculator] Loaded 2015-11-05T02:26:36.975Z,1446690396.975 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2015-11-05T02:26:36.987Z,1446690396.987 [SpeedCalculator] Loaded 2015-11-05T02:26:36.987Z,1446690396.987 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2015-11-05T02:26:37.005Z,1446690397.005 [TempGradientCalculator] Loaded 2015-11-05T02:26:37.005Z,1446690397.005 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2015-11-05T02:26:37.028Z,1446690397.028 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2015-11-05T02:26:37.028Z,1446690397.028 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2015-11-05T02:26:37.034Z,1446690397.034 [YawRateCalculator] Loaded 2015-11-05T02:26:37.034Z,1446690397.034 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2015-11-05T02:26:37.034Z,1446690397.034 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2015-11-05T02:26:37.039Z,1446690397.039 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2015-11-05T02:26:37.168Z,1446690397.168 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2015-11-05T02:26:37.168Z,1446690397.168 [StratificationFrontDetector](DEBUG): (re)initializing 2015-11-05T02:26:37.168Z,1446690397.168 [StratificationFrontDetector] Loaded 2015-11-05T02:26:37.168Z,1446690397.168 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2015-11-05T02:26:37.169Z,1446690397.169 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2015-11-05T02:26:37.169Z,1446690397.169 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2015-11-05T02:26:37.486Z,1446690397.486 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2015-11-05T02:26:37.486Z,1446690397.486 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2015-11-05T02:26:37.626Z,1446690397.626 [DeadReckonUsingMultipleVelocitySources] Loaded 2015-11-05T02:26:37.627Z,1446690397.626 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2015-11-05T02:26:37.696Z,1446690397.696 [DeadReckonUsingSpeedCalculator] Loaded 2015-11-05T02:26:37.697Z,1446690397.697 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2015-11-05T02:26:37.715Z,1446690397.715 [NavChart] Loaded 2015-11-05T02:26:37.715Z,1446690397.715 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2015-11-05T02:26:37.720Z,1446690397.720 [UniversalFixResidualReporter] Loaded 2015-11-05T02:26:37.720Z,1446690397.720 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2015-11-05T02:26:37.721Z,1446690397.721 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2015-11-05T02:26:37.721Z,1446690397.721 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2015-11-05T02:26:37.730Z,1446690397.730 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2015-11-05T02:26:37.730Z,1446690397.730 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2015-11-05T02:26:37.976Z,1446690397.976 [Aanderaa_O2] Loaded 2015-11-05T02:26:37.976Z,1446690397.976 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2015-11-05T02:26:38.072Z,1446690398.072 [CTD_NeilBrown] Loaded 2015-11-05T02:26:38.072Z,1446690398.072 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2015-11-05T02:26:38.075Z,1446690398.075 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059E4E0 2015-11-05T02:26:38.076Z,1446690398.076 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 847 2015-11-05T02:26:38.099Z,1446690398.099 [ESPComponent] Loaded 2015-11-05T02:26:38.099Z,1446690398.099 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2015-11-05T02:26:38.116Z,1446690398.116 [PAR_Licor] Loaded 2015-11-05T02:26:38.116Z,1446690398.116 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2015-11-05T02:26:38.176Z,1446690398.176 [WetLabsBB2FL] Loaded 2015-11-05T02:26:38.176Z,1446690398.176 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2015-11-05T02:26:38.179Z,1446690398.179 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CE4E0 2015-11-05T02:26:38.180Z,1446690398.180 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 848 2015-11-05T02:26:38.180Z,1446690398.180 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2015-11-05T02:26:38.181Z,1446690398.181 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2015-11-05T02:26:38.728Z,1446690398.728 [AcousticModem_Benthos_ATM900] Loaded 2015-11-05T02:26:38.728Z,1446690398.728 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2015-11-05T02:26:38.921Z,1446690398.921 [DataOverHttps] Loaded 2015-11-05T02:26:38.922Z,1446690398.922 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2015-11-05T02:26:38.936Z,1446690398.936 [Depth_Keller] Loaded 2015-11-05T02:26:38.937Z,1446690398.937 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2015-11-05T02:26:38.942Z,1446690398.942 [DropWeight] Loaded 2015-11-05T02:26:38.942Z,1446690398.942 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2015-11-05T02:26:39.070Z,1446690399.070 [NAL9602] Loaded 2015-11-05T02:26:39.071Z,1446690399.070 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2015-11-05T02:26:39.122Z,1446690399.122 [Onboard] Loaded 2015-11-05T02:26:39.122Z,1446690399.122 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2015-11-05T02:26:39.130Z,1446690399.130 [Radio_Surface] Loaded 2015-11-05T02:26:39.131Z,1446690399.131 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2015-11-05T02:26:39.135Z,1446690399.135 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408124E0 2015-11-05T02:26:39.136Z,1446690399.136 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 849 2015-11-05T02:26:39.353Z,1446690399.353 [PNI_TCM] Loaded 2015-11-05T02:26:39.354Z,1446690399.353 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2015-11-05T02:26:39.575Z,1446690399.575 [Rowe_600] Loaded 2015-11-05T02:26:39.575Z,1446690399.575 [ComponentRegistry](DEBUG): Component "Rowe_600" handled in its own thread. 2015-11-05T02:26:39.579Z,1446690399.579 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 408424E0 2015-11-05T02:26:39.580Z,1446690399.580 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 850 2015-11-05T02:26:41.854Z,1446690401.854 [BPC1] Loaded 2015-11-05T02:26:41.854Z,1446690401.854 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2015-11-05T02:26:41.855Z,1446690401.855 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2015-11-05T02:26:41.855Z,1446690401.855 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2015-11-05T02:26:41.994Z,1446690401.994 [BuoyancyServo] Loaded 2015-11-05T02:26:41.994Z,1446690401.994 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2015-11-05T02:26:42.011Z,1446690402.011 [ElevatorServo] Loaded 2015-11-05T02:26:42.012Z,1446690402.012 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2015-11-05T02:26:42.024Z,1446690402.024 [MassServo] Loaded 2015-11-05T02:26:42.025Z,1446690402.025 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2015-11-05T02:26:42.037Z,1446690402.037 [RudderServo] Loaded 2015-11-05T02:26:42.038Z,1446690402.038 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2015-11-05T02:26:42.054Z,1446690402.054 [ThrusterServo] Loaded 2015-11-05T02:26:42.054Z,1446690402.055 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2015-11-05T02:26:42.055Z,1446690402.055 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2015-11-05T02:26:42.056Z,1446690402.056 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2015-11-05T02:26:42.213Z,1446690402.213 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2015-11-05T02:26:42.214Z,1446690402.214 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2015-11-05T02:26:42.258Z,1446690402.258 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2015-11-05T02:26:42.262Z,1446690402.262 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2015-11-05T02:26:42.263Z,1446690402.263 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2015-11-05T02:26:42.274Z,1446690402.274 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2015-11-05T02:26:42.279Z,1446690402.279 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408F04E0 2015-11-05T02:26:42.280Z,1446690402.280 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 851 2015-11-05T02:26:42.284Z,1446690402.284 [Supervisor](INFO): Main Thread ID is 764 2015-11-05T02:26:42.284Z,1446690402.284 [Supervisor](DEBUG): Running supervisor. 2015-11-05T02:26:42.285Z,1446690402.285 [CommandLine ThreadHandler](INFO): Handler Thread ID is 852 2015-11-05T02:26:42.292Z,1446690402.292 [controlThread ThreadHandler](INFO): Handler Thread ID is 853 2015-11-05T02:26:42.292Z,1446690402.292 [controlThread](DEBUG): Initializing ControlThread 2015-11-05T02:26:42.293Z,1446690402.293 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2015-11-05T02:26:42.295Z,1446690402.295 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2015-11-05T02:26:42.296Z,1446690402.296 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2015-11-05T02:26:42.296Z,1446690402.296 [LoopControl](DEBUG): Initialize LoopControlComponent. 2015-11-05T02:26:42.296Z,1446690402.296 [SBIT](INFO): Initialize SBIT Component. 2015-11-05T02:26:42.297Z,1446690402.297 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12682 2015-11-05T02:26:42.297Z,1446690402.297 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2015-11-05T02:26:42.297Z,1446690402.297 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2015-11-05T02:26:42.298Z,1446690402.298 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2015-11-05T02:26:42.299Z,1446690402.299 [IBIT](INFO): Initialize IBIT Component. 2015-11-05T02:26:42.300Z,1446690402.300 [CBIT](DEBUG): Initialize CBIT Component. 2015-11-05T02:26:42.300Z,1446690402.300 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2015-11-05T02:26:42.300Z,1446690402.300 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2015-11-05T02:26:42.304Z,1446690402.304 [logger ThreadHandler](INFO): Handler Thread ID is 854 2015-11-05T02:26:42.323Z,1446690402.323 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2015-11-05T02:26:42.324Z,1446690402.324 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2015-11-05T02:26:42.324Z,1446690402.324 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2015-11-05T02:26:42.324Z,1446690402.324 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2015-11-05T02:26:42.325Z,1446690402.325 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2015-11-05T02:26:42.326Z,1446690402.326 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2015-11-05T02:26:42.326Z,1446690402.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-11-05T02:26:42.327Z,1446690402.327 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2015-11-05T02:26:42.327Z,1446690402.328 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2015-11-05T02:26:42.328Z,1446690402.328 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-11-05T02:26:42.328Z,1446690402.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2015-11-05T02:26:42.329Z,1446690402.329 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2015-11-05T02:26:42.329Z,1446690402.329 [NavChart](DEBUG): Initialize NavChart Navigation. 2015-11-05T02:26:42.329Z,1446690402.329 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2015-11-05T02:26:42.344Z,1446690402.344 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2015-11-05T02:26:42.356Z,1446690402.356 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 855 2015-11-05T02:26:42.358Z,1446690402.358 [CTD_NeilBrown](INFO): Powering down 2015-11-05T02:26:42.374Z,1446690402.374 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-11-05T02:26:42.380Z,1446690402.380 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 856 2015-11-05T02:26:42.381Z,1446690402.381 [WetLabsBB2FL](INFO): Powering down 2015-11-05T02:26:42.428Z,1446690402.428 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 857 2015-11-05T02:26:42.434Z,1446690402.434 [Radio_Surface](INFO): Powering up 2015-11-05T02:26:42.436Z,1446690402.436 [MissionManager](DEBUG): 2015-11-05T02:26:42.437Z,1446690402.437 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2015-11-05T02:26:42.448Z,1446690402.448 [Rowe_600 ThreadHandler](INFO): Handler Thread ID is 858 2015-11-05T02:26:42.470Z,1446690402.470 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859 2015-11-05T02:26:42.481Z,1446690402.480 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2015-11-05T02:26:42.482Z,1446690402.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2015-11-05T02:26:42.483Z,1446690402.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2015-11-05T02:26:42.483Z,1446690402.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2015-11-05T02:26:42.484Z,1446690402.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2015-11-05T02:26:42.484Z,1446690402.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2015-11-05T02:26:42.484Z,1446690402.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2015-11-05T02:26:42.484Z,1446690402.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2015-11-05T02:26:42.485Z,1446690402.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2015-11-05T02:26:42.499Z,1446690402.499 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2015-11-05T02:26:42.501Z,1446690402.501 [Default:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-11-05T02:26:42.536Z,1446690402.536 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2015-11-05T02:26:42.539Z,1446690402.539 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2015-11-05T02:26:42.573Z,1446690402.573 [Default:D.Execute](DEBUG): Construct Execute. 2015-11-05T02:26:42.576Z,1446690402.576 [MissionManager](DEBUG): 0 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2015-11-05T02:26:42.596Z,1446690402.596 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2015-11-05T02:26:42.696Z,1446690402.695 [AcousticModem_Benthos_ATM900](INFO): Powering up 2015-11-05T02:26:42.696Z,1446690402.696 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2015-11-05T02:26:42.723Z,1446690402.723 [Rowe_600](INFO): Powering down 2015-11-05T02:26:42.956Z,1446690402.956 [Rowe_600](INFO): Initializing 2015-11-05T02:26:42.975Z,1446690402.975 [Rowe_600](INFO): Powering up 2015-11-05T02:26:43.247Z,1446690403.247 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:43.515Z,1446690403.515 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:43.615Z,1446690403.615 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:43.627Z,1446690403.627 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2015-11-05T02:26:43.646Z,1446690403.646 [ElevatorServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:43.651Z,1446690403.651 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2015-11-05T02:26:43.669Z,1446690403.669 [MassServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:43.679Z,1446690403.679 [MassServo](DEBUG): Initializing MassServo. 2015-11-05T02:26:43.689Z,1446690403.689 [RudderServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:43.699Z,1446690403.699 [RudderServo](DEBUG): Initializing RudderServo. 2015-11-05T02:26:43.721Z,1446690403.721 [ThrusterServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:43.728Z,1446690403.728 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2015-11-05T02:26:44.039Z,1446690404.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:44.040Z,1446690404.040 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:44.237Z,1446690404.237 [NAL9602](INFO): Powering up NAL9602 2015-11-05T02:26:44.257Z,1446690404.257 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:44.258Z,1446690404.258 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:44.591Z,1446690404.591 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2015-11-05T02:26:44.591Z,1446690404.591 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2015-11-05T02:26:44.591Z,1446690404.592 [BuoyancyServo] Communications Fault, FailCount= 1 2015-11-05T02:26:44.592Z,1446690404.592 [BuoyancyServo](ERROR): Communications Fault 2015-11-05T02:26:44.805Z,1446690404.805 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2015-11-05T02:26:44.884Z,1446690404.884 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:44.885Z,1446690404.885 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:44.908Z,1446690404.908 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2015-11-05T02:26:44.908Z,1446690404.908 [BuoyancyServo](INFO): Powering down 2015-11-05T02:26:45.279Z,1446690405.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:45.280Z,1446690405.280 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:45.651Z,1446690405.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:45.652Z,1446690405.652 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:46.135Z,1446690406.135 [Aanderaa_O2](INFO): Powering down 2015-11-05T02:26:46.251Z,1446690406.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:46.259Z,1446690406.259 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:46.609Z,1446690406.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:46.610Z,1446690406.610 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-05T02:26:47.063Z,1446690407.063 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2015-11-05T02:26:47.063Z,1446690407.063 [BuoyancyServo] No Fault, FailCount= 1 2015-11-05T02:26:47.316Z,1446690407.316 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:47.438Z,1446690407.438 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2015-11-05T02:26:49.003Z,1446690409.003 [RudderServo](FAULT): Overload Error 2015-11-05T02:26:49.003Z,1446690409.003 [RudderServo] Hardware Fault, FailCount= 1 2015-11-05T02:26:49.004Z,1446690409.003 [RudderServo](ERROR): Hardware Fault 2015-11-05T02:26:49.042Z,1446690409.042 [CBIT](ERROR): Hardware Fault in component: RudderServo 2015-11-05T02:26:49.341Z,1446690409.341 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2015-11-05T02:26:49.341Z,1446690409.341 [RudderServo](INFO): Powering down 2015-11-05T02:26:50.177Z,1446690410.177 [RudderServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:50.296Z,1446690410.296 [RudderServo](DEBUG): Initializing RudderServo. 2015-11-05T02:26:50.300Z,1446690410.300 [CBIT](INFO): Clearing failed state for component RudderServo 2015-11-05T02:26:50.300Z,1446690410.300 [RudderServo] No Fault, FailCount= 1 2015-11-05T02:26:50.420Z,1446690410.420 [AcousticModem_Benthos_ATM900](DEBUG): 2015-11-05T02:26:51.263Z,1446690411.263 [Rowe_600](ERROR): payload size is too large: 4200 -- attempting to continue using maximum payload size: 1024 2015-11-05T02:26:51.904Z,1446690411.904 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2015-11-05T02:26:51.906Z,1446690411.906 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2015-11-05T02:26:51.917Z,1446690411.917 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2015-11-05T02:26:51.928Z,1446690411.928 [AcousticModem_Benthos_ATM900](DEBUG): Nov 5 2015 02:21:22 2015-11-05T02:26:53.083Z,1446690413.083 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2015-11-05T02:26:53.582Z,1446690413.582 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2015-11-05T02:26:53.607Z,1446690413.607 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2015-11-05T02:26:53.607Z,1446690413.607 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2015-11-05T02:26:54.209Z,1446690414.209 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2015-11-05T02:26:54.728Z,1446690414.728 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2015-11-05T02:26:54.751Z,1446690414.751 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2015-11-05T02:26:55.354Z,1446690415.355 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2015-11-05T02:26:55.535Z,1446690415.536 [NAL9602](INFO): NAL9602 initialized 2015-11-05T02:26:55.921Z,1446690415.921 [RudderServo](FAULT): Overload Error 2015-11-05T02:26:55.921Z,1446690415.921 [RudderServo] Hardware Fault, FailCount= 2 2015-11-05T02:26:55.921Z,1446690415.921 [RudderServo](ERROR): Hardware Fault 2015-11-05T02:26:55.976Z,1446690415.976 [CBIT](ERROR): Hardware Fault in component: RudderServo 2015-11-05T02:26:56.738Z,1446690416.738 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2015-11-05T02:26:56.739Z,1446690416.739 [RudderServo](INFO): Powering down 2015-11-05T02:26:58.179Z,1446690418.180 [RudderServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:26:58.303Z,1446690418.303 [RudderServo](DEBUG): Initializing RudderServo. 2015-11-05T02:26:58.325Z,1446690418.325 [CBIT](INFO): Clearing failed state for component RudderServo 2015-11-05T02:26:58.326Z,1446690418.326 [RudderServo] No Fault, FailCount= 2 2015-11-05T02:27:03.709Z,1446690423.709 [RudderServo](FAULT): Overload Error 2015-11-05T02:27:03.709Z,1446690423.709 [RudderServo] Hardware Fault, FailCount= 3 2015-11-05T02:27:03.710Z,1446690423.710 [RudderServo](ERROR): Hardware Fault 2015-11-05T02:27:03.763Z,1446690423.763 [CBIT](ERROR): Hardware Fault in component: RudderServo 2015-11-05T02:27:03.765Z,1446690423.765 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2015-11-05T02:27:04.312Z,1446690424.312 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2015-11-05T02:27:04.312Z,1446690424.312 [RudderServo](INFO): Powering down 2015-11-05T02:27:04.322Z,1446690424.322 [CommandLine](FAULT): Scheduling is paused 2015-11-05T02:27:05.463Z,1446690425.463 [RudderServo](DEBUG): Initializing EZServoServo. 2015-11-05T02:27:05.586Z,1446690425.586 [RudderServo](DEBUG): Initializing RudderServo. 2015-11-05T02:27:45.707Z,1446690465.707 [SBIT](IMPORTANT): Beginning Startup BIT 2015-11-05T02:27:45.734Z,1446690465.734 [CBIT](IMPORTANT): Beginning GF scan 2015-11-05T02:27:59.102Z,1446690479.102 [SBIT](ERROR): Could not read rudderAngleReader_. 2015-11-05T02:28:12.739Z,1446690492.739 [CBIT](IMPORTANT): No ground fault detected 2015-11-05T02:28:14.217Z,1446690494.217 [BPC1](ERROR): Failed to receive battery data 2015-11-05T02:28:14.217Z,1446690494.217 [BPC1] Communications Fault, FailCount= 1 2015-11-05T02:28:14.217Z,1446690494.217 [BPC1](ERROR): Communications Fault 2015-11-05T02:28:14.357Z,1446690494.357 [CBIT](ERROR): Communications Fault in component: BPC1 2015-11-05T02:28:15.552Z,1446690495.552 [CBIT](INFO): Clearing failed state for component BPC1 2015-11-05T02:28:15.552Z,1446690495.552 [BPC1] No Fault, FailCount= 1 2015-11-05T02:28:39.783Z,1446690519.783 [BPC1](ERROR): Failed to receive battery data 2015-11-05T02:28:39.784Z,1446690519.784 [BPC1] Communications Fault, FailCount= 2 2015-11-05T02:28:39.784Z,1446690519.784 [BPC1](ERROR): Communications Fault 2015-11-05T02:28:39.908Z,1446690519.908 [SBIT](ERROR): Could not read rudderAngleReader_. 2015-11-05T02:28:39.908Z,1446690519.908 [SBIT](FAULT): Control surface position failure. 2015-11-05T02:28:39.921Z,1446690519.921 [CBIT](ERROR): Communications Fault in component: BPC1 2015-11-05T02:28:40.015Z,1446690520.015 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:28:40.361Z,1446690520.361 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2015-11-05T02:28:41.053Z,1446690521.053 [CBIT](INFO): Clearing failed state for component BPC1 2015-11-05T02:28:41.054Z,1446690521.054 [BPC1] No Fault, FailCount= 2 2015-11-05T02:28:53.601Z,1446690533.601 [SBIT](ERROR): Could not read rudderAngleReader_. 2015-11-05T02:28:53.602Z,1446690533.602 [SBIT](FAULT): Control surface position failure. 2015-11-05T02:28:54.060Z,1446690534.060 [SBIT](CRITICAL): SBIT FAILED 2015-11-05T02:28:54.355Z,1446690534.355 [MissionManager](IMPORTANT): Started mission Startup 2015-11-05T02:28:54.364Z,1446690534.364 [Startup] Running Loop=1 2015-11-05T02:28:54.364Z,1446690534.364 [Startup](DEBUG): Aggregate::initialize Startup 2015-11-05T02:28:54.364Z,1446690534.365 [Startup:A.GoToSurface] Running Loop=1 2015-11-05T02:28:54.365Z,1446690534.365 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2015-11-05T02:28:54.366Z,1446690534.366 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2015-11-05T02:28:54.379Z,1446690534.379 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2015-11-05T02:28:54.380Z,1446690534.380 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2015-11-05T02:28:54.381Z,1446690534.381 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2015-11-05T02:28:54.382Z,1446690534.382 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2015-11-05T02:28:54.430Z,1446690534.430 [Startup:StartupSatComms] Running Loop=1 2015-11-05T02:28:54.430Z,1446690534.430 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2015-11-05T02:28:54.431Z,1446690534.431 [Startup:StartupSatComms:A] Running Loop=1 2015-11-05T02:28:54.829Z,1446690534.829 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2015-11-05T02:29:30.178Z,1446690570.178 [BPC1](ERROR): Failed to receive battery data 2015-11-05T02:29:30.178Z,1446690570.178 [BPC1] Communications Fault, FailCount= 1 2015-11-05T02:29:30.178Z,1446690570.178 [BPC1](ERROR): Communications Fault 2015-11-05T02:29:30.336Z,1446690570.336 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:24%. Temp:20 C. ABORTING MISSION 2015-11-05T02:29:30.338Z,1446690570.338 [CBIT](ERROR): Communications Fault in component: BPC1 2015-11-05T02:29:30.429Z,1446690570.429 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:29:31.225Z,1446690571.225 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:29:31.227Z,1446690571.227 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:29:31.228Z,1446690571.228 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:29:31.237Z,1446690571.237 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.012300 2015-11-05T02:29:31.510Z,1446690571.510 [CBIT](INFO): Clearing failed state for component BPC1 2015-11-05T02:29:31.511Z,1446690571.511 [BPC1] No Fault, FailCount= 1 2015-11-05T02:29:42.698Z,1446690582.698 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2015-11-05T02:29:42.698Z,1446690582.698 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2015-11-05T02:29:42.698Z,1446690582.698 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2015-11-05T02:29:42.725Z,1446690582.725 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2015-11-05T02:29:42.725Z,1446690582.725 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2015-11-05T02:29:42.725Z,1446690582.725 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2015-11-05T02:29:42.809Z,1446690582.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2015-11-05T02:29:42.810Z,1446690582.810 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2015-11-05T02:29:43.103Z,1446690583.103 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2015-11-05T02:29:43.104Z,1446690583.103 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2015-11-05T02:29:43.105Z,1446690583.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2015-11-05T02:29:43.105Z,1446690583.105 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2015-11-05T02:29:43.440Z,1446690583.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-11-05T02:29:43.441Z,1446690583.441 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2015-11-05T02:29:43.442Z,1446690583.442 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2015-11-05T02:29:43.443Z,1446690583.443 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-11-05T02:29:43.453Z,1446690583.453 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2015-11-05T02:29:43.453Z,1446690583.453 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2015-11-05T02:29:43.845Z,1446690583.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s. 2015-11-05T02:29:44.224Z,1446690584.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.5 s old, using for 20.0 s. 2015-11-05T02:29:44.744Z,1446690584.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2015-11-05T02:29:45.048Z,1446690585.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.3 s old, using for 20.0 s. 2015-11-05T02:29:45.452Z,1446690585.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2015-11-05T02:29:45.816Z,1446690585.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.1 s old, using for 20.0 s. 2015-11-05T02:29:46.237Z,1446690586.237 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s. 2015-11-05T02:29:46.644Z,1446690586.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.9 s old, using for 20.0 s. 2015-11-05T02:29:47.037Z,1446690587.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s. 2015-11-05T02:29:47.404Z,1446690587.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s. 2015-11-05T02:29:47.801Z,1446690587.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s. 2015-11-05T02:29:48.230Z,1446690588.230 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s. 2015-11-05T02:29:48.592Z,1446690588.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s. 2015-11-05T02:29:49.020Z,1446690589.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s. 2015-11-05T02:29:49.393Z,1446690589.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s. 2015-11-05T02:29:49.836Z,1446690589.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s. 2015-11-05T02:29:50.200Z,1446690590.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.5 s old, using for 20.0 s. 2015-11-05T02:29:50.597Z,1446690590.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s. 2015-11-05T02:29:51.039Z,1446690591.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.3 s old, using for 20.0 s. 2015-11-05T02:29:51.406Z,1446690591.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.7 s old, using for 20.0 s. 2015-11-05T02:29:51.861Z,1446690591.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2015-11-05T02:29:52.252Z,1446690592.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2015-11-05T02:29:52.602Z,1446690592.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s. 2015-11-05T02:29:53.020Z,1446690593.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.3 s old, using for 20.0 s. 2015-11-05T02:29:53.414Z,1446690593.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.7 s old, using for 20.0 s. 2015-11-05T02:29:53.804Z,1446690593.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.1 s old, using for 20.0 s. 2015-11-05T02:29:54.229Z,1446690594.229 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.5 s old, using for 20.0 s. 2015-11-05T02:29:54.634Z,1446690594.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.9 s old, using for 20.0 s. 2015-11-05T02:29:54.646Z,1446690594.645 [Startup:StartupSatComms:A](INFO): Timed out from 2015-11-05T02:28:54.4Z 2015-11-05T02:29:54.646Z,1446690594.646 [Startup:StartupSatComms:A] Stopped 2015-11-05T02:29:54.646Z,1446690594.646 [Startup:StartupSatComms:B] Running Loop=1 2015-11-05T02:29:55.040Z,1446690595.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.3 s old, using for 20.0 s. 2015-11-05T02:29:55.068Z,1446690595.068 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2015-11-05T02:29:56.800Z,1446690596.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s. 2015-11-05T02:29:57.191Z,1446690597.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s. 2015-11-05T02:30:10.644Z,1446690610.644 [BPC1](ERROR): Failed to receive battery data 2015-11-05T02:30:10.644Z,1446690610.644 [BPC1] Communications Fault, FailCount= 1 2015-11-05T02:30:10.644Z,1446690610.644 [BPC1](ERROR): Communications Fault 2015-11-05T02:30:10.780Z,1446690610.780 [CBIT](ERROR): Communications Fault in component: BPC1 2015-11-05T02:30:10.865Z,1446690610.865 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20151105T021352/Courier0000.lzma 2015-11-05T02:30:10.878Z,1446690610.878 [DataOverHttps](INFO): dataWriting with SSL WROTE 328 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: 161 imei=300234060751590&imeiPassword=Hde%603X&filename=Logs/20151105T021352/Courier0000.lzma&fileSize=18&file=%5d%00%00%04%00%00%00%00%00%00%00%00%00%00%00%00%00%00 2015-11-05T02:30:11.613Z,1446690611.613 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=true&momsn=3804255&filename=Logs%2F20151105T021352%2FCourier0000.lzma, 1 2015-11-05T02:30:11.614Z,1446690611.614 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3804255&filename=Logs%2F20151105T021352%2FCourier0000.lzma, key = 6, value = makai 2015-11-05T02:30:11.615Z,1446690611.615 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3804255&filename=Logs%2F20151105T021352%2FCourier0000.lzma, key = 0, value = true 2015-11-05T02:30:11.617Z,1446690611.617 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151105T021352%2FCourier0000.lzma, key = 4, value = 3804255 2015-11-05T02:30:11.618Z,1446690611.618 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151105T021352%2FCourier0000.lzma 2015-11-05T02:30:11.620Z,1446690611.620 [DataOverHttps](INFO): Moved sent file to Logs/20151105T021352/Courier0000.lzma.bak 2015-11-05T02:30:11.621Z,1446690611.621 [DataOverHttps](INFO): SBD MOMSN=3804255 2015-11-05T02:30:11.878Z,1446690611.878 [CBIT](INFO): Clearing failed state for component BPC1 2015-11-05T02:30:11.879Z,1446690611.879 [BPC1] No Fault, FailCount= 1 2015-11-05T02:30:26.357Z,1446690626.357 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:30:27.113Z,1446690627.113 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:30:27.114Z,1446690627.114 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:30:27.128Z,1446690627.128 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:30:41.480Z,1446690641.480 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:24%. Temp:21 C. ABORTING MISSION 2015-11-05T02:30:41.557Z,1446690641.557 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20151105T022630/Courier0000.lzma 2015-11-05T02:30:41.570Z,1446690641.570 [DataOverHttps](INFO): dataWriting with SSL WROTE 791 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: 624 imei=300234060751590&imeiPassword=Hde%603X&filename=Logs/20151105T022630/Courier0000.lzma&fileSize=204&file=%5d%00%00%04%00%e0%00%00%00%00%00%00%00%00%15%1d%80%03%0cVi%bf1U%5e%ae%14%09%a7%08%ce%24%89%b1T%8c%a5Fx%7fN%fc%cd%f6o4%d5%15%96%a7%0c%f6%1bq%afyG%7d%e2%a5%93M%c8%87%89d%b1%10%c0%f7Q%e1%ca%ad%c3+Y%28%c3%d1%18%a4%81z%f3%abfJ%0a%00%d7%9c%98%bdi%eb%9e%b8%13%e5t%92%fb%df%27%e2%7c%c87M%08%26%a7%9a%f5%7d%e5%e5%89%e3%fc%2fK%fc%a5%bcWz%db%03%cb%d2%aa%d7j%06Y%f1.yn%e3r%3c%c5a%c7%a9%e9%fb%d6%2b%83%d7%e8gP%2bA%9f%90%9f2%e7%94%1a%13%86%10%fd%2a%02%d1%96-%e4%c5%2c%f9m%ee%03%c5%ddV%8cUt9%1d%dfi%de%f8im%5b%10%2b%ac%b8%dd%7c%9b 2015-11-05T02:30:42.425Z,1446690642.425 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=true&momsn=3804258&filename=Logs%2F20151105T022630%2FCourier0000.lzma, 1 2015-11-05T02:30:42.427Z,1446690642.427 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3804258&filename=Logs%2F20151105T022630%2FCourier0000.lzma, key = 6, value = makai 2015-11-05T02:30:42.436Z,1446690642.436 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3804258&filename=Logs%2F20151105T022630%2FCourier0000.lzma, key = 0, value = true 2015-11-05T02:30:42.437Z,1446690642.437 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151105T022630%2FCourier0000.lzma, key = 4, value = 3804258 2015-11-05T02:30:42.438Z,1446690642.438 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151105T022630%2FCourier0000.lzma 2015-11-05T02:30:42.444Z,1446690642.444 [DataOverHttps](INFO): Moved sent file to Logs/20151105T022630/Courier0000.lzma.bak 2015-11-05T02:30:42.445Z,1446690642.445 [DataOverHttps](INFO): SBD MOMSN=3804258 2015-11-05T02:30:50.756Z,1446690650.756 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:30:51.539Z,1446690651.539 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:30:51.540Z,1446690651.540 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:30:51.541Z,1446690651.541 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:30:59.338Z,1446690659.338 [Startup:StartupSatComms:B](INFO): Timed out from 2015-11-05T02:29:54.6Z 2015-11-05T02:30:59.338Z,1446690659.338 [Startup:StartupSatComms:B] Stopped 2015-11-05T02:30:59.339Z,1446690659.339 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2015-11-05T02:30:59.343Z,1446690659.343 [Startup:StartupSatComms] Stopped 2015-11-05T02:30:59.344Z,1446690659.344 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2015-11-05T02:30:59.346Z,1446690659.346 [Startup](INFO): Completed Startup 2015-11-05T02:30:59.346Z,1446690659.346 [Startup] Stopped 2015-11-05T02:30:59.346Z,1446690659.346 [Startup](DEBUG): Aggregate::uninitialize Startup 2015-11-05T02:30:59.347Z,1446690659.346 [Startup:A.GoToSurface] Stopped 2015-11-05T02:30:59.347Z,1446690659.347 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2015-11-05T02:30:59.531Z,1446690659.531 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20151105T021352/Express0001.lzma 2015-11-05T02:30:59.537Z,1446690659.537 [DataOverHttps](INFO): dataWriting with SSL WROTE 328 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: 161 imei=300234060751590&imeiPassword=Hde%603X&filename=Logs/20151105T021352/Express0001.lzma&fileSize=18&file=%5d%00%00%04%00%00%00%00%00%00%00%00%00%00%00%00%00%00 2015-11-05T02:30:59.702Z,1446690659.702 [MissionManager](IMPORTANT): Started mission Default 2015-11-05T02:30:59.703Z,1446690659.703 [Default] Running Loop=1 2015-11-05T02:30:59.712Z,1446690659.712 [Default](DEBUG): Aggregate::initialize Default 2015-11-05T02:30:59.712Z,1446690659.712 [Default:A.GoToSurface] Running Loop=1 2015-11-05T02:30:59.712Z,1446690659.712 [Default:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2015-11-05T02:30:59.713Z,1446690659.713 [Default:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2015-11-05T02:30:59.714Z,1446690659.714 [Default:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2015-11-05T02:30:59.715Z,1446690659.714 [Default:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2015-11-05T02:30:59.728Z,1446690659.728 [Default:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2015-11-05T02:30:59.729Z,1446690659.729 [Default:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2015-11-05T02:30:59.773Z,1446690659.773 [Default:CheckIn] Running Loop=1 2015-11-05T02:30:59.773Z,1446690659.773 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2015-11-05T02:30:59.773Z,1446690659.773 [Default:CheckIn:Read_GPS] Running Loop=1 2015-11-05T02:31:00.105Z,1446690660.105 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2015-11-05T02:31:00.277Z,1446690660.277 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=true&momsn=3804264&filename=Logs%2F20151105T021352%2FExpress0001.lzma, 1 2015-11-05T02:31:00.278Z,1446690660.278 [DataOverHttps](DEBUG): ParseDataRead( data = busy=true&momsn=3804264&filename=Logs%2F20151105T021352%2FExpress0001.lzma, key = 6, value = makai 2015-11-05T02:31:00.284Z,1446690660.284 [DataOverHttps](DEBUG): ParseDataRead( data = momsn=3804264&filename=Logs%2F20151105T021352%2FExpress0001.lzma, key = 0, value = true 2015-11-05T02:31:00.285Z,1446690660.285 [DataOverHttps](DEBUG): ParseDataRead( data = filename=Logs%2F20151105T021352%2FExpress0001.lzma, key = 4, value = 3804264 2015-11-05T02:31:00.286Z,1446690660.286 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 2, value = Logs%2F20151105T021352%2FExpress0001.lzma 2015-11-05T02:31:00.292Z,1446690660.292 [DataOverHttps](INFO): Moved sent file to Logs/20151105T021352/Express0001.lzma.bak 2015-11-05T02:31:00.293Z,1446690660.293 [DataOverHttps](INFO): SBD MOMSN=3804264 2015-11-05T02:31:08.073Z,1446690668.073 [BPC1](ERROR): Failed to receive battery data 2015-11-05T02:31:08.073Z,1446690668.073 [BPC1] Communications Fault, FailCount= 1 2015-11-05T02:31:08.074Z,1446690668.074 [BPC1](ERROR): Communications Fault 2015-11-05T02:31:08.228Z,1446690668.228 [CBIT](ERROR): Communications Fault in component: BPC1 2015-11-05T02:31:08.284Z,1446690668.284 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:31:09.061Z,1446690669.061 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:31:09.062Z,1446690669.062 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:31:09.064Z,1446690669.064 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:31:09.256Z,1446690669.256 [CBIT](INFO): Clearing failed state for component BPC1 2015-11-05T02:31:09.256Z,1446690669.256 [BPC1] No Fault, FailCount= 1 2015-11-05T02:32:00.264Z,1446690720.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2015-11-05T02:32:08.730Z,1446690728.730 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:32:09.501Z,1446690729.501 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:32:09.503Z,1446690729.503 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:32:09.504Z,1446690729.504 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:32:35.965Z,1446690755.965 [BPC1](ERROR): Failed to parse bank A battery data 2015-11-05T02:32:35.965Z,1446690755.965 [BPC1] Data Fault, FailCount= 1 2015-11-05T02:32:35.965Z,1446690755.965 [BPC1](ERROR): Data Fault 2015-11-05T02:32:36.121Z,1446690756.121 [CBIT](ERROR): Data Fault in component: BPC1 2015-11-05T02:32:37.250Z,1446690757.250 [CBIT](INFO): Clearing failed state for component BPC1 2015-11-05T02:32:37.250Z,1446690757.250 [BPC1] No Fault, FailCount= 1 2015-11-05T02:32:43.606Z,1446690763.606 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2015-11-05T02:32:43.606Z,1446690763.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2015-11-05T02:32:43.606Z,1446690763.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2015-11-05T02:32:43.623Z,1446690763.623 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2015-11-05T02:32:43.624Z,1446690763.624 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2015-11-05T02:32:43.624Z,1446690763.624 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2015-11-05T02:32:43.674Z,1446690763.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2015-11-05T02:32:43.674Z,1446690763.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2015-11-05T02:32:44.068Z,1446690764.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2015-11-05T02:32:44.068Z,1446690764.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2015-11-05T02:32:44.070Z,1446690764.070 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2015-11-05T02:32:44.070Z,1446690764.070 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2015-11-05T02:32:44.413Z,1446690764.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-11-05T02:32:44.414Z,1446690764.414 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2015-11-05T02:32:44.415Z,1446690764.415 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2015-11-05T02:32:44.420Z,1446690764.420 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-11-05T02:32:44.421Z,1446690764.421 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2015-11-05T02:32:44.422Z,1446690764.422 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2015-11-05T02:32:44.808Z,1446690764.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2015-11-05T02:32:45.196Z,1446690765.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2015-11-05T02:32:45.603Z,1446690765.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2015-11-05T02:32:46.004Z,1446690766.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2015-11-05T02:32:46.391Z,1446690766.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2015-11-05T02:32:46.813Z,1446690766.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2015-11-05T02:32:47.216Z,1446690767.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2015-11-05T02:32:47.618Z,1446690767.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2015-11-05T02:32:47.994Z,1446690767.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2015-11-05T02:32:48.400Z,1446690768.400 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2015-11-05T02:32:48.794Z,1446690768.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2015-11-05T02:32:49.215Z,1446690769.215 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2015-11-05T02:32:49.610Z,1446690769.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2015-11-05T02:32:50.020Z,1446690770.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2015-11-05T02:32:50.528Z,1446690770.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2015-11-05T02:32:50.800Z,1446690770.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2015-11-05T02:32:51.224Z,1446690771.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2015-11-05T02:32:51.596Z,1446690771.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2015-11-05T02:32:52.024Z,1446690772.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2015-11-05T02:32:52.417Z,1446690772.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2015-11-05T02:32:52.827Z,1446690772.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2015-11-05T02:32:53.224Z,1446690773.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2015-11-05T02:32:53.598Z,1446690773.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s. 2015-11-05T02:32:54.001Z,1446690774.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2015-11-05T02:32:54.393Z,1446690774.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2015-11-05T02:32:54.788Z,1446690774.788 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2015-11-05T02:32:55.195Z,1446690775.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2015-11-05T02:32:55.602Z,1446690775.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2015-11-05T02:32:56.016Z,1446690776.016 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2015-11-05T02:32:56.422Z,1446690776.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2015-11-05T02:32:56.792Z,1446690776.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2015-11-05T02:32:57.199Z,1446690777.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2015-11-05T02:32:57.608Z,1446690777.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2015-11-05T02:32:57.996Z,1446690777.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2015-11-05T02:32:58.381Z,1446690778.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2015-11-05T02:32:58.821Z,1446690778.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2015-11-05T02:32:59.216Z,1446690779.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2015-11-05T02:32:59.625Z,1446690779.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2015-11-05T02:32:59.989Z,1446690779.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2015-11-05T02:33:00.398Z,1446690780.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2015-11-05T02:33:00.813Z,1446690780.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2015-11-05T02:33:01.200Z,1446690781.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2015-11-05T02:33:01.633Z,1446690781.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2015-11-05T02:33:08.781Z,1446690788.781 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:33:09.553Z,1446690789.553 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:33:09.554Z,1446690789.554 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:33:09.556Z,1446690789.556 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:34:08.826Z,1446690848.826 [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=300234060751590&imeiPassword=Hde%603X 2015-11-05T02:34:09.601Z,1446690849.601 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false&mtmsn=20151105T022423&fileSize=68&file=%EF%28-%F2X%2F%14%D2%DC%FFJN%EC%99%0F%F9%E7%AED%27%06%84%11%A0%BF%26%12%0F%8B0%8A%A0A%B6-%DB%BF%A5%F4%B3Mt%16y%AEfm%C9bC%3B%B9%7F%01%D7x%9EUSC%C8%DC2w%3B%5B%40%D7, 1 2015-11-05T02:34:09.602Z,1446690849.602 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false&mtmsn=20151105T022423&fileSize=68&file=%EF%28-%F2X%2F%14%D2%DC%FFJN%EC%99%0F%F9%E7%AED%27%06%84%11%A0%BF%26%12%0F%8B0%8A%A0A%B6-%DB%BF%A5%F4%B3Mt%16y%AEfm%C9bC%3B%B9%7F%01%D7x%9EUSC%C8%DC2w%3B%5B%40%D7, key = 6, value = makai 2015-11-05T02:34:09.604Z,1446690849.604 [DataOverHttps](DEBUG): ParseDataRead( data = mtmsn=20151105T022423&fileSize=68&file=%EF%28-%F2X%2F%14%D2%DC%FFJN%EC%99%0F%F9%E7%AED%27%06%84%11%A0%BF%26%12%0F%8B0%8A%A0A%B6-%DB%BF%A5%F4%B3Mt%16y%AEfm%C9bC%3B%B9%7F%01%D7x%9EUSC%C8%DC2w%3B%5B%40%D7, key = 0, value = false 2015-11-05T02:34:09.605Z,1446690849.605 [DataOverHttps](DEBUG): ParseDataRead( data = fileSize=68&file=%EF%28-%F2X%2F%14%D2%DC%FFJN%EC%99%0F%F9%E7%AED%27%06%84%11%A0%BF%26%12%0F%8B0%8A%A0A%B6-%DB%BF%A5%F4%B3Mt%16y%AEfm%C9bC%3B%B9%7F%01%D7x%9EUSC%C8%DC2w%3B%5B%40%D7, key = 5, value = 20151105T022423 2015-11-05T02:34:09.607Z,1446690849.606 [DataOverHttps](DEBUG): ParseDataRead( data = file=%EF%28-%F2X%2F%14%D2%DC%FFJN%EC%99%0F%F9%E7%AED%27%06%84%11%A0%BF%26%12%0F%8B0%8A%A0A%B6-%DB%BF%A5%F4%B3Mt%16y%AEfm%C9bC%3B%B9%7F%01%D7x%9EUSC%C8%DC2w%3B%5B%40%D7, key = 3, value = 68 2015-11-05T02:34:09.608Z,1446690849.608 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 1, value = %EF%28-%F2X%2F%14%D2%DC%FFJN%EC%99%0F%F9%E7%AED%27%06%84%11%A0%BF%26%12%0F%8B0%8A%A0A%B6-%DB%BF%A5%F4%B3Mt%16y%AEfm%C9bC%3B%B9%7F%01%D7x%9EUSC%C8%DC2w%3B%5B%40%D7 2015-11-05T02:34:09.609Z,1446690849.609 [DataOverHttps](IMPORTANT): SBD MTMSN=20151105T022423 2015-11-05T02:34:17.532Z,1446690857.532 [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=300234060751590&imeiPassword=Hde%603X&mtmsn=20151105T022423 2015-11-05T02:34:17.539Z,1446690857.539 [DataOverHttps](INFO): Received command:configSet CBIT.vehicleOpen 1 bool persist;restart sys 2015-11-05T02:34:18.068Z,1446690858.068 [CommandLine](IMPORTANT): got command configSet CBIT.vehicleOpen 1.000000 bool persist 2015-11-05T02:34:18.073Z,1446690858.073 [CommandLine](IMPORTANT): got command restart system 2015-11-05T02:34:18.765Z,1446690858.765 [DataOverHttps](DEBUG): dataRead() @791 received: vehicle=makai&busy=false, 1 2015-11-05T02:34:18.766Z,1446690858.766 [DataOverHttps](DEBUG): ParseDataRead( data = busy=false, key = 6, value = makai 2015-11-05T02:34:18.783Z,1446690858.783 [DataOverHttps](DEBUG): ParseDataRead( data = , key = 0, value = false 2015-11-05T02:34:20.925Z,1446690860.925 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:20.925Z,1446690860.925 [CommandLine ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:20.932Z,1446690860.931 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2015-11-05T02:34:20.932Z,1446690860.932 [CommandLine ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:20.933Z,1446690860.933 [CommandLine](INFO): Join timeout helper Thread ID is 895 2015-11-05T02:34:20.936Z,1446690860.936 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2015-11-05T02:34:20.936Z,1446690860.936 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:20.938Z,1446690860.938 [NavChartDb](INFO): Join timeout helper Thread ID is 896 2015-11-05T02:34:21.296Z,1446690861.296 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:21.297Z,1446690861.297 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:21.308Z,1446690861.308 [ComponentRegistry](INFO): Shutting down Rowe_600 ThreadHandler 2015-11-05T02:34:21.308Z,1446690861.308 [Rowe_600 ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:21.309Z,1446690861.309 [Rowe_600](INFO): Join timeout helper Thread ID is 897 2015-11-05T02:34:21.332Z,1446690861.332 [Rowe_600 ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:21.333Z,1446690861.333 [Rowe_600](INFO): Powering down 2015-11-05T02:34:21.421Z,1446690861.421 [Rowe_600 ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:21.431Z,1446690861.431 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2015-11-05T02:34:21.431Z,1446690861.431 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:21.432Z,1446690861.432 [Radio_Surface](INFO): Join timeout helper Thread ID is 898 2015-11-05T02:34:21.715Z,1446690861.716 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:21.716Z,1446690861.716 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:21.735Z,1446690861.735 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2015-11-05T02:34:21.735Z,1446690861.735 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:21.736Z,1446690861.736 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 899 2015-11-05T02:34:22.047Z,1446690862.047 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:22.048Z,1446690862.048 [WetLabsBB2FL](INFO): Powering down 2015-11-05T02:34:22.048Z,1446690862.048 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.052Z,1446690862.052 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2015-11-05T02:34:22.052Z,1446690862.052 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.053Z,1446690862.053 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 900 2015-11-05T02:34:22.155Z,1446690862.155 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:22.156Z,1446690862.156 [CTD_NeilBrown](INFO): Powering down 2015-11-05T02:34:22.156Z,1446690862.156 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.176Z,1446690862.176 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2015-11-05T02:34:22.176Z,1446690862.176 [logger ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.176Z,1446690862.177 [logger](INFO): Join timeout helper Thread ID is 901 2015-11-05T02:34:22.184Z,1446690862.184 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:22.184Z,1446690862.184 [logger ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.196Z,1446690862.196 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2015-11-05T02:34:22.196Z,1446690862.196 [CommandLine ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.196Z,1446690862.196 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2015-11-05T02:34:22.196Z,1446690862.196 [controlThread ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.197Z,1446690862.197 [controlThread](INFO): Join timeout helper Thread ID is 902 2015-11-05T02:34:22.351Z,1446690862.351 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2015-11-05T02:34:22.352Z,1446690862.352 [controlThread](DEBUG): Uninitializing ControlThread 2015-11-05T02:34:22.352Z,1446690862.352 [Aanderaa_O2](INFO): Powering down 2015-11-05T02:34:22.353Z,1446690862.353 [AcousticModem_Benthos_ATM900](INFO): Powering down 2015-11-05T02:34:22.472Z,1446690862.472 [NAL9602](INFO): Powering down 2015-11-05T02:34:22.473Z,1446690862.473 [PNI_TCM](INFO): Powering down 2015-11-05T02:34:22.560Z,1446690862.560 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2015-11-05T02:34:22.562Z,1446690862.562 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2015-11-05T02:34:22.562Z,1446690862.562 [Default] Stopped 2015-11-05T02:34:22.562Z,1446690862.562 [Default](DEBUG): Aggregate::uninitialize Default 2015-11-05T02:34:22.562Z,1446690862.562 [Default:A.GoToSurface] Stopped 2015-11-05T02:34:22.563Z,1446690862.563 [Default:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2015-11-05T02:34:22.563Z,1446690862.563 [Default:CheckIn] Stopped 2015-11-05T02:34:22.563Z,1446690862.563 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2015-11-05T02:34:22.563Z,1446690862.563 [Default:CheckIn:Read_GPS] Stopped 2015-11-05T02:34:22.565Z,1446690862.565 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2015-11-05T02:34:22.566Z,1446690862.566 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2015-11-05T02:34:22.566Z,1446690862.566 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2015-11-05T02:34:22.566Z,1446690862.566 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2015-11-05T02:34:22.566Z,1446690862.566 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2015-11-05T02:34:22.567Z,1446690862.566 [BuoyancyServo](INFO): Powering down 2015-11-05T02:34:22.579Z,1446690862.579 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2015-11-05T02:34:22.579Z,1446690862.579 [ElevatorServo](INFO): Powering down 2015-11-05T02:34:22.580Z,1446690862.580 [MassServo](DEBUG): Uninitialize Mass Servo. 2015-11-05T02:34:22.580Z,1446690862.580 [MassServo](INFO): Powering down 2015-11-05T02:34:22.581Z,1446690862.581 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2015-11-05T02:34:22.581Z,1446690862.581 [RudderServo](INFO): Powering down 2015-11-05T02:34:22.582Z,1446690862.582 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2015-11-05T02:34:22.582Z,1446690862.582 [ThrusterServo](INFO): Powering down 2015-11-05T02:34:22.583Z,1446690862.583 [SBIT](DEBUG): Uninitialize SBIT Component. 2015-11-05T02:34:22.584Z,1446690862.583 [IBIT](DEBUG): Uninitialize IBIT Component. 2015-11-05T02:34:22.584Z,1446690862.584 [CBIT](DEBUG): Uninitialize CBIT Component. 2015-11-05T02:34:22.616Z,1446690862.616 [controlThread ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.682Z,1446690862.682 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.687Z,1446690862.687 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.754Z,1446690862.754 [Rowe_600 ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.762Z,1446690862.763 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.926Z,1446690862.926 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2015-11-05T02:34:22.984Z,1446690862.984 [logger ThreadHandler](INFO): Thread cancelled.