2018-10-10T17:48:52.498Z,1539193732.498 [Supervisor](DEBUG): Initializing supervisor. 2018-10-10T17:48:52.501Z,1539193732.501 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-10T17:48:52.501Z,1539193732.501 [SyncHandler](INFO): Protected caller Thread ID is 797 2018-10-10T17:48:52.502Z,1539193732.502 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-10T17:48:52.503Z,1539193732.503 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-10T17:48:52.503Z,1539193732.503 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 798 2018-10-10T17:48:52.506Z,1539193732.506 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-10T17:48:52.518Z,1539193732.518 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-10T17:48:52.519Z,1539193732.519 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-10T17:48:52.519Z,1539193732.519 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 799 2018-10-10T17:48:52.520Z,1539193732.520 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-10T17:48:52.521Z,1539193732.521 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-10T17:48:52.522Z,1539193732.522 [logger ThreadHandler](INFO): Protected caller Thread ID is 800 2018-10-10T17:48:52.524Z,1539193732.524 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-10T17:48:52.524Z,1539193732.524 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-10T17:48:52.527Z,1539193732.527 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-10T17:48:52.627Z,1539193732.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-10T17:48:52.629Z,1539193732.629 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-10T17:48:52.732Z,1539193732.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-10T17:48:52.734Z,1539193732.734 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-10T17:48:52.943Z,1539193732.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-10T17:48:52.944Z,1539193732.944 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-10T17:48:53.285Z,1539193733.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-10T17:48:53.287Z,1539193733.287 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-10T17:48:53.636Z,1539193733.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-10T17:48:53.636Z,1539193733.636 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-10T17:48:54.109Z,1539193734.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-10T17:48:54.111Z,1539193734.111 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-10T17:48:54.590Z,1539193734.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-10T17:48:54.590Z,1539193734.590 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-10T17:48:55.083Z,1539193735.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-10T17:48:55.084Z,1539193735.084 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-10T17:48:55.167Z,1539193735.167 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-10T17:48:55.494Z,1539193735.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-10T17:48:55.496Z,1539193735.496 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-10T17:48:55.653Z,1539193735.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-10T17:48:55.654Z,1539193735.654 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-10T17:48:55.875Z,1539193735.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-10T17:48:55.877Z,1539193735.877 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-10T17:48:56.091Z,1539193736.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-10T17:48:56.092Z,1539193736.092 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-10T17:48:56.232Z,1539193736.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-10T17:48:56.392Z,1539193736.392 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-10T17:48:56.744Z,1539193736.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-10T17:48:56.746Z,1539193736.746 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-10-10T17:48:57.418Z,1539193737.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-10-10T17:48:57.653Z,1539193737.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-10T17:48:57.654Z,1539193737.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-10-10T17:48:57.762Z,1539193737.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-10-10T17:48:57.909Z,1539193737.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-10-10T17:48:58.004Z,1539193738.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-10-10T17:48:58.091Z,1539193738.091 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-10-10T17:48:58.243Z,1539193738.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-10-10T17:48:58.439Z,1539193738.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-10-10T17:48:58.539Z,1539193738.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-10-10T17:48:58.635Z,1539193738.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-10-10T17:48:58.735Z,1539193738.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-10-10T17:48:58.818Z,1539193738.818 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-10T17:48:58.823Z,1539193738.823 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-10T17:48:58.951Z,1539193738.951 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-10T17:48:58.951Z,1539193738.951 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-10T17:48:58.975Z,1539193738.975 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-10T17:48:58.976Z,1539193738.976 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-10T17:48:59.031Z,1539193739.031 [DepthRateCalculator] Loaded 2018-10-10T17:48:59.031Z,1539193739.031 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-10T17:48:59.037Z,1539193739.037 [PitchRateCalculator] Loaded 2018-10-10T17:48:59.038Z,1539193739.038 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-10T17:48:59.054Z,1539193739.054 [SpeedCalculator] Loaded 2018-10-10T17:48:59.054Z,1539193739.054 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-10T17:48:59.075Z,1539193739.075 [TempGradientCalculator] Loaded 2018-10-10T17:48:59.075Z,1539193739.075 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-10T17:48:59.081Z,1539193739.081 [YawRateCalculator] Loaded 2018-10-10T17:48:59.081Z,1539193739.081 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-10T17:48:59.121Z,1539193739.121 [ElevatorOffsetCalculator] Loaded 2018-10-10T17:48:59.121Z,1539193739.121 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-10T17:48:59.121Z,1539193739.121 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-10T17:48:59.122Z,1539193739.122 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-10T17:48:59.160Z,1539193739.160 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-10T17:48:59.161Z,1539193739.161 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-10T17:48:59.729Z,1539193739.729 [DataOverHttps] Loaded 2018-10-10T17:48:59.730Z,1539193739.730 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-10T17:48:59.756Z,1539193739.756 [Depth_Keller] Loaded 2018-10-10T17:48:59.756Z,1539193739.756 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-10T17:48:59.761Z,1539193739.761 [DropWeight] Loaded 2018-10-10T17:48:59.761Z,1539193739.761 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-10T17:48:59.860Z,1539193739.860 [NAL9602] Loaded 2018-10-10T17:48:59.860Z,1539193739.860 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-10T17:48:59.876Z,1539193739.876 [Onboard] Loaded 2018-10-10T17:48:59.877Z,1539193739.877 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-10T17:48:59.887Z,1539193739.887 [Radio_Surface] Loaded 2018-10-10T17:48:59.887Z,1539193739.887 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-10T17:48:59.888Z,1539193739.888 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-10-10T17:48:59.889Z,1539193739.889 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 880 2018-10-10T17:49:00.041Z,1539193740.041 [PNI_TCM] Loaded 2018-10-10T17:49:00.041Z,1539193740.041 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-10T17:49:00.103Z,1539193740.103 [Rowe_600LCM] Loaded 2018-10-10T17:49:00.103Z,1539193740.103 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-10-10T17:49:00.104Z,1539193740.104 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-10-10T17:49:00.105Z,1539193740.105 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 881 2018-10-10T17:49:02.405Z,1539193742.405 [BPC1] Loaded 2018-10-10T17:49:02.405Z,1539193742.405 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-10T17:49:02.406Z,1539193742.406 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-10T17:49:02.407Z,1539193742.407 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-10T17:49:02.560Z,1539193742.560 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-10T17:49:02.584Z,1539193742.584 [SBIT] Loaded 2018-10-10T17:49:02.584Z,1539193742.584 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-10T17:49:02.585Z,1539193742.585 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-10T17:49:02.597Z,1539193742.597 [IBIT] Loaded 2018-10-10T17:49:02.597Z,1539193742.597 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-10T17:49:02.600Z,1539193742.600 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-10T17:49:02.747Z,1539193742.747 [CBIT] Loaded 2018-10-10T17:49:02.748Z,1539193742.748 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-10T17:49:02.748Z,1539193742.748 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-10T17:49:02.749Z,1539193742.749 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-10T17:49:02.953Z,1539193742.953 [Aanderaa_O2] Loaded 2018-10-10T17:49:02.954Z,1539193742.954 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-10-10T17:49:03.006Z,1539193743.006 [CTD_Seabird] Loaded 2018-10-10T17:49:03.007Z,1539193743.007 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-10T17:49:03.008Z,1539193743.008 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BC4E0 2018-10-10T17:49:03.008Z,1539193743.008 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 882 2018-10-10T17:49:03.025Z,1539193743.025 [ESPComponent] Loaded 2018-10-10T17:49:03.026Z,1539193743.026 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-10-10T17:49:03.040Z,1539193743.040 [PAR_Licor] Loaded 2018-10-10T17:49:03.040Z,1539193743.040 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-10-10T17:49:03.088Z,1539193743.088 [WetLabsBB2FL] Loaded 2018-10-10T17:49:03.089Z,1539193743.089 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-10T17:49:03.090Z,1539193743.090 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2018-10-10T17:49:03.090Z,1539193743.090 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 883 2018-10-10T17:49:03.091Z,1539193743.091 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-10T17:49:03.091Z,1539193743.091 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-10T17:49:03.374Z,1539193743.374 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-10T17:49:03.376Z,1539193743.376 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-10T17:49:03.462Z,1539193743.462 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-10T17:49:03.557Z,1539193743.557 [VerticalControl] Loaded 2018-10-10T17:49:03.557Z,1539193743.557 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-10T17:49:03.558Z,1539193743.558 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-10T17:49:03.619Z,1539193743.619 [HorizontalControl] Loaded 2018-10-10T17:49:03.620Z,1539193743.620 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-10T17:49:03.621Z,1539193743.621 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-10T17:49:03.626Z,1539193743.626 [SpeedControl] Loaded 2018-10-10T17:49:03.626Z,1539193743.626 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-10T17:49:03.627Z,1539193743.627 [LoopControl](DEBUG): Construct LoopControl. 2018-10-10T17:49:03.627Z,1539193743.627 [LoopControl] Loaded 2018-10-10T17:49:03.627Z,1539193743.628 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-10T17:49:03.628Z,1539193743.628 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-10T17:49:03.629Z,1539193743.629 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-10T17:49:03.731Z,1539193743.731 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-10T17:49:03.732Z,1539193743.732 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-10T17:49:03.852Z,1539193743.852 [BuoyancyServo] Loaded 2018-10-10T17:49:03.852Z,1539193743.852 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-10T17:49:03.863Z,1539193743.863 [ElevatorServo] Loaded 2018-10-10T17:49:03.864Z,1539193743.864 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-10T17:49:03.875Z,1539193743.875 [MassServo] Loaded 2018-10-10T17:49:03.875Z,1539193743.875 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-10T17:49:03.886Z,1539193743.886 [RudderServo] Loaded 2018-10-10T17:49:03.887Z,1539193743.887 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-10T17:49:03.898Z,1539193743.898 [ThrusterServo] Loaded 2018-10-10T17:49:03.898Z,1539193743.898 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-10T17:49:03.898Z,1539193743.898 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-10T17:49:03.899Z,1539193743.899 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-10T17:49:04.006Z,1539193744.006 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-10T17:49:04.007Z,1539193744.007 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-10T17:49:04.023Z,1539193744.023 [NavChart] Loaded 2018-10-10T17:49:04.023Z,1539193744.023 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-10T17:49:04.027Z,1539193744.027 [UniversalFixResidualReporter] Loaded 2018-10-10T17:49:04.028Z,1539193744.028 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-10T17:49:04.028Z,1539193744.028 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-10T17:49:04.032Z,1539193744.032 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-10T17:49:04.033Z,1539193744.033 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-10T17:49:04.039Z,1539193744.039 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-10T17:49:04.041Z,1539193744.041 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2018-10-10T17:49:04.041Z,1539193744.041 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884 2018-10-10T17:49:04.046Z,1539193744.046 [Supervisor](INFO): Main Thread ID is 796 2018-10-10T17:49:04.046Z,1539193744.046 [Supervisor](DEBUG): Running supervisor. 2018-10-10T17:49:04.047Z,1539193744.047 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885 2018-10-10T17:49:04.049Z,1539193744.049 [controlThread ThreadHandler](INFO): Handler Thread ID is 886 2018-10-10T17:49:04.049Z,1539193744.049 [controlThread](DEBUG): Initializing ControlThread 2018-10-10T17:49:04.050Z,1539193744.050 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-10T17:49:04.051Z,1539193744.051 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-10T17:49:04.051Z,1539193744.051 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-10T17:49:04.051Z,1539193744.051 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-10T17:49:04.052Z,1539193744.052 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-10T17:49:04.052Z,1539193744.052 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-10T17:49:04.059Z,1539193744.059 [SBIT](INFO): Initialize SBIT Component. 2018-10-10T17:49:04.060Z,1539193744.060 [SBIT](IMPORTANT): git: 2018-10-09-5-g8e0f966 2018-10-10T17:49:04.060Z,1539193744.060 [SBIT](INFO): git hash: 8e0f9669898461179fe27a6b90da366188de3daf 2018-10-10T17:49:04.060Z,1539193744.060 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-10T17:49:04.061Z,1539193744.061 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-10T17:49:04.062Z,1539193744.062 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-10-10T17:49:04.062Z,1539193744.062 [IBIT](INFO): Initialize IBIT Component. 2018-10-10T17:49:04.063Z,1539193744.063 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-10T17:49:04.063Z,1539193744.063 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-10T17:49:04.064Z,1539193744.064 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-10-10T17:49:04.065Z,1539193744.065 [logger ThreadHandler](INFO): Handler Thread ID is 887 2018-10-10T17:49:04.081Z,1539193744.081 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 888 2018-10-10T17:49:04.087Z,1539193744.087 [Radio_Surface](INFO): Powering up 2018-10-10T17:49:04.093Z,1539193744.093 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 889 2018-10-10T17:49:04.094Z,1539193744.094 [Rowe_600LCM](INFO): Initializing 2018-10-10T17:49:04.094Z,1539193744.094 [Rowe_600LCM](INFO): Checking LCM 2018-10-10T17:49:04.185Z,1539193744.185 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 890 2018-10-10T17:49:04.186Z,1539193744.186 [CTD_Seabird](INFO): Initializing 2018-10-10T17:49:04.186Z,1539193744.186 [CTD_Seabird](INFO): Checking LCM 2018-10-10T17:49:04.189Z,1539193744.189 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-10T17:49:04.191Z,1539193744.191 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-10T17:49:04.192Z,1539193744.192 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-10T17:49:04.192Z,1539193744.192 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-10T17:49:04.194Z,1539193744.194 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-10T17:49:04.195Z,1539193744.195 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-10T17:49:04.195Z,1539193744.195 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-10T17:49:04.195Z,1539193744.195 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-10T17:49:04.196Z,1539193744.196 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-10T17:49:04.201Z,1539193744.201 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 891 2018-10-10T17:49:04.203Z,1539193744.203 [WetLabsBB2FL](INFO): Powering down 2018-10-10T17:49:04.221Z,1539193744.221 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-10T17:49:04.317Z,1539193744.317 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892 2018-10-10T17:49:04.336Z,1539193744.336 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-10-10T17:49:04.360Z,1539193744.360 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-10T17:49:04.435Z,1539193744.435 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T17:49:04.437Z,1539193744.437 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-10-10T17:49:04.438Z,1539193744.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-10T17:49:04.439Z,1539193744.439 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-10-10T17:49:04.439Z,1539193744.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-10T17:49:04.441Z,1539193744.441 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-10-10T17:49:04.441Z,1539193744.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-10T17:49:04.442Z,1539193744.442 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-10-10T17:49:04.442Z,1539193744.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-10T17:49:04.444Z,1539193744.444 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-10-10T17:49:04.444Z,1539193744.444 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-10T17:49:04.493Z,1539193744.493 [MissionManager](DEBUG): 2018-10-10T17:49:04.496Z,1539193744.496 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-10T17:49:04.497Z,1539193744.497 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-10-10T17:49:04.497Z,1539193744.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-10T17:49:04.499Z,1539193744.499 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-10-10T17:49:04.499Z,1539193744.499 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:49:04.509Z,1539193744.509 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-10-10T17:49:04.513Z,1539193744.513 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:49:04.525Z,1539193744.525 [CTD_Seabird](INFO): LCM OK 2018-10-10T17:49:04.525Z,1539193744.525 [CTD_Seabird](INFO): Powering up 2018-10-10T17:49:04.529Z,1539193744.529 [Rowe_600LCM](INFO): LCM OK 2018-10-10T17:49:04.529Z,1539193744.529 [Rowe_600LCM](INFO): Powering up 2018-10-10T17:49:04.599Z,1539193744.599 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-10T17:49:04.600Z,1539193744.600 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-10T17:49:04.602Z,1539193744.602 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T17:49:04.659Z,1539193744.659 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-10T17:49:04.661Z,1539193744.661 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-10T17:49:04.677Z,1539193744.677 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-10T17:49:04.681Z,1539193744.681 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-10-10T17:49:04.697Z,1539193744.697 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-10-10T17:49:04.745Z,1539193744.745 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-10T17:49:05.039Z,1539193745.039 [ESPComponent](INFO): powering down ESP 2018-10-10T17:49:05.443Z,1539193745.443 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:05.633Z,1539193745.633 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:05.641Z,1539193745.641 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:49:05.652Z,1539193745.652 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:05.657Z,1539193745.657 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:49:05.663Z,1539193745.663 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:05.668Z,1539193745.668 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:49:05.686Z,1539193745.686 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:05.693Z,1539193745.693 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:49:05.699Z,1539193745.699 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:05.705Z,1539193745.705 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:49:05.894Z,1539193745.894 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-10T17:49:05.895Z,1539193745.895 [DropWeight] Hardware Fault, FailCount= 1 2018-10-10T17:49:05.895Z,1539193745.895 [DropWeight](ERROR): Hardware Fault 2018-10-10T17:49:06.005Z,1539193746.005 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:06.044Z,1539193746.044 [CommandLine](FAULT): Scheduling is paused 2018-10-10T17:49:06.045Z,1539193746.045 [CBIT](INFO): Critical error at 20181010T174905 2018-10-10T17:49:06.045Z,1539193746.045 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-10T17:49:06.047Z,1539193746.047 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-10T17:49:06.048Z,1539193746.048 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-10T17:49:06.268Z,1539193746.268 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:06.613Z,1539193746.613 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:49:06.613Z,1539193746.613 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:49:06.613Z,1539193746.613 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-10T17:49:06.613Z,1539193746.613 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:49:06.925Z,1539193746.925 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T17:49:06.925Z,1539193746.925 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T17:49:06.925Z,1539193746.925 [ElevatorServo] Communications Fault, FailCount= 1 2018-10-10T17:49:06.925Z,1539193746.925 [ElevatorServo](ERROR): Communications Fault 2018-10-10T17:49:07.237Z,1539193747.237 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:49:07.237Z,1539193747.237 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:49:07.237Z,1539193747.237 [RudderServo] Communications Fault, FailCount= 1 2018-10-10T17:49:07.237Z,1539193747.237 [RudderServo](ERROR): Communications Fault 2018-10-10T17:49:07.557Z,1539193747.557 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:49:07.582Z,1539193747.582 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:49:07.582Z,1539193747.582 [ThrusterServo] Communications Fault, FailCount= 1 2018-10-10T17:49:07.583Z,1539193747.583 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:49:07.594Z,1539193747.594 [CBIT](INFO): Critical error at 20181010T174906 2018-10-10T17:49:07.610Z,1539193747.610 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:49:07.611Z,1539193747.611 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T17:49:07.611Z,1539193747.611 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:49:07.611Z,1539193747.611 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:49:07.711Z,1539193747.711 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:07.750Z,1539193747.750 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:49:07.751Z,1539193747.751 [BuoyancyServo](INFO): Powering down 2018-10-10T17:49:07.833Z,1539193747.833 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T17:49:07.833Z,1539193747.833 [ElevatorServo](INFO): Powering down 2018-10-10T17:49:07.882Z,1539193747.882 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:49:07.882Z,1539193747.882 [RudderServo](INFO): Powering down 2018-10-10T17:49:07.945Z,1539193747.945 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:49:07.945Z,1539193747.945 [ThrusterServo](INFO): Powering down 2018-10-10T17:49:08.103Z,1539193748.103 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:08.354Z,1539193748.354 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-10-10T17:49:08.436Z,1539193748.436 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-10-10T17:49:08.500Z,1539193748.500 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:08.561Z,1539193748.561 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:08.562Z,1539193748.562 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:49:08.635Z,1539193748.635 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-10-10T17:49:08.636Z,1539193748.636 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-10-10T17:49:08.637Z,1539193748.637 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-10-10T17:49:08.825Z,1539193748.825 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T17:49:08.825Z,1539193748.825 [MassServo] Communications Fault, FailCount= 1 2018-10-10T17:49:08.825Z,1539193748.825 [MassServo](ERROR): Communications Fault 2018-10-10T17:49:08.826Z,1539193748.826 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:08.827Z,1539193748.827 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:49:08.833Z,1539193748.833 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T17:49:08.833Z,1539193748.833 [ElevatorServo] No Fault, FailCount= 1 2018-10-10T17:49:08.833Z,1539193748.833 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:49:08.834Z,1539193748.834 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T17:49:08.834Z,1539193748.834 [RudderServo] No Fault, FailCount= 1 2018-10-10T17:49:08.834Z,1539193748.834 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:49:08.834Z,1539193748.834 [ThrusterServo] No Fault, FailCount= 1 2018-10-10T17:49:08.943Z,1539193748.943 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:08.972Z,1539193748.972 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:49:08.973Z,1539193748.973 [MassServo](INFO): Powering down 2018-10-10T17:49:09.070Z,1539193749.070 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:09.070Z,1539193749.070 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:49:09.277Z,1539193749.277 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:09.567Z,1539193749.567 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T17:49:09.652Z,1539193749.652 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-10-10T17:49:09.653Z,1539193749.653 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-10T17:49:09.685Z,1539193749.685 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:49:09.685Z,1539193749.685 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:49:09.685Z,1539193749.685 [RudderServo] Communications Fault, FailCount= 2 2018-10-10T17:49:09.686Z,1539193749.686 [RudderServo](ERROR): Communications Fault 2018-10-10T17:49:09.948Z,1539193749.948 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T17:49:09.949Z,1539193749.949 [CTD_Seabird](INFO): Powering down 2018-10-10T17:49:10.013Z,1539193750.013 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:49:10.081Z,1539193750.081 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:49:10.081Z,1539193750.081 [ThrusterServo] Communications Fault, FailCount= 2 2018-10-10T17:49:10.082Z,1539193750.082 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:49:10.086Z,1539193750.086 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:49:10.087Z,1539193750.087 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:49:10.201Z,1539193750.201 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:10.246Z,1539193750.246 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:10.247Z,1539193750.247 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:49:10.248Z,1539193750.248 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:49:10.248Z,1539193750.248 [RudderServo](INFO): Powering down 2018-10-10T17:49:10.253Z,1539193750.253 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:49:10.253Z,1539193750.253 [ThrusterServo](INFO): Powering down 2018-10-10T17:49:10.266Z,1539193750.266 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:49:10.266Z,1539193750.266 [MassServo] No Fault, FailCount= 1 2018-10-10T17:49:10.581Z,1539193750.581 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:10.602Z,1539193750.602 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:49:10.602Z,1539193750.602 [BuoyancyServo] No Fault, FailCount= 1 2018-10-10T17:49:10.978Z,1539193750.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:10.988Z,1539193750.988 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:10.993Z,1539193750.993 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:49:11.069Z,1539193751.069 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:11.069Z,1539193751.069 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:49:11.078Z,1539193751.078 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T17:49:11.078Z,1539193751.078 [RudderServo] No Fault, FailCount= 2 2018-10-10T17:49:11.079Z,1539193751.079 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:49:11.079Z,1539193751.079 [ThrusterServo] No Fault, FailCount= 2 2018-10-10T17:49:11.377Z,1539193751.377 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:11.397Z,1539193751.397 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:11.397Z,1539193751.397 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:49:11.742Z,1539193751.742 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:12.073Z,1539193752.073 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:49:12.073Z,1539193752.073 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:49:12.073Z,1539193752.073 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-10T17:49:12.073Z,1539193752.073 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:49:12.381Z,1539193752.381 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-10-10T17:49:12.381Z,1539193752.381 [MassServo] Communications Fault, FailCount= 2 2018-10-10T17:49:12.381Z,1539193752.381 [MassServo](ERROR): Communications Fault 2018-10-10T17:49:12.685Z,1539193752.685 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:49:12.685Z,1539193752.685 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:49:12.685Z,1539193752.685 [RudderServo] Communications Fault, FailCount= 3 2018-10-10T17:49:12.685Z,1539193752.685 [RudderServo](ERROR): Communications Fault 2018-10-10T17:49:12.801Z,1539193752.801 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:49:12.802Z,1539193752.802 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:49:12.802Z,1539193752.802 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:49:12.802Z,1539193752.802 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-10-10T17:49:12.880Z,1539193752.880 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:12.898Z,1539193752.898 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:49:12.899Z,1539193752.899 [BuoyancyServo](INFO): Powering down 2018-10-10T17:49:12.914Z,1539193752.914 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:49:12.914Z,1539193752.914 [MassServo](INFO): Powering down 2018-10-10T17:49:12.915Z,1539193752.915 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:49:12.915Z,1539193752.915 [RudderServo](INFO): Powering down 2018-10-10T17:49:12.927Z,1539193752.927 [CBIT](INFO): Critical error at 20181010T174912 2018-10-10T17:49:13.238Z,1539193753.238 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:49:13.238Z,1539193753.238 [PNI_TCM] Data Fault, FailCount= 1 2018-10-10T17:49:13.238Z,1539193753.238 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:49:13.249Z,1539193753.249 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:13.281Z,1539193753.281 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:49:13.631Z,1539193753.631 [PNI_TCM](INFO): Powering down 2018-10-10T17:49:13.699Z,1539193753.699 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:13.707Z,1539193753.707 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:13.707Z,1539193753.707 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:49:13.708Z,1539193753.708 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:13.709Z,1539193753.709 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:49:13.726Z,1539193753.726 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:49:13.726Z,1539193753.726 [MassServo] No Fault, FailCount= 2 2018-10-10T17:49:14.049Z,1539193754.049 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:14.089Z,1539193754.089 [CBIT](CRITICAL): Environmental Failure. Press:14.689036 PSI. Humidity:39%. Temp:26 C. ABORTING MISSION 2018-10-10T17:49:14.462Z,1539193754.462 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:14.541Z,1539193754.541 [CBIT](INFO): Critical error at 20181010T174914 2018-10-10T17:49:14.858Z,1539193754.858 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:14.888Z,1539193754.888 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T17:49:14.888Z,1539193754.888 [PNI_TCM] No Fault, FailCount= 1 2018-10-10T17:49:14.888Z,1539193754.888 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:49:14.889Z,1539193754.889 [BuoyancyServo] No Fault, FailCount= 2 2018-10-10T17:49:15.259Z,1539193755.259 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T17:49:15.259Z,1539193755.259 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-10-10T17:49:15.259Z,1539193755.259 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T17:49:15.279Z,1539193755.279 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:15.286Z,1539193755.286 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:15.287Z,1539193755.287 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:49:15.601Z,1539193755.601 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2018-10-10T17:49:15.601Z,1539193755.601 [ThrusterServo] Communications Fault, FailCount= 3 2018-10-10T17:49:15.601Z,1539193755.601 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:49:15.605Z,1539193755.605 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T17:49:15.606Z,1539193755.606 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:49:15.645Z,1539193755.645 [Aanderaa_O2](INFO): Powering down 2018-10-10T17:49:15.703Z,1539193755.703 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:15.716Z,1539193755.716 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:49:15.716Z,1539193755.716 [ThrusterServo](INFO): Powering down 2018-10-10T17:49:16.098Z,1539193756.098 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:16.433Z,1539193756.433 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:49:16.433Z,1539193756.433 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:49:16.433Z,1539193756.433 [BuoyancyServo] Communications Fault, FailCount= 3 2018-10-10T17:49:16.433Z,1539193756.433 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:49:16.489Z,1539193756.489 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:49:16.569Z,1539193756.569 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:16.588Z,1539193756.588 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:49:16.588Z,1539193756.588 [BuoyancyServo](INFO): Powering down 2018-10-10T17:49:16.610Z,1539193756.610 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T17:49:16.610Z,1539193756.610 [Aanderaa_O2] No Fault, FailCount= 1 2018-10-10T17:49:16.611Z,1539193756.611 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:49:16.611Z,1539193756.611 [ThrusterServo] No Fault, FailCount= 3 2018-10-10T17:49:16.958Z,1539193756.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:16.999Z,1539193756.999 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:16.000Z,1539193757.000 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:49:17.374Z,1539193757.374 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:17.761Z,1539193757.761 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:18.158Z,1539193758.158 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:18.546Z,1539193758.546 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:18.627Z,1539193758.627 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:49:18.627Z,1539193758.627 [BuoyancyServo] No Fault, FailCount= 3 2018-10-10T17:49:18.967Z,1539193758.967 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:49:18.980Z,1539193758.980 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:18.999Z,1539193758.999 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:18.999Z,1539193758.999 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:49:19.362Z,1539193759.362 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:19.750Z,1539193759.750 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:20.085Z,1539193760.085 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:49:20.085Z,1539193760.085 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:49:20.085Z,1539193760.085 [BuoyancyServo] Communications Fault, FailCount= 4 2018-10-10T17:49:20.085Z,1539193760.085 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:49:20.135Z,1539193760.135 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:49:20.232Z,1539193760.232 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:20.251Z,1539193760.251 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:49:20.252Z,1539193760.252 [BuoyancyServo](INFO): Powering down 2018-10-10T17:49:20.585Z,1539193760.585 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2018-10-10T17:49:20.585Z,1539193760.585 [ThrusterServo] Communications Fault, FailCount= 4 2018-10-10T17:49:20.585Z,1539193760.585 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:49:20.589Z,1539193760.589 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:49:20.663Z,1539193760.663 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:20.671Z,1539193760.671 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:49:20.672Z,1539193760.672 [ThrusterServo](INFO): Powering down 2018-10-10T17:49:21.046Z,1539193761.046 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:21.468Z,1539193761.468 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:21.553Z,1539193761.553 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:49:21.553Z,1539193761.553 [ThrusterServo] No Fault, FailCount= 4 2018-10-10T17:49:21.854Z,1539193761.854 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:21.871Z,1539193761.871 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:21.871Z,1539193761.871 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:49:22.271Z,1539193762.271 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:22.302Z,1539193762.302 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:49:22.302Z,1539193762.302 [BuoyancyServo] No Fault, FailCount= 4 2018-10-10T17:49:22.625Z,1539193762.625 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:49:22.625Z,1539193762.625 [PNI_TCM] Data Fault, FailCount= 2 2018-10-10T17:49:22.625Z,1539193762.625 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:49:22.637Z,1539193762.637 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:22.678Z,1539193762.678 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:22.679Z,1539193762.679 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:49:22.841Z,1539193762.841 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:49:23.035Z,1539193763.035 [PNI_TCM](INFO): Powering down 2018-10-10T17:49:23.065Z,1539193763.065 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:23.465Z,1539193763.465 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:23.573Z,1539193763.573 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:49:23.573Z,1539193763.573 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:49:23.573Z,1539193763.573 [BuoyancyServo] Communications Fault, FailCount= 5 2018-10-10T17:49:23.573Z,1539193763.573 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:49:23.579Z,1539193763.579 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:49:23.873Z,1539193763.873 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:23.879Z,1539193763.879 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:49:23.879Z,1539193763.879 [BuoyancyServo](INFO): Powering down 2018-10-10T17:49:24.255Z,1539193764.255 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:24.325Z,1539193764.325 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T17:49:24.325Z,1539193764.325 [PNI_TCM] No Fault, FailCount= 2 2018-10-10T17:49:24.650Z,1539193764.650 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:25.057Z,1539193765.057 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:25.385Z,1539193765.385 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2018-10-10T17:49:25.385Z,1539193765.385 [ThrusterServo] Communications Fault, FailCount= 5 2018-10-10T17:49:25.385Z,1539193765.385 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:49:25.390Z,1539193765.390 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:49:25.390Z,1539193765.390 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2018-10-10T17:49:25.463Z,1539193765.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:25.485Z,1539193765.485 [BuoyancyServo](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated. 2018-10-10T17:49:25.486Z,1539193765.486 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-10-10T17:49:25.486Z,1539193765.486 [BuoyancyServo](ERROR): Hardware Fault 2018-10-10T17:49:25.487Z,1539193765.487 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:49:25.488Z,1539193765.488 [ThrusterServo](INFO): Powering down 2018-10-10T17:49:25.490Z,1539193765.490 [CBIT](INFO): Critical error at 20181010T174925 2018-10-10T17:49:25.845Z,1539193765.845 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:25.879Z,1539193765.879 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:49:25.879Z,1539193765.879 [BuoyancyServo] No Fault, FailCount= 5 2018-10-10T17:49:26.254Z,1539193766.254 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:49:26.271Z,1539193766.271 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:49:26.271Z,1539193766.271 [BuoyancyServo](DEBUG): Initializing Buoy