2018-10-10T00:00:58.704Z,1539129658.704 [Supervisor](DEBUG): Initializing supervisor. 2018-10-10T00:00:58.707Z,1539129658.707 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-10T00:00:58.708Z,1539129658.708 [SyncHandler](INFO): Protected caller Thread ID is 3816 2018-10-10T00:00:58.708Z,1539129658.708 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-10T00:00:58.709Z,1539129658.709 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-10T00:00:58.710Z,1539129658.710 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3817 2018-10-10T00:00:58.713Z,1539129658.713 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-10T00:00:58.725Z,1539129658.725 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-10T00:00:58.726Z,1539129658.726 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-10T00:00:58.726Z,1539129658.726 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3818 2018-10-10T00:00:58.727Z,1539129658.727 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-10T00:00:58.728Z,1539129658.728 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-10T00:00:58.728Z,1539129658.728 [logger ThreadHandler](INFO): Protected caller Thread ID is 3819 2018-10-10T00:00:58.730Z,1539129658.730 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-10T00:00:58.730Z,1539129658.730 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-10T00:00:58.732Z,1539129658.732 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-10T00:00:58.830Z,1539129658.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-10T00:00:58.830Z,1539129658.830 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-10T00:00:58.931Z,1539129658.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-10T00:00:58.932Z,1539129658.932 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-10T00:00:59.136Z,1539129659.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-10T00:00:59.136Z,1539129659.136 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-10T00:00:59.473Z,1539129659.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-10T00:00:59.474Z,1539129659.474 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-10T00:00:59.814Z,1539129659.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-10T00:00:59.814Z,1539129659.814 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-10T00:01:00.282Z,1539129660.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-10T00:01:00.282Z,1539129660.282 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-10T00:01:00.827Z,1539129660.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-10T00:01:00.827Z,1539129660.827 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-10T00:01:01.293Z,1539129661.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-10T00:01:01.294Z,1539129661.294 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-10T00:01:01.373Z,1539129661.373 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-10T00:01:01.694Z,1539129661.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-10T00:01:01.694Z,1539129661.694 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-10T00:01:01.844Z,1539129661.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-10T00:01:01.845Z,1539129661.845 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-10T00:01:02.063Z,1539129662.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-10T00:01:02.064Z,1539129662.064 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-10T00:01:02.273Z,1539129662.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-10T00:01:02.274Z,1539129662.274 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-10T00:01:02.373Z,1539129662.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-10T00:01:02.373Z,1539129662.373 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-10T00:01:02.520Z,1539129662.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-10T00:01:02.522Z,1539129662.522 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-10-10T00:01:02.523Z,1539129662.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-10-10T00:01:02.753Z,1539129662.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-10T00:01:02.753Z,1539129662.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-10-10T00:01:02.860Z,1539129662.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-10-10T00:01:03.013Z,1539129663.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-10-10T00:01:03.107Z,1539129663.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-10-10T00:01:03.192Z,1539129663.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-10-10T00:01:03.352Z,1539129663.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-10-10T00:01:03.564Z,1539129663.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-10-10T00:01:03.997Z,1539129663.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-10-10T00:01:04.501Z,1539129664.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-10-10T00:01:04.600Z,1539129664.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-10-10T00:01:04.684Z,1539129664.684 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2018-10-10T00:01:04.684Z,1539129664.684 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-10T00:01:04.686Z,1539129664.686 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-10T00:01:04.786Z,1539129664.786 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-10T00:01:04.786Z,1539129664.786 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-10T00:01:04.800Z,1539129664.800 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-10T00:01:04.800Z,1539129664.800 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-10T00:01:04.843Z,1539129664.843 [DepthRateCalculator] Loaded 2018-10-10T00:01:04.843Z,1539129664.843 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-10T00:01:04.849Z,1539129664.849 [PitchRateCalculator] Loaded 2018-10-10T00:01:04.849Z,1539129664.849 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-10T00:01:04.865Z,1539129664.865 [SpeedCalculator] Loaded 2018-10-10T00:01:04.865Z,1539129664.865 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-10T00:01:04.886Z,1539129664.886 [TempGradientCalculator] Loaded 2018-10-10T00:01:04.886Z,1539129664.886 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-10T00:01:04.892Z,1539129664.892 [YawRateCalculator] Loaded 2018-10-10T00:01:04.892Z,1539129664.892 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-10T00:01:04.931Z,1539129664.931 [ElevatorOffsetCalculator] Loaded 2018-10-10T00:01:04.931Z,1539129664.931 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-10T00:01:04.932Z,1539129664.932 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-10T00:01:04.932Z,1539129664.932 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-10T00:01:04.958Z,1539129664.958 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-10T00:01:04.958Z,1539129664.958 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-10T00:01:05.375Z,1539129665.375 [AcousticModem_Benthos_ATM900] Loaded 2018-10-10T00:01:05.375Z,1539129665.375 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-10-10T00:01:05.513Z,1539129665.513 [DataOverHttps] Loaded 2018-10-10T00:01:05.514Z,1539129665.514 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-10T00:01:05.527Z,1539129665.527 [Depth_Keller] Loaded 2018-10-10T00:01:05.527Z,1539129665.527 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-10T00:01:05.532Z,1539129665.532 [DropWeight] Loaded 2018-10-10T00:01:05.532Z,1539129665.532 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-10T00:01:05.628Z,1539129665.628 [NAL9602] Loaded 2018-10-10T00:01:05.628Z,1539129665.628 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-10T00:01:05.644Z,1539129665.644 [Onboard] Loaded 2018-10-10T00:01:05.644Z,1539129665.644 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-10T00:01:05.654Z,1539129665.654 [Radio_Surface] Loaded 2018-10-10T00:01:05.654Z,1539129665.654 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-10T00:01:05.655Z,1539129665.655 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-10-10T00:01:05.656Z,1539129665.656 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3899 2018-10-10T00:01:05.808Z,1539129665.808 [PNI_TCM] Loaded 2018-10-10T00:01:05.808Z,1539129665.808 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-10T00:01:05.868Z,1539129665.868 [Rowe_600LCM] Loaded 2018-10-10T00:01:05.869Z,1539129665.869 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-10-10T00:01:05.870Z,1539129665.870 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-10-10T00:01:05.870Z,1539129665.870 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 3900 2018-10-10T00:01:07.608Z,1539129667.608 [BPC1] Loaded 2018-10-10T00:01:07.609Z,1539129667.609 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-10T00:01:07.609Z,1539129667.609 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-10T00:01:07.610Z,1539129667.610 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-10T00:01:07.739Z,1539129667.739 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-10T00:01:07.762Z,1539129667.762 [SBIT] Loaded 2018-10-10T00:01:07.763Z,1539129667.763 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-10T00:01:07.763Z,1539129667.763 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-10T00:01:07.775Z,1539129667.775 [IBIT] Loaded 2018-10-10T00:01:07.775Z,1539129667.775 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-10T00:01:07.778Z,1539129667.778 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-10T00:01:07.924Z,1539129667.924 [CBIT] Loaded 2018-10-10T00:01:07.925Z,1539129667.925 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-10T00:01:07.925Z,1539129667.925 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-10T00:01:07.926Z,1539129667.926 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-10T00:01:08.086Z,1539129668.086 [Aanderaa_O2] Loaded 2018-10-10T00:01:08.086Z,1539129668.086 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-10-10T00:01:08.138Z,1539129668.138 [CTD_Seabird] Loaded 2018-10-10T00:01:08.138Z,1539129668.138 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-10T00:01:08.139Z,1539129668.139 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BC4E0 2018-10-10T00:01:08.140Z,1539129668.140 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3901 2018-10-10T00:01:08.157Z,1539129668.157 [ESPComponent] Loaded 2018-10-10T00:01:08.157Z,1539129668.157 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-10-10T00:01:08.171Z,1539129668.171 [PAR_Licor] Loaded 2018-10-10T00:01:08.171Z,1539129668.171 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-10-10T00:01:08.219Z,1539129668.219 [WetLabsBB2FL] Loaded 2018-10-10T00:01:08.220Z,1539129668.220 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-10T00:01:08.221Z,1539129668.221 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2018-10-10T00:01:08.221Z,1539129668.221 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3902 2018-10-10T00:01:08.222Z,1539129668.222 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-10T00:01:08.222Z,1539129668.222 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-10T00:01:08.463Z,1539129668.463 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-10T00:01:08.463Z,1539129668.463 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-10T00:01:08.532Z,1539129668.532 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-10T00:01:08.625Z,1539129668.625 [VerticalControl] Loaded 2018-10-10T00:01:08.626Z,1539129668.626 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-10T00:01:08.626Z,1539129668.626 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-10T00:01:08.686Z,1539129668.686 [HorizontalControl] Loaded 2018-10-10T00:01:08.687Z,1539129668.687 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-10T00:01:08.687Z,1539129668.687 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-10T00:01:08.692Z,1539129668.692 [SpeedControl] Loaded 2018-10-10T00:01:08.693Z,1539129668.693 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-10T00:01:08.694Z,1539129668.694 [LoopControl](DEBUG): Construct LoopControl. 2018-10-10T00:01:08.694Z,1539129668.694 [LoopControl] Loaded 2018-10-10T00:01:08.694Z,1539129668.694 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-10T00:01:08.695Z,1539129668.695 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-10T00:01:08.695Z,1539129668.695 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-10T00:01:08.923Z,1539129668.923 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-10T00:01:08.924Z,1539129668.924 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-10T00:01:09.022Z,1539129669.022 [BuoyancyServo] Loaded 2018-10-10T00:01:09.022Z,1539129669.022 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-10T00:01:09.034Z,1539129669.034 [ElevatorServo] Loaded 2018-10-10T00:01:09.034Z,1539129669.034 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-10T00:01:09.045Z,1539129669.045 [MassServo] Loaded 2018-10-10T00:01:09.045Z,1539129669.045 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-10T00:01:09.056Z,1539129669.056 [RudderServo] Loaded 2018-10-10T00:01:09.057Z,1539129669.057 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-10T00:01:09.067Z,1539129669.067 [ThrusterServo] Loaded 2018-10-10T00:01:09.068Z,1539129669.068 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-10T00:01:09.068Z,1539129669.068 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-10T00:01:09.069Z,1539129669.069 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-10T00:01:09.159Z,1539129669.159 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-10T00:01:09.159Z,1539129669.159 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-10T00:01:09.175Z,1539129669.175 [NavChart] Loaded 2018-10-10T00:01:09.176Z,1539129669.176 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-10T00:01:09.180Z,1539129669.180 [UniversalFixResidualReporter] Loaded 2018-10-10T00:01:09.180Z,1539129669.180 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-10T00:01:09.180Z,1539129669.180 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-10T00:01:09.184Z,1539129669.184 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-10T00:01:09.185Z,1539129669.185 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-10T00:01:09.192Z,1539129669.192 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-10T00:01:09.193Z,1539129669.193 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2018-10-10T00:01:09.193Z,1539129669.193 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3903 2018-10-10T00:01:09.198Z,1539129669.198 [Supervisor](INFO): Main Thread ID is 3815 2018-10-10T00:01:09.198Z,1539129669.198 [Supervisor](DEBUG): Running supervisor. 2018-10-10T00:01:09.199Z,1539129669.199 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3904 2018-10-10T00:01:09.202Z,1539129669.202 [controlThread ThreadHandler](INFO): Handler Thread ID is 3905 2018-10-10T00:01:09.202Z,1539129669.202 [controlThread](DEBUG): Initializing ControlThread 2018-10-10T00:01:09.203Z,1539129669.203 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-10T00:01:09.204Z,1539129669.204 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-10T00:01:09.204Z,1539129669.204 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-10T00:01:09.204Z,1539129669.204 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-10T00:01:09.205Z,1539129669.205 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-10T00:01:09.205Z,1539129669.205 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-10T00:01:09.212Z,1539129669.212 [SBIT](INFO): Initialize SBIT Component. 2018-10-10T00:01:09.213Z,1539129669.213 [SBIT](IMPORTANT): git: 2018-10-09-5-g8e0f966 2018-10-10T00:01:09.213Z,1539129669.213 [SBIT](INFO): git hash: 8e0f9669898461179fe27a6b90da366188de3daf 2018-10-10T00:01:09.213Z,1539129669.213 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-10T00:01:09.213Z,1539129669.213 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-10T00:01:09.215Z,1539129669.215 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-10-10T00:01:09.215Z,1539129669.215 [IBIT](INFO): Initialize IBIT Component. 2018-10-10T00:01:09.216Z,1539129669.216 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-10T00:01:09.216Z,1539129669.216 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-10T00:01:09.216Z,1539129669.216 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-10-10T00:01:09.217Z,1539129669.217 [logger ThreadHandler](INFO): Handler Thread ID is 3906 2018-10-10T00:01:09.234Z,1539129669.234 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3907 2018-10-10T00:01:09.240Z,1539129669.240 [Radio_Surface](INFO): Powering up 2018-10-10T00:01:09.246Z,1539129669.246 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 3908 2018-10-10T00:01:09.247Z,1539129669.247 [Rowe_600LCM](INFO): Initializing 2018-10-10T00:01:09.247Z,1539129669.247 [Rowe_600LCM](INFO): Checking LCM 2018-10-10T00:01:09.326Z,1539129669.326 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-10T00:01:09.356Z,1539129669.356 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-10T00:01:09.357Z,1539129669.357 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-10T00:01:09.358Z,1539129669.358 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-10T00:01:09.359Z,1539129669.359 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-10T00:01:09.360Z,1539129669.360 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-10T00:01:09.360Z,1539129669.360 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-10T00:01:09.361Z,1539129669.361 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-10T00:01:09.361Z,1539129669.361 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-10T00:01:09.363Z,1539129669.363 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3909 2018-10-10T00:01:09.363Z,1539129669.363 [CTD_Seabird](INFO): Initializing 2018-10-10T00:01:09.364Z,1539129669.364 [CTD_Seabird](INFO): Checking LCM 2018-10-10T00:01:09.370Z,1539129669.370 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-10T00:01:09.372Z,1539129669.372 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3910 2018-10-10T00:01:09.375Z,1539129669.375 [WetLabsBB2FL](INFO): Powering down 2018-10-10T00:01:09.482Z,1539129669.482 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3911 2018-10-10T00:01:09.487Z,1539129669.487 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-10-10T00:01:09.494Z,1539129669.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-10T00:01:09.495Z,1539129669.495 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-10-10T00:01:09.495Z,1539129669.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-10T00:01:09.497Z,1539129669.497 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-10-10T00:01:09.497Z,1539129669.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-10T00:01:09.499Z,1539129669.499 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-10-10T00:01:09.499Z,1539129669.499 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-10T00:01:09.500Z,1539129669.500 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-10-10T00:01:09.500Z,1539129669.500 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-10T00:01:09.502Z,1539129669.502 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-10-10T00:01:09.502Z,1539129669.502 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-10T00:01:09.504Z,1539129669.504 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-10-10T00:01:09.504Z,1539129669.504 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-10T00:01:09.509Z,1539129669.509 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-10-10T00:01:09.510Z,1539129669.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T00:01:09.511Z,1539129669.511 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-10-10T00:01:09.530Z,1539129669.530 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T00:01:09.604Z,1539129669.604 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T00:01:09.606Z,1539129669.606 [MissionManager](DEBUG): 2018-10-10T00:01:09.607Z,1539129669.607 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-10T00:01:09.688Z,1539129669.688 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-10T00:01:09.689Z,1539129669.689 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-10T00:01:09.707Z,1539129669.707 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T00:01:09.722Z,1539129669.722 [CTD_Seabird](INFO): LCM OK 2018-10-10T00:01:09.722Z,1539129669.722 [CTD_Seabird](INFO): Powering up 2018-10-10T00:01:09.726Z,1539129669.726 [Rowe_600LCM](INFO): LCM OK 2018-10-10T00:01:09.726Z,1539129669.726 [Rowe_600LCM](INFO): Powering up 2018-10-10T00:01:09.753Z,1539129669.753 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-10T00:01:09.772Z,1539129669.772 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-10T00:01:09.778Z,1539129669.778 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-10T00:01:09.818Z,1539129669.818 [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-10T00:01:09.823Z,1539129669.823 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,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-10T00:01:09.852Z,1539129669.852 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-10-10T00:01:09.852Z,1539129669.852 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-10-10T00:01:09.874Z,1539129669.874 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-10T00:01:10.240Z,1539129670.240 [ESPComponent](INFO): powering down ESP 2018-10-10T00:01:10.742Z,1539129670.742 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:10.930Z,1539129670.930 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:10.938Z,1539129670.938 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T00:01:10.949Z,1539129670.949 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:10.954Z,1539129670.954 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T00:01:10.960Z,1539129670.960 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:10.966Z,1539129670.966 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T00:01:10.972Z,1539129670.972 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:10.978Z,1539129670.978 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T00:01:10.984Z,1539129670.984 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:10.990Z,1539129670.990 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T00:01:11.043Z,1539129671.043 [CommandLine](FAULT): Scheduling is paused 2018-10-10T00:01:11.043Z,1539129671.043 [CBIT](INFO): Critical error at 20181010T000109 2018-10-10T00:01:11.044Z,1539129671.044 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-10T00:01:11.211Z,1539129671.211 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-10T00:01:11.212Z,1539129671.212 [DropWeight] Hardware Fault, FailCount= 1 2018-10-10T00:01:11.212Z,1539129671.212 [DropWeight](ERROR): Hardware Fault 2018-10-10T00:01:11.318Z,1539129671.318 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:11.343Z,1539129671.343 [CBIT](INFO): Critical error at 20181010T000111 2018-10-10T00:01:11.354Z,1539129671.354 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-10T00:01:11.355Z,1539129671.355 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-10T00:01:13.588Z,1539129673.588 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-10-10T00:01:13.729Z,1539129673.729 [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-10T00:01:13.874Z,1539129673.874 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-10-10T00:01:13.875Z,1539129673.875 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-10-10T00:01:13.875Z,1539129673.875 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-10-10T00:01:14.884Z,1539129674.884 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T00:01:15.033Z,1539129675.033 [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-10T00:01:15.034Z,1539129675.034 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-10T00:01:15.416Z,1539129675.416 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T00:01:15.417Z,1539129675.417 [CTD_Seabird](INFO): Powering down 2018-10-10T00:01:20.234Z,1539129680.234 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T00:01:20.234Z,1539129680.234 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-10-10T00:01:20.235Z,1539129680.235 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T00:01:20.274Z,1539129680.274 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:20.614Z,1539129680.614 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T00:01:20.614Z,1539129680.614 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T00:01:20.614Z,1539129680.614 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-10T00:01:20.615Z,1539129680.615 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T00:01:20.926Z,1539129680.926 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T00:01:20.926Z,1539129680.926 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T00:01:20.926Z,1539129680.926 [ElevatorServo] Communications Fault, FailCount= 1 2018-10-10T00:01:20.926Z,1539129680.926 [ElevatorServo](ERROR): Communications Fault 2018-10-10T00:01:21.238Z,1539129681.238 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T00:01:21.238Z,1539129681.238 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T00:01:21.238Z,1539129681.238 [RudderServo] Communications Fault, FailCount= 1 2018-10-10T00:01:21.238Z,1539129681.238 [RudderServo](ERROR): Communications Fault 2018-10-10T00:01:21.558Z,1539129681.558 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T00:01:21.582Z,1539129681.582 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T00:01:21.583Z,1539129681.583 [ThrusterServo] Communications Fault, FailCount= 1 2018-10-10T00:01:21.583Z,1539129681.583 [ThrusterServo](ERROR): Communications Fault 2018-10-10T00:01:21.619Z,1539129681.619 [CBIT](INFO): Critical error at 20181010T000111 2018-10-10T00:01:21.622Z,1539129681.622 [CBIT](CRITICAL): Environmental Failure. Press:14.623810 PSI. Humidity:42%. Temp:26 C. ABORTING MISSION 2018-10-10T00:01:21.622Z,1539129681.622 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T00:01:21.622Z,1539129681.622 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T00:01:21.623Z,1539129681.623 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T00:01:21.623Z,1539129681.623 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T00:01:21.623Z,1539129681.623 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T00:01:21.647Z,1539129681.647 [AcousticModem_Benthos_ATM900](DEBUG): 2018-10-10T00:01:21.649Z,1539129681.649 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-10-10T00:01:21.650Z,1539129681.650 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-10-10T00:01:21.650Z,1539129681.650 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-10-10T00:01:21.651Z,1539129681.651 [AcousticModem_Benthos_ATM900](DEBUG): Oct 5 2018 22:19:46 2018-10-10T00:01:21.652Z,1539129681.652 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-10-10T00:01:21.726Z,1539129681.726 [Aanderaa_O2](INFO): Powering down 2018-10-10T00:01:21.850Z,1539129681.850 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:21.867Z,1539129681.867 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T00:01:21.868Z,1539129681.868 [BuoyancyServo](INFO): Powering down 2018-10-10T00:01:21.950Z,1539129681.950 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T00:01:21.950Z,1539129681.950 [ElevatorServo](INFO): Powering down 2018-10-10T00:01:21.990Z,1539129681.990 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T00:01:21.990Z,1539129681.990 [RudderServo](INFO): Powering down 2018-10-10T00:01:22.058Z,1539129682.058 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T00:01:22.058Z,1539129682.058 [ThrusterServo](INFO): Powering down 2018-10-10T00:01:22.119Z,1539129682.119 [CBIT](INFO): Critical error at 20181010T000121 2018-10-10T00:01:22.230Z,1539129682.230 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:22.514Z,1539129682.514 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T00:01:22.514Z,1539129682.514 [MassServo] Communications Fault, FailCount= 1 2018-10-10T00:01:22.514Z,1539129682.514 [MassServo](ERROR): Communications Fault 2018-10-10T00:01:22.520Z,1539129682.520 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T00:01:22.539Z,1539129682.539 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-10-10T00:01:22.556Z,1539129682.556 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009442 2018-10-10T00:01:22.619Z,1539129682.619 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:22.631Z,1539129682.631 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:22.631Z,1539129682.631 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T00:01:22.632Z,1539129682.632 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T00:01:22.632Z,1539129682.632 [MassServo](INFO): Powering down 2018-10-10T00:01:22.674Z,1539129682.674 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:22.675Z,1539129682.675 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T00:01:22.684Z,1539129682.684 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T00:01:22.684Z,1539129682.684 [Aanderaa_O2] No Fault, FailCount= 1 2018-10-10T00:01:22.684Z,1539129682.684 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T00:01:22.685Z,1539129682.685 [ElevatorServo] No Fault, FailCount= 1 2018-10-10T00:01:22.685Z,1539129682.685 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T00:01:22.685Z,1539129682.685 [RudderServo] No Fault, FailCount= 1 2018-10-10T00:01:22.685Z,1539129682.685 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T00:01:22.685Z,1539129682.685 [ThrusterServo] No Fault, FailCount= 1 2018-10-10T00:01:22.939Z,1539129682.939 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:22.987Z,1539129682.987 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:23.018Z,1539129683.018 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:23.018Z,1539129683.018 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T00:01:23.339Z,1539129683.339 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:23.405Z,1539129683.405 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:23.762Z,1539129683.762 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T00:01:23.762Z,1539129683.762 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T00:01:23.762Z,1539129683.762 [ElevatorServo] Communications Fault, FailCount= 2 2018-10-10T00:01:23.762Z,1539129683.762 [ElevatorServo](ERROR): Communications Fault 2018-10-10T00:01:23.763Z,1539129683.763 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:23.763Z,1539129683.763 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T00:01:24.007Z,1539129684.007 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T00:01:24.074Z,1539129684.074 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T00:01:24.074Z,1539129684.074 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T00:01:24.074Z,1539129684.074 [RudderServo] Communications Fault, FailCount= 2 2018-10-10T00:01:24.074Z,1539129684.074 [RudderServo](ERROR): Communications Fault 2018-10-10T00:01:24.398Z,1539129684.398 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T00:01:24.552Z,1539129684.552 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T00:01:24.552Z,1539129684.552 [ThrusterServo] Communications Fault, FailCount= 2 2018-10-10T00:01:24.553Z,1539129684.553 [ThrusterServo](ERROR): Communications Fault 2018-10-10T00:01:24.557Z,1539129684.557 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T00:01:24.557Z,1539129684.557 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T00:01:24.557Z,1539129684.557 [MassServo] No Fault, FailCount= 1 2018-10-10T00:01:24.557Z,1539129684.557 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T00:01:24.558Z,1539129684.558 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T00:01:24.572Z,1539129684.572 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:24.639Z,1539129684.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:24.655Z,1539129684.655 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T00:01:24.655Z,1539129684.655 [ElevatorServo](INFO): Powering down 2018-10-10T00:01:24.673Z,1539129684.673 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T00:01:24.770Z,1539129684.770 [RudderServo](INFO): Powering down 2018-10-10T00:01:24.771Z,1539129684.771 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T00:01:24.771Z,1539129684.771 [ThrusterServo](INFO): Powering down 2018-10-10T00:01:24.975Z,1539129684.975 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:25.034Z,1539129685.034 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:25.065Z,1539129685.065 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T00:01:25.065Z,1539129685.065 [BuoyancyServo] No Fault, FailCount= 1 2018-10-10T00:01:25.375Z,1539129685.375 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:25.454Z,1539129685.454 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:25.464Z,1539129685.464 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:25.465Z,1539129685.465 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T00:01:25.470Z,1539129685.470 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:25.471Z,1539129685.471 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T00:01:25.472Z,1539129685.472 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:25.472Z,1539129685.472 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T00:01:25.486Z,1539129685.486 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T00:01:25.486Z,1539129685.486 [ElevatorServo] No Fault, FailCount= 2 2018-10-10T00:01:25.486Z,1539129685.486 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T00:01:25.486Z,1539129685.486 [RudderServo] No Fault, FailCount= 2 2018-10-10T00:01:25.486Z,1539129685.486 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T00:01:25.486Z,1539129685.486 [ThrusterServo] No Fault, FailCount= 2 2018-10-10T00:01:25.775Z,1539129685.775 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:25.834Z,1539129685.834 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:26.162Z,1539129686.162 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-10-10T00:01:26.162Z,1539129686.162 [MassServo] Communications Fault, FailCount= 2 2018-10-10T00:01:26.162Z,1539129686.162 [MassServo](ERROR): Communications Fault 2018-10-10T00:01:26.474Z,1539129686.474 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T00:01:26.474Z,1539129686.474 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T00:01:26.474Z,1539129686.474 [RudderServo] Communications Fault, FailCount= 3 2018-10-10T00:01:26.474Z,1539129686.474 [RudderServo](ERROR): Communications Fault 2018-10-10T00:01:26.475Z,1539129686.475 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:26.475Z,1539129686.475 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T00:01:26.479Z,1539129686.479 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T00:01:26.480Z,1539129686.480 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T00:01:26.480Z,1539129686.480 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-10-10T00:01:26.488Z,1539129686.488 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:26.547Z,1539129686.547 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:26.870Z,1539129686.870 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T00:01:26.870Z,1539129686.870 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T00:01:26.870Z,1539129686.870 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-10T00:01:26.870Z,1539129686.870 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T00:01:27.174Z,1539129687.174 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T00:01:27.174Z,1539129687.174 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T00:01:27.174Z,1539129687.174 [ElevatorServo] Communications Fault, FailCount= 3 2018-10-10T00:01:27.174Z,1539129687.174 [ElevatorServo](ERROR): Communications Fault 2018-10-10T00:01:27.175Z,1539129687.175 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T00:01:27.175Z,1539129687.175 [MassServo](INFO): Powering down 2018-10-10T00:01:27.176Z,1539129687.176 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T00:01:27.176Z,1539129687.176 [RudderServo](INFO): Powering down 2018-10-10T00:01:27.502Z,1539129687.502 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T00:01:27.527Z,1539129687.527 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T00:01:27.527Z,1539129687.527 [ThrusterServo] Communications Fault, FailCount= 3 2018-10-10T00:01:27.528Z,1539129687.528 [ThrusterServo](ERROR): Communications Fault 2018-10-10T00:01:27.538Z,1539129687.538 [CBIT](INFO): Critical error at 20181010T000126 2018-10-10T00:01:27.549Z,1539129687.549 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T00:01:27.555Z,1539129687.555 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T00:01:27.556Z,1539129687.556 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo 2018-10-10T00:01:27.556Z,1539129687.556 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T00:01:27.574Z,1539129687.574 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:27.626Z,1539129687.626 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T00:01:27.626Z,1539129687.626 [PNI_TCM] Data Fault, FailCount= 1 2018-10-10T00:01:27.626Z,1539129687.626 [PNI_TCM](ERROR): Data Fault 2018-10-10T00:01:27.669Z,1539129687.669 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:27.687Z,1539129687.687 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T00:01:27.688Z,1539129687.688 [BuoyancyServo](INFO): Powering down 2018-10-10T00:01:27.702Z,1539129687.702 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T00:01:27.702Z,1539129687.702 [ElevatorServo](INFO): Powering down 2018-10-10T00:01:27.704Z,1539129687.704 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T00:01:27.704Z,1539129687.704 [ThrusterServo](INFO): Powering down 2018-10-10T00:01:27.715Z,1539129687.715 [CBIT](INFO): Critical error at 20181010T000127 2018-10-10T00:01:27.718Z,1539129687.718 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T00:01:27.967Z,1539129687.967 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:27.988Z,1539129687.988 [PNI_TCM](INFO): Powering down 2018-10-10T00:01:28.067Z,1539129688.067 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:28.074Z,1539129688.074 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:28.075Z,1539129688.075 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T00:01:28.076Z,1539129688.076 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:28.076Z,1539129688.076 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T00:01:28.086Z,1539129688.086 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T00:01:28.086Z,1539129688.086 [MassServo] No Fault, FailCount= 2 2018-10-10T00:01:28.368Z,1539129688.368 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:28.446Z,1539129688.446 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:28.467Z,1539129688.467 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:28.468Z,1539129688.468 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T00:01:28.478Z,1539129688.478 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T00:01:28.478Z,1539129688.478 [ThrusterServo] No Fault, FailCount= 3 2018-10-10T00:01:28.768Z,1539129688.768 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:28.827Z,1539129688.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:28.886Z,1539129688.886 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T00:01:28.886Z,1539129688.886 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T00:01:29.167Z,1539129689.167 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:29.247Z,1539129689.247 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:29.260Z,1539129689.260 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T00:01:29.261Z,1539129689.261 [PNI_TCM] No Fault, FailCount= 1 2018-10-10T00:01:29.567Z,1539129689.567 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-10-10T00:01:29.630Z,1539129689.630 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T00:01:29.959Z,1539129689.959 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T00:01:30.026Z,1539129690.026 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T00:01:30.026Z,1539129690.026 [ThrusterServo] Communications Fault, FailCount= 4