2017-09-18T19:09:46.041Z,1505761786.041 [Supervisor](DEBUG): Initializing supervisor. 2017-09-18T19:09:46.059Z,1505761786.059 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-09-18T19:09:46.060Z,1505761786.060 [SyncHandler](INFO): Protected caller Thread ID is 3918 2017-09-18T19:09:46.061Z,1505761786.061 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-09-18T19:09:46.075Z,1505761786.075 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-09-18T19:09:46.075Z,1505761786.075 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3919 2017-09-18T19:09:46.079Z,1505761786.079 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-09-18T19:09:46.098Z,1505761786.098 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-09-18T19:09:46.099Z,1505761786.099 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-09-18T19:09:46.100Z,1505761786.100 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3920 2017-09-18T19:09:46.101Z,1505761786.101 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-09-18T19:09:46.102Z,1505761786.102 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-09-18T19:09:46.102Z,1505761786.102 [logger ThreadHandler](INFO): Protected caller Thread ID is 3921 2017-09-18T19:09:46.105Z,1505761786.105 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-09-18T19:09:46.105Z,1505761786.105 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-09-18T19:09:46.107Z,1505761786.107 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-09-18T19:09:46.645Z,1505761786.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-09-18T19:09:46.646Z,1505761786.646 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-09-18T19:09:47.201Z,1505761787.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-09-18T19:09:47.202Z,1505761787.202 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-09-18T19:09:47.858Z,1505761787.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-09-18T19:09:47.859Z,1505761787.859 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-09-18T19:09:48.174Z,1505761788.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-09-18T19:09:48.175Z,1505761788.175 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-09-18T19:09:48.272Z,1505761788.272 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-09-18T19:09:48.708Z,1505761788.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-09-18T19:09:48.709Z,1505761788.709 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-09-18T19:09:48.966Z,1505761788.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-09-18T19:09:48.966Z,1505761788.966 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-09-18T19:09:49.487Z,1505761789.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-09-18T19:09:49.488Z,1505761789.488 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-09-18T19:09:50.252Z,1505761790.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-09-18T19:09:50.252Z,1505761790.252 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-09-18T19:09:50.934Z,1505761790.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-09-18T19:09:50.935Z,1505761790.935 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-09-18T19:09:52.057Z,1505761792.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-09-18T19:09:52.961Z,1505761792.961 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-09-18T19:09:53.373Z,1505761793.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-09-18T19:09:53.373Z,1505761793.373 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-09-18T19:09:54.613Z,1505761794.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-09-18T19:09:54.614Z,1505761794.614 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-09-18T19:09:55.591Z,1505761795.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-09-18T19:09:55.591Z,1505761795.591 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-09-18T19:09:55.833Z,1505761795.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-09-18T19:09:55.835Z,1505761795.835 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-09-18T19:09:55.836Z,1505761795.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-09-18T19:09:56.396Z,1505761796.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-09-18T19:09:56.397Z,1505761796.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-09-18T19:09:56.585Z,1505761796.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-09-18T19:09:56.688Z,1505761796.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-09-18T19:09:56.965Z,1505761796.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-09-18T19:09:57.174Z,1505761797.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-09-18T19:09:57.419Z,1505761797.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-09-18T19:09:57.764Z,1505761797.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-09-18T19:09:57.870Z,1505761797.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-09-18T19:09:58.129Z,1505761798.129 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-09-18T19:09:58.223Z,1505761798.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-09-18T19:09:58.797Z,1505761798.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-09-18T19:09:59.023Z,1505761799.023 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-09-18T19:09:59.027Z,1505761799.027 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-09-18T19:09:59.356Z,1505761799.356 [SBIT](DEBUG): Construct Startup Built In Test. 2017-09-18T19:09:59.395Z,1505761799.395 [SBIT] Loaded 2017-09-18T19:09:59.395Z,1505761799.395 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-09-18T19:09:59.396Z,1505761799.396 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-09-18T19:09:59.431Z,1505761799.431 [IBIT] Loaded 2017-09-18T19:09:59.431Z,1505761799.431 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-09-18T19:09:59.438Z,1505761799.438 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-09-18T19:09:59.803Z,1505761799.803 [CBIT] Loaded 2017-09-18T19:09:59.804Z,1505761799.804 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-09-18T19:09:59.804Z,1505761799.804 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-09-18T19:09:59.805Z,1505761799.805 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-09-18T19:09:59.992Z,1505761799.992 [VerticalControl](DEBUG): Construct VerticalControl. 2017-09-18T19:10:00.257Z,1505761800.257 [VerticalControl] Loaded 2017-09-18T19:10:00.258Z,1505761800.258 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-09-18T19:10:00.259Z,1505761800.259 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-09-18T19:10:00.549Z,1505761800.549 [HorizontalControl] Loaded 2017-09-18T19:10:00.554Z,1505761800.554 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-09-18T19:10:00.555Z,1505761800.555 [SpeedControl](DEBUG): Construct SpeedControl. 2017-09-18T19:10:00.562Z,1505761800.562 [SpeedControl] Loaded 2017-09-18T19:10:00.562Z,1505761800.562 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-09-18T19:10:00.563Z,1505761800.563 [LoopControl](DEBUG): Construct LoopControl. 2017-09-18T19:10:00.564Z,1505761800.564 [LoopControl] Loaded 2017-09-18T19:10:00.564Z,1505761800.564 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-09-18T19:10:00.565Z,1505761800.565 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-09-18T19:10:00.565Z,1505761800.565 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-09-18T19:10:00.610Z,1505761800.610 [DepthRateCalculator] Loaded 2017-09-18T19:10:00.610Z,1505761800.610 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-09-18T19:10:00.617Z,1505761800.617 [PitchRateCalculator] Loaded 2017-09-18T19:10:00.617Z,1505761800.617 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-09-18T19:10:00.636Z,1505761800.636 [SpeedCalculator] Loaded 2017-09-18T19:10:00.636Z,1505761800.636 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-09-18T19:10:00.665Z,1505761800.665 [TempGradientCalculator] Loaded 2017-09-18T19:10:00.665Z,1505761800.665 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-09-18T19:10:00.684Z,1505761800.684 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-09-18T19:10:00.684Z,1505761800.684 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-09-18T19:10:00.712Z,1505761800.712 [YawRateCalculator] Loaded 2017-09-18T19:10:00.713Z,1505761800.713 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-09-18T19:10:00.713Z,1505761800.713 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-09-18T19:10:00.714Z,1505761800.714 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-09-18T19:10:00.956Z,1505761800.956 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-09-18T19:10:00.957Z,1505761800.957 [StratificationFrontDetector](DEBUG): (re)initializing 2017-09-18T19:10:00.957Z,1505761800.957 [StratificationFrontDetector] Loaded 2017-09-18T19:10:00.957Z,1505761800.957 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-09-18T19:10:00.958Z,1505761800.958 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-09-18T19:10:00.959Z,1505761800.959 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-09-18T19:10:01.406Z,1505761801.406 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-09-18T19:10:01.406Z,1505761801.406 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-09-18T19:10:01.690Z,1505761801.690 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-09-18T19:10:01.690Z,1505761801.690 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-09-18T19:10:01.783Z,1505761801.783 [DeadReckonUsingSpeedCalculator] Loaded 2017-09-18T19:10:01.783Z,1505761801.783 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-09-18T19:10:01.804Z,1505761801.804 [NavChart] Loaded 2017-09-18T19:10:01.804Z,1505761801.804 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-09-18T19:10:01.810Z,1505761801.810 [UniversalFixResidualReporter] Loaded 2017-09-18T19:10:01.810Z,1505761801.810 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-09-18T19:10:01.811Z,1505761801.811 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-09-18T19:10:01.811Z,1505761801.811 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-09-18T19:10:01.838Z,1505761801.838 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-09-18T19:10:01.839Z,1505761801.839 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-09-18T19:10:02.203Z,1505761802.203 [Aanderaa_O2] Loaded 2017-09-18T19:10:02.204Z,1505761802.204 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-09-18T19:10:02.416Z,1505761802.416 [CTD_NeilBrown] Loaded 2017-09-18T19:10:02.425Z,1505761802.425 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-09-18T19:10:02.437Z,1505761802.437 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408134E0 2017-09-18T19:10:02.438Z,1505761802.438 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4004 2017-09-18T19:10:02.518Z,1505761802.518 [CTD_Seabird] Loaded 2017-09-18T19:10:02.518Z,1505761802.518 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-09-18T19:10:02.520Z,1505761802.520 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408434E0 2017-09-18T19:10:02.520Z,1505761802.520 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4005 2017-09-18T19:10:02.541Z,1505761802.541 [PAR_Licor] Loaded 2017-09-18T19:10:02.541Z,1505761802.541 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-09-18T19:10:02.602Z,1505761802.602 [WetLabsBB2FL] Loaded 2017-09-18T19:10:02.602Z,1505761802.602 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-09-18T19:10:02.603Z,1505761802.603 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408734E0 2017-09-18T19:10:02.604Z,1505761802.604 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4006 2017-09-18T19:10:02.604Z,1505761802.604 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-09-18T19:10:02.605Z,1505761802.605 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-09-18T19:10:03.429Z,1505761803.429 [DataOverHttps] Loaded 2017-09-18T19:10:03.430Z,1505761803.430 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-09-18T19:10:03.448Z,1505761803.448 [Depth_Keller] Loaded 2017-09-18T19:10:03.448Z,1505761803.448 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-09-18T19:10:03.456Z,1505761803.456 [DropWeight] Loaded 2017-09-18T19:10:03.457Z,1505761803.457 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-09-18T19:10:03.730Z,1505761803.730 [NAL9602] Loaded 2017-09-18T19:10:03.730Z,1505761803.730 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-09-18T19:10:03.742Z,1505761803.742 [Onboard] Loaded 2017-09-18T19:10:03.742Z,1505761803.742 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-09-18T19:10:03.751Z,1505761803.751 [Radio_Surface] Loaded 2017-09-18T19:10:03.751Z,1505761803.751 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-09-18T19:10:03.753Z,1505761803.753 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AF74E0 2017-09-18T19:10:03.753Z,1505761803.753 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4007 2017-09-18T19:10:04.074Z,1505761804.074 [DAT] Loaded 2017-09-18T19:10:04.074Z,1505761804.074 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2017-09-18T19:10:04.154Z,1505761804.154 [PNI_TCM] Loaded 2017-09-18T19:10:04.154Z,1505761804.154 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-09-18T19:10:04.235Z,1505761804.235 [Rowe_600LCM] Loaded 2017-09-18T19:10:04.236Z,1505761804.236 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-09-18T19:10:04.237Z,1505761804.237 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40B274E0 2017-09-18T19:10:04.237Z,1505761804.237 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 4008 2017-09-18T19:10:08.574Z,1505761808.574 [BPC1] Loaded 2017-09-18T19:10:08.575Z,1505761808.575 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-09-18T19:10:08.575Z,1505761808.575 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-09-18T19:10:08.576Z,1505761808.576 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-09-18T19:10:08.901Z,1505761808.901 [BuoyancyServo] Loaded 2017-09-18T19:10:08.902Z,1505761808.902 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-09-18T19:10:08.918Z,1505761808.918 [ElevatorServo] Loaded 2017-09-18T19:10:08.918Z,1505761808.918 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-09-18T19:10:08.937Z,1505761808.937 [MassServo] Loaded 2017-09-18T19:10:08.937Z,1505761808.937 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-09-18T19:10:08.953Z,1505761808.953 [RudderServo] Loaded 2017-09-18T19:10:08.953Z,1505761808.953 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-09-18T19:10:08.969Z,1505761808.969 [ThrusterServo] Loaded 2017-09-18T19:10:08.969Z,1505761808.969 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-09-18T19:10:08.970Z,1505761808.970 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-09-18T19:10:08.971Z,1505761808.971 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-09-18T19:10:09.225Z,1505761809.225 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-09-18T19:10:09.226Z,1505761809.226 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-09-18T19:10:09.295Z,1505761809.295 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-09-18T19:10:09.301Z,1505761809.301 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-09-18T19:10:09.302Z,1505761809.302 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-09-18T19:10:09.313Z,1505761809.313 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-09-18T19:10:09.314Z,1505761809.314 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFB4E0 2017-09-18T19:10:09.315Z,1505761809.315 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4009 2017-09-18T19:10:09.322Z,1505761809.322 [Supervisor](INFO): Main Thread ID is 3917 2017-09-18T19:10:09.322Z,1505761809.322 [Supervisor](DEBUG): Running supervisor. 2017-09-18T19:10:09.323Z,1505761809.323 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4010 2017-09-18T19:10:09.326Z,1505761809.326 [controlThread ThreadHandler](INFO): Handler Thread ID is 4011 2017-09-18T19:10:09.327Z,1505761809.327 [controlThread](DEBUG): Initializing ControlThread 2017-09-18T19:10:09.328Z,1505761809.328 [SBIT](INFO): Initialize SBIT Component. 2017-09-18T19:10:09.329Z,1505761809.329 [SBIT](IMPORTANT): git: 2017-08-02-115-g578c285 2017-09-18T19:10:09.329Z,1505761809.329 [SBIT](INFO): git hash: 578c285a2bbd310b0721fe6585a19844c60a5fcb 2017-09-18T19:10:09.331Z,1505761809.331 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-09-18T19:10:09.332Z,1505761809.332 [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-09-18T19:10:09.333Z,1505761809.333 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2017-09-18T19:10:09.333Z,1505761809.333 [IBIT](INFO): Initialize IBIT Component. 2017-09-18T19:10:09.335Z,1505761809.335 [CBIT](DEBUG): Initialize CBIT Component. 2017-09-18T19:10:09.335Z,1505761809.335 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-09-18T19:10:09.335Z,1505761809.335 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-09-18T19:10:09.339Z,1505761809.339 [logger ThreadHandler](INFO): Handler Thread ID is 4012 2017-09-18T19:10:09.356Z,1505761809.356 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4013 2017-09-18T19:10:09.357Z,1505761809.357 [CTD_NeilBrown](INFO): Powering down 2017-09-18T19:10:09.377Z,1505761809.377 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4014 2017-09-18T19:10:09.378Z,1505761809.378 [CTD_Seabird](INFO): Initializing 2017-09-18T19:10:09.414Z,1505761809.414 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4016 2017-09-18T19:10:09.416Z,1505761809.416 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-09-18T19:10:09.420Z,1505761809.420 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-09-18T19:10:09.421Z,1505761809.421 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-09-18T19:10:09.422Z,1505761809.422 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-09-18T19:10:09.422Z,1505761809.422 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-09-18T19:10:09.423Z,1505761809.423 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-09-18T19:10:09.423Z,1505761809.423 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-09-18T19:10:09.423Z,1505761809.423 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-09-18T19:10:09.425Z,1505761809.425 [WetLabsBB2FL](INFO): Powering down 2017-09-18T19:10:09.430Z,1505761809.430 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-09-18T19:10:09.432Z,1505761809.432 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-09-18T19:10:09.433Z,1505761809.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-09-18T19:10:09.434Z,1505761809.434 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-09-18T19:10:09.435Z,1505761809.435 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-09-18T19:10:09.435Z,1505761809.435 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-09-18T19:10:09.438Z,1505761809.438 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-09-18T19:10:09.439Z,1505761809.439 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-09-18T19:10:09.439Z,1505761809.439 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-09-18T19:10:09.440Z,1505761809.440 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-09-18T19:10:09.467Z,1505761809.467 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-09-18T19:10:09.501Z,1505761809.501 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4017 2017-09-18T19:10:09.525Z,1505761809.525 [Radio_Surface](INFO): Powering up 2017-09-18T19:10:09.545Z,1505761809.545 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 4018 2017-09-18T19:10:09.546Z,1505761809.546 [Rowe_600LCM](INFO): Initializing 2017-09-18T19:10:09.547Z,1505761809.547 [Rowe_600LCM](INFO): Checking LCM 2017-09-18T19:10:09.556Z,1505761809.556 [Rowe_600LCM](INFO): LCM OK 2017-09-18T19:10:09.556Z,1505761809.556 [Rowe_600LCM](INFO): Powering up 2017-09-18T19:10:09.571Z,1505761809.571 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-18T19:10:09.585Z,1505761809.585 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4019 2017-09-18T19:10:09.601Z,1505761809.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-09-18T19:10:09.601Z,1505761809.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-09-18T19:10:09.601Z,1505761809.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-09-18T19:10:09.601Z,1505761809.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-09-18T19:10:09.602Z,1505761809.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-09-18T19:10:09.602Z,1505761809.602 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-09-18T19:10:09.602Z,1505761809.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-09-18T19:10:09.602Z,1505761809.602 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-09-18T19:10:09.602Z,1505761809.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-09-18T19:10:09.603Z,1505761809.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-09-18T19:10:09.603Z,1505761809.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-09-18T19:10:09.603Z,1505761809.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-09-18T19:10:09.603Z,1505761809.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-09-18T19:10:09.604Z,1505761809.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-09-18T19:10:09.604Z,1505761809.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-09-18T19:10:09.604Z,1505761809.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-09-18T19:10:09.729Z,1505761809.729 [MissionManager](DEBUG): 2017-09-18T19:10:09.730Z,1505761809.730 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-09-18T19:10:09.934Z,1505761809.934 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-09-18T19:10:09.935Z,1505761809.935 [Default:A.Wait](DEBUG): Construct Wait. 2017-09-18T19:10:09.953Z,1505761809.953 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-18T19:10:10.042Z,1505761810.042 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-09-18T19:10:10.042Z,1505761810.042 [CTD_Seabird](INFO): Powering down 2017-09-18T19:10:10.084Z,1505761810.084 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-09-18T19:10:10.108Z,1505761810.108 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-09-18T19:10:10.148Z,1505761810.148 [Default:E.Execute](DEBUG): Construct Execute. 2017-09-18T19:10:10.180Z,1505761810.180 [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-09-18T19:10:10.186Z,1505761810.186 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,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, 2017-09-18T19:10:10.378Z,1505761810.378 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-09-18T19:10:10.510Z,1505761810.510 [NAL9602](INFO): Powering up NAL9602 2017-09-18T19:10:10.590Z,1505761810.590 [DAT](INFO): Powering up 2017-09-18T19:10:10.590Z,1505761810.590 [DAT](DEBUG): Initializing DAT. 2017-09-18T19:10:11.031Z,1505761811.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:11.067Z,1505761811.067 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:11.348Z,1505761811.348 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:11.360Z,1505761811.360 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-09-18T19:10:11.399Z,1505761811.399 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:11.408Z,1505761811.408 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-09-18T19:10:11.435Z,1505761811.435 [MassServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:11.447Z,1505761811.447 [MassServo](DEBUG): Initializing MassServo. 2017-09-18T19:10:11.484Z,1505761811.484 [RudderServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:11.492Z,1505761811.492 [RudderServo](DEBUG): Initializing RudderServo. 2017-09-18T19:10:11.507Z,1505761811.507 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:11.508Z,1505761811.508 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:10:12.009Z,1505761812.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:12.011Z,1505761812.011 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:12.489Z,1505761812.489 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2017-09-18T19:10:12.489Z,1505761812.489 [RudderServo](FAULT): Rudder failed to initialize 2017-09-18T19:10:12.489Z,1505761812.489 [RudderServo] Communications Fault, FailCount= 1 2017-09-18T19:10:12.489Z,1505761812.489 [RudderServo](ERROR): Communications Fault 2017-09-18T19:10:12.594Z,1505761812.594 [CBIT](ERROR): Communications Fault in component: RudderServo 2017-09-18T19:10:14.260Z,1505761814.260 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-09-18T19:10:14.571Z,1505761814.571 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-09-18T19:10:14.675Z,1505761814.675 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-09-18T19:10:14.696Z,1505761814.696 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-09-18T19:10:14.697Z,1505761814.697 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-09-18T19:10:27.980Z,1505761827.980 [BPC1](ERROR): Failed to receive battery data 2017-09-18T19:10:27.980Z,1505761827.980 [BPC1] Communications Fault, FailCount= 1 2017-09-18T19:10:27.980Z,1505761827.980 [BPC1](ERROR): Communications Fault 2017-09-18T19:10:28.042Z,1505761828.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:28.066Z,1505761828.066 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:28.116Z,1505761828.116 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2017-09-18T19:10:28.116Z,1505761828.116 [RudderServo](INFO): Powering down 2017-09-18T19:10:28.293Z,1505761828.293 [CBIT](ERROR): Communications Fault in component: BPC1 2017-09-18T19:10:28.441Z,1505761828.441 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:28.442Z,1505761828.442 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:28.855Z,1505761828.855 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2017-09-18T19:10:28.855Z,1505761828.855 [ThrusterServo] Communications Fault, FailCount= 1 2017-09-18T19:10:28.855Z,1505761828.855 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:10:28.859Z,1505761828.859 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:10:28.867Z,1505761828.867 [Aanderaa_O2](INFO): Powering down 2017-09-18T19:10:29.214Z,1505761829.214 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:29.215Z,1505761829.215 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:29.254Z,1505761829.254 [RudderServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:29.272Z,1505761829.272 [RudderServo](DEBUG): Initializing RudderServo. 2017-09-18T19:10:29.273Z,1505761829.273 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:10:29.273Z,1505761829.273 [ThrusterServo](INFO): Powering down 2017-09-18T19:10:29.437Z,1505761829.437 [CBIT](INFO): Clearing failed state for component RudderServo 2017-09-18T19:10:29.437Z,1505761829.437 [RudderServo] No Fault, FailCount= 1 2017-09-18T19:10:29.496Z,1505761829.496 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.016025 2017-09-18T19:10:29.557Z,1505761829.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:29.559Z,1505761829.559 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:29.677Z,1505761829.677 [CBIT](INFO): Clearing failed state for component BPC1 2017-09-18T19:10:29.677Z,1505761829.677 [BPC1] No Fault, FailCount= 1 2017-09-18T19:10:30.262Z,1505761830.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:30.263Z,1505761830.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:30.580Z,1505761830.580 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:10:30.580Z,1505761830.580 [ThrusterServo] No Fault, FailCount= 1 2017-09-18T19:10:30.813Z,1505761830.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:30.831Z,1505761830.831 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:30.899Z,1505761830.899 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:30.900Z,1505761830.900 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:10:31.091Z,1505761831.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:31.092Z,1505761831.092 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:31.717Z,1505761831.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:31.718Z,1505761831.718 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:32.164Z,1505761832.164 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-09-18T19:10:32.485Z,1505761832.485 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-09-18T19:10:32.485Z,1505761832.485 [ThrusterServo](FAULT): Thruster failed to initialize 2017-09-18T19:10:32.485Z,1505761832.485 [ThrusterServo] Communications Fault, FailCount= 2 2017-09-18T19:10:32.485Z,1505761832.485 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:10:32.489Z,1505761832.489 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:10:32.622Z,1505761832.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:32.624Z,1505761832.624 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:32.830Z,1505761832.830 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:10:32.830Z,1505761832.830 [ThrusterServo](INFO): Powering down 2017-09-18T19:10:32.831Z,1505761832.831 [SBIT](IMPORTANT): Beginning Startup BIT 2017-09-18T19:10:32.837Z,1505761832.837 [CBIT](IMPORTANT): Beginning ground fault scan 2017-09-18T19:10:33.012Z,1505761833.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:33.026Z,1505761833.026 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:33.378Z,1505761833.378 [ElevatorServo](ERROR): getPosition uart error serial timeout 2017-09-18T19:10:33.378Z,1505761833.378 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2017-09-18T19:10:33.379Z,1505761833.379 [ElevatorServo] Communications Fault, FailCount= 1 2017-09-18T19:10:33.379Z,1505761833.379 [ElevatorServo](ERROR): Communications Fault 2017-09-18T19:10:33.547Z,1505761833.547 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2017-09-18T19:10:33.716Z,1505761833.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:33.717Z,1505761833.717 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:33.763Z,1505761833.763 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-09-18T19:10:33.763Z,1505761833.763 [ElevatorServo](INFO): Powering down 2017-09-18T19:10:34.081Z,1505761834.081 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:10:34.081Z,1505761834.081 [ThrusterServo] No Fault, FailCount= 2 2017-09-18T19:10:34.332Z,1505761834.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:34.365Z,1505761834.365 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-18T19:10:34.581Z,1505761834.581 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:34.581Z,1505761834.581 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:10:34.899Z,1505761834.899 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:34.899Z,1505761834.899 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-09-18T19:10:35.006Z,1505761835.006 [CBIT](INFO): Clearing failed state for component ElevatorServo 2017-09-18T19:10:35.007Z,1505761835.007 [ElevatorServo] No Fault, FailCount= 1 2017-09-18T19:10:35.734Z,1505761835.734 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-09-18T19:10:36.050Z,1505761836.050 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-09-18T19:10:36.050Z,1505761836.050 [ThrusterServo](FAULT): Thruster failed to initialize 2017-09-18T19:10:36.050Z,1505761836.050 [ThrusterServo] Communications Fault, FailCount= 3 2017-09-18T19:10:36.050Z,1505761836.050 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:10:36.056Z,1505761836.056 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:10:36.363Z,1505761836.363 [PAR_Licor](INFO): A/D timeout, 6 tries over 156 ms 2017-09-18T19:10:36.363Z,1505761836.363 [PAR_Licor] Data Fault, FailCount= 1 2017-09-18T19:10:36.363Z,1505761836.363 [PAR_Licor](ERROR): Data Fault 2017-09-18T19:10:36.628Z,1505761836.628 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:10:36.628Z,1505761836.628 [ThrusterServo](INFO): Powering down 2017-09-18T19:10:36.633Z,1505761836.633 [CBIT](ERROR): Data Fault in component: PAR_Licor 2017-09-18T19:10:36.634Z,1505761836.634 [CBIT](INFO): Clearing failed state for component PAR_Licor 2017-09-18T19:10:36.634Z,1505761836.634 [PAR_Licor] No Fault, FailCount= 1 2017-09-18T19:10:37.590Z,1505761837.590 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:10:37.590Z,1505761837.590 [ThrusterServo] No Fault, FailCount= 3 2017-09-18T19:10:38.067Z,1505761838.067 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:38.068Z,1505761838.068 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:10:38.679Z,1505761838.679 [NAL9602](ERROR): NAL9602 initialization error. 2017-09-18T19:10:38.679Z,1505761838.679 [NAL9602] Communications Fault, FailCount= 1 2017-09-18T19:10:38.680Z,1505761838.680 [NAL9602](ERROR): Communications Fault 2017-09-18T19:10:38.843Z,1505761838.843 [Radio_Surface](INFO): Powering down 2017-09-18T19:10:39.028Z,1505761839.028 [CBIT](ERROR): Communications Fault in component: NAL9602 2017-09-18T19:10:39.178Z,1505761839.178 [NAL9602](INFO): Powering down 2017-09-18T19:10:40.970Z,1505761840.970 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2017-09-18T19:10:40.970Z,1505761840.970 [ThrusterServo] Communications Fault, FailCount= 4 2017-09-18T19:10:40.970Z,1505761840.970 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:10:40.975Z,1505761840.975 [CBIT](INFO): Clearing failed state for component NAL9602 2017-09-18T19:10:40.975Z,1505761840.975 [NAL9602] No Fault, FailCount= 1 2017-09-18T19:10:40.975Z,1505761840.975 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:10:41.567Z,1505761841.567 [ElevatorServo](ERROR): getPosition uart error serial timeout 2017-09-18T19:10:41.567Z,1505761841.567 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2017-09-18T19:10:41.567Z,1505761841.567 [ElevatorServo] Communications Fault, FailCount= 2 2017-09-18T19:10:41.568Z,1505761841.568 [ElevatorServo](ERROR): Communications Fault 2017-09-18T19:10:41.569Z,1505761841.569 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:10:41.569Z,1505761841.569 [ThrusterServo](INFO): Powering down 2017-09-18T19:10:41.576Z,1505761841.576 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2017-09-18T19:10:41.719Z,1505761841.719 [Depth_Keller](ERROR): Pressure reading out of range: 915.616455 decibar 2017-09-18T19:10:41.878Z,1505761841.878 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-09-18T19:10:41.878Z,1505761841.878 [ElevatorServo](INFO): Powering down 2017-09-18T19:10:42.507Z,1505761842.507 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:10:42.507Z,1505761842.507 [ThrusterServo] No Fault, FailCount= 4 2017-09-18T19:10:42.722Z,1505761842.722 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:42.722Z,1505761842.722 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-09-18T19:10:42.724Z,1505761842.724 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:42.737Z,1505761842.737 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:10:42.754Z,1505761842.754 [CBIT](INFO): Clearing failed state for component ElevatorServo 2017-09-18T19:10:42.754Z,1505761842.754 [ElevatorServo] No Fault, FailCount= 2 2017-09-18T19:10:43.971Z,1505761843.971 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2017-09-18T19:10:43.971Z,1505761843.971 [ElevatorServo](FAULT): Elevator failed to initialize 2017-09-18T19:10:43.971Z,1505761843.971 [ElevatorServo] Communications Fault, FailCount= 3 2017-09-18T19:10:43.971Z,1505761843.971 [ElevatorServo](ERROR): Communications Fault 2017-09-18T19:10:44.291Z,1505761844.291 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-09-18T19:10:44.604Z,1505761844.604 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-09-18T19:10:44.604Z,1505761844.604 [ThrusterServo](FAULT): Thruster failed to initialize 2017-09-18T19:10:44.604Z,1505761844.604 [ThrusterServo] Communications Fault, FailCount= 5 2017-09-18T19:10:44.604Z,1505761844.604 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:10:44.609Z,1505761844.609 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2017-09-18T19:10:44.610Z,1505761844.610 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo 2017-09-18T19:10:44.610Z,1505761844.610 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:10:44.611Z,1505761844.611 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2017-09-18T19:10:44.770Z,1505761844.770 [NAL9602](INFO): Powering up NAL9602 2017-09-18T19:10:44.905Z,1505761844.905 [Radio_Surface](INFO): Powering up 2017-09-18T19:10:44.924Z,1505761844.924 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-09-18T19:10:44.962Z,1505761844.962 [ElevatorServo](INFO): Powering down 2017-09-18T19:10:45.002Z,1505761845.002 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:10:45.002Z,1505761845.002 [ThrusterServo](INFO): Powering down 2017-09-18T19:10:45.007Z,1505761845.007 [CommandLine](FAULT): Scheduling is paused 2017-09-18T19:10:45.025Z,1505761845.025 [CBIT](INFO): Critical error at 20170918T191044 2017-09-18T19:10:45.026Z,1505761845.026 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-09-18T19:10:45.077Z,1505761845.077 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.003804 CHAN A1 (24V): 0.128081 CHAN A2 (12V): -0.001859 CHAN A3 (5V): 0.000353 CHAN B0 (3.3V): 0.000217 CHAN B1 (3.15aV): -0.000857 CHAN B2 (3.15bV): -0.000198 CHAN B3 (GND): 0.000542 OPEN: -0.000274 Full Scale Calc: 4.765 mA, -1.589 mA 2017-09-18T19:10:45.677Z,1505761845.677 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:10:45.687Z,1505761845.687 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-09-18T19:10:46.268Z,1505761846.268 [BPC1](ERROR): Failed to receive battery data 2017-09-18T19:10:46.269Z,1505761846.269 [BPC1] Communications Fault, FailCount= 2 2017-09-18T19:10:46.269Z,1505761846.269 [BPC1](ERROR): Communications Fault 2017-09-18T19:10:46.455Z,1505761846.455 [SBIT](ERROR): Could not read elevatorAngleReader_. 2017-09-18T19:10:46.475Z,1505761846.475 [CBIT](ERROR): Communications Fault in component: BPC1 2017-09-18T19:10:47.456Z,1505761847.456 [CBIT](INFO): Clearing failed state for component BPC1 2017-09-18T19:10:47.456Z,1505761847.456 [BPC1] No Fault, FailCount= 2 2017-09-18T19:10:57.183Z,1505761857.183 [PNI_TCM](ERROR): CRC does not match. Expected:0x14437 got:0x0 2017-09-18T19:11:00.389Z,1505761860.389 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2017-09-18T19:11:00.389Z,1505761860.389 [PNI_TCM] Data Fault, FailCount= 1 2017-09-18T19:11:00.389Z,1505761860.389 [PNI_TCM](ERROR): Data Fault 2017-09-18T19:11:00.437Z,1505761860.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.430984 seconds old. 2017-09-18T19:11:00.439Z,1505761860.439 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.431484 seconds old. 2017-09-18T19:11:00.564Z,1505761860.564 [CBIT](ERROR): Data Fault in component: PNI_TCM 2017-09-18T19:11:00.755Z,1505761860.755 [PNI_TCM](INFO): Powering down 2017-09-18T19:11:00.775Z,1505761860.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.768616 seconds old. 2017-09-18T19:11:00.777Z,1505761860.777 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.769116 seconds old. 2017-09-18T19:11:01.200Z,1505761861.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.193587 seconds old. 2017-09-18T19:11:01.202Z,1505761861.202 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.194087 seconds old. 2017-09-18T19:11:01.585Z,1505761861.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.578595 seconds old. 2017-09-18T19:11:01.603Z,1505761861.603 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.595327 seconds old. 2017-09-18T19:11:01.970Z,1505761861.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.963304 seconds old. 2017-09-18T19:11:01.984Z,1505761861.984 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.976329 seconds old. 2017-09-18T19:11:02.050Z,1505761862.050 [CBIT](INFO): Clearing failed state for component PNI_TCM 2017-09-18T19:11:02.050Z,1505761862.050 [PNI_TCM] No Fault, FailCount= 1 2017-09-18T19:11:02.353Z,1505761862.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.346512 seconds old. 2017-09-18T19:11:02.355Z,1505761862.355 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.347012 seconds old. 2017-09-18T19:11:02.771Z,1505761862.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.764170 seconds old. 2017-09-18T19:11:02.784Z,1505761862.784 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.776796 seconds old. 2017-09-18T19:11:03.190Z,1505761863.190 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.183836 seconds old. 2017-09-18T19:11:03.192Z,1505761863.192 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.184336 seconds old. 2017-09-18T19:11:03.592Z,1505761863.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.585869 seconds old. 2017-09-18T19:11:03.606Z,1505761863.606 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.598494 seconds old. 2017-09-18T19:11:04.113Z,1505761864.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.106693 seconds old. 2017-09-18T19:11:04.131Z,1505761864.131 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.123424 seconds old. 2017-09-18T19:11:04.494Z,1505761864.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.487194 seconds old. 2017-09-18T19:11:04.495Z,1505761864.495 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.487901 seconds old. 2017-09-18T19:11:04.834Z,1505761864.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.827533 seconds old. 2017-09-18T19:11:04.852Z,1505761864.852 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.844364 seconds old. 2017-09-18T19:11:05.159Z,1505761865.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.152746 seconds old. 2017-09-18T19:11:05.177Z,1505761865.177 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.169778 seconds old. 2017-09-18T19:11:05.560Z,1505761865.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.553379 seconds old. 2017-09-18T19:11:05.573Z,1505761865.573 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.565605 seconds old. 2017-09-18T19:11:05.919Z,1505761865.919 [NAL9602](ERROR): NAL9602 initialization error. 2017-09-18T19:11:05.919Z,1505761865.919 [NAL9602] Communications Fault, FailCount= 2 2017-09-18T19:11:05.920Z,1505761865.920 [NAL9602](ERROR): Communications Fault 2017-09-18T19:11:05.966Z,1505761865.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.950706 seconds old. 2017-09-18T19:11:05.970Z,1505761865.970 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.962232 seconds old. 2017-09-18T19:11:06.033Z,1505761866.033 [CBIT](ERROR): Communications Fault in component: NAL9602 2017-09-18T19:11:06.324Z,1505761866.324 [NAL9602](INFO): Powering down 2017-09-18T19:11:06.388Z,1505761866.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.381391 seconds old. 2017-09-18T19:11:06.389Z,1505761866.389 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.381891 seconds old. 2017-09-18T19:11:06.772Z,1505761866.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.766099 seconds old. 2017-09-18T19:11:06.774Z,1505761866.774 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.766599 seconds old. 2017-09-18T19:11:07.155Z,1505761867.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.148201 seconds old. 2017-09-18T19:11:07.165Z,1505761867.165 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.157320 seconds old. 2017-09-18T19:11:07.301Z,1505761867.301 [CBIT](INFO): Clearing failed state for component NAL9602 2017-09-18T19:11:07.301Z,1505761867.301 [NAL9602] No Fault, FailCount= 2 2017-09-18T19:11:07.583Z,1505761867.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.576578 seconds old. 2017-09-18T19:11:07.597Z,1505761867.597 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.589704 seconds old. 2017-09-18T19:11:07.971Z,1505761867.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.964493 seconds old. 2017-09-18T19:11:07.973Z,1505761867.973 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.965199 seconds old. 2017-09-18T19:11:08.393Z,1505761868.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.386058 seconds old. 2017-09-18T19:11:08.407Z,1505761868.407 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.398983 seconds old. 2017-09-18T19:11:08.800Z,1505761868.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.793204 seconds old. 2017-09-18T19:11:08.801Z,1505761868.801 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.793604 seconds old. 2017-09-18T19:11:09.171Z,1505761869.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.164893 seconds old. 2017-09-18T19:11:09.173Z,1505761869.173 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.165293 seconds old. 2017-09-18T19:11:09.552Z,1505761869.552 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2017-09-18T19:11:09.552Z,1505761869.552 [PNI_TCM] Data Fault, FailCount= 2 2017-09-18T19:11:09.552Z,1505761869.552 [PNI_TCM](ERROR): Data Fault 2017-09-18T19:11:09.573Z,1505761869.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.566427 seconds old. 2017-09-18T19:11:09.574Z,1505761869.574 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.566927 seconds old. 2017-09-18T19:11:09.630Z,1505761869.630 [CBIT](ERROR): Data Fault in component: PNI_TCM 2017-09-18T19:11:09.953Z,1505761869.953 [PNI_TCM](INFO): Powering down 2017-09-18T19:11:09.982Z,1505761869.982 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.975173 seconds old. 2017-09-18T19:11:09.983Z,1505761869.983 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.975673 seconds old. 2017-09-18T19:11:10.379Z,1505761870.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 10.372300 seconds old. 2017-09-18T19:11:10.380Z,1505761870.380 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.372906 seconds old. 2017-09-18T19:11:10.741Z,1505761870.741 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2017-09-18T19:11:10.741Z,1505761870.741 [DAT] Communications Fault, FailCount= 1 2017-09-18T19:11:10.741Z,1505761870.741 [DAT](ERROR): Communications Fault 2017-09-18T19:11:10.754Z,1505761870.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 10.747495 seconds old. 2017-09-18T19:11:10.756Z,1505761870.756 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.747995 seconds old. 2017-09-18T19:11:10.802Z,1505761870.802 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-18T19:11:11.140Z,1505761871.140 [DAT](INFO): Powering down 2017-09-18T19:11:11.264Z,1505761871.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.257400 seconds old. 2017-09-18T19:11:11.266Z,1505761871.266 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.258206 seconds old. 2017-09-18T19:11:11.303Z,1505761871.303 [CBIT](INFO): Clearing failed state for component PNI_TCM 2017-09-18T19:11:11.303Z,1505761871.303 [PNI_TCM] No Fault, FailCount= 2 2017-09-18T19:11:11.529Z,1505761871.529 [NAL9602](INFO): Powering up NAL9602 2017-09-18T19:11:11.684Z,1505761871.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.677165 seconds old. 2017-09-18T19:11:11.685Z,1505761871.685 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.677665 seconds old. 2017-09-18T19:11:12.002Z,1505761872.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.995965 seconds old. 2017-09-18T19:11:12.020Z,1505761872.020 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.012197 seconds old. 2017-09-18T19:11:12.066Z,1505761872.066 [CBIT](INFO): Clearing failed state for component DAT 2017-09-18T19:11:12.066Z,1505761872.066 [DAT] No Fault, FailCount= 1 2017-09-18T19:11:12.392Z,1505761872.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 12.385486 seconds old. 2017-09-18T19:11:12.394Z,1505761872.394 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.385986 seconds old. 2017-09-18T19:11:12.757Z,1505761872.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 12.750263 seconds old. 2017-09-18T19:11:12.758Z,1505761872.758 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.750863 seconds old. 2017-09-18T19:11:12.805Z,1505761872.805 [SBIT](ERROR): Could not read elevatorAngleReader_. 2017-09-18T19:11:12.806Z,1505761872.806 [SBIT](FAULT): Control surface position failure. 2017-09-18T19:11:13.162Z,1505761873.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.155702 seconds old. 2017-09-18T19:11:13.164Z,1505761873.164 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.156202 seconds old. 2017-09-18T19:11:13.402Z,1505761873.402 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2017-09-18T19:11:13.608Z,1505761873.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.601306 seconds old. 2017-09-18T19:11:13.609Z,1505761873.609 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.601912 seconds old. 2017-09-18T19:11:13.989Z,1505761873.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.982907 seconds old. 2017-09-18T19:11:13.998Z,1505761873.998 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.990726 seconds old. 2017-09-18T19:11:14.200Z,1505761874.200 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2017-09-18T19:11:14.350Z,1505761874.350 [DAT](INFO): Powering up 2017-09-18T19:11:14.351Z,1505761874.351 [DAT](DEBUG): Initializing DAT. 2017-09-18T19:11:14.428Z,1505761874.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.421404 seconds old. 2017-09-18T19:11:14.430Z,1505761874.430 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.421904 seconds old. 2017-09-18T19:11:14.784Z,1505761874.784 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2017-09-18T19:11:14.945Z,1505761874.945 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:11:14.945Z,1505761874.945 [ThrusterServo] No Fault, FailCount= 5 2017-09-18T19:11:15.578Z,1505761875.578 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2017-09-18T19:11:15.654Z,1505761875.654 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:11:15.654Z,1505761875.654 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:11:16.372Z,1505761876.372 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2017-09-18T19:11:16.751Z,1505761876.751 [PAR_Licor](INFO): A/D timeout, 5 tries over 128 ms 2017-09-18T19:11:16.751Z,1505761876.751 [PAR_Licor] Data Fault, FailCount= 1 2017-09-18T19:11:16.751Z,1505761876.751 [PAR_Licor](ERROR): Data Fault 2017-09-18T19:11:16.787Z,1505761876.787 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2017-09-18T19:11:17.053Z,1505761877.053 [CBIT](ERROR): Data Fault in component: PAR_Licor 2017-09-18T19:11:17.053Z,1505761877.053 [CBIT](INFO): Clearing failed state for component PAR_Licor 2017-09-18T19:11:17.053Z,1505761877.053 [PAR_Licor] No Fault, FailCount= 1 2017-09-18T19:11:18.229Z,1505761878.229 [BPC1](ERROR): Failed to receive battery data 2017-09-18T19:11:18.229Z,1505761878.229 [BPC1] Communications Fault, FailCount= 3 2017-09-18T19:11:18.229Z,1505761878.229 [BPC1](ERROR): Communications Fault 2017-09-18T19:11:18.666Z,1505761878.666 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2017-09-18T19:11:18.666Z,1505761878.666 [ThrusterServo] Communications Fault, FailCount= 1 2017-09-18T19:11:18.666Z,1505761878.666 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:11:18.671Z,1505761878.671 [CBIT](ERROR): Communications Fault in component: BPC1 2017-09-18T19:11:18.671Z,1505761878.671 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:11:19.000Z,1505761879.000 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:11:19.000Z,1505761879.000 [ThrusterServo](INFO): Powering down 2017-09-18T19:11:19.818Z,1505761879.818 [CBIT](INFO): Clearing failed state for component BPC1 2017-09-18T19:11:19.818Z,1505761879.818 [BPC1] No Fault, FailCount= 3 2017-09-18T19:11:19.832Z,1505761879.832 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:11:19.832Z,1505761879.832 [ThrusterServo] No Fault, FailCount= 1 2017-09-18T19:11:20.186Z,1505761880.186 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:11:20.187Z,1505761880.187 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:11:23.201Z,1505761883.201 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2017-09-18T19:11:23.201Z,1505761883.201 [ThrusterServo] Communications Fault, FailCount= 2 2017-09-18T19:11:23.201Z,1505761883.201 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:11:23.212Z,1505761883.212 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:11:23.491Z,1505761883.491 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:11:23.492Z,1505761883.492 [ThrusterServo](INFO): Powering down 2017-09-18T19:11:24.438Z,1505761884.438 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:11:24.438Z,1505761884.438 [ThrusterServo] No Fault, FailCount= 2 2017-09-18T19:11:24.801Z,1505761884.801 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:11:24.802Z,1505761884.802 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:11:25.408Z,1505761885.408 [PAR_Licor](INFO): A/D timeout, 8 tries over 127 ms 2017-09-18T19:11:25.408Z,1505761885.408 [PAR_Licor] Data Fault, FailCount= 1 2017-09-18T19:11:25.409Z,1505761885.409 [PAR_Licor](ERROR): Data Fault 2017-09-18T19:11:25.942Z,1505761885.942 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2017-09-18T19:11:26.265Z,1505761886.265 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2017-09-18T19:11:26.266Z,1505761886.266 [ThrusterServo](FAULT): Thruster failed to initialize 2017-09-18T19:11:26.266Z,1505761886.266 [ThrusterServo] Communications Fault, FailCount= 3 2017-09-18T19:11:26.266Z,1505761886.266 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:11:26.267Z,1505761886.267 [SBIT](ERROR): Could not read elevatorAngleReader_. 2017-09-18T19:11:26.267Z,1505761886.267 [SBIT](FAULT): Control surface position failure. 2017-09-18T19:11:26.271Z,1505761886.271 [CBIT](ERROR): Data Fault in component: PAR_Licor 2017-09-18T19:11:26.271Z,1505761886.271 [CBIT](INFO): Clearing failed state for component PAR_Licor 2017-09-18T19:11:26.271Z,1505761886.271 [PAR_Licor] No Fault, FailCount= 1 2017-09-18T19:11:26.272Z,1505761886.272 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:11:26.424Z,1505761886.424 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:11:26.425Z,1505761886.425 [ThrusterServo](INFO): Powering down 2017-09-18T19:11:26.438Z,1505761886.438 [SBIT](CRITICAL): SBIT FAILED 2017-09-18T19:11:26.440Z,1505761886.440 [CBIT](INFO): Critical error at 20170918T191126 2017-09-18T19:11:26.828Z,1505761886.828 [MissionManager](IMPORTANT): Started mission Startup 2017-09-18T19:11:26.828Z,1505761886.828 [Startup] Running Loop=1 2017-09-18T19:11:26.829Z,1505761886.829 [Startup](DEBUG): Aggregate::initialize Startup 2017-09-18T19:11:26.829Z,1505761886.829 [Startup:A.GoToSurface] Running Loop=1 2017-09-18T19:11:26.829Z,1505761886.829 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-09-18T19:11:26.829Z,1505761886.829 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-09-18T19:11:26.830Z,1505761886.830 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-09-18T19:11:26.839Z,1505761886.839 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-09-18T19:11:26.839Z,1505761886.839 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-09-18T19:11:26.839Z,1505761886.839 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-09-18T19:11:26.923Z,1505761886.923 [Startup:StartupSatComms] Running Loop=1 2017-09-18T19:11:26.923Z,1505761886.923 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2017-09-18T19:11:26.923Z,1505761886.923 [Startup:StartupSatComms:A] Running Loop=1 2017-09-18T19:11:27.209Z,1505761887.209 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2017-09-18T19:11:27.302Z,1505761887.302 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:11:27.302Z,1505761887.302 [ThrusterServo] No Fault, FailCount= 3 2017-09-18T19:11:27.975Z,1505761887.975 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:11:27.975Z,1505761887.975 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:11:31.382Z,1505761891.382 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2017-09-18T19:11:31.382Z,1505761891.382 [ThrusterServo] Communications Fault, FailCount= 4 2017-09-18T19:11:31.382Z,1505761891.382 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:11:31.391Z,1505761891.391 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:11:31.766Z,1505761891.766 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:11:31.766Z,1505761891.766 [ThrusterServo](INFO): Powering down 2017-09-18T19:11:31.867Z,1505761891.867 [Radio_Surface](INFO): Powering down 2017-09-18T19:11:32.017Z,1505761892.017 [NAL9602](ERROR): NAL9602 initialization error. 2017-09-18T19:11:32.017Z,1505761892.017 [NAL9602] Communications Fault, FailCount= 3 2017-09-18T19:11:32.017Z,1505761892.017 [NAL9602](ERROR): Communications Fault 2017-09-18T19:11:32.155Z,1505761892.155 [CBIT](ERROR): Communications Fault in component: NAL9602 2017-09-18T19:11:32.237Z,1505761892.237 [NAL9602](INFO): Powering down 2017-09-18T19:11:32.422Z,1505761892.422 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-18T19:11:32.422Z,1505761892.422 [ThrusterServo] No Fault, FailCount= 4 2017-09-18T19:11:32.942Z,1505761892.942 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-18T19:11:32.942Z,1505761892.942 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-18T19:11:33.149Z,1505761893.149 [PAR_Licor](INFO): A/D timeout, 5 tries over 127 ms 2017-09-18T19:11:33.149Z,1505761893.149 [PAR_Licor] Data Fault, FailCount= 1 2017-09-18T19:11:33.149Z,1505761893.149 [PAR_Licor](ERROR): Data Fault 2017-09-18T19:11:33.344Z,1505761893.344 [CBIT](ERROR): Data Fault in component: PAR_Licor 2017-09-18T19:11:33.357Z,1505761893.357 [CBIT](INFO): Clearing failed state for component PAR_Licor 2017-09-18T19:11:33.357Z,1505761893.357 [PAR_Licor] No Fault, FailCount= 1 2017-09-18T19:11:33.357Z,1505761893.357 [CBIT](INFO): Clearing failed state for component NAL9602 2017-09-18T19:11:33.357Z,1505761893.357 [NAL9602] No Fault, FailCount= 3 2017-09-18T19:11:35.739Z,1505761895.739 [BPC1](ERROR): Failed to receive battery data 2017-09-18T19:11:35.739Z,1505761895.739 [BPC1] Communications Fault, FailCount= 4 2017-09-18T19:11:35.739Z,1505761895.739 [BPC1](ERROR): Communications Fault 2017-09-18T19:11:36.150Z,1505761896.150 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2017-09-18T19:11:36.150Z,1505761896.150 [ThrusterServo] Communications Fault, FailCount= 5 2017-09-18T19:11:36.150Z,1505761896.150 [ThrusterServo](ERROR): Communications Fault 2017-09-18T19:11:36.154Z,1505761896.154 [CBIT](ERROR): Communications Fault in component: BPC1 2017-09-18T19:11:36.155Z,1505761896.155 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-18T19:11:36.155Z,1505761896.155 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2017-09-18T19:11:36.306Z,1505761896.306 [PAR_Licor](INFO): A/D timeout, 6 tries over 139 ms 2017-09-18T19:11:36.306Z,1505761896.306 [PAR_Licor] Data Fault, FailCount= 1 2017-09-18T19:11:36.306Z,1505761896.306 [PAR_Licor](ERROR): Data Fault 2017-09-18T19:11:36.497Z,1505761896.497 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-18T19:11:36.497Z,1505761896.497 [ThrusterServo](INFO): Powering down 2017-09-18T19:11:36.512Z,1505761896.512 [CBIT](INFO): Critical error at 20170918T191136 2017-09-18T19:11:36.586Z,1505761896.586 [CBIT](ERROR): Data Fault in component: PAR_Licor 2017-09-18T19:11:36.586Z