2018-10-10T15:59:46.640Z,1539187186.640 [Supervisor](DEBUG): Initializing supervisor. 2018-10-10T15:59:46.643Z,1539187186.643 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-10T15:59:46.643Z,1539187186.643 [SyncHandler](INFO): Protected caller Thread ID is 2144 2018-10-10T15:59:46.644Z,1539187186.644 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-10T15:59:46.645Z,1539187186.645 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-10T15:59:46.645Z,1539187186.645 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2145 2018-10-10T15:59:46.648Z,1539187186.648 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-10T15:59:46.671Z,1539187186.671 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-10T15:59:46.672Z,1539187186.672 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-10T15:59:46.672Z,1539187186.672 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2146 2018-10-10T15:59:46.673Z,1539187186.673 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-10T15:59:46.674Z,1539187186.674 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-10T15:59:46.674Z,1539187186.674 [logger ThreadHandler](INFO): Protected caller Thread ID is 2147 2018-10-10T15:59:46.676Z,1539187186.676 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-10T15:59:46.676Z,1539187186.676 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-10T15:59:46.678Z,1539187186.678 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-10T15:59:46.779Z,1539187186.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-10T15:59:46.779Z,1539187186.779 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-10T15:59:46.883Z,1539187186.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-10T15:59:46.883Z,1539187186.883 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-10T15:59:47.092Z,1539187187.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-10T15:59:47.093Z,1539187187.093 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-10T15:59:47.434Z,1539187187.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-10T15:59:47.435Z,1539187187.435 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-10T15:59:47.782Z,1539187187.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-10T15:59:47.783Z,1539187187.783 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-10T15:59:48.256Z,1539187188.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-10T15:59:48.257Z,1539187188.257 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-10T15:59:48.735Z,1539187188.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-10T15:59:48.735Z,1539187188.735 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-10T15:59:49.211Z,1539187189.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-10T15:59:49.211Z,1539187189.211 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-10T15:59:49.294Z,1539187189.294 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-10T15:59:49.621Z,1539187189.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-10T15:59:49.622Z,1539187189.622 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-10T15:59:49.776Z,1539187189.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-10T15:59:49.777Z,1539187189.777 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-10T15:59:49.000Z,1539187190.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-10T15:59:50.000Z,1539187190.000 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-10T15:59:50.214Z,1539187190.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-10T15:59:50.215Z,1539187190.215 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-10T15:59:50.316Z,1539187190.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-10T15:59:50.316Z,1539187190.316 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-10T15:59:50.466Z,1539187190.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-10T15:59:50.468Z,1539187190.468 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-10-10T15:59:50.469Z,1539187190.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-10-10T15:59:50.702Z,1539187190.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-10T15:59:50.703Z,1539187190.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-10-10T15:59:50.811Z,1539187190.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-10-10T15:59:50.960Z,1539187190.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-10-10T15:59:51.057Z,1539187191.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-10-10T15:59:51.144Z,1539187191.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-10-10T15:59:51.298Z,1539187191.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-10-10T15:59:51.755Z,1539187191.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-10-10T15:59:51.951Z,1539187191.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-10-10T15:59:52.236Z,1539187192.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-10-10T15:59:52.337Z,1539187192.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-10-10T15:59:52.423Z,1539187192.423 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/ 2018-10-10T15:59:52.423Z,1539187192.423 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-10T15:59:52.425Z,1539187192.425 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-10T15:59:52.521Z,1539187192.521 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-10T15:59:52.522Z,1539187192.522 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-10T15:59:52.534Z,1539187192.534 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-10T15:59:52.535Z,1539187192.535 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-10T15:59:52.576Z,1539187192.576 [DepthRateCalculator] Loaded 2018-10-10T15:59:52.576Z,1539187192.576 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-10T15:59:52.582Z,1539187192.582 [PitchRateCalculator] Loaded 2018-10-10T15:59:52.582Z,1539187192.582 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-10T15:59:52.598Z,1539187192.598 [SpeedCalculator] Loaded 2018-10-10T15:59:52.598Z,1539187192.598 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-10T15:59:52.618Z,1539187192.618 [TempGradientCalculator] Loaded 2018-10-10T15:59:52.619Z,1539187192.619 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-10T15:59:52.625Z,1539187192.625 [YawRateCalculator] Loaded 2018-10-10T15:59:52.625Z,1539187192.625 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-10T15:59:52.664Z,1539187192.664 [ElevatorOffsetCalculator] Loaded 2018-10-10T15:59:52.664Z,1539187192.664 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-10T15:59:52.665Z,1539187192.665 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-10T15:59:52.665Z,1539187192.665 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-10T15:59:52.690Z,1539187192.690 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-10T15:59:52.690Z,1539187192.690 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-10T15:59:56.050Z,1539187196.050 [DataOverHttps] Loaded 2018-10-10T15:59:56.050Z,1539187196.050 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-10T15:59:56.077Z,1539187196.077 [Depth_Keller] Loaded 2018-10-10T15:59:56.077Z,1539187196.077 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-10T15:59:56.082Z,1539187196.082 [DropWeight] Loaded 2018-10-10T15:59:56.082Z,1539187196.082 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-10T15:59:56.178Z,1539187196.178 [NAL9602] Loaded 2018-10-10T15:59:56.178Z,1539187196.178 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-10T15:59:56.194Z,1539187196.194 [Onboard] Loaded 2018-10-10T15:59:56.194Z,1539187196.194 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-10T15:59:56.204Z,1539187196.204 [Radio_Surface] Loaded 2018-10-10T15:59:56.204Z,1539187196.204 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-10T15:59:56.206Z,1539187196.206 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4197F4E0 2018-10-10T15:59:56.206Z,1539187196.206 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2226 2018-10-10T15:59:56.358Z,1539187196.358 [PNI_TCM] Loaded 2018-10-10T15:59:56.358Z,1539187196.358 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-10T15:59:56.418Z,1539187196.418 [Rowe_600LCM] Loaded 2018-10-10T15:59:56.418Z,1539187196.418 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-10-10T15:59:56.419Z,1539187196.419 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 419AF4E0 2018-10-10T15:59:56.419Z,1539187196.419 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 2227 2018-10-10T15:59:58.266Z,1539187198.266 [BPC1] Loaded 2018-10-10T15:59:58.266Z,1539187198.266 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-10T15:59:58.267Z,1539187198.267 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-10T15:59:58.268Z,1539187198.268 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-10T15:59:58.418Z,1539187198.418 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-10T15:59:58.441Z,1539187198.441 [SBIT] Loaded 2018-10-10T15:59:58.441Z,1539187198.441 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-10T15:59:58.442Z,1539187198.442 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-10T15:59:58.454Z,1539187198.454 [IBIT] Loaded 2018-10-10T15:59:58.454Z,1539187198.454 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-10T15:59:58.457Z,1539187198.457 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-10T15:59:58.603Z,1539187198.603 [CBIT] Loaded 2018-10-10T15:59:58.603Z,1539187198.603 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-10T15:59:58.603Z,1539187198.603 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-10T15:59:58.604Z,1539187198.604 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-10T15:59:58.804Z,1539187198.804 [Aanderaa_O2] Loaded 2018-10-10T15:59:58.805Z,1539187198.805 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-10-10T15:59:58.856Z,1539187198.856 [CTD_Seabird] Loaded 2018-10-10T15:59:58.856Z,1539187198.856 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-10T15:59:58.857Z,1539187198.857 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 41A794E0 2018-10-10T15:59:58.858Z,1539187198.858 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2228 2018-10-10T15:59:58.875Z,1539187198.875 [ESPComponent] Loaded 2018-10-10T15:59:58.875Z,1539187198.875 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-10-10T15:59:58.889Z,1539187198.889 [PAR_Licor] Loaded 2018-10-10T15:59:58.889Z,1539187198.889 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-10-10T15:59:58.937Z,1539187198.937 [WetLabsBB2FL] Loaded 2018-10-10T15:59:58.937Z,1539187198.937 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-10T15:59:58.938Z,1539187198.938 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 41AA94E0 2018-10-10T15:59:58.939Z,1539187198.939 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2229 2018-10-10T15:59:58.939Z,1539187198.939 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-10T15:59:58.940Z,1539187198.940 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-10T15:59:59.219Z,1539187199.219 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-10T15:59:59.221Z,1539187199.221 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-10T15:59:59.308Z,1539187199.308 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-10T15:59:59.401Z,1539187199.401 [VerticalControl] Loaded 2018-10-10T15:59:59.401Z,1539187199.401 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-10T15:59:59.402Z,1539187199.402 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-10T15:59:59.462Z,1539187199.462 [HorizontalControl] Loaded 2018-10-10T15:59:59.462Z,1539187199.462 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-10T15:59:59.462Z,1539187199.462 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-10T15:59:59.468Z,1539187199.468 [SpeedControl] Loaded 2018-10-10T15:59:59.468Z,1539187199.468 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-10T15:59:59.469Z,1539187199.469 [LoopControl](DEBUG): Construct LoopControl. 2018-10-10T15:59:59.469Z,1539187199.469 [LoopControl] Loaded 2018-10-10T15:59:59.469Z,1539187199.469 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-10T15:59:59.470Z,1539187199.470 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-10T15:59:59.471Z,1539187199.471 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-10T15:59:59.572Z,1539187199.572 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-10T15:59:59.573Z,1539187199.573 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-10T15:59:59.692Z,1539187199.692 [BuoyancyServo] Loaded 2018-10-10T15:59:59.692Z,1539187199.692 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-10T15:59:59.704Z,1539187199.704 [ElevatorServo] Loaded 2018-10-10T15:59:59.704Z,1539187199.704 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-10T15:59:59.715Z,1539187199.715 [MassServo] Loaded 2018-10-10T15:59:59.715Z,1539187199.715 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-10T15:59:59.726Z,1539187199.726 [RudderServo] Loaded 2018-10-10T15:59:59.727Z,1539187199.727 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-10T15:59:59.737Z,1539187199.737 [ThrusterServo] Loaded 2018-10-10T15:59:59.738Z,1539187199.738 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-10T15:59:59.738Z,1539187199.738 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-10T15:59:59.739Z,1539187199.739 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-10T15:59:59.845Z,1539187199.845 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-10T15:59:59.845Z,1539187199.845 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-10T15:59:59.861Z,1539187199.861 [NavChart] Loaded 2018-10-10T15:59:59.861Z,1539187199.861 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-10T15:59:59.865Z,1539187199.865 [UniversalFixResidualReporter] Loaded 2018-10-10T15:59:59.865Z,1539187199.865 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-10T15:59:59.866Z,1539187199.866 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-10T15:59:59.869Z,1539187199.869 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-10T15:59:59.870Z,1539187199.870 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-10T15:59:59.877Z,1539187199.877 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-10T15:59:59.878Z,1539187199.878 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 41C024E0 2018-10-10T15:59:59.878Z,1539187199.878 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2230 2018-10-10T15:59:59.883Z,1539187199.883 [Supervisor](INFO): Main Thread ID is 2143 2018-10-10T15:59:59.883Z,1539187199.883 [Supervisor](DEBUG): Running supervisor. 2018-10-10T15:59:59.884Z,1539187199.884 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2231 2018-10-10T15:59:59.886Z,1539187199.886 [controlThread ThreadHandler](INFO): Handler Thread ID is 2232 2018-10-10T15:59:59.886Z,1539187199.886 [controlThread](DEBUG): Initializing ControlThread 2018-10-10T15:59:59.887Z,1539187199.887 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-10T15:59:59.887Z,1539187199.887 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-10T15:59:59.888Z,1539187199.888 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-10T15:59:59.888Z,1539187199.888 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-10T15:59:59.889Z,1539187199.889 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-10T15:59:59.889Z,1539187199.889 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-10T15:59:59.896Z,1539187199.896 [SBIT](INFO): Initialize SBIT Component. 2018-10-10T15:59:59.897Z,1539187199.897 [SBIT](IMPORTANT): git: 2018-10-09-5-g8e0f966 2018-10-10T15:59:59.897Z,1539187199.897 [SBIT](INFO): git hash: 8e0f9669898461179fe27a6b90da366188de3daf 2018-10-10T15:59:59.897Z,1539187199.897 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-10T15:59:59.898Z,1539187199.898 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-10T15:59:59.899Z,1539187199.899 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-10-10T15:59:59.899Z,1539187199.899 [IBIT](INFO): Initialize IBIT Component. 2018-10-10T15:59:59.900Z,1539187199.900 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-10T15:59:59.900Z,1539187199.900 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-10T15:59:59.901Z,1539187199.901 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-10-10T15:59:59.902Z,1539187199.902 [logger ThreadHandler](INFO): Handler Thread ID is 2233 2018-10-10T15:59:59.918Z,1539187199.918 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2234 2018-10-10T15:59:59.927Z,1539187199.927 [Radio_Surface](INFO): Powering up 2018-10-10T15:59:59.934Z,1539187199.934 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 2235 2018-10-10T15:59:59.935Z,1539187199.935 [Rowe_600LCM](INFO): Initializing 2018-10-10T15:59:59.935Z,1539187199.935 [Rowe_600LCM](INFO): Checking LCM 2018-10-10T16:00:00.010Z,1539187200.010 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-10T16:00:00.012Z,1539187200.012 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-10T16:00:00.013Z,1539187200.013 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-10T16:00:00.050Z,1539187200.050 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-10T16:00:00.052Z,1539187200.052 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-10T16:00:00.053Z,1539187200.053 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-10T16:00:00.053Z,1539187200.053 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-10T16:00:00.054Z,1539187200.054 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-10T16:00:00.054Z,1539187200.054 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-10T16:00:00.055Z,1539187200.055 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-10T16:00:00.066Z,1539187200.066 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2236 2018-10-10T16:00:00.067Z,1539187200.067 [CTD_Seabird](INFO): Initializing 2018-10-10T16:00:00.067Z,1539187200.067 [CTD_Seabird](INFO): Checking LCM 2018-10-10T16:00:00.069Z,1539187200.069 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2237 2018-10-10T16:00:00.075Z,1539187200.075 [WetLabsBB2FL](INFO): Powering down 2018-10-10T16:00:00.098Z,1539187200.098 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2238 2018-10-10T16:00:00.276Z,1539187200.276 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T16:00:00.282Z,1539187200.282 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-10-10T16:00:00.287Z,1539187200.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-10T16:00:00.289Z,1539187200.289 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-10-10T16:00:00.289Z,1539187200.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-10T16:00:00.290Z,1539187200.290 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-10-10T16:00:00.291Z,1539187200.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-10T16:00:00.292Z,1539187200.292 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-10-10T16:00:00.292Z,1539187200.292 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-10T16:00:00.294Z,1539187200.294 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-10-10T16:00:00.294Z,1539187200.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-10T16:00:00.295Z,1539187200.295 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-10-10T16:00:00.296Z,1539187200.296 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-10T16:00:00.297Z,1539187200.297 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-10-10T16:00:00.297Z,1539187200.297 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-10T16:00:00.299Z,1539187200.299 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-10-10T16:00:00.299Z,1539187200.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T16:00:00.301Z,1539187200.301 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-10-10T16:00:00.360Z,1539187200.360 [MissionManager](DEBUG): 2018-10-10T16:00:00.360Z,1539187200.360 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-10T16:00:00.382Z,1539187200.382 [CTD_Seabird](INFO): LCM OK 2018-10-10T16:00:00.382Z,1539187200.382 [CTD_Seabird](INFO): Powering up 2018-10-10T16:00:00.382Z,1539187200.382 [Rowe_600LCM](INFO): LCM OK 2018-10-10T16:00:00.382Z,1539187200.382 [Rowe_600LCM](INFO): Powering up 2018-10-10T16:00:00.390Z,1539187200.390 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T16:00:00.427Z,1539187200.427 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-10T16:00:00.428Z,1539187200.428 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-10T16:00:00.430Z,1539187200.430 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T16:00:00.447Z,1539187200.447 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-10T16:00:00.452Z,1539187200.452 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-10T16:00:00.458Z,1539187200.458 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-10T16:00:00.475Z,1539187200.475 [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-10T16:00:00.479Z,1539187200.479 [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-10T16:00:00.526Z,1539187200.526 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-10T16:00:00.856Z,1539187200.856 [ESPComponent](INFO): powering down ESP 2018-10-10T16:00:01.257Z,1539187201.257 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:01.456Z,1539187201.456 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:01.462Z,1539187201.462 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T16:00:01.469Z,1539187201.469 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:01.474Z,1539187201.474 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T16:00:01.480Z,1539187201.480 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:01.486Z,1539187201.486 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T16:00:01.512Z,1539187201.512 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:01.518Z,1539187201.518 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T16:00:01.532Z,1539187201.532 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:01.538Z,1539187201.538 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T16:00:01.577Z,1539187201.577 [CommandLine](FAULT): Scheduling is paused 2018-10-10T16:00:01.577Z,1539187201.577 [CBIT](INFO): Critical error at 20181010T155959 2018-10-10T16:00:01.578Z,1539187201.578 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-10T16:00:01.759Z,1539187201.759 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-10T16:00:01.759Z,1539187201.759 [DropWeight] Hardware Fault, FailCount= 1 2018-10-10T16:00:01.759Z,1539187201.759 [DropWeight](ERROR): Hardware Fault 2018-10-10T16:00:01.842Z,1539187201.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:01.853Z,1539187201.853 [CBIT](INFO): Critical error at 20181010T160001 2018-10-10T16:00:01.856Z,1539187201.856 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-10T16:00:01.856Z,1539187201.856 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-10T16:00:04.319Z,1539187204.319 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-10-10T16:00:04.474Z,1539187204.474 [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-10T16:00:04.627Z,1539187204.627 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-10-10T16:00:04.628Z,1539187204.628 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-10-10T16:00:04.629Z,1539187204.629 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-10-10T16:00:05.558Z,1539187205.558 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T16:00:05.713Z,1539187205.713 [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-10T16:00:05.715Z,1539187205.715 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-10T16:00:06.068Z,1539187206.068 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T16:00:06.068Z,1539187206.068 [CTD_Seabird](INFO): Powering down 2018-10-10T16:00:10.568Z,1539187210.568 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:10.910Z,1539187210.910 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T16:00:10.910Z,1539187210.910 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T16:00:10.910Z,1539187210.910 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-10T16:00:10.910Z,1539187210.910 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T16:00:11.316Z,1539187211.316 [CBIT](INFO): Critical error at 20181010T160001 2018-10-10T16:00:11.319Z,1539187211.319 [CBIT](CRITICAL): Environmental Failure. Press:14.710109 PSI. Humidity:39%. Temp:26 C. ABORTING MISSION 2018-10-10T16:00:11.319Z,1539187211.319 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T16:00:11.373Z,1539187211.373 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T16:00:11.373Z,1539187211.373 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-10-10T16:00:11.374Z,1539187211.374 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T16:00:11.421Z,1539187211.421 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:11.440Z,1539187211.440 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T16:00:11.441Z,1539187211.441 [BuoyancyServo](INFO): Powering down 2018-10-10T16:00:11.514Z,1539187211.514 [CBIT](INFO): Critical error at 20181010T160011 2018-10-10T16:00:11.516Z,1539187211.516 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T16:00:11.806Z,1539187211.806 [Aanderaa_O2](INFO): Powering down 2018-10-10T16:00:11.879Z,1539187211.879 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:12.149Z,1539187212.149 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004872 2018-10-10T16:00:12.229Z,1539187212.229 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:12.610Z,1539187212.610 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:12.731Z,1539187212.731 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T16:00:12.731Z,1539187212.731 [Aanderaa_O2] No Fault, FailCount= 1 2018-10-10T16:00:12.998Z,1539187212.998 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:13.334Z,1539187213.334 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2018-10-10T16:00:13.334Z,1539187213.334 [ThrusterServo] Communications Fault, FailCount= 1 2018-10-10T16:00:13.334Z,1539187213.334 [ThrusterServo](ERROR): Communications Fault 2018-10-10T16:00:13.338Z,1539187213.338 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T16:00:13.423Z,1539187213.423 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:13.810Z,1539187213.810 [RudderServo](ERROR): getPosition uart error serial timeout 2018-10-10T16:00:13.810Z,1539187213.810 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2018-10-10T16:00:13.810Z,1539187213.810 [RudderServo] Communications Fault, FailCount= 1 2018-10-10T16:00:13.810Z,1539187213.810 [RudderServo](ERROR): Communications Fault 2018-10-10T16:00:13.811Z,1539187213.811 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T16:00:13.811Z,1539187213.811 [ThrusterServo](INFO): Powering down 2018-10-10T16:00:13.843Z,1539187213.843 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T16:00:13.843Z,1539187213.843 [BuoyancyServo] No Fault, FailCount= 1 2018-10-10T16:00:13.843Z,1539187213.843 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T16:00:13.965Z,1539187213.965 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:13.972Z,1539187213.972 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:13.972Z,1539187213.972 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T16:00:14.007Z,1539187214.007 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T16:00:14.007Z,1539187214.007 [RudderServo](INFO): Powering down 2018-10-10T16:00:14.314Z,1539187214.314 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:14.374Z,1539187214.374 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T16:00:14.374Z,1539187214.374 [ThrusterServo] No Fault, FailCount= 1 2018-10-10T16:00:14.722Z,1539187214.722 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:15.058Z,1539187215.058 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T16:00:15.058Z,1539187215.058 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T16:00:15.058Z,1539187215.058 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-10T16:00:15.058Z,1539187215.058 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T16:00:15.108Z,1539187215.108 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T16:00:15.406Z,1539187215.406 [MassServo](ERROR): getPosition uart error serial timeout 2018-10-10T16:00:15.406Z,1539187215.406 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T16:00:15.406Z,1539187215.406 [MassServo] Communications Fault, FailCount= 1 2018-10-10T16:00:15.407Z,1539187215.407 [MassServo](ERROR): Communications Fault 2018-10-10T16:00:15.408Z,1539187215.408 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:15.408Z,1539187215.408 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T16:00:15.409Z,1539187215.409 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:15.409Z,1539187215.409 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T16:00:15.414Z,1539187215.414 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T16:00:15.414Z,1539187215.414 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T16:00:15.414Z,1539187215.414 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T16:00:15.415Z,1539187215.415 [RudderServo] No Fault, FailCount= 1 2018-10-10T16:00:15.486Z,1539187215.486 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:15.508Z,1539187215.508 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T16:00:15.509Z,1539187215.509 [BuoyancyServo](INFO): Powering down 2018-10-10T16:00:15.531Z,1539187215.531 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T16:00:15.531Z,1539187215.531 [MassServo](INFO): Powering down 2018-10-10T16:00:15.881Z,1539187215.881 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:16.234Z,1539187216.234 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T16:00:16.234Z,1539187216.234 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T16:00:16.234Z,1539187216.234 [RudderServo] Communications Fault, FailCount= 2 2018-10-10T16:00:16.234Z,1539187216.234 [RudderServo](ERROR): Communications Fault 2018-10-10T16:00:16.558Z,1539187216.558 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T16:00:16.583Z,1539187216.583 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T16:00:16.583Z,1539187216.583 [ThrusterServo] Communications Fault, FailCount= 2 2018-10-10T16:00:16.584Z,1539187216.584 [ThrusterServo](ERROR): Communications Fault 2018-10-10T16:00:16.611Z,1539187216.611 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T16:00:16.612Z,1539187216.612 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T16:00:16.682Z,1539187216.682 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:16.714Z,1539187216.714 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:16.714Z,1539187216.714 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T16:00:16.715Z,1539187216.715 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T16:00:16.715Z,1539187216.715 [RudderServo](INFO): Powering down 2018-10-10T16:00:16.717Z,1539187216.717 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T16:00:16.717Z,1539187216.717 [ThrusterServo](INFO): Powering down 2018-10-10T16:00:16.738Z,1539187216.738 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T16:00:16.738Z,1539187216.738 [MassServo] No Fault, FailCount= 1 2018-10-10T16:00:17.044Z,1539187217.044 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T16:00:17.044Z,1539187217.044 [PNI_TCM] Data Fault, FailCount= 1 2018-10-10T16:00:17.044Z,1539187217.044 [PNI_TCM](ERROR): Data Fault 2018-10-10T16:00:17.073Z,1539187217.073 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:17.114Z,1539187217.114 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T16:00:17.444Z,1539187217.444 [PNI_TCM](INFO): Powering down 2018-10-10T16:00:17.551Z,1539187217.551 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:17.611Z,1539187217.611 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:17.613Z,1539187217.613 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T16:00:17.638Z,1539187217.638 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T16:00:17.638Z,1539187217.638 [BuoyancyServo] No Fault, FailCount= 2 2018-10-10T16:00:17.638Z,1539187217.638 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T16:00:17.639Z,1539187217.639 [RudderServo] No Fault, FailCount= 2 2018-10-10T16:00:17.639Z,1539187217.639 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T16:00:17.639Z,1539187217.639 [ThrusterServo] No Fault, FailCount= 2 2018-10-10T16:00:17.877Z,1539187217.877 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:17.917Z,1539187217.917 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:17.942Z,1539187217.942 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T16:00:17.945Z,1539187217.945 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:17.958Z,1539187217.958 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T16:00:18.263Z,1539187218.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:18.669Z,1539187218.669 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:19.012Z,1539187219.012 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T16:00:19.012Z,1539187219.012 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T16:00:19.012Z,1539187219.012 [BuoyancyServo] Communications Fault, FailCount= 3 2018-10-10T16:00:19.012Z,1539187219.012 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T16:00:19.334Z,1539187219.334 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T16:00:19.359Z,1539187219.359 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T16:00:19.359Z,1539187219.359 [ThrusterServo] Communications Fault, FailCount= 3 2018-10-10T16:00:19.360Z,1539187219.360 [ThrusterServo](ERROR): Communications Fault 2018-10-10T16:00:19.377Z,1539187219.377 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T16:00:19.377Z,1539187219.377 [PNI_TCM] No Fault, FailCount= 1 2018-10-10T16:00:19.380Z,1539187219.380 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T16:00:19.381Z,1539187219.381 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T16:00:19.463Z,1539187219.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:19.478Z,1539187219.478 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T16:00:19.478Z,1539187219.478 [BuoyancyServo](INFO): Powering down 2018-10-10T16:00:19.657Z,1539187219.657 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T16:00:19.657Z,1539187219.657 [ThrusterServo](INFO): Powering down 2018-10-10T16:00:19.863Z,1539187219.863 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:20.299Z,1539187220.299 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:20.626Z,1539187220.626 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T16:00:20.626Z,1539187220.626 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T16:00:20.626Z,1539187220.626 [MassServo] Communications Fault, FailCount= 2 2018-10-10T16:00:20.626Z,1539187220.626 [MassServo](ERROR): Communications Fault 2018-10-10T16:00:20.627Z,1539187220.627 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T16:00:20.627Z,1539187220.627 [MassServo] Hardware Fault, FailCount= 2 2018-10-10T16:00:20.627Z,1539187220.627 [MassServo](ERROR): Hardware Fault 2018-10-10T16:00:20.954Z,1539187220.954 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T16:00:20.954Z,1539187220.954 [MassServo] Communications Fault, FailCount= 2 2018-10-10T16:00:20.955Z,1539187220.955 [MassServo](ERROR): Communications Fault 2018-10-10T16:00:20.974Z,1539187220.974 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T16:00:20.974Z,1539187220.974 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T16:00:20.974Z,1539187220.974 [ThrusterServo] No Fault, FailCount= 3 2018-10-10T16:00:21.059Z,1539187221.059 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:21.079Z,1539187221.079 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T16:00:21.079Z,1539187221.079 [MassServo](INFO): Powering down 2018-10-10T16:00:21.402Z,1539187221.402 [RudderServo](ERROR): getPosition uart error serial timeout 2018-10-10T16:00:21.402Z,1539187221.402 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2018-10-10T16:00:21.402Z,1539187221.402 [RudderServo] Communications Fault, FailCount= 3 2018-10-10T16:00:21.402Z,1539187221.402 [RudderServo](ERROR): Communications Fault 2018-10-10T16:00:21.403Z,1539187221.403 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:21.403Z,1539187221.403 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T16:00:21.408Z,1539187221.408 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T16:00:21.408Z,1539187221.408 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-10-10T16:00:21.484Z,1539187221.484 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:21.500Z,1539187221.500 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T16:00:21.500Z,1539187221.500 [RudderServo](INFO): Powering down 2018-10-10T16:00:21.504Z,1539187221.504 [CBIT](INFO): Critical error at 20181010T160021 2018-10-10T16:00:21.511Z,1539187221.511 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T16:00:21.511Z,1539187221.511 [BuoyancyServo] No Fault, FailCount= 3 2018-10-10T16:00:21.909Z,1539187221.909 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:21.931Z,1539187221.931 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:21.931Z,1539187221.931 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T16:00:21.933Z,1539187221.933 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:21.938Z,1539187221.938 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T16:00:22.262Z,1539187222.262 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T16:00:22.330Z,1539187222.330 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T16:00:22.330Z,1539187222.330 [ThrusterServo] Communications Fault, FailCount= 4 2018-10-10T16:00:22.330Z,1539187222.330 [ThrusterServo](ERROR): Communications Fault 2018-10-10T16:00:22.334Z,1539187222.334 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T16:00:22.335Z,1539187222.335 [MassServo] No Fault, FailCount= 2 2018-10-10T16:00:22.335Z,1539187222.335 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T16:00:22.413Z,1539187222.413 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:22.762Z,1539187222.762 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T16:00:22.762Z,1539187222.762 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T16:00:22.762Z,1539187222.762 [BuoyancyServo] Communications Fault, FailCount= 4 2018-10-10T16:00:22.762Z,1539187222.762 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T16:00:22.764Z,1539187222.764 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:22.765Z,1539187222.765 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T16:00:22.766Z,1539187222.766 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T16:00:22.766Z,1539187222.766 [ThrusterServo](INFO): Powering down 2018-10-10T16:00:22.805Z,1539187222.805 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T16:00:22.879Z,1539187222.879 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:22.894Z,1539187222.894 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T16:00:22.895Z,1539187222.895 [BuoyancyServo](INFO): Powering down 2018-10-10T16:00:23.237Z,1539187223.237 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T16:00:23.237Z,1539187223.237 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-10-10T16:00:23.237Z,1539187223.237 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T16:00:23.250Z,1539187223.250 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:23.291Z,1539187223.291 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T16:00:23.291Z,1539187223.291 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T16:00:23.291Z,1539187223.291 [ThrusterServo] No Fault, FailCount= 4 2018-10-10T16:00:23.637Z,1539187223.637 [Aanderaa_O2](INFO): Powering down 2018-10-10T16:00:23.662Z,1539187223.662 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:23.670Z,1539187223.670 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:23.670Z,1539187223.670 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T16:00:24.075Z,1539187224.075 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:24.485Z,1539187224.485 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:24.663Z,1539187224.663 [SBIT](IMPORTANT): Beginning Startup BIT 2018-10-10T16:00:24.667Z,1539187224.667 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T16:00:24.667Z,1539187224.667 [Aanderaa_O2] No Fault, FailCount= 2 2018-10-10T16:00:24.668Z,1539187224.668 [CBIT](IMPORTANT): Beginning ground fault scan 2018-10-10T16:00:24.881Z,1539187224.881 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:25.206Z,1539187225.206 [ElevatorServo](ERROR): getPosition uart error serial timeout 2018-10-10T16:00:25.206Z,1539187225.206 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2018-10-10T16:00:25.206Z,1539187225.206 [ElevatorServo] Communications Fault, FailCount= 1 2018-10-10T16:00:25.206Z,1539187225.206 [ElevatorServo](ERROR): Communications Fault 2018-10-10T16:00:25.212Z,1539187225.212 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T16:00:25.212Z,1539187225.212 [BuoyancyServo] No Fault, FailCount= 4 2018-10-10T16:00:25.212Z,1539187225.212 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T16:00:25.287Z,1539187225.287 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:25.300Z,1539187225.300 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:25.300Z,1539187225.300 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T16:00:25.301Z,1539187225.301 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T16:00:25.301Z,1539187225.301 [ElevatorServo](INFO): Powering down 2018-10-10T16:00:25.710Z,1539187225.710 [MassServo](ERROR): getPosition uart error serial timeout 2018-10-10T16:00:25.710Z,1539187225.710 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T16:00:25.710Z,1539187225.710 [MassServo] Communications Fault, FailCount= 3 2018-10-10T16:00:25.710Z,1539187225.710 [MassServo](ERROR): Communications Fault 2018-10-10T16:00:25.716Z,1539187225.716 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T16:00:25.763Z,1539187225.763 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:25.781Z,1539187225.781 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T16:00:25.781Z,1539187225.781 [MassServo](INFO): Powering down 2018-10-10T16:00:26.175Z,1539187226.175 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:26.506Z,1539187226.506 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T16:00:26.506Z,1539187226.506 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T16:00:26.506Z,1539187226.506 [BuoyancyServo] Communications Fault, FailCount= 5 2018-10-10T16:00:26.506Z,1539187226.506 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T16:00:26.507Z,1539187226.507 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:26.507Z,1539187226.507 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T16:00:26.513Z,1539187226.513 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T16:00:26.514Z,1539187226.514 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T16:00:26.514Z,1539187226.514 [ElevatorServo] No Fault, FailCount= 1 2018-10-10T16:00:26.604Z,1539187226.604 [NAL9602](INFO): Powering up NAL9602 2018-10-10T16:00:26.678Z,1539187226.678 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:26.704Z,1539187226.704 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T16:00:26.705Z,1539187226.705 [BuoyancyServo](INFO): Powering down 2018-10-10T16:00:26.719Z,1539187226.719 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:26.719Z,1539187226.719 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T16:00:27.026Z,1539187227.026 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2018-10-10T16:00:27.026Z,1539187227.026 [ThrusterServo] Communications Fault, FailCount= 5 2018-10-10T16:00:27.026Z,1539187227.026 [ThrusterServo](ERROR): Communications Fault 2018-10-10T16:00:27.031Z,1539187227.031 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T16:00:27.031Z,1539187227.031 [MassServo] No Fault, FailCount= 3 2018-10-10T16:00:27.031Z,1539187227.031 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T16:00:27.032Z,1539187227.032 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2018-10-10T16:00:27.101Z,1539187227.101 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T16:00:27.101Z,1539187227.101 [PNI_TCM] Data Fault, FailCount= 2 2018-10-10T16:00:27.101Z,1539187227.101 [PNI_TCM](ERROR): Data Fault 2018-10-10T16:00:27.153Z,1539187227.153 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:27.236Z,1539187227.236 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T16:00:27.236Z,1539187227.236 [ThrusterServo](INFO): Powering down 2018-10-10T16:00:27.263Z,1539187227.263 [CBIT](INFO): Critical error at 20181010T160027 2018-10-10T16:00:27.290Z,1539187227.290 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T16:00:27.472Z,1539187227.472 [PNI_TCM](INFO): Powering down 2018-10-10T16:00:27.516Z,1539187227.516 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:27.873Z,1539187227.873 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:28.285Z,1539187228.285 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:28.634Z,1539187228.634 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-10-10T16:00:28.634Z,1539187228.634 [MassServo] Communications Fault, FailCount= 4 2018-10-10T16:00:28.634Z,1539187228.634 [MassServo](ERROR): Communications Fault 2018-10-10T16:00:28.639Z,1539187228.639 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T16:00:28.719Z,1539187228.719 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:29.047Z,1539187229.047 [ElevatorServo](FAULT): Elevator initialization uart error:serial timeout 2018-10-10T16:00:29.047Z,1539187229.047 [ElevatorServo] Communications Fault, FailCount= 2 2018-10-10T16:00:29.048Z,1539187229.048 [ElevatorServo](ERROR): Communications Fault 2018-10-10T16:00:29.048Z,1539187229.048 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T16:00:29.048Z,1539187229.048 [MassServo](INFO): Powering down 2018-10-10T16:00:29.054Z,1539187229.054 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T16:00:29.054Z,1539187229.054 [PNI_TCM] No Fault, FailCount= 2 2018-10-10T16:00:29.054Z,1539187229.054 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T16:00:29.055Z,1539187229.055 [BuoyancyServo] No Fault, FailCount= 5 2018-10-10T16:00:29.055Z,1539187229.055 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T16:00:29.257Z,1539187229.257 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:29.284Z,1539187229.284 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:29.284Z,1539187229.284 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T16:00:29.285Z,1539187229.285 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T16:00:29.286Z,1539187229.286 [ElevatorServo](INFO): Powering down 2018-10-10T16:00:29.654Z,1539187229.654 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:29.677Z,1539187229.677 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T16:00:29.677Z,1539187229.677 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T16:00:29.682Z,1539187229.682 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T16:00:29.682Z,1539187229.682 [MassServo] No Fault, FailCount= 4 2018-10-10T16:00:30.087Z,1539187230.087 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T16:00:30.434Z,1539187230.434 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T16:00:30.434Z,1539187230.434 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T16:00:30.434Z,153918