2018-10-10T17:01:06.981Z,1539190866.981 [Supervisor](DEBUG): Initializing supervisor. 2018-10-10T17:01:06.984Z,1539190866.984 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-10T17:01:06.984Z,1539190866.984 [SyncHandler](INFO): Protected caller Thread ID is 7328 2018-10-10T17:01:06.985Z,1539190866.985 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-10T17:01:06.986Z,1539190866.986 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-10T17:01:06.986Z,1539190866.986 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7329 2018-10-10T17:01:06.989Z,1539190866.989 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-10T17:01:07.111Z,1539190867.111 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-10T17:01:07.112Z,1539190867.112 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-10T17:01:07.113Z,1539190867.113 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7330 2018-10-10T17:01:07.114Z,1539190867.114 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-10T17:01:07.115Z,1539190867.115 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-10T17:01:07.115Z,1539190867.115 [logger ThreadHandler](INFO): Protected caller Thread ID is 7331 2018-10-10T17:01:07.117Z,1539190867.117 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-10T17:01:07.117Z,1539190867.117 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-10T17:01:07.119Z,1539190867.119 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-10T17:01:07.222Z,1539190867.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-10T17:01:07.223Z,1539190867.223 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-10T17:01:07.330Z,1539190867.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-10T17:01:07.330Z,1539190867.330 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-10T17:01:07.555Z,1539190867.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-10T17:01:07.556Z,1539190867.556 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-10T17:01:07.915Z,1539190867.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-10T17:01:07.915Z,1539190867.915 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-10T17:01:08.283Z,1539190868.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-10T17:01:08.284Z,1539190868.284 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-10T17:01:08.783Z,1539190868.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-10T17:01:08.783Z,1539190868.783 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-10T17:01:09.288Z,1539190869.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-10T17:01:09.289Z,1539190869.289 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-10T17:01:09.788Z,1539190869.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-10T17:01:09.788Z,1539190869.788 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-10T17:01:09.873Z,1539190869.873 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-10T17:01:10.218Z,1539190870.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-10T17:01:10.218Z,1539190870.218 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-10T17:01:10.378Z,1539190870.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-10T17:01:10.379Z,1539190870.379 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-10T17:01:10.612Z,1539190870.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-10T17:01:10.612Z,1539190870.612 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-10T17:01:10.836Z,1539190870.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-10T17:01:10.837Z,1539190870.837 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-10T17:01:10.947Z,1539190870.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-10T17:01:10.947Z,1539190870.947 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-10T17:01:11.665Z,1539190871.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-10T17:01:11.667Z,1539190871.667 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-10-10T17:01:11.668Z,1539190871.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-10-10T17:01:11.908Z,1539190871.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-10T17:01:11.909Z,1539190871.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-10-10T17:01:12.022Z,1539190872.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-10-10T17:01:12.178Z,1539190872.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-10-10T17:01:12.277Z,1539190872.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-10-10T17:01:12.366Z,1539190872.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-10-10T17:01:12.527Z,1539190872.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-10-10T17:01:12.734Z,1539190872.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-10-10T17:01:12.839Z,1539190872.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-10-10T17:01:12.940Z,1539190872.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-10-10T17:01:13.044Z,1539190873.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-10-10T17:01:13.133Z,1539190873.133 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/ 2018-10-10T17:01:13.133Z,1539190873.133 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-10T17:01:13.134Z,1539190873.134 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-10T17:01:13.240Z,1539190873.240 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-10T17:01:13.241Z,1539190873.241 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-10T17:01:13.254Z,1539190873.254 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-10T17:01:13.255Z,1539190873.255 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-10T17:01:13.299Z,1539190873.299 [DepthRateCalculator] Loaded 2018-10-10T17:01:13.299Z,1539190873.299 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-10T17:01:13.305Z,1539190873.305 [PitchRateCalculator] Loaded 2018-10-10T17:01:13.305Z,1539190873.305 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-10T17:01:13.322Z,1539190873.322 [SpeedCalculator] Loaded 2018-10-10T17:01:13.322Z,1539190873.322 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-10T17:01:13.343Z,1539190873.343 [TempGradientCalculator] Loaded 2018-10-10T17:01:13.344Z,1539190873.344 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-10T17:01:13.350Z,1539190873.350 [YawRateCalculator] Loaded 2018-10-10T17:01:13.350Z,1539190873.350 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-10T17:01:13.390Z,1539190873.390 [ElevatorOffsetCalculator] Loaded 2018-10-10T17:01:13.391Z,1539190873.391 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-10T17:01:13.391Z,1539190873.391 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-10T17:01:13.392Z,1539190873.392 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-10T17:01:13.418Z,1539190873.418 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-10T17:01:13.419Z,1539190873.419 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-10T17:01:13.926Z,1539190873.926 [DataOverHttps] Loaded 2018-10-10T17:01:13.927Z,1539190873.927 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-10T17:01:13.953Z,1539190873.953 [Depth_Keller] Loaded 2018-10-10T17:01:13.953Z,1539190873.953 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-10T17:01:13.958Z,1539190873.958 [DropWeight] Loaded 2018-10-10T17:01:13.959Z,1539190873.959 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-10T17:01:14.060Z,1539190874.060 [NAL9602] Loaded 2018-10-10T17:01:14.060Z,1539190874.060 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-10T17:01:14.077Z,1539190874.077 [Onboard] Loaded 2018-10-10T17:01:14.077Z,1539190874.077 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-10T17:01:14.087Z,1539190874.087 [Radio_Surface] Loaded 2018-10-10T17:01:14.088Z,1539190874.088 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-10T17:01:14.089Z,1539190874.089 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-10-10T17:01:14.089Z,1539190874.089 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7410 2018-10-10T17:01:14.243Z,1539190874.243 [PNI_TCM] Loaded 2018-10-10T17:01:14.243Z,1539190874.243 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-10T17:01:14.306Z,1539190874.306 [Rowe_600LCM] Loaded 2018-10-10T17:01:14.307Z,1539190874.307 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-10-10T17:01:14.308Z,1539190874.308 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-10-10T17:01:14.308Z,1539190874.308 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 7411 2018-10-10T17:01:16.144Z,1539190876.144 [BPC1] Loaded 2018-10-10T17:01:16.144Z,1539190876.144 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-10T17:01:16.144Z,1539190876.144 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-10T17:01:16.145Z,1539190876.145 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-10T17:01:16.575Z,1539190876.575 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-10T17:01:16.665Z,1539190876.665 [SBIT] Loaded 2018-10-10T17:01:16.668Z,1539190876.668 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-10T17:01:16.668Z,1539190876.668 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-10T17:01:16.680Z,1539190876.680 [IBIT] Loaded 2018-10-10T17:01:16.680Z,1539190876.680 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-10T17:01:16.684Z,1539190876.684 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-10T17:01:16.962Z,1539190876.962 [CBIT] Loaded 2018-10-10T17:01:16.963Z,1539190876.963 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-10T17:01:16.963Z,1539190876.963 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-10T17:01:16.964Z,1539190876.964 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-10T17:01:17.131Z,1539190877.131 [Aanderaa_O2] Loaded 2018-10-10T17:01:17.131Z,1539190877.131 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-10-10T17:01:17.184Z,1539190877.184 [CTD_Seabird] Loaded 2018-10-10T17:01:17.184Z,1539190877.184 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-10T17:01:17.186Z,1539190877.186 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BC4E0 2018-10-10T17:01:17.186Z,1539190877.186 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7412 2018-10-10T17:01:17.203Z,1539190877.203 [ESPComponent] Loaded 2018-10-10T17:01:17.204Z,1539190877.204 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-10-10T17:01:17.218Z,1539190877.218 [PAR_Licor] Loaded 2018-10-10T17:01:17.218Z,1539190877.218 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-10-10T17:01:17.268Z,1539190877.268 [WetLabsBB2FL] Loaded 2018-10-10T17:01:17.268Z,1539190877.268 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-10T17:01:17.269Z,1539190877.269 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2018-10-10T17:01:17.270Z,1539190877.270 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7413 2018-10-10T17:01:17.270Z,1539190877.270 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-10T17:01:17.271Z,1539190877.271 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-10T17:01:17.526Z,1539190877.526 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-10T17:01:17.527Z,1539190877.527 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-10T17:01:17.597Z,1539190877.597 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-10T17:01:17.694Z,1539190877.694 [VerticalControl] Loaded 2018-10-10T17:01:17.694Z,1539190877.694 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-10T17:01:17.695Z,1539190877.695 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-10T17:01:17.758Z,1539190877.758 [HorizontalControl] Loaded 2018-10-10T17:01:17.758Z,1539190877.758 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-10T17:01:17.759Z,1539190877.759 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-10T17:01:17.764Z,1539190877.764 [SpeedControl] Loaded 2018-10-10T17:01:17.764Z,1539190877.764 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-10T17:01:17.765Z,1539190877.765 [LoopControl](DEBUG): Construct LoopControl. 2018-10-10T17:01:17.766Z,1539190877.766 [LoopControl] Loaded 2018-10-10T17:01:17.766Z,1539190877.766 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-10T17:01:17.767Z,1539190877.767 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-10T17:01:17.767Z,1539190877.767 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-10T17:01:17.837Z,1539190877.837 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-10T17:01:17.838Z,1539190877.838 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-10T17:01:17.942Z,1539190877.942 [BuoyancyServo] Loaded 2018-10-10T17:01:17.943Z,1539190877.943 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-10T17:01:17.954Z,1539190877.954 [ElevatorServo] Loaded 2018-10-10T17:01:17.954Z,1539190877.954 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-10T17:01:17.966Z,1539190877.966 [MassServo] Loaded 2018-10-10T17:01:17.966Z,1539190877.966 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-10T17:01:17.977Z,1539190877.977 [RudderServo] Loaded 2018-10-10T17:01:17.978Z,1539190877.978 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-10T17:01:17.989Z,1539190877.989 [ThrusterServo] Loaded 2018-10-10T17:01:17.989Z,1539190877.989 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-10T17:01:17.990Z,1539190877.990 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-10T17:01:17.990Z,1539190877.990 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-10T17:01:18.084Z,1539190878.084 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-10T17:01:18.085Z,1539190878.085 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-10T17:01:18.101Z,1539190878.101 [NavChart] Loaded 2018-10-10T17:01:18.102Z,1539190878.102 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-10T17:01:18.106Z,1539190878.106 [UniversalFixResidualReporter] Loaded 2018-10-10T17:01:18.106Z,1539190878.106 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-10T17:01:18.107Z,1539190878.107 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-10T17:01:18.110Z,1539190878.110 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-10T17:01:18.111Z,1539190878.111 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-10T17:01:18.118Z,1539190878.118 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-10T17:01:18.119Z,1539190878.119 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2018-10-10T17:01:18.120Z,1539190878.120 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7414 2018-10-10T17:01:18.125Z,1539190878.125 [Supervisor](INFO): Main Thread ID is 7327 2018-10-10T17:01:18.125Z,1539190878.125 [Supervisor](DEBUG): Running supervisor. 2018-10-10T17:01:18.126Z,1539190878.126 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7415 2018-10-10T17:01:18.128Z,1539190878.128 [controlThread ThreadHandler](INFO): Handler Thread ID is 7416 2018-10-10T17:01:18.128Z,1539190878.128 [controlThread](DEBUG): Initializing ControlThread 2018-10-10T17:01:18.129Z,1539190878.129 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-10T17:01:18.129Z,1539190878.129 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-10T17:01:18.130Z,1539190878.130 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-10T17:01:18.130Z,1539190878.130 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-10T17:01:18.131Z,1539190878.131 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-10T17:01:18.131Z,1539190878.131 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-10T17:01:18.136Z,1539190878.136 [SBIT](INFO): Initialize SBIT Component. 2018-10-10T17:01:18.136Z,1539190878.136 [SBIT](IMPORTANT): git: 2018-10-09-5-g8e0f966 2018-10-10T17:01:18.137Z,1539190878.137 [SBIT](INFO): git hash: 8e0f9669898461179fe27a6b90da366188de3daf 2018-10-10T17:01:18.137Z,1539190878.137 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-10T17:01:18.137Z,1539190878.137 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-10T17:01:18.139Z,1539190878.139 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-10-10T17:01:18.139Z,1539190878.139 [IBIT](INFO): Initialize IBIT Component. 2018-10-10T17:01:18.140Z,1539190878.140 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-10T17:01:18.140Z,1539190878.140 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-10T17:01:18.140Z,1539190878.140 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-10-10T17:01:18.141Z,1539190878.141 [logger ThreadHandler](INFO): Handler Thread ID is 7417 2018-10-10T17:01:18.158Z,1539190878.158 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7418 2018-10-10T17:01:18.164Z,1539190878.164 [Radio_Surface](INFO): Powering up 2018-10-10T17:01:18.170Z,1539190878.170 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 7419 2018-10-10T17:01:18.170Z,1539190878.170 [Rowe_600LCM](INFO): Initializing 2018-10-10T17:01:18.171Z,1539190878.171 [Rowe_600LCM](INFO): Checking LCM 2018-10-10T17:01:18.262Z,1539190878.262 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7420 2018-10-10T17:01:18.262Z,1539190878.262 [CTD_Seabird](INFO): Initializing 2018-10-10T17:01:18.263Z,1539190878.263 [CTD_Seabird](INFO): Checking LCM 2018-10-10T17:01:18.266Z,1539190878.266 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-10T17:01:18.268Z,1539190878.268 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-10T17:01:18.269Z,1539190878.269 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-10T17:01:18.269Z,1539190878.269 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-10T17:01:18.271Z,1539190878.271 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-10T17:01:18.271Z,1539190878.271 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-10T17:01:18.272Z,1539190878.272 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-10T17:01:18.272Z,1539190878.272 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-10T17:01:18.272Z,1539190878.272 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-10T17:01:18.278Z,1539190878.278 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7421 2018-10-10T17:01:18.279Z,1539190878.279 [WetLabsBB2FL](INFO): Powering down 2018-10-10T17:01:18.298Z,1539190878.298 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-10T17:01:18.387Z,1539190878.387 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7422 2018-10-10T17:01:18.393Z,1539190878.393 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-10-10T17:01:18.400Z,1539190878.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-10T17:01:18.401Z,1539190878.401 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-10-10T17:01:18.402Z,1539190878.402 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-10T17:01:18.403Z,1539190878.403 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-10-10T17:01:18.403Z,1539190878.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-10T17:01:18.405Z,1539190878.405 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-10-10T17:01:18.405Z,1539190878.405 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-10T17:01:18.406Z,1539190878.406 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-10-10T17:01:18.407Z,1539190878.407 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-10T17:01:18.408Z,1539190878.408 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-10-10T17:01:18.408Z,1539190878.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-10T17:01:18.410Z,1539190878.410 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-10-10T17:01:18.410Z,1539190878.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-10T17:01:18.412Z,1539190878.412 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-10-10T17:01:18.412Z,1539190878.412 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:01:18.413Z,1539190878.413 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-10-10T17:01:18.506Z,1539190878.506 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:01:18.512Z,1539190878.512 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T17:01:18.582Z,1539190878.582 [MissionManager](DEBUG): 2018-10-10T17:01:18.583Z,1539190878.583 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-10T17:01:18.612Z,1539190878.612 [CTD_Seabird](INFO): LCM OK 2018-10-10T17:01:18.613Z,1539190878.613 [CTD_Seabird](INFO): Powering up 2018-10-10T17:01:18.613Z,1539190878.613 [Rowe_600LCM](INFO): LCM OK 2018-10-10T17:01:18.613Z,1539190878.613 [Rowe_600LCM](INFO): Powering up 2018-10-10T17:01:18.662Z,1539190878.662 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-10T17:01:18.663Z,1539190878.663 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-10T17:01:18.665Z,1539190878.665 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T17:01:18.686Z,1539190878.686 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-10T17:01:18.688Z,1539190878.688 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-10T17:01:18.727Z,1539190878.727 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-10T17:01:18.731Z,1539190878.731 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-10-10T17:01:18.742Z,1539190878.742 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-10-10T17:01:18.773Z,1539190878.773 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-10T17:01:19.064Z,1539190879.064 [ESPComponent](INFO): powering down ESP 2018-10-10T17:01:19.462Z,1539190879.462 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:19.639Z,1539190879.639 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:19.646Z,1539190879.646 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:01:19.664Z,1539190879.664 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:19.670Z,1539190879.670 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:01:19.676Z,1539190879.676 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:19.682Z,1539190879.682 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:01:19.688Z,1539190879.688 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:19.694Z,1539190879.694 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:01:19.700Z,1539190879.700 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:19.734Z,1539190879.734 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:01:19.762Z,1539190879.762 [CommandLine](FAULT): Scheduling is paused 2018-10-10T17:01:19.763Z,1539190879.763 [CBIT](INFO): Critical error at 20181010T170118 2018-10-10T17:01:19.763Z,1539190879.763 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-10T17:01:19.955Z,1539190879.955 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-10T17:01:19.955Z,1539190879.955 [DropWeight] Hardware Fault, FailCount= 1 2018-10-10T17:01:19.955Z,1539190879.955 [DropWeight](ERROR): Hardware Fault 2018-10-10T17:01:20.021Z,1539190880.021 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:20.059Z,1539190880.059 [CBIT](INFO): Critical error at 20181010T170119 2018-10-10T17:01:20.062Z,1539190880.062 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-10T17:01:20.062Z,1539190880.062 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-10T17:01:20.246Z,1539190880.246 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:20.594Z,1539190880.594 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:01:20.594Z,1539190880.594 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:01:20.594Z,1539190880.594 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-10T17:01:20.594Z,1539190880.594 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:01:20.909Z,1539190880.909 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T17:01:20.910Z,1539190880.910 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T17:01:20.910Z,1539190880.910 [ElevatorServo] Communications Fault, FailCount= 1 2018-10-10T17:01:20.910Z,1539190880.910 [ElevatorServo](ERROR): Communications Fault 2018-10-10T17:01:21.221Z,1539190881.221 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:01:21.222Z,1539190881.222 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:01:21.222Z,1539190881.222 [RudderServo] Communications Fault, FailCount= 1 2018-10-10T17:01:21.222Z,1539190881.222 [RudderServo](ERROR): Communications Fault 2018-10-10T17:01:21.546Z,1539190881.546 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:01:21.570Z,1539190881.570 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:01:21.571Z,1539190881.571 [ThrusterServo] Communications Fault, FailCount= 1 2018-10-10T17:01:21.572Z,1539190881.572 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:01:21.582Z,1539190881.582 [CBIT](INFO): Critical error at 20181010T170120 2018-10-10T17:01:21.590Z,1539190881.590 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:01:21.591Z,1539190881.591 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T17:01:21.592Z,1539190881.592 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:01:21.594Z,1539190881.594 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:01:21.726Z,1539190881.726 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:21.739Z,1539190881.739 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:01:21.740Z,1539190881.740 [BuoyancyServo](INFO): Powering down 2018-10-10T17:01:21.798Z,1539190881.798 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T17:01:21.799Z,1539190881.799 [ElevatorServo](INFO): Powering down 2018-10-10T17:01:21.875Z,1539190881.875 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:01:21.875Z,1539190881.875 [RudderServo](INFO): Powering down 2018-10-10T17:01:21.921Z,1539190881.921 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:01:21.922Z,1539190881.922 [ThrusterServo](INFO): Powering down 2018-10-10T17:01:22.093Z,1539190882.093 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:22.476Z,1539190882.476 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:22.486Z,1539190882.486 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-10-10T17:01:22.536Z,1539190882.536 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:22.536Z,1539190882.536 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:01:22.720Z,1539190882.720 [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 9600 >& /dev/null & 2018-10-10T17:01:22.798Z,1539190882.798 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T17:01:22.798Z,1539190882.798 [MassServo] Communications Fault, FailCount= 1 2018-10-10T17:01:22.798Z,1539190882.798 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:22.799Z,1539190882.799 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:22.800Z,1539190882.800 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:01:22.810Z,1539190882.810 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T17:01:22.810Z,1539190882.810 [ElevatorServo] No Fault, FailCount= 1 2018-10-10T17:01:22.810Z,1539190882.810 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:01:22.810Z,1539190882.810 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T17:01:22.811Z,1539190882.811 [RudderServo] No Fault, FailCount= 1 2018-10-10T17:01:22.811Z,1539190882.811 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:01:22.811Z,1539190882.811 [ThrusterServo] No Fault, FailCount= 1 2018-10-10T17:01:22.853Z,1539190882.853 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-10-10T17:01:22.870Z,1539190882.870 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-10-10T17:01:22.870Z,1539190882.870 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-10-10T17:01:22.895Z,1539190882.895 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:22.942Z,1539190882.942 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:01:22.942Z,1539190882.942 [MassServo](INFO): Powering down 2018-10-10T17:01:23.082Z,1539190883.082 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:23.083Z,1539190883.083 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:01:23.267Z,1539190883.267 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:23.638Z,1539190883.638 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:01:23.638Z,1539190883.638 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:01:23.638Z,1539190883.638 [RudderServo] Communications Fault, FailCount= 2 2018-10-10T17:01:23.638Z,1539190883.638 [RudderServo](ERROR): Communications Fault 2018-10-10T17:01:23.734Z,1539190883.734 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T17:01:23.830Z,1539190883.830 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-10-10T17:01:23.832Z,1539190883.832 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-10T17:01:23.954Z,1539190883.954 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:01:24.034Z,1539190884.034 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:01:24.034Z,1539190884.034 [ThrusterServo] Communications Fault, FailCount= 2 2018-10-10T17:01:24.034Z,1539190884.034 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:01:24.039Z,1539190884.039 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:01:24.039Z,1539190884.039 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:01:24.107Z,1539190884.107 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:24.154Z,1539190884.154 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:24.154Z,1539190884.154 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:01:24.155Z,1539190884.155 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:01:24.155Z,1539190884.155 [RudderServo](INFO): Powering down 2018-10-10T17:01:24.157Z,1539190884.157 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:01:24.157Z,1539190884.157 [ThrusterServo](INFO): Powering down 2018-10-10T17:01:24.178Z,1539190884.178 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:01:24.178Z,1539190884.178 [MassServo] No Fault, FailCount= 1 2018-10-10T17:01:24.390Z,1539190884.390 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T17:01:24.390Z,1539190884.390 [CTD_Seabird](INFO): Powering down 2018-10-10T17:01:24.491Z,1539190884.491 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:24.520Z,1539190884.520 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:01:24.520Z,1539190884.520 [BuoyancyServo] No Fault, FailCount= 1 2018-10-10T17:01:24.890Z,1539190884.890 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:24.901Z,1539190884.901 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:24.901Z,1539190884.901 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:01:24.959Z,1539190884.959 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:24.960Z,1539190884.960 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:01:25.047Z,1539190885.047 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T17:01:25.048Z,1539190885.048 [RudderServo] No Fault, FailCount= 2 2018-10-10T17:01:25.048Z,1539190885.048 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:01:25.048Z,1539190885.048 [ThrusterServo] No Fault, FailCount= 2 2018-10-10T17:01:25.290Z,1539190885.290 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:25.315Z,1539190885.315 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:25.315Z,1539190885.315 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:01:25.713Z,1539190885.713 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:25.838Z,1539190885.838 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:01:25.838Z,1539190885.838 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:01:25.838Z,1539190885.838 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-10T17:01:25.838Z,1539190885.838 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:01:26.214Z,1539190886.214 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:01:26.286Z,1539190886.286 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:01:26.286Z,1539190886.286 [ThrusterServo] Communications Fault, FailCount= 3 2018-10-10T17:01:26.286Z,1539190886.286 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:01:26.290Z,1539190886.290 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:01:26.290Z,1539190886.290 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:01:26.351Z,1539190886.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:26.366Z,1539190886.366 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:01:26.366Z,1539190886.366 [BuoyancyServo](INFO): Powering down 2018-10-10T17:01:26.384Z,1539190886.384 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:01:26.384Z,1539190886.384 [ThrusterServo](INFO): Powering down 2018-10-10T17:01:26.755Z,1539190886.755 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:27.123Z,1539190887.123 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:01:27.124Z,1539190887.124 [PNI_TCM] Data Fault, FailCount= 1 2018-10-10T17:01:27.124Z,1539190887.124 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:01:27.137Z,1539190887.137 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:27.164Z,1539190887.164 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:01:27.164Z,1539190887.164 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:01:27.164Z,1539190887.164 [ThrusterServo] No Fault, FailCount= 3 2018-10-10T17:01:27.531Z,1539190887.531 [PNI_TCM](INFO): Powering down 2018-10-10T17:01:27.579Z,1539190887.579 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:28.014Z,1539190888.014 [RudderServo](FAULT): Rudder initialization uart error:serial timeout 2018-10-10T17:01:28.014Z,1539190888.014 [RudderServo] Communications Fault, FailCount= 3 2018-10-10T17:01:28.014Z,1539190888.014 [RudderServo](ERROR): Communications Fault 2018-10-10T17:01:28.015Z,1539190888.015 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:28.015Z,1539190888.015 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:01:28.020Z,1539190888.020 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:01:28.020Z,1539190888.020 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-10-10T17:01:28.065Z,1539190888.065 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:28.425Z,1539190888.425 [MassServo](ERROR): getPosition uart error serial timeout 2018-10-10T17:01:28.426Z,1539190888.426 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:01:28.426Z,1539190888.426 [MassServo] Communications Fault, FailCount= 2 2018-10-10T17:01:28.426Z,1539190888.426 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:28.427Z,1539190888.427 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:01:28.427Z,1539190888.427 [RudderServo](INFO): Powering down 2018-10-10T17:01:28.430Z,1539190888.430 [CBIT](INFO): Critical error at 20181010T170128 2018-10-10T17:01:28.441Z,1539190888.441 [CBIT](CRITICAL): Environmental Failure. Press:14.687189 PSI. Humidity:40%. Temp:26 C. ABORTING MISSION 2018-10-10T17:01:28.442Z,1539190888.442 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:01:28.493Z,1539190888.493 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:28.502Z,1539190888.502 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:01:28.502Z,1539190888.502 [MassServo](INFO): Powering down 2018-10-10T17:01:28.510Z,1539190888.510 [CBIT](INFO): Critical error at 20181010T170128 2018-10-10T17:01:28.512Z,1539190888.512 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:01:28.512Z,1539190888.512 [BuoyancyServo] No Fault, FailCount= 2 2018-10-10T17:01:28.922Z,1539190888.922 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:28.933Z,1539190888.933 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:28.945Z,1539190888.945 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:01:28.948Z,1539190888.948 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:28.948Z,1539190888.948 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:01:29.266Z,1539190889.266 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:01:29.290Z,1539190889.290 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:01:29.291Z,1539190889.291 [ThrusterServo] Communications Fault, FailCount= 4 2018-10-10T17:01:29.291Z,1539190889.291 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:01:29.306Z,1539190889.306 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T17:01:29.306Z,1539190889.306 [PNI_TCM] No Fault, FailCount= 1 2018-10-10T17:01:29.309Z,1539190889.309 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:01:29.368Z,1539190889.368 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T17:01:29.368Z,1539190889.368 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-10-10T17:01:29.368Z,1539190889.368 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T17:01:29.391Z,1539190889.391 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:29.407Z,1539190889.407 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:29.408Z,1539190889.408 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:01:29.409Z,1539190889.409 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:01:29.409Z,1539190889.409 [ThrusterServo](INFO): Powering down 2018-10-10T17:01:29.422Z,1539190889.422 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T17:01:29.422Z,1539190889.422 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:01:29.422Z,1539190889.422 [MassServo] No Fault, FailCount= 2 2018-10-10T17:01:29.754Z,1539190889.754 [Aanderaa_O2](INFO): Powering down 2018-10-10T17:01:29.826Z,1539190889.826 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:30.178Z,1539190890.178 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:01:30.178Z,1539190890.178 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:01:30.178Z,1539190890.178 [BuoyancyServo] Communications Fault, FailCount= 3 2018-10-10T17:01:30.178Z,1539190890.178 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:01:30.195Z,1539190890.195 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:01:33.050Z,1539190893.050 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:01:38.257Z,1539190898.257 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:38.276Z,1539190898.276 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:01:38.276Z,1539190898.276 [BuoyancyServo](INFO): Powering down 2018-10-10T17:01:38.304Z,1539190898.304 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:01:38.304Z,1539190898.304 [ThrusterServo] No Fault, FailCount= 4 2018-10-10T17:01:38.413Z,1539190898.413 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:38.710Z,1539190898.710 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T17:01:38.710Z,1539190898.710 [MassServo] Communications Fault, FailCount= 3 2018-10-10T17:01:38.710Z,1539190898.710 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:38.711Z,1539190898.711 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:38.711Z,1539190898.711 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:01:38.715Z,1539190898.715 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T17:01:38.715Z,1539190898.715 [Aanderaa_O2] No Fault, FailCount= 1 2018-10-10T17:01:38.716Z,1539190898.716 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:01:38.805Z,1539190898.805 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:38.824Z,1539190898.824 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:01:38.825Z,1539190898.825 [MassServo](INFO): Powering down 2018-10-10T17:01:39.128Z,1539190899.128 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004012 2018-10-10T17:01:39.198Z,1539190899.198 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:39.530Z,1539190899.530 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:01:39.603Z,1539190899.603 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:01:39.603Z,1539190899.603 [ThrusterServo] Communications Fault, FailCount= 5 2018-10-10T17:01:39.604Z,1539190899.604 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:01:39.608Z,1539190899.608 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:01:39.609Z,1539190899.609 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2018-10-10T17:01:39.675Z,1539190899.675 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:39.691Z,1539190899.691 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:39.691Z,1539190899.691 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:01:39.692Z,1539190899.692 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:01:39.692Z,1539190899.692 [ThrusterServo](INFO): Powering down 2018-10-10T17:01:39.703Z,1539190899.703 [CBIT](INFO): Critical error at 20181010T170139 2018-10-10T17:01:39.714Z,1539190899.714 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:01:39.714Z,1539190899.714 [MassServo] No Fault, FailCount= 3 2018-10-10T17:01:40.081Z,1539190900.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:40.483Z,1539190900.483 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:40.570Z,1539190900.570 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:01:40.570Z,1539190900.570 [BuoyancyServo] No Fault, FailCount= 3 2018-10-10T17:01:40.858Z,1539190900.858 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:40.865Z,1539190900.865 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:40.869Z,1539190900.869 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:01:41.262Z,1539190901.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:41.678Z,1539190901.678 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:41.782Z,1539190901.782 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:01:41.782Z,1539190901.782 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:01:41.782Z,1539190901.782 [BuoyancyServo] Communications Fault, FailCount= 4 2018-10-10T17:01:41.782Z,1539190901.782 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:01:41.787Z,1539190901.787 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:01:42.069Z,1539190902.069 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:42.087Z,1539190902.087 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:01:42.092Z,1539190902.092 [BuoyancyServo](INFO): Powering down 2018-10-10T17:01:42.498Z,1539190902.498 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:42.523Z,1539190902.523 [SBIT](IMPORTANT): Beginning Startup BIT 2018-10-10T17:01:42.536Z,1539190902.536 [CBIT](IMPORTANT): Beginning ground fault scan 2018-10-10T17:01:42.892Z,1539190902.892 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:43.226Z,1539190903.226 [ElevatorServo](ERROR): getPosition uart error serial timeout 2018-10-10T17:01:43.226Z,1539190903.226 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2018-10-10T17:01:43.226Z,1539190903.226 [ElevatorServo] Communications Fault, FailCount= 2 2018-10-10T17:01:43.226Z,1539190903.226 [ElevatorServo](ERROR): Communications Fault 2018-10-10T17:01:43.259Z,1539190903.259 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T17:01:43.359Z,1539190903.359 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:43.374Z,1539190903.374 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T17:01:43.374Z,1539190903.374 [ElevatorServo](INFO): Powering down 2018-10-10T17:01:43.678Z,1539190903.678 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T17:01:43.678Z,1539190903.678 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:01:43.678Z,1539190903.678 [MassServo] Communications Fault, FailCount= 4 2018-10-10T17:01:43.678Z,1539190903.678 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:43.678Z,1539190903.678 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T17:01:43.678Z,1539190903.678 [MassServo] Hardware Fault, FailCount= 4 2018-10-10T17:01:43.679Z,1539190903.679 [MassServo](ERROR): Hardware Fault 2018-10-10T17:01:44.014Z,1539190904.014 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:01:44.015Z,1539190904.015 [MassServo] Communications Fault, FailCount= 4 2018-10-10T17:01:44.015Z,1539190904.015 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:44.036Z,1539190904.036 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:01:44.133Z,1539190904.133 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:44.157Z,1539190904.157 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:01:44.157Z,1539190904.157 [MassServo](INFO): Powering down 2018-10-10T17:01:44.451Z,1539190904.451 [NAL9602](INFO): Powering up NAL9602 2018-10-10T17:01:44.479Z,1539190904.479 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:01:44.479Z,1539190904.479 [PNI_TCM] Data Fault, FailCount= 2 2018-10-10T17:01:44.479Z,1539190904.479 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:01:44.489Z,1539190904.489 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:44.508Z,1539190904.508 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:44.509Z,1539190904.509 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:01:44.514Z,1539190904.514 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:01:44.515Z,1539190904.515 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:01:44.515Z,1539190904.515 [BuoyancyServo] No Fault, FailCount= 4 2018-10-10T17:01:44.515Z,1539190904.515 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T17:01:44.515Z,1539190904.515 [ElevatorServo] No Fault, FailCount= 2 2018-10-10T17:01:44.868Z,1539190904.868 [PNI_TCM](INFO): Powering down 2018-10-10T17:01:44.909Z,1539190904.909 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:44.916Z,1539190904.916 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:44.916Z,1539190904.916 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:01:44.918Z,1539190904.918 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:44.918Z,1539190904.918 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:01:44.956Z,1539190904.956 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:01:44.956Z,1539190904.956 [MassServo] No Fault, FailCount= 4 2018-10-10T17:01:45.300Z,1539190905.300 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:45.706Z,1539190905.706 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:46.042Z,1539190906.042 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:01:46.042Z,1539190906.042 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:01:46.042Z,1539190906.042 [BuoyancyServo] Communications Fault, FailCount= 5 2018-10-10T17:01:46.042Z,1539190906.042 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:01:46.087Z,1539190906.087 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:01:46.262Z,1539190906.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:46.280Z,1539190906.280 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:01:46.280Z,1539190906.280 [BuoyancyServo](INFO): Powering down 2018-10-10T17:01:46.315Z,1539190906.315 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T17:01:46.315Z,1539190906.315 [PNI_TCM] No Fault, FailCount= 2 2018-10-10T17:01:46.666Z,1539190906.666 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:47.039Z,1539190907.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:47.390Z,1539190907.390 [ElevatorServo](FAULT): Elevator initialization uart error:serial timeout 2018-10-10T17:01:47.390Z,1539190907.390 [ElevatorServo] Communications Fault, FailCount= 3 2018-10-10T17:01:47.390Z,1539190907.390 [ElevatorServo](ERROR): Communications Fault 2018-10-10T17:01:47.395Z,1539190907.395 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T17:01:47.396Z,1539190907.396 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo 2018-10-10T17:01:47.580Z,1539190907.580 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:47.587Z,1539190907.587 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T17:01:47.587Z,1539190907.587 [ElevatorServo](INFO): Powering down 2018-10-10T17:01:47.660Z,1539190907.660 [CBIT](INFO): Critical error at 20181010T170147 2018-10-10T17:01:47.947Z,1539190907.947 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:48.387Z,1539190908.387 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:48.395Z,1539190908.395 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:48.396Z,1539190908.396 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo] Communications Fault, FailCount= 5 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo] Hardware Fault, FailCount= 5 2018-10-10T17:01:48.706Z,1539190908.706 [MassServo](ERROR): Hardware Fault 2018-10-10T17:01:49.117Z,1539190909.117 [MassServo](ERROR): getPosition uart error serial timeout 2018-10-10T17:01:49.118Z,1539190909.118 [MassServo](FAULT): Invalid EZ Servo response:"\n" 2018-10-10T17:01:49.118Z,1539190909.118 [MassServo] Communications Fault, FailCount= 5 2018-10-10T17:01:49.118Z,1539190909.118 [MassServo](ERROR): Communications Fault 2018-10-10T17:01:49.123Z,1539190909.123 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:01:49.123Z,1539190909.123 [BuoyancyServo] No Fault, FailCount= 5 2018-10-10T17:01:49.123Z,1539190909.123 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:01:49.124Z,1539190909.124 [CBIT](CRITICAL): Communications Fault in component: MassServo 2018-10-10T17:01:49.251Z,1539190909.251 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T17:01:49.251Z,1539190909.251 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-10-10T17:01:49.252Z,1539190909.252 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T17:01:49.284Z,1539190909.284 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:01:49.295Z,1539190909.295 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:01:55.502Z,1539190915.502 [NavChartDb](INFO): # of records loaded: 5000 2018-10-10T17:02:19.414Z,1539190939.414 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:02:19.416Z,1539190939.416 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:02:19.416Z,1539190939.416 [MassServo](INFO): Powering down 2018-10-10T17:02:19.418Z,1539190939.418 [SBIT](ERROR): Could not read elevatorAngleReader_. 2018-10-10T17:02:19.418Z,1539190939.418 [SBIT](ERROR): Could not read rudderAngleReader_. 2018-10-10T17:02:19.418Z,1539190939.418 [SBIT](ERROR): Could not read massPosReader_. 2018-10-10T17:02:19.420Z,1539190939.420 [CBIT](INFO): Critical error at 20181010T170149 2018-10-10T17:02:19.423Z,1539190939.423 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T17:02:19.423Z,1539190939.423 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:02:19.424Z,1539190939.424 [ThrusterServo] No Fault, FailCount= 5 2018-10-10T17:02:19.500Z,1539190939.500 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:02:19.500Z,1539190939.500 [PNI_TCM] Data Fault, FailCount= 3 2018-10-10T17:02:19.501Z,1539190939.501 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:02:19.626Z,1539190939.626 [Aanderaa_O2](INFO): Powering down 2018-10-10T17:02:19.633Z,1539190939.633 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:02:19.641Z,1539190939.641 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:02:19.642Z,1539190939.642 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:02:19.699Z,1539190939.699 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:02:19.877Z,1539190939.877 [PNI_TCM](INFO): Powering down