2017-04-07T17:56:41.692Z,1491587801.692 [Supervisor](DEBUG): Initializing supervisor. 2017-04-07T17:56:41.694Z,1491587801.694 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-04-07T17:56:41.695Z,1491587801.695 [SyncHandler](INFO): Protected caller Thread ID is 5188 2017-04-07T17:56:41.695Z,1491587801.695 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-04-07T17:56:41.696Z,1491587801.696 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-04-07T17:56:41.697Z,1491587801.697 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5189 2017-04-07T17:56:41.700Z,1491587801.700 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-04-07T17:56:41.712Z,1491587801.712 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-04-07T17:56:41.713Z,1491587801.713 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-04-07T17:56:41.714Z,1491587801.714 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5190 2017-04-07T17:56:41.715Z,1491587801.715 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-04-07T17:56:41.716Z,1491587801.716 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-04-07T17:56:41.716Z,1491587801.716 [logger ThreadHandler](INFO): Protected caller Thread ID is 5191 2017-04-07T17:56:41.718Z,1491587801.718 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-04-07T17:56:41.719Z,1491587801.719 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-04-07T17:56:41.720Z,1491587801.720 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-04-07T17:56:41.804Z,1491587801.804 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-04-07T17:56:42.143Z,1491587802.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-04-07T17:56:42.143Z,1491587802.143 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-04-07T17:56:42.279Z,1491587802.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-04-07T17:56:42.279Z,1491587802.279 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-04-07T17:56:42.490Z,1491587802.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-04-07T17:56:42.491Z,1491587802.491 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-04-07T17:56:42.605Z,1491587802.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-04-07T17:56:42.605Z,1491587802.605 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-04-07T17:56:42.808Z,1491587802.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-04-07T17:56:42.809Z,1491587802.809 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-04-07T17:56:43.299Z,1491587803.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-04-07T17:56:43.299Z,1491587803.299 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-04-07T17:56:43.686Z,1491587803.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-04-07T17:56:43.687Z,1491587803.687 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-04-07T17:56:43.772Z,1491587803.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-04-07T17:56:43.773Z,1491587803.773 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-04-07T17:56:43.917Z,1491587803.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-04-07T17:56:43.918Z,1491587803.918 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-04-07T17:56:44.124Z,1491587804.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-04-07T17:56:44.124Z,1491587804.124 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-04-07T17:56:44.576Z,1491587804.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-04-07T17:56:44.577Z,1491587804.577 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-04-07T17:56:44.953Z,1491587804.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-04-07T17:56:44.954Z,1491587804.954 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-04-07T17:56:45.269Z,1491587805.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-04-07T17:56:45.270Z,1491587805.270 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-04-07T17:56:45.381Z,1491587805.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-04-07T17:56:45.383Z,1491587805.383 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-04-07T17:56:45.384Z,1491587805.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-04-07T17:56:45.488Z,1491587805.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-04-07T17:56:45.581Z,1491587805.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-04-07T17:56:45.668Z,1491587805.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-04-07T17:56:45.773Z,1491587805.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-04-07T17:56:45.862Z,1491587805.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-04-07T17:56:45.991Z,1491587805.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-04-07T17:56:46.087Z,1491587806.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-04-07T17:56:46.267Z,1491587806.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-04-07T17:56:46.509Z,1491587806.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-04-07T17:56:46.509Z,1491587806.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-04-07T17:56:46.662Z,1491587806.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-04-07T17:56:46.761Z,1491587806.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-04-07T17:56:46.842Z,1491587806.842 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2017-04-07T17:56:46.842Z,1491587806.842 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2017-04-07T17:56:46.844Z,1491587806.844 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-04-07T17:56:46.872Z,1491587806.872 [DepthRateCalculator] Loaded 2017-04-07T17:56:46.872Z,1491587806.872 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-04-07T17:56:46.878Z,1491587806.878 [PitchRateCalculator] Loaded 2017-04-07T17:56:46.879Z,1491587806.879 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-04-07T17:56:46.891Z,1491587806.891 [SpeedCalculator] Loaded 2017-04-07T17:56:46.891Z,1491587806.891 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-04-07T17:56:46.914Z,1491587806.914 [TempGradientCalculator] Loaded 2017-04-07T17:56:46.915Z,1491587806.915 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-04-07T17:56:46.932Z,1491587806.932 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-04-07T17:56:46.932Z,1491587806.932 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-04-07T17:56:46.938Z,1491587806.938 [YawRateCalculator] Loaded 2017-04-07T17:56:46.938Z,1491587806.938 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-04-07T17:56:46.939Z,1491587806.939 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-04-07T17:56:46.939Z,1491587806.939 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-04-07T17:56:47.043Z,1491587807.043 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-04-07T17:56:47.044Z,1491587807.044 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-04-07T17:56:47.100Z,1491587807.100 [DeadReckonUsingSpeedCalculator] Loaded 2017-04-07T17:56:47.101Z,1491587807.101 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-04-07T17:56:47.118Z,1491587807.118 [NavChart] Loaded 2017-04-07T17:56:47.119Z,1491587807.119 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-04-07T17:56:47.123Z,1491587807.123 [UniversalFixResidualReporter] Loaded 2017-04-07T17:56:47.123Z,1491587807.123 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-04-07T17:56:47.123Z,1491587807.123 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-04-07T17:56:47.124Z,1491587807.124 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-04-07T17:56:47.277Z,1491587807.277 [Aanderaa_O2] Loaded 2017-04-07T17:56:47.277Z,1491587807.277 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-04-07T17:56:47.359Z,1491587807.359 [CTD_NeilBrown] Loaded 2017-04-07T17:56:47.360Z,1491587807.360 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-04-07T17:56:47.361Z,1491587807.361 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4066F4E0 2017-04-07T17:56:47.361Z,1491587807.361 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5274 2017-04-07T17:56:47.375Z,1491587807.375 [ISUS] Loaded 2017-04-07T17:56:47.375Z,1491587807.375 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread. 2017-04-07T17:56:47.390Z,1491587807.390 [PAR_Licor] Loaded 2017-04-07T17:56:47.390Z,1491587807.390 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-04-07T17:56:47.443Z,1491587807.443 [WetLabsBB2FL] Loaded 2017-04-07T17:56:47.443Z,1491587807.443 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-04-07T17:56:47.445Z,1491587807.445 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069F4E0 2017-04-07T17:56:47.445Z,1491587807.445 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5275 2017-04-07T17:56:47.446Z,1491587807.446 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-04-07T17:56:47.446Z,1491587807.446 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-04-07T17:56:47.472Z,1491587807.472 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-04-07T17:56:47.472Z,1491587807.472 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-04-07T17:56:47.479Z,1491587807.479 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-04-07T17:56:47.479Z,1491587807.479 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-04-07T17:56:47.798Z,1491587807.798 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-04-07T17:56:47.799Z,1491587807.799 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-04-07T17:56:47.935Z,1491587807.935 [SBIT](DEBUG): Construct Startup Built In Test. 2017-04-07T17:56:47.968Z,1491587807.968 [SBIT] Loaded 2017-04-07T17:56:47.968Z,1491587807.968 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-04-07T17:56:47.969Z,1491587807.969 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-04-07T17:56:47.998Z,1491587807.998 [IBIT] Loaded 2017-04-07T17:56:47.998Z,1491587807.998 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-04-07T17:56:48.002Z,1491587808.002 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-04-07T17:56:48.180Z,1491587808.180 [CBIT] Loaded 2017-04-07T17:56:48.180Z,1491587808.180 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-04-07T17:56:48.181Z,1491587808.181 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-04-07T17:56:48.181Z,1491587808.181 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-04-07T17:56:48.465Z,1491587808.465 [AHRS_sp3003D] Loaded 2017-04-07T17:56:48.465Z,1491587808.465 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2017-04-07T17:56:48.540Z,1491587808.540 [DataOverHttps] Loaded 2017-04-07T17:56:48.540Z,1491587808.540 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-04-07T17:56:48.554Z,1491587808.554 [Depth_Keller] Loaded 2017-04-07T17:56:48.554Z,1491587808.554 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-04-07T17:56:48.560Z,1491587808.560 [DropWeight] Loaded 2017-04-07T17:56:48.560Z,1491587808.560 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-04-07T17:56:48.700Z,1491587808.700 [DVL_micro] Loaded 2017-04-07T17:56:48.700Z,1491587808.700 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2017-04-07T17:56:48.799Z,1491587808.799 [NAL9602] Loaded 2017-04-07T17:56:48.799Z,1491587808.799 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-04-07T17:56:48.809Z,1491587808.809 [Onboard] Loaded 2017-04-07T17:56:48.809Z,1491587808.809 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-04-07T17:56:48.816Z,1491587808.816 [Radio_Surface] Loaded 2017-04-07T17:56:48.817Z,1491587808.817 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-04-07T17:56:48.818Z,1491587808.818 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409904E0 2017-04-07T17:56:48.818Z,1491587808.818 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5276 2017-04-07T17:56:48.962Z,1491587808.962 [DAT] Loaded 2017-04-07T17:56:48.962Z,1491587808.962 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2017-04-07T17:56:50.877Z,1491587810.877 [BPC1] Loaded 2017-04-07T17:56:50.878Z,1491587810.878 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-04-07T17:56:50.878Z,1491587810.878 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-04-07T17:56:50.879Z,1491587810.879 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-04-07T17:56:50.986Z,1491587810.986 [BuoyancyServo] Loaded 2017-04-07T17:56:50.986Z,1491587810.986 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-04-07T17:56:51.002Z,1491587811.002 [ElevatorServo] Loaded 2017-04-07T17:56:51.003Z,1491587811.003 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-04-07T17:56:51.018Z,1491587811.018 [MassServo] Loaded 2017-04-07T17:56:51.018Z,1491587811.018 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-04-07T17:56:51.035Z,1491587811.035 [RudderServo] Loaded 2017-04-07T17:56:51.035Z,1491587811.035 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-04-07T17:56:51.050Z,1491587811.050 [ThrusterServo] Loaded 2017-04-07T17:56:51.051Z,1491587811.051 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-04-07T17:56:51.051Z,1491587811.051 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-04-07T17:56:51.052Z,1491587811.052 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-04-07T17:56:51.104Z,1491587811.104 [VerticalControl](DEBUG): Construct VerticalControl. 2017-04-07T17:56:51.199Z,1491587811.199 [VerticalControl] Loaded 2017-04-07T17:56:51.200Z,1491587811.200 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-04-07T17:56:51.200Z,1491587811.200 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-04-07T17:56:51.262Z,1491587811.262 [HorizontalControl] Loaded 2017-04-07T17:56:51.263Z,1491587811.263 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-04-07T17:56:51.263Z,1491587811.263 [SpeedControl](DEBUG): Construct SpeedControl. 2017-04-07T17:56:51.265Z,1491587811.265 [SpeedControl] Loaded 2017-04-07T17:56:51.266Z,1491587811.266 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-04-07T17:56:51.266Z,1491587811.266 [LoopControl](DEBUG): Construct LoopControl. 2017-04-07T17:56:51.267Z,1491587811.267 [LoopControl] Loaded 2017-04-07T17:56:51.267Z,1491587811.267 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-04-07T17:56:51.268Z,1491587811.268 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-04-07T17:56:51.268Z,1491587811.268 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-04-07T17:56:51.353Z,1491587811.353 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-04-07T17:56:51.354Z,1491587811.354 [StratificationFrontDetector](DEBUG): (re)initializing 2017-04-07T17:56:51.354Z,1491587811.354 [StratificationFrontDetector] Loaded 2017-04-07T17:56:51.354Z,1491587811.354 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-04-07T17:56:51.380Z,1491587811.380 [DepAvgTempFrontDetector] Loaded 2017-04-07T17:56:51.380Z,1491587811.380 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread. 2017-04-07T17:56:51.381Z,1491587811.381 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-04-07T17:56:51.381Z,1491587811.381 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-04-07T17:56:51.488Z,1491587811.488 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-04-07T17:56:51.492Z,1491587811.492 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-04-07T17:56:51.492Z,1491587811.492 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-04-07T17:56:51.500Z,1491587811.500 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-04-07T17:56:51.501Z,1491587811.501 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2017-04-07T17:56:51.501Z,1491587811.501 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5277 2017-04-07T17:56:51.506Z,1491587811.506 [Supervisor](INFO): Main Thread ID is 5187 2017-04-07T17:56:51.506Z,1491587811.506 [Supervisor](DEBUG): Running supervisor. 2017-04-07T17:56:51.507Z,1491587811.507 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5278 2017-04-07T17:56:51.510Z,1491587811.510 [controlThread ThreadHandler](INFO): Handler Thread ID is 5279 2017-04-07T17:56:51.511Z,1491587811.511 [controlThread](DEBUG): Initializing ControlThread 2017-04-07T17:56:51.511Z,1491587811.511 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-04-07T17:56:51.512Z,1491587811.512 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-04-07T17:56:51.512Z,1491587811.512 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-04-07T17:56:51.513Z,1491587811.513 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-04-07T17:56:51.513Z,1491587811.513 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-04-07T17:56:51.514Z,1491587811.514 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-04-07T17:56:51.514Z,1491587811.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-04-07T17:56:51.515Z,1491587811.515 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-04-07T17:56:51.516Z,1491587811.516 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-04-07T17:56:51.516Z,1491587811.516 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-04-07T17:56:51.517Z,1491587811.517 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-04-07T17:56:51.517Z,1491587811.517 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-04-07T17:56:51.517Z,1491587811.517 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-04-07T17:56:51.518Z,1491587811.518 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-04-07T17:56:51.521Z,1491587811.521 [SBIT](INFO): Initialize SBIT Component. 2017-04-07T17:56:51.522Z,1491587811.522 [SBIT](IMPORTANT): git: 2017-02-07-42-g770a181 2017-04-07T17:56:51.522Z,1491587811.522 [SBIT](INFO): git hash: 770a181585674743d86f29932ec8234b5d7ae45c 2017-04-07T17:56:51.524Z,1491587811.524 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-04-07T17:56:51.524Z,1491587811.524 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-04-07T17:56:51.525Z,1491587811.525 [SBIT](INFO): Beginning SBIT in 32.000000 seconds. 2017-04-07T17:56:51.526Z,1491587811.526 [IBIT](INFO): Initialize IBIT Component. 2017-04-07T17:56:51.526Z,1491587811.526 [CBIT](DEBUG): Initialize CBIT Component. 2017-04-07T17:56:51.527Z,1491587811.527 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-04-07T17:56:51.528Z,1491587811.528 [logger ThreadHandler](INFO): Handler Thread ID is 5280 2017-04-07T17:56:51.546Z,1491587811.546 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5281 2017-04-07T17:56:51.547Z,1491587811.547 [CTD_NeilBrown](INFO): Powering down 2017-04-07T17:56:51.574Z,1491587811.574 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5282 2017-04-07T17:56:51.575Z,1491587811.575 [WetLabsBB2FL](INFO): Powering down 2017-04-07T17:56:51.618Z,1491587811.618 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-04-07T17:56:51.620Z,1491587811.620 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-04-07T17:56:51.620Z,1491587811.620 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-04-07T17:56:51.621Z,1491587811.621 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-04-07T17:56:51.623Z,1491587811.623 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-04-07T17:56:51.630Z,1491587811.630 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5283 2017-04-07T17:56:51.636Z,1491587811.636 [Radio_Surface](INFO): Powering up 2017-04-07T17:56:51.645Z,1491587811.645 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-07T17:56:51.647Z,1491587811.647 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5284 2017-04-07T17:56:51.650Z,1491587811.650 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2017-04-07T17:56:51.655Z,1491587811.655 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-04-07T17:56:51.657Z,1491587811.657 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2017-04-07T17:56:51.657Z,1491587811.657 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-04-07T17:56:51.658Z,1491587811.658 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2017-04-07T17:56:51.659Z,1491587811.659 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-04-07T17:56:51.660Z,1491587811.660 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2017-04-07T17:56:51.660Z,1491587811.660 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-04-07T17:56:51.681Z,1491587811.681 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2017-04-07T17:56:51.681Z,1491587811.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-04-07T17:56:51.684Z,1491587811.684 [MissionManager](DEBUG): 2017-04-07T17:56:51.685Z,1491587811.685 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-04-07T17:56:51.689Z,1491587811.689 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2017-04-07T17:56:51.689Z,1491587811.689 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-04-07T17:56:51.691Z,1491587811.691 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2017-04-07T17:56:51.691Z,1491587811.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-04-07T17:56:51.693Z,1491587811.693 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2017-04-07T17:56:51.693Z,1491587811.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-07T17:56:51.695Z,1491587811.695 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2017-04-07T17:56:51.744Z,1491587811.744 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-04-07T17:56:51.745Z,1491587811.745 [Default:A.Wait](DEBUG): Construct Wait. 2017-04-07T17:56:51.747Z,1491587811.747 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-07T17:56:51.758Z,1491587811.758 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-07T17:56:51.797Z,1491587811.797 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-04-07T17:56:51.812Z,1491587811.812 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-04-07T17:56:51.825Z,1491587811.825 [Default:E.Execute](DEBUG): Construct Execute. 2017-04-07T17:56:51.833Z,1491587811.833 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2017-04-07T17:56:51.839Z,1491587811.839 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ISUS,PAR_Licor,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-04-07T17:56:52.031Z,1491587812.031 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2017-04-07T17:56:52.082Z,1491587812.082 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-04-07T17:56:52.141Z,1491587812.141 [DVL_micro](INFO): Initializing 2017-04-07T17:56:52.236Z,1491587812.236 [DAT](INFO): Powering up 2017-04-07T17:56:52.236Z,1491587812.236 [DAT](DEBUG): Initializing DAT. 2017-04-07T17:56:52.474Z,1491587812.474 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:56:52.493Z,1491587812.493 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:56:52.605Z,1491587812.605 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:56:52.610Z,1491587812.610 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-07T17:56:52.617Z,1491587812.617 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:56:52.641Z,1491587812.641 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-07T17:56:52.647Z,1491587812.647 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:56:52.653Z,1491587812.653 [MassServo](DEBUG): Initializing MassServo. 2017-04-07T17:56:52.671Z,1491587812.671 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:56:52.681Z,1491587812.681 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-07T17:56:52.687Z,1491587812.687 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:56:52.693Z,1491587812.693 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-07T17:56:52.883Z,1491587812.883 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2017-04-07T17:56:52.883Z,1491587812.883 [DropWeight] Hardware Fault, FailCount= 1 2017-04-07T17:56:52.883Z,1491587812.883 [DropWeight](ERROR): Hardware Fault 2017-04-07T17:56:52.972Z,1491587812.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:56:52.993Z,1491587812.993 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:56:53.011Z,1491587813.011 [CommandLine](FAULT): Scheduling is paused 2017-04-07T17:56:53.011Z,1491587813.011 [CBIT](INFO): Critical error at 20170407T175652 2017-04-07T17:56:53.012Z,1491587813.012 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-04-07T17:56:53.014Z,1491587813.014 [CBIT](ERROR): Hardware Fault in component: DropWeight 2017-04-07T17:56:53.014Z,1491587813.014 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2017-04-07T17:56:53.513Z,1491587813.513 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout 2017-04-07T17:56:53.513Z,1491587813.513 [AHRS_sp3003D](FAULT): SP3003D failed to initialize 2017-04-07T17:56:53.513Z,1491587813.513 [AHRS_sp3003D] Hardware Fault, FailCount= 1 2017-04-07T17:56:53.513Z,1491587813.513 [AHRS_sp3003D](ERROR): Hardware Fault 2017-04-07T17:57:01.828Z,1491587821.828 [NAL9602](INFO): Powering up NAL9602 2017-04-07T17:57:01.917Z,1491587821.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:01.918Z,1491587821.918 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:02.245Z,1491587822.245 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-04-07T17:57:02.245Z,1491587822.245 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-04-07T17:57:02.246Z,1491587822.246 [BuoyancyServo] Communications Fault, FailCount= 1 2017-04-07T17:57:02.246Z,1491587822.246 [BuoyancyServo](ERROR): Communications Fault 2017-04-07T17:57:02.549Z,1491587822.549 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2017-04-07T17:57:02.549Z,1491587822.549 [ElevatorServo](FAULT): Elevator failed to initialize 2017-04-07T17:57:02.549Z,1491587822.549 [ElevatorServo] Communications Fault, FailCount= 1 2017-04-07T17:57:02.549Z,1491587822.549 [ElevatorServo](ERROR): Communications Fault 2017-04-07T17:57:02.865Z,1491587822.865 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2017-04-07T17:57:02.865Z,1491587822.865 [RudderServo](FAULT): Rudder failed to initialize 2017-04-07T17:57:02.865Z,1491587822.865 [RudderServo] Communications Fault, FailCount= 1 2017-04-07T17:57:02.865Z,1491587822.865 [RudderServo](ERROR): Communications Fault 2017-04-07T17:57:03.181Z,1491587823.181 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-04-07T17:57:03.505Z,1491587823.505 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-04-07T17:57:03.505Z,1491587823.505 [ThrusterServo](FAULT): Thruster failed to initialize 2017-04-07T17:57:03.505Z,1491587823.505 [ThrusterServo] Communications Fault, FailCount= 1 2017-04-07T17:57:03.505Z,1491587823.505 [ThrusterServo](ERROR): Communications Fault 2017-04-07T17:57:03.507Z,1491587823.507 [CBIT](INFO): Critical error at 20170407T175653 2017-04-07T17:57:03.510Z,1491587823.510 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D 2017-04-07T17:57:03.510Z,1491587823.510 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-04-07T17:57:03.510Z,1491587823.510 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2017-04-07T17:57:03.511Z,1491587823.511 [CBIT](ERROR): Communications Fault in component: RudderServo 2017-04-07T17:57:03.511Z,1491587823.511 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-04-07T17:57:03.516Z,1491587823.516 [Aanderaa_O2](FAULT): Timed out starting 2017-04-07T17:57:03.516Z,1491587823.516 [Aanderaa_O2] Communications Fault, FailCount= 1 2017-04-07T17:57:03.516Z,1491587823.516 [Aanderaa_O2](ERROR): Communications Fault 2017-04-07T17:57:03.530Z,1491587823.530 [AHRS_sp3003D](INFO): Powering down 2017-04-07T17:57:03.654Z,1491587823.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:03.655Z,1491587823.655 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:03.693Z,1491587823.693 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-04-07T17:57:03.694Z,1491587823.694 [BuoyancyServo](INFO): Powering down 2017-04-07T17:57:03.777Z,1491587823.777 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-04-07T17:57:03.777Z,1491587823.777 [ElevatorServo](INFO): Powering down 2017-04-07T17:57:04.137Z,1491587824.137 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2017-04-07T17:57:04.137Z,1491587824.137 [MassServo] Communications Fault, FailCount= 1 2017-04-07T17:57:04.137Z,1491587824.137 [MassServo](ERROR): Communications Fault 2017-04-07T17:57:04.138Z,1491587824.138 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2017-04-07T17:57:04.138Z,1491587824.138 [RudderServo](INFO): Powering down 2017-04-07T17:57:04.193Z,1491587824.193 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-04-07T17:57:04.193Z,1491587824.193 [ThrusterServo](INFO): Powering down 2017-04-07T17:57:04.238Z,1491587824.238 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-04-07T17:57:04.239Z,1491587824.239 [CBIT](ERROR): Communications Fault in component: MassServo 2017-04-07T17:57:04.373Z,1491587824.373 [Aanderaa_O2](INFO): Powering down 2017-04-07T17:57:04.509Z,1491587824.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:04.521Z,1491587824.521 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:04.528Z,1491587824.528 [MassServo](DEBUG): Uninitialize Mass Servo. 2017-04-07T17:57:04.528Z,1491587824.528 [MassServo](INFO): Powering down 2017-04-07T17:57:04.656Z,1491587824.656 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004300 2017-04-07T17:57:04.710Z,1491587824.710 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:04.711Z,1491587824.711 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:04.726Z,1491587824.726 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:04.846Z,1491587824.846 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-07T17:57:04.848Z,1491587824.848 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:04.965Z,1491587824.965 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-07T17:57:05.093Z,1491587825.093 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D 2017-04-07T17:57:05.093Z,1491587825.093 [AHRS_sp3003D] No Fault, FailCount= 1 2017-04-07T17:57:05.094Z,1491587825.094 [CBIT](INFO): Clearing failed state for component ElevatorServo 2017-04-07T17:57:05.094Z,1491587825.094 [ElevatorServo] No Fault, FailCount= 1 2017-04-07T17:57:05.094Z,1491587825.094 [CBIT](INFO): Clearing failed state for component RudderServo 2017-04-07T17:57:05.094Z,1491587825.094 [RudderServo] No Fault, FailCount= 1 2017-04-07T17:57:05.094Z,1491587825.094 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-04-07T17:57:05.094Z,1491587825.094 [ThrusterServo] No Fault, FailCount= 1 2017-04-07T17:57:05.110Z,1491587825.110 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2017-04-07T17:57:05.154Z,1491587825.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:05.155Z,1491587825.155 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:05.202Z,1491587825.202 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:05.322Z,1491587825.322 [MassServo](DEBUG): Initializing MassServo. 2017-04-07T17:57:05.324Z,1491587825.324 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:05.329Z,1491587825.329 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-07T17:57:05.333Z,1491587825.333 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-04-07T17:57:05.333Z,1491587825.333 [Aanderaa_O2] No Fault, FailCount= 1 2017-04-07T17:57:05.334Z,1491587825.334 [CBIT](INFO): Clearing failed state for component MassServo 2017-04-07T17:57:05.334Z,1491587825.334 [MassServo] No Fault, FailCount= 1 2017-04-07T17:57:05.549Z,1491587825.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:05.550Z,1491587825.550 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:05.893Z,1491587825.893 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2017-04-07T17:57:05.893Z,1491587825.893 [ElevatorServo](FAULT): Elevator failed to initialize 2017-04-07T17:57:05.893Z,1491587825.893 [ElevatorServo] Communications Fault, FailCount= 2 2017-04-07T17:57:05.893Z,1491587825.893 [ElevatorServo](ERROR): Communications Fault 2017-04-07T17:57:06.201Z,1491587826.201 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2017-04-07T17:57:06.201Z,1491587826.201 [RudderServo](FAULT): Rudder failed to initialize 2017-04-07T17:57:06.201Z,1491587826.201 [RudderServo] Communications Fault, FailCount= 2 2017-04-07T17:57:06.201Z,1491587826.201 [RudderServo](ERROR): Communications Fault 2017-04-07T17:57:06.525Z,1491587826.525 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-04-07T17:57:06.849Z,1491587826.849 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-04-07T17:57:06.849Z,1491587826.849 [ThrusterServo](FAULT): Thruster failed to initialize 2017-04-07T17:57:06.849Z,1491587826.849 [ThrusterServo] Communications Fault, FailCount= 2 2017-04-07T17:57:06.849Z,1491587826.849 [ThrusterServo](ERROR): Communications Fault 2017-04-07T17:57:06.853Z,1491587826.853 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2017-04-07T17:57:06.854Z,1491587826.854 [CBIT](ERROR): Communications Fault in component: RudderServo 2017-04-07T17:57:06.854Z,1491587826.854 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-04-07T17:57:06.863Z,1491587826.863 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-07T17:57:07.237Z,1491587827.237 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout 2017-04-07T17:57:07.237Z,1491587827.237 [AHRS_sp3003D](FAULT): SP3003D failed to initialize 2017-04-07T17:57:07.237Z,1491587827.237 [AHRS_sp3003D] Hardware Fault, FailCount= 2 2017-04-07T17:57:07.237Z,1491587827.237 [AHRS_sp3003D](ERROR): Hardware Fault 2017-04-07T17:57:07.301Z,1491587827.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:07.302Z,1491587827.302 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:07.309Z,1491587827.309 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-04-07T17:57:07.309Z,1491587827.309 [ElevatorServo](INFO): Powering down 2017-04-07T17:57:07.613Z,1491587827.613 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2017-04-07T17:57:07.613Z,1491587827.613 [MassServo] Communications Fault, FailCount= 2 2017-04-07T17:57:07.613Z,1491587827.613 [MassServo](ERROR): Communications Fault 2017-04-07T17:57:07.614Z,1491587827.614 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2017-04-07T17:57:07.614Z,1491587827.614 [RudderServo](INFO): Powering down 2017-04-07T17:57:07.615Z,1491587827.615 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-04-07T17:57:07.615Z,1491587827.615 [ThrusterServo](INFO): Powering down 2017-04-07T17:57:07.619Z,1491587827.619 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D 2017-04-07T17:57:07.619Z,1491587827.619 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-04-07T17:57:07.619Z,1491587827.619 [BuoyancyServo] No Fault, FailCount= 1 2017-04-07T17:57:07.620Z,1491587827.620 [CBIT](ERROR): Communications Fault in component: MassServo 2017-04-07T17:57:07.634Z,1491587827.634 [AHRS_sp3003D](INFO): Powering down 2017-04-07T17:57:07.744Z,1491587827.744 [BPC1](FAULT): Failed to initialize 2017-04-07T17:57:07.744Z,1491587827.744 [BPC1] Communications Fault, FailCount= 1 2017-04-07T17:57:07.744Z,1491587827.744 [BPC1](ERROR): Communications Fault 2017-04-07T17:57:07.774Z,1491587827.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:07.775Z,1491587827.775 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:07.801Z,1491587827.801 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:07.922Z,1491587827.922 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-07T17:57:07.924Z,1491587827.924 [MassServo](DEBUG): Uninitialize Mass Servo. 2017-04-07T17:57:07.924Z,1491587827.924 [MassServo](INFO): Powering down 2017-04-07T17:57:07.929Z,1491587827.929 [CBIT](ERROR): Communications Fault in component: BPC1 2017-04-07T17:57:08.086Z,1491587828.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:08.088Z,1491587828.088 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:08.110Z,1491587828.110 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:08.230Z,1491587828.230 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-07T17:57:08.232Z,1491587828.232 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:08.347Z,1491587828.347 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-07T17:57:08.469Z,1491587828.469 [CBIT](INFO): Clearing failed state for component ElevatorServo 2017-04-07T17:57:08.469Z,1491587828.469 [ElevatorServo] No Fault, FailCount= 2 2017-04-07T17:57:08.469Z,1491587828.469 [CBIT](INFO): Clearing failed state for component RudderServo 2017-04-07T17:57:08.469Z,1491587828.469 [RudderServo] No Fault, FailCount= 2 2017-04-07T17:57:08.470Z,1491587828.470 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-04-07T17:57:08.470Z,1491587828.470 [ThrusterServo] No Fault, FailCount= 2 2017-04-07T17:57:08.545Z,1491587828.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:08.546Z,1491587828.546 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:08.889Z,1491587828.889 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-04-07T17:57:08.889Z,1491587828.889 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-04-07T17:57:08.889Z,1491587828.889 [BuoyancyServo] Communications Fault, FailCount= 2 2017-04-07T17:57:08.889Z,1491587828.889 [BuoyancyServo](ERROR): Communications Fault 2017-04-07T17:57:09.201Z,1491587829.201 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2017-04-07T17:57:09.201Z,1491587829.201 [ElevatorServo](FAULT): Elevator failed to initialize 2017-04-07T17:57:09.201Z,1491587829.201 [ElevatorServo] Communications Fault, FailCount= 3 2017-04-07T17:57:09.201Z,1491587829.201 [ElevatorServo](ERROR): Communications Fault 2017-04-07T17:57:09.202Z,1491587829.202 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:09.322Z,1491587829.322 [MassServo](DEBUG): Initializing MassServo. 2017-04-07T17:57:09.629Z,1491587829.629 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2017-04-07T17:57:09.629Z,1491587829.629 [RudderServo](FAULT): Rudder failed to initialize 2017-04-07T17:57:09.629Z,1491587829.629 [RudderServo] Communications Fault, FailCount= 3 2017-04-07T17:57:09.629Z,1491587829.629 [RudderServo](ERROR): Communications Fault 2017-04-07T17:57:09.630Z,1491587829.630 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:09.637Z,1491587829.637 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-07T17:57:09.642Z,1491587829.642 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-04-07T17:57:09.642Z,1491587829.642 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2017-04-07T17:57:09.642Z,1491587829.642 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo 2017-04-07T17:57:09.642Z,1491587829.642 [CBIT](INFO): Clearing failed state for component MassServo 2017-04-07T17:57:09.642Z,1491587829.642 [MassServo] No Fault, FailCount= 2 2017-04-07T17:57:09.643Z,1491587829.643 [CBIT](ERROR): Communications Fault in component: RudderServo 2017-04-07T17:57:09.643Z,1491587829.643 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2017-04-07T17:57:09.742Z,1491587829.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:09.743Z,1491587829.743 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:09.750Z,1491587829.750 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-04-07T17:57:09.750Z,1491587829.750 [BuoyancyServo](INFO): Powering down 2017-04-07T17:57:09.772Z,1491587829.772 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-04-07T17:57:09.772Z,1491587829.772 [ElevatorServo](INFO): Powering down 2017-04-07T17:57:09.778Z,1491587829.778 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2017-04-07T17:57:09.778Z,1491587829.778 [RudderServo](INFO): Powering down 2017-04-07T17:57:09.781Z,1491587829.781 [CBIT](INFO): Critical error at 20170407T175709 2017-04-07T17:57:09.796Z,1491587829.796 [CBIT](INFO): Clearing failed state for component BPC1 2017-04-07T17:57:09.796Z,1491587829.796 [BPC1] No Fault, FailCount= 1 2017-04-07T17:57:10.130Z,1491587830.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:10.132Z,1491587830.132 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:10.505Z,1491587830.505 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-04-07T17:57:10.825Z,1491587830.825 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-04-07T17:57:10.825Z,1491587830.825 [ThrusterServo](FAULT): Thruster failed to initialize 2017-04-07T17:57:10.825Z,1491587830.825 [ThrusterServo] Communications Fault, FailCount= 3 2017-04-07T17:57:10.826Z,1491587830.826 [ThrusterServo](ERROR): Communications Fault 2017-04-07T17:57:10.833Z,1491587830.833 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D 2017-04-07T17:57:10.833Z,1491587830.833 [AHRS_sp3003D] No Fault, FailCount= 2 2017-04-07T17:57:10.834Z,1491587830.834 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-04-07T17:57:10.850Z,1491587830.850 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2017-04-07T17:57:10.918Z,1491587830.918 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:10.919Z,1491587830.919 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:10.935Z,1491587830.935 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:11.050Z,1491587831.050 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-07T17:57:11.052Z,1491587831.052 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:11.177Z,1491587831.177 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-07T17:57:11.178Z,1491587831.178 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-04-07T17:57:11.178Z,1491587831.178 [ThrusterServo](INFO): Powering down 2017-04-07T17:57:11.305Z,1491587831.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:11.306Z,1491587831.306 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:11.613Z,1491587831.613 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2017-04-07T17:57:11.613Z,1491587831.613 [MassServo] Communications Fault, FailCount= 3 2017-04-07T17:57:11.613Z,1491587831.613 [MassServo](ERROR): Communications Fault 2017-04-07T17:57:11.618Z,1491587831.618 [CBIT](ERROR): Communications Fault in component: MassServo 2017-04-07T17:57:11.722Z,1491587831.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:11.724Z,1491587831.724 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:11.738Z,1491587831.738 [MassServo](DEBUG): Uninitialize Mass Servo. 2017-04-07T17:57:11.738Z,1491587831.738 [MassServo](INFO): Powering down 2017-04-07T17:57:11.865Z,1491587831.865 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-04-07T17:57:11.865Z,1491587831.865 [BuoyancyServo] No Fault, FailCount= 2 2017-04-07T17:57:11.866Z,1491587831.866 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-04-07T17:57:11.866Z,1491587831.866 [ThrusterServo] No Fault, FailCount= 3 2017-04-07T17:57:12.393Z,1491587832.393 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout 2017-04-07T17:57:12.393Z,1491587832.393 [AHRS_sp3003D](FAULT): SP3003D failed to initialize 2017-04-07T17:57:12.393Z,1491587832.393 [AHRS_sp3003D] Hardware Fault, FailCount= 3 2017-04-07T17:57:12.393Z,1491587832.393 [AHRS_sp3003D](ERROR): Hardware Fault 2017-04-07T17:57:12.440Z,1491587832.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:12.441Z,1491587832.441 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:12.480Z,1491587832.480 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:12.598Z,1491587832.598 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-07T17:57:12.600Z,1491587832.600 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:12.605Z,1491587832.605 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-07T17:57:12.609Z,1491587832.609 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D 2017-04-07T17:57:12.655Z,1491587832.655 [AHRS_sp3003D](INFO): Powering down 2017-04-07T17:57:12.805Z,1491587832.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:12.806Z,1491587832.806 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:12.826Z,1491587832.826 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:12.942Z,1491587832.942 [MassServo](DEBUG): Initializing MassServo. 2017-04-07T17:57:12.947Z,1491587832.947 [CBIT](INFO): Clearing failed state for component MassServo 2017-04-07T17:57:12.947Z,1491587832.947 [MassServo] No Fault, FailCount= 3 2017-04-07T17:57:13.082Z,1491587833.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:13.083Z,1491587833.083 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:13.425Z,1491587833.425 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-04-07T17:57:13.425Z,1491587833.425 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-04-07T17:57:13.425Z,1491587833.425 [BuoyancyServo] Communications Fault, FailCount= 3 2017-04-07T17:57:13.425Z,1491587833.425 [BuoyancyServo](ERROR): Communications Fault 2017-04-07T17:57:13.745Z,1491587833.745 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-04-07T17:57:14.069Z,1491587834.069 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-04-07T17:57:14.069Z,1491587834.069 [ThrusterServo](FAULT): Thruster failed to initialize 2017-04-07T17:57:14.069Z,1491587834.069 [ThrusterServo] Communications Fault, FailCount= 4 2017-04-07T17:57:14.069Z,1491587834.069 [ThrusterServo](ERROR): Communications Fault 2017-04-07T17:57:14.073Z,1491587834.073 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-04-07T17:57:14.074Z,1491587834.074 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-04-07T17:57:14.110Z,1491587834.110 [DVL_micro](ERROR): No DVL communication! Re-initializing 2017-04-07T17:57:14.110Z,1491587834.110 [DVL_micro] Communications Fault, FailCount= 1 2017-04-07T17:57:14.110Z,1491587834.110 [DVL_micro](ERROR): Communications Fault 2017-04-07T17:57:14.158Z,1491587834.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:14.160Z,1491587834.160 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:14.172Z,1491587834.172 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-04-07T17:57:14.182Z,1491587834.182 [BuoyancyServo](INFO): Powering down 2017-04-07T17:57:14.196Z,1491587834.196 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-04-07T17:57:14.196Z,1491587834.196 [ThrusterServo](INFO): Powering down 2017-04-07T17:57:14.240Z,1491587834.240 [CBIT](ERROR): Communications Fault in component: DVL_micro 2017-04-07T17:57:20.139Z,1491587840.139 [CommandLine](IMPORTANT): got command restart system 2017-04-07T17:57:21.406Z,1491587841.406 [DVL_micro](INFO): uninitialize:Powering down 2017-04-07T17:57:21.495Z,1491587841.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:21.496Z,1491587841.496 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:21.821Z,1491587841.821 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2017-04-07T17:57:21.821Z,1491587841.821 [MassServo] Communications Fault, FailCount= 4 2017-04-07T17:57:21.821Z,1491587841.821 [MassServo](ERROR): Communications Fault 2017-04-07T17:57:21.826Z,1491587841.826 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D 2017-04-07T17:57:21.826Z,1491587841.826 [AHRS_sp3003D] No Fault, FailCount= 3 2017-04-07T17:57:21.827Z,1491587841.827 [CBIT](ERROR): Communications Fault in component: MassServo 2017-04-07T17:57:21.833Z,1491587841.833 [Aanderaa_O2](FAULT): Timed out starting 2017-04-07T17:57:21.833Z,1491587841.833 [Aanderaa_O2] Communications Fault, FailCount= 2 2017-04-07T17:57:21.833Z,1491587841.833 [Aanderaa_O2](ERROR): Communications Fault 2017-04-07T17:57:21.842Z,1491587841.842 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2017-04-07T17:57:21.871Z,1491587841.871 [DVL_micro](DEBUG): LCB error:LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated. 2017-04-07T17:57:21.945Z,1491587841.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:21.946Z,1491587841.946 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:21.959Z,1491587841.959 [MassServo](DEBUG): Uninitialize Mass Servo. 2017-04-07T17:57:21.960Z,1491587841.960 [MassServo](INFO): Powering down 2017-04-07T17:57:22.079Z,1491587842.079 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-04-07T17:57:22.080Z,1491587842.080 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-04-07T17:57:22.080Z,1491587842.080 [BuoyancyServo] No Fault, FailCount= 3 2017-04-07T17:57:22.080Z,1491587842.080 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-04-07T17:57:22.080Z,1491587842.080 [ThrusterServo] No Fault, FailCount= 4 2017-04-07T17:57:22.357Z,1491587842.357 [Aanderaa_O2](INFO): Powering down 2017-04-07T17:57:22.399Z,1491587842.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:22.400Z,1491587842.400 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:22.408Z,1491587842.408 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-07T17:57:22.409Z,1491587842.409 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.423Z,1491587842.423 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:22.549Z,1491587842.549 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-07T17:57:22.551Z,1491587842.551 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:22.557Z,1491587842.557 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2017-04-07T17:57:22.557Z,1491587842.557 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.558Z,1491587842.558 [CommandLine](INFO): Join timeout helper Thread ID is 5316 2017-04-07T17:57:22.559Z,1491587842.559 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-07T17:57:22.563Z,1491587842.563 [CBIT](INFO): Clearing failed state for component DVL_micro 2017-04-07T17:57:22.563Z,1491587842.563 [DVL_micro] No Fault, FailCount= 1 2017-04-07T17:57:22.592Z,1491587842.592 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2017-04-07T17:57:22.592Z,1491587842.592 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.593Z,1491587842.593 [NavChartDb](INFO): Join timeout helper Thread ID is 5317 2017-04-07T17:57:22.605Z,1491587842.605 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-07T17:57:22.605Z,1491587842.605 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.621Z,1491587842.621 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2017-04-07T17:57:22.621Z,1491587842.621 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.622Z,1491587842.622 [Radio_Surface](INFO): Join timeout helper Thread ID is 5318 2017-04-07T17:57:22.653Z,1491587842.653 [DVL_micro](INFO): Initializing 2017-04-07T17:57:22.693Z,1491587842.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:22.695Z,1491587842.695 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-07T17:57:22.714Z,1491587842.714 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-07T17:57:22.789Z,1491587842.789 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-07T17:57:22.790Z,1491587842.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.801Z,1491587842.801 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2017-04-07T17:57:22.801Z,1491587842.801 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:22.802Z,1491587842.802 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5320 2017-04-07T17:57:22.843Z,1491587842.843 [MassServo](DEBUG): Initializing MassServo. 2017-04-07T17:57:22.848Z,1491587842.848 [CBIT](INFO): Clearing failed state for component MassServo 2017-04-07T17:57:22.848Z,1491587842.848 [MassServo] No Fault, FailCount= 4 2017-04-07T17:57:23.142Z,1491587843.142 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-07T17:57:23.142Z,1491587843.142 [WetLabsBB2FL](INFO): Powering down 2017-04-07T17:57:23.143Z,1491587843.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:23.145Z,1491587843.145 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2017-04-07T17:57:23.145Z,1491587843.145 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2017-04-07T17:57:23.146Z,1491587843.146 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5322 2017-04-07T17:57:23.389Z,1491587843.389 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout 2017-04-07T17:57:23.389Z,1491587843.389 [AHRS_sp3003D](FAULT): SP3003D failed to initialize 2017-04-07T17:57:23.389Z,1491587843.389 [AHRS_sp3003D] Hardware Fault, FailCount= 4 2017-04-07T17:57:23.389Z,1491587843.389 [AHRS_sp3003D](ERROR): Hardware Fault 2017-04-07T17:57:23.408Z,1491587843.408 [NAL9602](ERROR): NAL9602 initialization error. 2017-04-07T17:57:23.408Z,1491587843.408 [NAL9602] Communications Fault, FailCount= 1 2017-04-07T17:57:23.408Z,1491587843.408 [NAL9602](ERROR): Communications Fault 2017-04-07T17:57:23.430Z,1491587843.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have or