2018-10-10T18:15:38.309Z,1539195338.309 [Supervisor](DEBUG): Initializing supervisor. 2018-10-10T18:15:38.312Z,1539195338.312 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-10T18:15:38.312Z,1539195338.312 [SyncHandler](INFO): Protected caller Thread ID is 797 2018-10-10T18:15:38.313Z,1539195338.313 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-10T18:15:38.314Z,1539195338.314 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-10T18:15:38.314Z,1539195338.314 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 798 2018-10-10T18:15:38.317Z,1539195338.317 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-10T18:15:38.328Z,1539195338.328 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-10T18:15:38.329Z,1539195338.329 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-10T18:15:38.330Z,1539195338.330 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 799 2018-10-10T18:15:38.330Z,1539195338.330 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-10T18:15:38.331Z,1539195338.331 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-10T18:15:38.332Z,1539195338.332 [logger ThreadHandler](INFO): Protected caller Thread ID is 800 2018-10-10T18:15:38.334Z,1539195338.334 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-10T18:15:38.334Z,1539195338.334 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-10T18:15:38.337Z,1539195338.337 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-10T18:15:38.437Z,1539195338.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-10T18:15:38.440Z,1539195338.440 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-10T18:15:38.542Z,1539195338.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-10T18:15:38.544Z,1539195338.544 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-10T18:15:38.755Z,1539195338.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-10T18:15:38.756Z,1539195338.756 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-10T18:15:39.099Z,1539195339.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-10T18:15:39.101Z,1539195339.101 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-10T18:15:39.451Z,1539195339.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-10T18:15:39.452Z,1539195339.452 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-10T18:15:39.928Z,1539195339.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-10T18:15:39.929Z,1539195339.929 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-10T18:15:40.413Z,1539195340.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-10T18:15:40.413Z,1539195340.413 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-10T18:15:40.890Z,1539195340.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-10T18:15:40.891Z,1539195340.891 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-10T18:15:40.974Z,1539195340.974 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-10T18:15:41.303Z,1539195341.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-10T18:15:41.305Z,1539195341.305 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-10T18:15:41.459Z,1539195341.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-10T18:15:41.460Z,1539195341.460 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-10T18:15:41.683Z,1539195341.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-10T18:15:41.685Z,1539195341.685 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-10T18:15:41.927Z,1539195341.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-10T18:15:41.928Z,1539195341.928 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-10T18:15:42.211Z,1539195342.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-10T18:15:42.464Z,1539195342.464 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-10T18:15:43.071Z,1539195343.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-10T18:15:43.073Z,1539195343.073 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-10-10T18:15:43.076Z,1539195343.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-10-10T18:15:43.354Z,1539195343.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-10T18:15:43.354Z,1539195343.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-10-10T18:15:43.463Z,1539195343.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-10-10T18:15:43.612Z,1539195343.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-10-10T18:15:43.707Z,1539195343.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-10-10T18:15:43.794Z,1539195343.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-10-10T18:15:43.946Z,1539195343.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-10-10T18:15:44.143Z,1539195344.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-10-10T18:15:44.243Z,1539195344.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-10-10T18:15:44.339Z,1539195344.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-10-10T18:15:44.438Z,1539195344.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-10-10T18:15:44.522Z,1539195344.522 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-10T18:15:44.526Z,1539195344.526 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-10T18:15:44.654Z,1539195344.654 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-10T18:15:44.655Z,1539195344.655 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-10T18:15:44.679Z,1539195344.679 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-10T18:15:44.679Z,1539195344.679 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-10T18:15:44.735Z,1539195344.735 [DepthRateCalculator] Loaded 2018-10-10T18:15:44.735Z,1539195344.735 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-10T18:15:44.741Z,1539195344.741 [PitchRateCalculator] Loaded 2018-10-10T18:15:44.742Z,1539195344.742 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-10T18:15:44.757Z,1539195344.757 [SpeedCalculator] Loaded 2018-10-10T18:15:44.758Z,1539195344.758 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-10T18:15:44.778Z,1539195344.778 [TempGradientCalculator] Loaded 2018-10-10T18:15:44.779Z,1539195344.779 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-10T18:15:44.784Z,1539195344.784 [YawRateCalculator] Loaded 2018-10-10T18:15:44.785Z,1539195344.785 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-10T18:15:44.824Z,1539195344.824 [ElevatorOffsetCalculator] Loaded 2018-10-10T18:15:44.824Z,1539195344.824 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-10T18:15:44.825Z,1539195344.825 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-10T18:15:44.825Z,1539195344.825 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-10T18:15:44.863Z,1539195344.863 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-10T18:15:44.863Z,1539195344.863 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-10T18:15:45.431Z,1539195345.431 [DataOverHttps] Loaded 2018-10-10T18:15:45.431Z,1539195345.431 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-10T18:15:45.457Z,1539195345.457 [Depth_Keller] Loaded 2018-10-10T18:15:45.457Z,1539195345.457 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-10T18:15:45.462Z,1539195345.462 [DropWeight] Loaded 2018-10-10T18:15:45.462Z,1539195345.462 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-10T18:15:45.560Z,1539195345.560 [NAL9602] Loaded 2018-10-10T18:15:45.560Z,1539195345.560 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-10T18:15:45.576Z,1539195345.576 [Onboard] Loaded 2018-10-10T18:15:45.576Z,1539195345.576 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-10T18:15:45.587Z,1539195345.587 [Radio_Surface] Loaded 2018-10-10T18:15:45.587Z,1539195345.587 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-10T18:15:45.588Z,1539195345.588 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-10-10T18:15:45.588Z,1539195345.588 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 880 2018-10-10T18:15:45.741Z,1539195345.741 [PNI_TCM] Loaded 2018-10-10T18:15:45.741Z,1539195345.741 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-10T18:15:45.802Z,1539195345.802 [Rowe_600LCM] Loaded 2018-10-10T18:15:45.802Z,1539195345.802 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-10-10T18:15:45.803Z,1539195345.803 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-10-10T18:15:45.803Z,1539195345.803 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 881 2018-10-10T18:15:47.919Z,1539195347.919 [BPC1] Loaded 2018-10-10T18:15:47.920Z,1539195347.920 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-10T18:15:47.920Z,1539195347.920 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-10T18:15:47.921Z,1539195347.921 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-10T18:15:48.072Z,1539195348.072 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-10T18:15:48.096Z,1539195348.096 [SBIT] Loaded 2018-10-10T18:15:48.096Z,1539195348.096 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-10T18:15:48.097Z,1539195348.097 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-10T18:15:48.108Z,1539195348.108 [IBIT] Loaded 2018-10-10T18:15:48.108Z,1539195348.108 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-10T18:15:48.112Z,1539195348.112 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-10T18:15:48.259Z,1539195348.259 [CBIT] Loaded 2018-10-10T18:15:48.259Z,1539195348.259 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-10T18:15:48.260Z,1539195348.260 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-10T18:15:48.260Z,1539195348.260 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-10T18:15:48.463Z,1539195348.463 [Aanderaa_O2] Loaded 2018-10-10T18:15:48.463Z,1539195348.463 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-10-10T18:15:48.515Z,1539195348.515 [CTD_Seabird] Loaded 2018-10-10T18:15:48.515Z,1539195348.515 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-10T18:15:48.516Z,1539195348.516 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BC4E0 2018-10-10T18:15:48.517Z,1539195348.517 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 882 2018-10-10T18:15:48.534Z,1539195348.534 [ESPComponent] Loaded 2018-10-10T18:15:48.534Z,1539195348.534 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-10-10T18:15:48.548Z,1539195348.548 [PAR_Licor] Loaded 2018-10-10T18:15:48.549Z,1539195348.549 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-10-10T18:15:48.597Z,1539195348.597 [WetLabsBB2FL] Loaded 2018-10-10T18:15:48.597Z,1539195348.597 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-10T18:15:48.598Z,1539195348.598 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2018-10-10T18:15:48.599Z,1539195348.599 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 883 2018-10-10T18:15:48.599Z,1539195348.599 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-10T18:15:48.600Z,1539195348.600 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-10T18:15:48.877Z,1539195348.877 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-10T18:15:48.879Z,1539195348.879 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-10T18:15:48.966Z,1539195348.966 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-10T18:15:49.060Z,1539195349.060 [VerticalControl] Loaded 2018-10-10T18:15:49.061Z,1539195349.061 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-10T18:15:49.062Z,1539195349.062 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-10T18:15:49.122Z,1539195349.122 [HorizontalControl] Loaded 2018-10-10T18:15:49.122Z,1539195349.122 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-10T18:15:49.123Z,1539195349.123 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-10T18:15:49.128Z,1539195349.128 [SpeedControl] Loaded 2018-10-10T18:15:49.128Z,1539195349.128 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-10T18:15:49.129Z,1539195349.129 [LoopControl](DEBUG): Construct LoopControl. 2018-10-10T18:15:49.130Z,1539195349.130 [LoopControl] Loaded 2018-10-10T18:15:49.130Z,1539195349.130 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-10T18:15:49.130Z,1539195349.130 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-10T18:15:49.131Z,1539195349.131 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-10T18:15:49.234Z,1539195349.234 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-10T18:15:49.235Z,1539195349.235 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-10T18:15:49.354Z,1539195349.354 [BuoyancyServo] Loaded 2018-10-10T18:15:49.354Z,1539195349.354 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-10T18:15:49.365Z,1539195349.365 [ElevatorServo] Loaded 2018-10-10T18:15:49.366Z,1539195349.366 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-10T18:15:49.377Z,1539195349.377 [MassServo] Loaded 2018-10-10T18:15:49.377Z,1539195349.377 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-10T18:15:49.388Z,1539195349.388 [RudderServo] Loaded 2018-10-10T18:15:49.388Z,1539195349.388 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-10T18:15:49.399Z,1539195349.399 [ThrusterServo] Loaded 2018-10-10T18:15:49.400Z,1539195349.400 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-10T18:15:49.400Z,1539195349.400 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-10T18:15:49.401Z,1539195349.401 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-10T18:15:49.507Z,1539195349.507 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-10T18:15:49.507Z,1539195349.507 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-10T18:15:49.523Z,1539195349.523 [NavChart] Loaded 2018-10-10T18:15:49.524Z,1539195349.524 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-10T18:15:49.528Z,1539195349.528 [UniversalFixResidualReporter] Loaded 2018-10-10T18:15:49.528Z,1539195349.528 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-10T18:15:49.528Z,1539195349.528 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-10T18:15:49.532Z,1539195349.532 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-10T18:15:49.533Z,1539195349.533 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-10T18:15:49.540Z,1539195349.540 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-10T18:15:49.541Z,1539195349.541 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2018-10-10T18:15:49.541Z,1539195349.541 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884 2018-10-10T18:15:49.546Z,1539195349.546 [Supervisor](INFO): Main Thread ID is 796 2018-10-10T18:15:49.546Z,1539195349.546 [Supervisor](DEBUG): Running supervisor. 2018-10-10T18:15:49.547Z,1539195349.547 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885 2018-10-10T18:15:49.549Z,1539195349.549 [controlThread ThreadHandler](INFO): Handler Thread ID is 886 2018-10-10T18:15:49.549Z,1539195349.549 [controlThread](DEBUG): Initializing ControlThread 2018-10-10T18:15:49.550Z,1539195349.550 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-10T18:15:49.551Z,1539195349.551 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-10T18:15:49.551Z,1539195349.551 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-10T18:15:49.551Z,1539195349.551 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-10T18:15:49.552Z,1539195349.552 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-10T18:15:49.552Z,1539195349.552 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-10T18:15:49.556Z,1539195349.556 [SBIT](INFO): Initialize SBIT Component. 2018-10-10T18:15:49.557Z,1539195349.557 [SBIT](IMPORTANT): git: 2018-10-09-5-g8e0f966 2018-10-10T18:15:49.557Z,1539195349.557 [SBIT](INFO): git hash: 8e0f9669898461179fe27a6b90da366188de3daf 2018-10-10T18:15:49.557Z,1539195349.557 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-10T18:15:49.558Z,1539195349.558 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-10T18:15:49.559Z,1539195349.559 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-10-10T18:15:49.559Z,1539195349.559 [IBIT](INFO): Initialize IBIT Component. 2018-10-10T18:15:49.560Z,1539195349.560 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-10T18:15:49.560Z,1539195349.560 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-10T18:15:49.561Z,1539195349.561 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-10-10T18:15:49.562Z,1539195349.562 [logger ThreadHandler](INFO): Handler Thread ID is 887 2018-10-10T18:15:49.578Z,1539195349.578 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 888 2018-10-10T18:15:49.584Z,1539195349.584 [Radio_Surface](INFO): Powering up 2018-10-10T18:15:49.590Z,1539195349.590 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 889 2018-10-10T18:15:49.591Z,1539195349.591 [Rowe_600LCM](INFO): Initializing 2018-10-10T18:15:49.591Z,1539195349.591 [Rowe_600LCM](INFO): Checking LCM 2018-10-10T18:15:49.676Z,1539195349.676 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 890 2018-10-10T18:15:49.682Z,1539195349.682 [CTD_Seabird](INFO): Initializing 2018-10-10T18:15:49.683Z,1539195349.683 [CTD_Seabird](INFO): Checking LCM 2018-10-10T18:15:49.684Z,1539195349.684 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 891 2018-10-10T18:15:49.685Z,1539195349.685 [WetLabsBB2FL](INFO): Powering down 2018-10-10T18:15:49.706Z,1539195349.706 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-10T18:15:49.708Z,1539195349.708 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-10T18:15:49.709Z,1539195349.709 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-10T18:15:49.709Z,1539195349.709 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-10T18:15:49.719Z,1539195349.719 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-10T18:15:49.719Z,1539195349.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-10T18:15:49.720Z,1539195349.720 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-10T18:15:49.720Z,1539195349.720 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-10T18:15:49.721Z,1539195349.721 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-10T18:15:49.721Z,1539195349.721 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-10T18:15:49.821Z,1539195349.821 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892 2018-10-10T18:15:49.959Z,1539195349.959 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-10-10T18:15:49.963Z,1539195349.963 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T18:15:49.971Z,1539195349.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-10T18:15:49.972Z,1539195349.972 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-10-10T18:15:49.973Z,1539195349.973 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-10T18:15:49.974Z,1539195349.974 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-10-10T18:15:49.974Z,1539195349.974 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-10T18:15:49.976Z,1539195349.976 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-10-10T18:15:49.976Z,1539195349.976 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-10T18:15:49.977Z,1539195349.977 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-10-10T18:15:49.978Z,1539195349.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-10T18:15:49.979Z,1539195349.979 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-10-10T18:15:49.979Z,1539195349.979 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-10T18:15:49.981Z,1539195349.981 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-10-10T18:15:49.981Z,1539195349.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-10T18:15:49.986Z,1539195349.986 [CTD_Seabird](INFO): LCM OK 2018-10-10T18:15:49.986Z,1539195349.986 [CTD_Seabird](INFO): Powering up 2018-10-10T18:15:49.987Z,1539195349.987 [Rowe_600LCM](INFO): LCM OK 2018-10-10T18:15:49.987Z,1539195349.987 [Rowe_600LCM](INFO): Powering up 2018-10-10T18:15:49.988Z,1539195349.988 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-10-10T18:15:49.988Z,1539195349.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T18:15:49.996Z,1539195349.996 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-10-10T18:15:50.025Z,1539195350.025 [MissionManager](DEBUG): 2018-10-10T18:15:50.026Z,1539195350.026 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-10T18:15:50.070Z,1539195350.070 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T18:15:50.098Z,1539195350.098 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-10T18:15:50.099Z,1539195350.099 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-10T18:15:50.101Z,1539195350.101 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T18:15:50.127Z,1539195350.127 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-10T18:15:50.130Z,1539195350.130 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-10T18:15:50.148Z,1539195350.148 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-10T18:15:50.152Z,1539195350.152 [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-10T18:15:50.166Z,1539195350.166 [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-10T18:15:50.192Z,1539195350.192 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-10T18:15:50.220Z,1539195350.220 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar 2018-10-10T18:15:50.357Z,1539195350.357 [ESPComponent](INFO): powering down ESP 2018-10-10T18:15:50.687Z,1539195350.687 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-10-10T18:15:50.762Z,1539195350.762 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:50.988Z,1539195350.988 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:50.994Z,1539195350.994 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T18:15:51.017Z,1539195351.017 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:51.022Z,1539195351.022 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T18:15:51.029Z,1539195351.029 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:51.042Z,1539195351.042 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T18:15:51.049Z,1539195351.049 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:51.054Z,1539195351.054 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T18:15:51.060Z,1539195351.060 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:51.066Z,1539195351.066 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T18:15:51.316Z,1539195351.316 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-10T18:15:51.316Z,1539195351.316 [DropWeight] Hardware Fault, FailCount= 1 2018-10-10T18:15:51.316Z,1539195351.316 [DropWeight](ERROR): Hardware Fault 2018-10-10T18:15:51.388Z,1539195351.388 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:51.422Z,1539195351.422 [CommandLine](FAULT): Scheduling is paused 2018-10-10T18:15:51.423Z,1539195351.423 [CBIT](INFO): Critical error at 20181010T181551 2018-10-10T18:15:51.423Z,1539195351.423 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-10T18:15:51.426Z,1539195351.426 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-10T18:15:51.426Z,1539195351.426 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-10T18:15:51.612Z,1539195351.612 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:51.938Z,1539195351.938 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T18:15:51.938Z,1539195351.938 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T18:15:51.938Z,1539195351.938 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-10T18:15:51.938Z,1539195351.938 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T18:15:52.250Z,1539195352.250 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T18:15:52.250Z,1539195352.250 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T18:15:52.250Z,1539195352.250 [ElevatorServo] Communications Fault, FailCount= 1 2018-10-10T18:15:52.250Z,1539195352.250 [ElevatorServo](ERROR): Communications Fault 2018-10-10T18:15:52.562Z,1539195352.562 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T18:15:52.562Z,1539195352.562 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T18:15:52.562Z,1539195352.562 [RudderServo] Communications Fault, FailCount= 1 2018-10-10T18:15:52.562Z,1539195352.562 [RudderServo](ERROR): Communications Fault 2018-10-10T18:15:52.886Z,1539195352.886 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T18:15:52.911Z,1539195352.911 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T18:15:52.911Z,1539195352.911 [ThrusterServo] Communications Fault, FailCount= 1 2018-10-10T18:15:52.912Z,1539195352.912 [ThrusterServo](ERROR): Communications Fault 2018-10-10T18:15:52.922Z,1539195352.922 [CBIT](INFO): Critical error at 20181010T181551 2018-10-10T18:15:52.930Z,1539195352.930 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T18:15:52.931Z,1539195352.931 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T18:15:52.933Z,1539195352.933 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T18:15:52.986Z,1539195352.986 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T18:15:53.096Z,1539195353.096 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:53.143Z,1539195353.143 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T18:15:53.143Z,1539195353.143 [BuoyancyServo](INFO): Powering down 2018-10-10T18:15:53.246Z,1539195353.246 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T18:15:53.247Z,1539195353.247 [ElevatorServo](INFO): Powering down 2018-10-10T18:15:53.367Z,1539195353.367 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T18:15:53.367Z,1539195353.367 [RudderServo](INFO): Powering down 2018-10-10T18:15:53.461Z,1539195353.461 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T18:15:53.461Z,1539195353.461 [ThrusterServo](INFO): Powering down 2018-10-10T18:15:53.706Z,1539195353.706 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:53.925Z,1539195353.925 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-10-10T18:15:54.002Z,1539195354.002 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T18:15:54.002Z,1539195354.002 [MassServo] Communications Fault, FailCount= 1 2018-10-10T18:15:54.002Z,1539195354.002 [MassServo](ERROR): Communications Fault 2018-10-10T18:15:54.008Z,1539195354.008 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T18:15:54.055Z,1539195354.055 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:54.063Z,1539195354.063 [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-10T18:15:54.096Z,1539195354.096 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:54.097Z,1539195354.097 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T18:15:54.111Z,1539195354.111 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T18:15:54.111Z,1539195354.111 [MassServo](INFO): Powering down 2018-10-10T18:15:54.230Z,1539195354.230 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-10-10T18:15:54.231Z,1539195354.231 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-10-10T18:15:54.231Z,1539195354.231 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-10-10T18:15:54.232Z,1539195354.232 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:54.233Z,1539195354.233 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T18:15:54.252Z,1539195354.252 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T18:15:54.252Z,1539195354.252 [ElevatorServo] No Fault, FailCount= 1 2018-10-10T18:15:54.252Z,1539195354.252 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T18:15:54.252Z,1539195354.252 [RudderServo] No Fault, FailCount= 1 2018-10-10T18:15:54.253Z,1539195354.253 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T18:15:54.253Z,1539195354.253 [ThrusterServo] No Fault, FailCount= 1 2018-10-10T18:15:54.493Z,1539195354.493 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:54.519Z,1539195354.519 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:54.519Z,1539195354.519 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T18:15:54.907Z,1539195354.907 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:54.987Z,1539195354.987 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:54.987Z,1539195354.987 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T18:15:55.177Z,1539195355.177 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T18:15:55.273Z,1539195355.273 [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-10T18:15:55.274Z,1539195355.274 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-10T18:15:55.370Z,1539195355.370 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T18:15:55.450Z,1539195355.450 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T18:15:55.450Z,1539195355.450 [ThrusterServo] Communications Fault, FailCount= 2 2018-10-10T18:15:55.450Z,1539195355.450 [ThrusterServo](ERROR): Communications Fault 2018-10-10T18:15:55.459Z,1539195355.459 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T18:15:55.459Z,1539195355.459 [MassServo] No Fault, FailCount= 1 2018-10-10T18:15:55.460Z,1539195355.460 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T18:15:55.547Z,1539195355.547 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:55.623Z,1539195355.623 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T18:15:55.623Z,1539195355.623 [ThrusterServo](INFO): Powering down 2018-10-10T18:15:55.644Z,1539195355.644 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T18:15:55.644Z,1539195355.644 [BuoyancyServo] No Fault, FailCount= 1 2018-10-10T18:15:55.665Z,1539195355.665 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T18:15:55.665Z,1539195355.665 [CTD_Seabird](INFO): Powering down 2018-10-10T18:15:55.919Z,1539195355.919 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:55.928Z,1539195355.928 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:55.928Z,1539195355.928 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T18:15:56.323Z,1539195356.323 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:56.349Z,1539195356.349 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T18:15:56.349Z,1539195356.349 [ThrusterServo] No Fault, FailCount= 2 2018-10-10T18:15:56.727Z,1539195356.727 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:56.830Z,1539195356.830 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T18:15:56.830Z,1539195356.830 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T18:15:56.830Z,1539195356.830 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-10T18:15:56.830Z,1539195356.830 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T18:15:57.158Z,1539195357.158 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-10-10T18:15:57.158Z,1539195357.158 [MassServo] Communications Fault, FailCount= 2 2018-10-10T18:15:57.158Z,1539195357.158 [MassServo](ERROR): Communications Fault 2018-10-10T18:15:57.183Z,1539195357.183 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:57.184Z,1539195357.184 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T18:15:57.188Z,1539195357.188 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T18:15:57.189Z,1539195357.189 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T18:15:57.283Z,1539195357.283 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:57.308Z,1539195357.308 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T18:15:57.309Z,1539195357.309 [BuoyancyServo](INFO): Powering down 2018-10-10T18:15:57.327Z,1539195357.327 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T18:15:57.327Z,1539195357.327 [MassServo](INFO): Powering down 2018-10-10T18:15:57.669Z,1539195357.669 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:57.986Z,1539195357.986 [RudderServo](ERROR): getPosition uart error serial timeout 2018-10-10T18:15:57.986Z,1539195357.986 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2018-10-10T18:15:57.986Z,1539195357.986 [RudderServo] Communications Fault, FailCount= 2 2018-10-10T18:15:57.986Z,1539195357.986 [RudderServo](ERROR): Communications Fault 2018-10-10T18:15:58.314Z,1539195358.314 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T18:15:58.339Z,1539195358.339 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T18:15:58.339Z,1539195358.339 [ThrusterServo] Communications Fault, FailCount= 3 2018-10-10T18:15:58.340Z,1539195358.340 [ThrusterServo](ERROR): Communications Fault 2018-10-10T18:15:58.355Z,1539195358.355 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T18:15:58.356Z,1539195358.356 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T18:15:58.471Z,1539195358.471 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:58.479Z,1539195358.479 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:58.480Z,1539195358.480 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T18:15:58.481Z,1539195358.481 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T18:15:58.481Z,1539195358.481 [RudderServo](INFO): Powering down 2018-10-10T18:15:58.487Z,1539195358.487 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T18:15:58.487Z,1539195358.487 [ThrusterServo](INFO): Powering down 2018-10-10T18:15:58.508Z,1539195358.508 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T18:15:58.508Z,1539195358.508 [MassServo] No Fault, FailCount= 2 2018-10-10T18:15:58.802Z,1539195358.802 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T18:15:58.803Z,1539195358.803 [PNI_TCM] Data Fault, FailCount= 1 2018-10-10T18:15:58.803Z,1539195358.803 [PNI_TCM](ERROR): Data Fault 2018-10-10T18:15:58.850Z,1539195358.850 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:58.874Z,1539195358.874 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T18:15:59.204Z,1539195359.204 [PNI_TCM](INFO): Powering down 2018-10-10T18:15:59.310Z,1539195359.310 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:59.350Z,1539195359.350 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:59.351Z,1539195359.351 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T18:15:59.382Z,1539195359.382 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T18:15:59.383Z,1539195359.383 [BuoyancyServo] No Fault, FailCount= 2 2018-10-10T18:15:59.384Z,1539195359.384 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T18:15:59.384Z,1539195359.384 [RudderServo] No Fault, FailCount= 2 2018-10-10T18:15:59.385Z,1539195359.385 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T18:15:59.418Z,1539195359.418 [ThrusterServo] No Fault, FailCount= 3 2018-10-10T18:15:59.642Z,1539195359.642 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:15:59.653Z,1539195359.653 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:59.658Z,1539195359.658 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T18:15:59.661Z,1539195359.661 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:15:59.661Z,1539195359.661 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T18:15:59.674Z,1539195359.674 [CBIT](CRITICAL): Environmental Failure. Press:14.690842 PSI. Humidity:40%. Temp:26 C. ABORTING MISSION 2018-10-10T18:16:00.054Z,1539195360.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:00.120Z,1539195360.120 [CBIT](INFO): Critical error at 20181010T181559 2018-10-10T18:16:00.405Z,1539195360.405 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T18:16:00.405Z,1539195360.405 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-10-10T18:16:00.405Z,1539195360.405 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T18:16:00.434Z,1539195360.434 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:00.774Z,1539195360.774 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T18:16:00.774Z,1539195360.774 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T18:16:00.774Z,1539195360.774 [BuoyancyServo] Communications Fault, FailCount= 3 2018-10-10T18:16:00.774Z,1539195360.774 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T18:16:00.906Z,1539195360.906 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T18:16:00.906Z,1539195360.906 [PNI_TCM] No Fault, FailCount= 1 2018-10-10T18:16:00.906Z,1539195360.906 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T18:16:00.907Z,1539195360.907 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T18:16:00.955Z,1539195360.955 [Aanderaa_O2](INFO): Powering down 2018-10-10T18:16:01.032Z,1539195361.032 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:01.047Z,1539195361.047 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T18:16:01.048Z,1539195361.048 [BuoyancyServo](INFO): Powering down 2018-10-10T18:16:01.315Z,1539195361.315 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T18:16:01.315Z,1539195361.315 [MassServo] Communications Fault, FailCount= 3 2018-10-10T18:16:01.315Z,1539195361.315 [MassServo](ERROR): Communications Fault 2018-10-10T18:16:01.320Z,1539195361.320 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T18:16:01.371Z,1539195361.371 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:01.388Z,1539195361.388 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T18:16:01.388Z,1539195361.388 [MassServo](INFO): Powering down 2018-10-10T18:16:01.726Z,1539195361.726 [RudderServo](FAULT): Rudder initialization uart error:serial timeout 2018-10-10T18:16:01.726Z,1539195361.726 [RudderServo] Communications Fault, FailCount= 3 2018-10-10T18:16:01.726Z,1539195361.726 [RudderServo](ERROR): Communications Fault 2018-10-10T18:16:01.731Z,1539195361.731 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T18:16:01.731Z,1539195361.731 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-10-10T18:16:01.821Z,1539195361.821 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:01.846Z,1539195361.846 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T18:16:01.846Z,1539195361.846 [RudderServo](INFO): Powering down 2018-10-10T18:16:01.854Z,1539195361.854 [CBIT](INFO): Critical error at 20181010T181601 2018-10-10T18:16:01.856Z,1539195361.856 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T18:16:01.856Z,1539195361.856 [Aanderaa_O2] No Fault, FailCount= 1 2018-10-10T18:16:02.183Z,1539195362.183 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:02.213Z,1539195362.213 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:16:02.213Z,1539195362.213 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T18:16:02.235Z,1539195362.235 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T18:16:02.235Z,1539195362.235 [MassServo] No Fault, FailCount= 3 2018-10-10T18:16:02.588Z,1539195362.588 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:02.621Z,1539195362.621 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:16:02.621Z,1539195362.621 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T18:16:02.938Z,1539195362.938 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2018-10-10T18:16:02.938Z,1539195362.938 [ThrusterServo] Communications Fault, FailCount= 4 2018-10-10T18:16:02.938Z,1539195362.938 [ThrusterServo](ERROR): Communications Fault 2018-10-10T18:16:02.943Z,1539195362.943 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T18:16:03.053Z,1539195363.053 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:03.119Z,1539195363.119 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T18:16:03.120Z,1539195363.120 [ThrusterServo](INFO): Powering down 2018-10-10T18:16:03.125Z,1539195363.125 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T18:16:03.125Z,1539195363.125 [BuoyancyServo] No Fault, FailCount= 3 2018-10-10T18:16:03.401Z,1539195363.401 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:03.428Z,1539195363.428 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:16:03.428Z,1539195363.428 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T18:16:03.604Z,1539195363.604 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T18:16:03.790Z,1539195363.790 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:03.831Z,1539195363.831 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T18:16:03.831Z,1539195363.831 [ThrusterServo] No Fault, FailCount= 4 2018-10-10T18:16:04.218Z,1539195364.218 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:04.326Z,1539195364.326 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T18:16:04.326Z,1539195364.326 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T18:16:04.326Z,1539195364.326 [BuoyancyServo] Communications Fault, FailCount= 4 2018-10-10T18:16:04.326Z,1539195364.326 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T18:16:04.328Z,1539195364.328 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:16:04.329Z,1539195364.329 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T18:16:04.333Z,1539195364.333 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T18:16:04.618Z,1539195364.618 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:04.633Z,1539195364.633 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T18:16:04.633Z,1539195364.633 [BuoyancyServo](INFO): Powering down 2018-10-10T18:16:05.006Z,1539195365.006 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:05.387Z,1539195365.387 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:05.817Z,1539195365.817 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:06.150Z,1539195366.150 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T18:16:06.150Z,1539195366.150 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T18:16:06.150Z,1539195366.150 [MassServo] Communications Fault, FailCount= 4 2018-10-10T18:16:06.150Z,1539195366.150 [MassServo](ERROR): Communications Fault 2018-10-10T18:16:06.151Z,1539195366.151 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T18:16:06.151Z,1539195366.151 [MassServo] Hardware Fault, FailCount= 4 2018-10-10T18:16:06.151Z,1539195366.151 [MassServo](ERROR): Hardware Fault 2018-10-10T18:16:06.487Z,1539195366.487 [MassServo](FAULT): Invalid EZ Servo response:"98\r" 2018-10-10T18:16:06.487Z,1539195366.487 [MassServo] Communications Fault, FailCount= 4 2018-10-10T18:16:06.487Z,1539195366.487 [MassServo](ERROR): Communications Fault 2018-10-10T18:16:06.492Z,1539195366.492 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T18:16:06.567Z,1539195366.567 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:06.585Z,1539195366.585 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T18:16:06.585Z,1539195366.585 [MassServo](INFO): Powering down 2018-10-10T18:16:06.898Z,1539195366.898 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2018-10-10T18:16:06.898Z,1539195366.898 [ThrusterServo] Communications Fault, FailCount= 5 2018-10-10T18:16:06.898Z,1539195366.898 [ThrusterServo](ERROR): Communications Fault 2018-10-10T18:16:06.903Z,1539195366.903 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T18:16:06.903Z,1539195366.903 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2018-10-10T18:16:06.971Z,1539195366.971 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:06.979Z,1539195366.979 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T18:16:06.979Z,1539195366.979 [ThrusterServo](INFO): Powering down 2018-10-10T18:16:06.987Z,1539195366.987 [CBIT](INFO): Critical error at 20181010T181606 2018-10-10T18:16:06.989Z,1539195366.989 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T18:16:06.989Z,1539195366.989 [BuoyancyServo] No Fault, FailCount= 4 2018-10-10T18:16:07.369Z,1539195367.369 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:07.377Z,1539195367.377 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:16:07.377Z,1539195367.377 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T18:16:07.383Z,1539195367.383 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T18:16:07.384Z,1539195367.384 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T18:16:07.393Z,1539195367.393 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T18:16:07.393Z,1539195367.393 [MassServo] No Fault, FailCount= 4 2018-10-10T18:16:07.754Z,1539195367.754 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:08.138Z,1539195368.138 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T18:16:08.139Z,1539195368.139 [PNI_TCM] Data Fault, FailCount= 2 2018-10-10T18:16:08.139Z,1539195368.139 [PNI_TCM](ERROR): Data Fault 2018-10-10T18:16:08.191Z,1539195368.191 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T18:16:08.326Z,1539195368.326 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T18:16:08.326Z,1539195368.326 [BuoyancyServo](FAULT): Buoyancy failed to initial