2018-10-10T17:51:40.941Z,1539193900.941 [Supervisor](DEBUG): Initializing supervisor. 2018-10-10T17:51:40.944Z,1539193900.944 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-10T17:51:40.945Z,1539193900.945 [SyncHandler](INFO): Protected caller Thread ID is 797 2018-10-10T17:51:40.945Z,1539193900.945 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-10T17:51:40.946Z,1539193900.946 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-10T17:51:40.946Z,1539193900.946 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 798 2018-10-10T17:51:40.949Z,1539193900.949 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-10T17:51:40.960Z,1539193900.960 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-10T17:51:40.961Z,1539193900.961 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-10T17:51:40.962Z,1539193900.962 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 799 2018-10-10T17:51:40.963Z,1539193900.963 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-10T17:51:40.964Z,1539193900.964 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-10T17:51:40.964Z,1539193900.964 [logger ThreadHandler](INFO): Protected caller Thread ID is 800 2018-10-10T17:51:40.966Z,1539193900.966 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-10T17:51:40.966Z,1539193900.966 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-10T17:51:40.969Z,1539193900.969 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-10T17:51:41.070Z,1539193901.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-10T17:51:41.072Z,1539193901.072 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-10T17:51:41.175Z,1539193901.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-10T17:51:41.177Z,1539193901.177 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-10T17:51:41.388Z,1539193901.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-10T17:51:41.388Z,1539193901.388 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-10T17:51:41.732Z,1539193901.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-10T17:51:41.734Z,1539193901.734 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-10T17:51:42.084Z,1539193902.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-10T17:51:42.085Z,1539193902.085 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-10T17:51:42.561Z,1539193902.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-10T17:51:42.563Z,1539193902.563 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-10T17:51:43.046Z,1539193903.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-10T17:51:43.046Z,1539193903.046 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-10T17:51:43.525Z,1539193903.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-10T17:51:43.526Z,1539193903.526 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-10T17:51:43.609Z,1539193903.609 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-10T17:51:43.954Z,1539193903.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-10T17:51:43.956Z,1539193903.956 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-10T17:51:44.110Z,1539193904.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-10T17:51:44.111Z,1539193904.111 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-10T17:51:44.334Z,1539193904.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-10T17:51:44.336Z,1539193904.336 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-10T17:51:44.551Z,1539193904.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-10T17:51:44.551Z,1539193904.551 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-10T17:51:44.693Z,1539193904.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-10T17:51:44.853Z,1539193904.853 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-10T17:51:45.726Z,1539193905.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-10T17:51:45.728Z,1539193905.728 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-10-10T17:51:45.771Z,1539193905.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-10-10T17:51:46.004Z,1539193906.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-10T17:51:46.005Z,1539193906.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-10-10T17:51:46.114Z,1539193906.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-10-10T17:51:46.262Z,1539193906.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-10-10T17:51:46.358Z,1539193906.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-10-10T17:51:46.445Z,1539193906.445 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-10-10T17:51:46.641Z,1539193906.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-10-10T17:51:46.838Z,1539193906.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-10-10T17:51:46.938Z,1539193906.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-10-10T17:51:47.034Z,1539193907.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-10-10T17:51:47.134Z,1539193907.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-10-10T17:51:47.218Z,1539193907.218 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-10T17:51:47.222Z,1539193907.222 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-10T17:51:47.350Z,1539193907.350 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-10T17:51:47.350Z,1539193907.350 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-10T17:51:47.374Z,1539193907.374 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-10T17:51:47.375Z,1539193907.375 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-10T17:51:47.430Z,1539193907.430 [DepthRateCalculator] Loaded 2018-10-10T17:51:47.430Z,1539193907.430 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-10T17:51:47.436Z,1539193907.436 [PitchRateCalculator] Loaded 2018-10-10T17:51:47.437Z,1539193907.437 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-10T17:51:47.452Z,1539193907.452 [SpeedCalculator] Loaded 2018-10-10T17:51:47.453Z,1539193907.453 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-10T17:51:47.474Z,1539193907.474 [TempGradientCalculator] Loaded 2018-10-10T17:51:47.474Z,1539193907.474 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-10T17:51:47.480Z,1539193907.480 [YawRateCalculator] Loaded 2018-10-10T17:51:47.480Z,1539193907.480 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-10T17:51:47.519Z,1539193907.519 [ElevatorOffsetCalculator] Loaded 2018-10-10T17:51:47.520Z,1539193907.520 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-10T17:51:47.520Z,1539193907.520 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-10T17:51:47.521Z,1539193907.521 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-10T17:51:47.559Z,1539193907.559 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-10T17:51:47.559Z,1539193907.559 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-10T17:51:48.128Z,1539193908.128 [DataOverHttps] Loaded 2018-10-10T17:51:48.128Z,1539193908.128 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-10T17:51:48.155Z,1539193908.155 [Depth_Keller] Loaded 2018-10-10T17:51:48.156Z,1539193908.156 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-10T17:51:48.161Z,1539193908.161 [DropWeight] Loaded 2018-10-10T17:51:48.161Z,1539193908.161 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-10T17:51:48.259Z,1539193908.259 [NAL9602] Loaded 2018-10-10T17:51:48.259Z,1539193908.259 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-10T17:51:48.275Z,1539193908.275 [Onboard] Loaded 2018-10-10T17:51:48.276Z,1539193908.276 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-10T17:51:48.286Z,1539193908.286 [Radio_Surface] Loaded 2018-10-10T17:51:48.286Z,1539193908.286 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-10T17:51:48.287Z,1539193908.287 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-10-10T17:51:48.288Z,1539193908.288 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 880 2018-10-10T17:51:48.440Z,1539193908.440 [PNI_TCM] Loaded 2018-10-10T17:51:48.441Z,1539193908.441 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-10T17:51:48.502Z,1539193908.502 [Rowe_600LCM] Loaded 2018-10-10T17:51:48.502Z,1539193908.502 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-10-10T17:51:48.503Z,1539193908.503 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-10-10T17:51:48.504Z,1539193908.504 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 881 2018-10-10T17:51:50.258Z,1539193910.258 [BPC1] Loaded 2018-10-10T17:51:50.259Z,1539193910.259 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-10T17:51:50.259Z,1539193910.259 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-10T17:51:50.260Z,1539193910.260 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-10T17:51:50.765Z,1539193910.765 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-10T17:51:50.811Z,1539193910.811 [SBIT] Loaded 2018-10-10T17:51:50.811Z,1539193910.811 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-10T17:51:50.812Z,1539193910.812 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-10T17:51:50.824Z,1539193910.824 [IBIT] Loaded 2018-10-10T17:51:50.824Z,1539193910.824 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-10T17:51:50.827Z,1539193910.827 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-10T17:51:50.975Z,1539193910.975 [CBIT] Loaded 2018-10-10T17:51:50.975Z,1539193910.975 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-10T17:51:50.975Z,1539193910.975 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-10T17:51:50.976Z,1539193910.976 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-10T17:51:51.181Z,1539193911.181 [Aanderaa_O2] Loaded 2018-10-10T17:51:51.181Z,1539193911.181 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-10-10T17:51:51.233Z,1539193911.233 [CTD_Seabird] Loaded 2018-10-10T17:51:51.233Z,1539193911.233 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-10T17:51:51.234Z,1539193911.234 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BC4E0 2018-10-10T17:51:51.235Z,1539193911.235 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 882 2018-10-10T17:51:51.252Z,1539193911.252 [ESPComponent] Loaded 2018-10-10T17:51:51.252Z,1539193911.252 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-10-10T17:51:51.266Z,1539193911.266 [PAR_Licor] Loaded 2018-10-10T17:51:51.266Z,1539193911.266 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-10-10T17:51:51.315Z,1539193911.315 [WetLabsBB2FL] Loaded 2018-10-10T17:51:51.315Z,1539193911.315 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-10T17:51:51.316Z,1539193911.316 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2018-10-10T17:51:51.317Z,1539193911.317 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 883 2018-10-10T17:51:51.317Z,1539193911.317 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-10T17:51:51.318Z,1539193911.318 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-10T17:51:51.598Z,1539193911.598 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-10T17:51:51.600Z,1539193911.600 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-10T17:51:51.686Z,1539193911.686 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-10T17:51:51.781Z,1539193911.781 [VerticalControl] Loaded 2018-10-10T17:51:51.781Z,1539193911.781 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-10T17:51:51.782Z,1539193911.782 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-10T17:51:51.843Z,1539193911.843 [HorizontalControl] Loaded 2018-10-10T17:51:51.843Z,1539193911.843 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-10T17:51:51.844Z,1539193911.844 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-10T17:51:51.849Z,1539193911.849 [SpeedControl] Loaded 2018-10-10T17:51:51.849Z,1539193911.849 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-10T17:51:51.850Z,1539193911.850 [LoopControl](DEBUG): Construct LoopControl. 2018-10-10T17:51:51.851Z,1539193911.851 [LoopControl] Loaded 2018-10-10T17:51:51.851Z,1539193911.851 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-10T17:51:51.851Z,1539193911.851 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-10T17:51:51.852Z,1539193911.852 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-10T17:51:51.955Z,1539193911.955 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-10T17:51:51.956Z,1539193911.956 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-10T17:51:52.076Z,1539193912.076 [BuoyancyServo] Loaded 2018-10-10T17:51:52.076Z,1539193912.076 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-10T17:51:52.087Z,1539193912.087 [ElevatorServo] Loaded 2018-10-10T17:51:52.088Z,1539193912.088 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-10T17:51:52.099Z,1539193912.099 [MassServo] Loaded 2018-10-10T17:51:52.099Z,1539193912.099 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-10T17:51:52.110Z,1539193912.110 [RudderServo] Loaded 2018-10-10T17:51:52.111Z,1539193912.111 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-10T17:51:52.122Z,1539193912.122 [ThrusterServo] Loaded 2018-10-10T17:51:52.122Z,1539193912.122 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-10T17:51:52.122Z,1539193912.122 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-10T17:51:52.123Z,1539193912.123 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-10T17:51:52.230Z,1539193912.230 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-10T17:51:52.231Z,1539193912.231 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-10T17:51:52.247Z,1539193912.247 [NavChart] Loaded 2018-10-10T17:51:52.247Z,1539193912.247 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-10T17:51:52.251Z,1539193912.251 [UniversalFixResidualReporter] Loaded 2018-10-10T17:51:52.252Z,1539193912.252 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-10T17:51:52.252Z,1539193912.252 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-10T17:51:52.256Z,1539193912.256 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-10T17:51:52.257Z,1539193912.257 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-10T17:51:52.263Z,1539193912.263 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-10T17:51:52.264Z,1539193912.264 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2018-10-10T17:51:52.265Z,1539193912.265 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884 2018-10-10T17:51:52.269Z,1539193912.269 [Supervisor](INFO): Main Thread ID is 796 2018-10-10T17:51:52.269Z,1539193912.270 [Supervisor](DEBUG): Running supervisor. 2018-10-10T17:51:52.270Z,1539193912.270 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885 2018-10-10T17:51:52.273Z,1539193912.273 [controlThread ThreadHandler](INFO): Handler Thread ID is 886 2018-10-10T17:51:52.273Z,1539193912.273 [controlThread](DEBUG): Initializing ControlThread 2018-10-10T17:51:52.274Z,1539193912.274 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-10T17:51:52.274Z,1539193912.274 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-10T17:51:52.275Z,1539193912.275 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-10T17:51:52.275Z,1539193912.275 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-10T17:51:52.276Z,1539193912.276 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-10T17:51:52.276Z,1539193912.276 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-10T17:51:52.283Z,1539193912.283 [SBIT](INFO): Initialize SBIT Component. 2018-10-10T17:51:52.283Z,1539193912.283 [SBIT](IMPORTANT): git: 2018-10-09-5-g8e0f966 2018-10-10T17:51:52.283Z,1539193912.283 [SBIT](INFO): git hash: 8e0f9669898461179fe27a6b90da366188de3daf 2018-10-10T17:51:52.284Z,1539193912.284 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-10T17:51:52.284Z,1539193912.284 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-10T17:51:52.285Z,1539193912.285 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-10-10T17:51:52.286Z,1539193912.286 [IBIT](INFO): Initialize IBIT Component. 2018-10-10T17:51:52.287Z,1539193912.287 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-10T17:51:52.287Z,1539193912.287 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-10T17:51:52.287Z,1539193912.287 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-10-10T17:51:52.288Z,1539193912.288 [logger ThreadHandler](INFO): Handler Thread ID is 887 2018-10-10T17:51:52.305Z,1539193912.305 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 888 2018-10-10T17:51:52.311Z,1539193912.311 [Radio_Surface](INFO): Powering up 2018-10-10T17:51:52.317Z,1539193912.317 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 889 2018-10-10T17:51:52.317Z,1539193912.317 [Rowe_600LCM](INFO): Initializing 2018-10-10T17:51:52.318Z,1539193912.318 [Rowe_600LCM](INFO): Checking LCM 2018-10-10T17:51:52.402Z,1539193912.402 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 890 2018-10-10T17:51:52.405Z,1539193912.405 [CTD_Seabird](INFO): Initializing 2018-10-10T17:51:52.405Z,1539193912.405 [CTD_Seabird](INFO): Checking LCM 2018-10-10T17:51:52.406Z,1539193912.406 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 891 2018-10-10T17:51:52.407Z,1539193912.407 [WetLabsBB2FL](INFO): Powering down 2018-10-10T17:51:52.433Z,1539193912.433 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892 2018-10-10T17:51:52.434Z,1539193912.434 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-10T17:51:52.437Z,1539193912.437 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-10T17:51:52.438Z,1539193912.438 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-10T17:51:52.439Z,1539193912.439 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-10T17:51:52.440Z,1539193912.440 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-10T17:51:52.443Z,1539193912.443 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-10-10T17:51:52.456Z,1539193912.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-10T17:51:52.463Z,1539193912.463 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-10T17:51:52.463Z,1539193912.463 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-10T17:51:52.466Z,1539193912.466 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-10T17:51:52.468Z,1539193912.468 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-10T17:51:52.473Z,1539193912.473 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-10T17:51:52.561Z,1539193912.561 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-10-10T17:51:52.561Z,1539193912.561 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-10T17:51:52.563Z,1539193912.563 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-10-10T17:51:52.564Z,1539193912.564 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-10T17:51:52.650Z,1539193912.650 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-10-10T17:51:52.651Z,1539193912.651 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-10T17:51:52.652Z,1539193912.652 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-10-10T17:51:52.653Z,1539193912.653 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-10T17:51:52.654Z,1539193912.654 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-10-10T17:51:52.654Z,1539193912.654 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-10T17:51:52.656Z,1539193912.656 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-10-10T17:51:52.656Z,1539193912.656 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-10T17:51:52.658Z,1539193912.658 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-10-10T17:51:52.658Z,1539193912.658 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:51:52.659Z,1539193912.659 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-10-10T17:51:52.665Z,1539193912.665 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T17:51:52.686Z,1539193912.686 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:51:52.729Z,1539193912.729 [MissionManager](DEBUG): 2018-10-10T17:51:52.736Z,1539193912.736 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-10T17:51:52.750Z,1539193912.750 [CTD_Seabird](INFO): LCM OK 2018-10-10T17:51:52.750Z,1539193912.750 [CTD_Seabird](INFO): Powering up 2018-10-10T17:51:52.752Z,1539193912.752 [Rowe_600LCM](INFO): LCM OK 2018-10-10T17:51:52.752Z,1539193912.752 [Rowe_600LCM](INFO): Powering up 2018-10-10T17:51:52.822Z,1539193912.822 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-10T17:51:52.823Z,1539193912.823 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-10T17:51:52.837Z,1539193912.837 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-10T17:51:52.848Z,1539193912.848 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-10T17:51:52.859Z,1539193912.859 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-10T17:51:52.864Z,1539193912.864 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-10T17:51:52.868Z,1539193912.868 [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:51:52.902Z,1539193912.902 [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:51:52.945Z,1539193912.945 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-10T17:51:53.267Z,1539193913.267 [ESPComponent](INFO): powering down ESP 2018-10-10T17:51:53.655Z,1539193913.655 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:53.917Z,1539193913.917 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:53.921Z,1539193913.921 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:51:53.940Z,1539193913.940 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:53.945Z,1539193913.945 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:51:53.994Z,1539193913.994 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:54.040Z,1539193914.040 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:51:54.079Z,1539193914.079 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:54.085Z,1539193914.085 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:51:54.126Z,1539193914.126 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:54.129Z,1539193914.129 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:51:54.146Z,1539193914.146 [CommandLine](FAULT): Scheduling is paused 2018-10-10T17:51:54.147Z,1539193914.147 [CBIT](INFO): Critical error at 20181010T175152 2018-10-10T17:51:54.147Z,1539193914.147 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-10T17:51:54.285Z,1539193914.285 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-10T17:51:54.285Z,1539193914.285 [DropWeight] Hardware Fault, FailCount= 1 2018-10-10T17:51:54.285Z,1539193914.285 [DropWeight](ERROR): Hardware Fault 2018-10-10T17:51:54.345Z,1539193914.345 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:54.355Z,1539193914.355 [CBIT](INFO): Critical error at 20181010T175154 2018-10-10T17:51:54.358Z,1539193914.358 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-10T17:51:54.358Z,1539193914.358 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-10T17:51:54.644Z,1539193914.644 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:55.000Z,1539193915.000 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:51:55.001Z,1539193915.001 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:51:55.001Z,1539193915.001 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-10T17:51:55.001Z,1539193915.001 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:51:55.312Z,1539193915.312 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-10-10T17:51:55.312Z,1539193915.312 [ElevatorServo](FAULT): Elevator failed to initialize 2018-10-10T17:51:55.313Z,1539193915.313 [ElevatorServo] Communications Fault, FailCount= 1 2018-10-10T17:51:55.313Z,1539193915.313 [ElevatorServo](ERROR): Communications Fault 2018-10-10T17:51:55.624Z,1539193915.624 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:51:55.624Z,1539193915.624 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:51:55.625Z,1539193915.625 [RudderServo] Communications Fault, FailCount= 1 2018-10-10T17:51:55.625Z,1539193915.625 [RudderServo](ERROR): Communications Fault 2018-10-10T17:51:55.948Z,1539193915.948 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-10-10T17:51:55.977Z,1539193915.977 [ThrusterServo](FAULT): Thruster failed to initialize 2018-10-10T17:51:55.978Z,1539193915.978 [ThrusterServo] Communications Fault, FailCount= 1 2018-10-10T17:51:55.978Z,1539193915.978 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:51:55.995Z,1539193915.995 [CBIT](INFO): Critical error at 20181010T175154 2018-10-10T17:51:56.013Z,1539193916.013 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:51:56.013Z,1539193916.013 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-10-10T17:51:56.013Z,1539193916.013 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:51:56.013Z,1539193916.013 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:51:56.153Z,1539193916.153 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:56.164Z,1539193916.164 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:51:56.165Z,1539193916.165 [BuoyancyServo](INFO): Powering down 2018-10-10T17:51:56.281Z,1539193916.281 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-10-10T17:51:56.281Z,1539193916.281 [ElevatorServo](INFO): Powering down 2018-10-10T17:51:56.331Z,1539193916.331 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:51:56.331Z,1539193916.331 [RudderServo](INFO): Powering down 2018-10-10T17:51:56.389Z,1539193916.389 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:51:56.389Z,1539193916.389 [ThrusterServo](INFO): Powering down 2018-10-10T17:51:56.596Z,1539193916.596 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:56.726Z,1539193916.726 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-10-10T17:51:56.793Z,1539193916.793 [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:51:56.896Z,1539193916.896 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-10-10T17:51:56.897Z,1539193916.897 [MassServo] Communications Fault, FailCount= 1 2018-10-10T17:51:56.897Z,1539193916.897 [MassServo](ERROR): Communications Fault 2018-10-10T17:51:56.903Z,1539193916.903 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:51:56.982Z,1539193916.982 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-10-10T17:51:56.983Z,1539193916.983 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-10-10T17:51:56.983Z,1539193916.983 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-10-10T17:51:57.032Z,1539193917.032 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:57.055Z,1539193917.055 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:57.056Z,1539193917.056 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-10T17:51:57.057Z,1539193917.057 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:51:57.057Z,1539193917.057 [MassServo](INFO): Powering down 2018-10-10T17:51:57.153Z,1539193917.153 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:57.154Z,1539193917.154 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:51:57.187Z,1539193917.187 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-10-10T17:51:57.187Z,1539193917.187 [ElevatorServo] No Fault, FailCount= 1 2018-10-10T17:51:57.188Z,1539193917.188 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T17:51:57.188Z,1539193917.188 [RudderServo] No Fault, FailCount= 1 2018-10-10T17:51:57.188Z,1539193917.188 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:51:57.188Z,1539193917.188 [ThrusterServo] No Fault, FailCount= 1 2018-10-10T17:51:57.398Z,1539193917.398 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:57.427Z,1539193917.427 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:57.427Z,1539193917.427 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:51:57.781Z,1539193917.781 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:57.860Z,1539193917.860 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:57.865Z,1539193917.865 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:51:57.921Z,1539193917.921 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:51:57.921Z,1539193917.921 [MassServo] No Fault, FailCount= 1 2018-10-10T17:51:58.050Z,1539193918.050 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T17:51:58.201Z,1539193918.201 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:58.207Z,1539193918.207 [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:51:58.209Z,1539193918.209 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-10T17:51:58.405Z,1539193918.405 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:51:58.405Z,1539193918.405 [BuoyancyServo] No Fault, FailCount= 1 2018-10-10T17:51:58.607Z,1539193918.607 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:58.651Z,1539193918.651 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:51:58.652Z,1539193918.652 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:51:58.864Z,1539193918.864 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-10T17:51:58.865Z,1539193918.865 [CTD_Seabird](INFO): Powering down 2018-10-10T17:51:58.987Z,1539193918.987 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:59.377Z,1539193919.377 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:59.484Z,1539193919.484 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:51:59.484Z,1539193919.484 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:51:59.485Z,1539193919.485 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-10T17:51:59.485Z,1539193919.485 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:51:59.541Z,1539193919.541 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:51:59.794Z,1539193919.794 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:51:59.820Z,1539193919.820 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:51:59.821Z,1539193919.821 [BuoyancyServo](INFO): Powering down 2018-10-10T17:52:00.144Z,1539193920.144 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout 2018-10-10T17:52:00.144Z,1539193920.144 [ThrusterServo] Communications Fault, FailCount= 2 2018-10-10T17:52:00.145Z,1539193920.145 [ThrusterServo](ERROR): Communications Fault 2018-10-10T17:52:00.149Z,1539193920.149 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2018-10-10T17:52:00.236Z,1539193920.236 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:00.612Z,1539193920.612 [RudderServo](ERROR): getPosition uart error serial timeout 2018-10-10T17:52:00.613Z,1539193920.613 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2018-10-10T17:52:00.613Z,1539193920.613 [RudderServo] Communications Fault, FailCount= 2 2018-10-10T17:52:00.613Z,1539193920.613 [RudderServo](ERROR): Communications Fault 2018-10-10T17:52:00.614Z,1539193920.614 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-10-10T17:52:00.614Z,1539193920.614 [ThrusterServo](INFO): Powering down 2018-10-10T17:52:00.619Z,1539193920.619 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:52:00.682Z,1539193920.682 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:00.706Z,1539193920.706 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:52:00.706Z,1539193920.706 [RudderServo](INFO): Powering down 2018-10-10T17:52:01.112Z,1539193921.112 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:01.166Z,1539193921.166 [CBIT](INFO): Clearing failed state for component ThrusterServo 2018-10-10T17:52:01.166Z,1539193921.166 [ThrusterServo] No Fault, FailCount= 2 2018-10-10T17:52:01.504Z,1539193921.504 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:01.557Z,1539193921.557 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:01.557Z,1539193921.557 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:52:01.558Z,1539193921.558 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:01.559Z,1539193921.559 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-10T17:52:01.563Z,1539193921.563 [CBIT](INFO): Clearing failed state for component RudderServo 2018-10-10T17:52:01.563Z,1539193921.563 [RudderServo] No Fault, FailCount= 2 2018-10-10T17:52:01.850Z,1539193921.850 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:52:01.850Z,1539193921.850 [PNI_TCM] Data Fault, FailCount= 1 2018-10-10T17:52:01.850Z,1539193921.850 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:52:01.861Z,1539193921.861 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:01.913Z,1539193921.913 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:52:01.913Z,1539193921.913 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:52:01.913Z,1539193921.913 [BuoyancyServo] No Fault, FailCount= 2 2018-10-10T17:52:02.250Z,1539193922.250 [PNI_TCM](INFO): Powering down 2018-10-10T17:52:02.315Z,1539193922.315 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:02.329Z,1539193922.329 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:02.330Z,1539193922.330 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:52:02.648Z,1539193922.648 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T17:52:02.649Z,1539193922.649 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:52:02.649Z,1539193922.649 [MassServo] Communications Fault, FailCount= 2 2018-10-10T17:52:02.649Z,1539193922.649 [MassServo](ERROR): Communications Fault 2018-10-10T17:52:02.649Z,1539193922.649 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T17:52:02.649Z,1539193922.649 [MassServo] Hardware Fault, FailCount= 2 2018-10-10T17:52:02.649Z,1539193922.649 [MassServo](ERROR): Hardware Fault 2018-10-10T17:52:02.985Z,1539193922.985 [MassServo](FAULT): Invalid EZ Servo response:"8\r" 2018-10-10T17:52:02.985Z,1539193922.985 [MassServo] Communications Fault, FailCount= 2 2018-10-10T17:52:02.985Z,1539193922.985 [MassServo](ERROR): Communications Fault 2018-10-10T17:52:03.288Z,1539193923.288 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-10-10T17:52:03.288Z,1539193923.288 [RudderServo](FAULT): Rudder failed to initialize 2018-10-10T17:52:03.289Z,1539193923.289 [RudderServo] Communications Fault, FailCount= 3 2018-10-10T17:52:03.289Z,1539193923.289 [RudderServo](ERROR): Communications Fault 2018-10-10T17:52:03.401Z,1539193923.401 [CBIT](CRITICAL): Environmental Failure. Press:14.705774 PSI. Humidity:40%. Temp:25 C. ABORTING MISSION 2018-10-10T17:52:03.401Z,1539193923.401 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:52:03.402Z,1539193923.402 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-10-10T17:52:03.402Z,1539193923.402 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-10-10T17:52:03.446Z,1539193923.446 [Aanderaa_O2](FAULT): Timed out starting 2018-10-10T17:52:03.446Z,1539193923.446 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-10-10T17:52:03.446Z,1539193923.446 [Aanderaa_O2](ERROR): Communications Fault 2018-10-10T17:52:03.508Z,1539193923.508 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:03.850Z,1539193923.850 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:52:03.850Z,1539193923.850 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:52:03.850Z,1539193923.850 [BuoyancyServo] Communications Fault, FailCount= 3 2018-10-10T17:52:03.850Z,1539193923.850 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:52:03.852Z,1539193923.852 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:52:03.852Z,1539193923.852 [MassServo](INFO): Powering down 2018-10-10T17:52:03.853Z,1539193923.853 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-10-10T17:52:03.853Z,1539193923.853 [RudderServo](INFO): Powering down 2018-10-10T17:52:03.857Z,1539193923.857 [CBIT](INFO): Critical error at 20181010T175203 2018-10-10T17:52:03.859Z,1539193923.859 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-10-10T17:52:03.860Z,1539193923.860 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:52:03.914Z,1539193923.914 [Aanderaa_O2](INFO): Powering down 2018-10-10T17:52:03.992Z,1539193923.992 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:04.006Z,1539193924.006 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:52:04.007Z,1539193924.007 [BuoyancyServo](INFO): Powering down 2018-10-10T17:52:04.026Z,1539193924.026 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T17:52:04.027Z,1539193924.027 [PNI_TCM] No Fault, FailCount= 1 2018-10-10T17:52:04.340Z,1539193924.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:04.360Z,1539193924.360 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:04.360Z,1539193924.360 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:52:04.361Z,1539193924.361 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:04.362Z,1539193924.362 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-10T17:52:04.367Z,1539193924.367 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:52:04.367Z,1539193924.367 [MassServo] No Fault, FailCount= 2 2018-10-10T17:52:04.721Z,1539193924.721 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:04.744Z,1539193924.744 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-10-10T17:52:04.744Z,1539193924.744 [Aanderaa_O2] No Fault, FailCount= 1 2018-10-10T17:52:05.132Z,1539193925.132 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:05.526Z,1539193925.526 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:05.916Z,1539193925.916 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:05.963Z,1539193925.963 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:52:05.963Z,1539193925.963 [BuoyancyServo] No Fault, FailCount= 3 2018-10-10T17:52:06.329Z,1539193926.329 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:06.336Z,1539193926.336 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:06.336Z,1539193926.336 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:52:06.745Z,1539193926.745 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:07.122Z,1539193927.122 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:07.456Z,1539193927.456 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:52:07.456Z,1539193927.456 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:52:07.457Z,1539193927.457 [BuoyancyServo] Communications Fault, FailCount= 4 2018-10-10T17:52:07.457Z,1539193927.457 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:52:07.463Z,1539193927.463 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:52:07.543Z,1539193927.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:07.550Z,1539193927.550 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:52:07.551Z,1539193927.551 [BuoyancyServo](INFO): Powering down 2018-10-10T17:52:07.692Z,1539193927.692 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-10T17:52:07.925Z,1539193927.925 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:08.341Z,1539193928.341 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:08.731Z,1539193928.731 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:09.060Z,1539193929.060 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T17:52:09.061Z,1539193929.061 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:52:09.061Z,1539193929.061 [MassServo] Communications Fault, FailCount= 3 2018-10-10T17:52:09.061Z,1539193929.061 [MassServo](ERROR): Communications Fault 2018-10-10T17:52:09.061Z,1539193929.061 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T17:52:09.061Z,1539193929.061 [MassServo] Hardware Fault, FailCount= 3 2018-10-10T17:52:09.061Z,1539193929.061 [MassServo](ERROR): Hardware Fault 2018-10-10T17:52:09.399Z,1539193929.399 [MassServo](FAULT): Invalid EZ Servo response:"84\r" 2018-10-10T17:52:09.399Z,1539193929.399 [MassServo] Communications Fault, FailCount= 3 2018-10-10T17:52:09.400Z,1539193929.400 [MassServo](ERROR): Communications Fault 2018-10-10T17:52:09.422Z,1539193929.422 [CBIT](ERROR): Communications Fault in component: MassServo 2018-10-10T17:52:09.540Z,1539193929.540 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:09.556Z,1539193929.556 [BuoyancyServo](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated. 2018-10-10T17:52:09.556Z,1539193929.556 [BuoyancyServo] Hardware Fault, FailCount= 4 2018-10-10T17:52:09.556Z,1539193929.556 [BuoyancyServo](ERROR): Hardware Fault 2018-10-10T17:52:09.558Z,1539193929.558 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-10-10T17:52:09.558Z,1539193929.558 [MassServo](INFO): Powering down 2018-10-10T17:52:09.865Z,1539193929.865 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:09.896Z,1539193929.896 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-10T17:52:09.896Z,1539193929.896 [BuoyancyServo] No Fault, FailCount= 4 2018-10-10T17:52:10.312Z,1539193930.312 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:10.323Z,1539193930.323 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:10.323Z,1539193930.323 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-10T17:52:10.330Z,1539193930.330 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-10T17:52:10.330Z,1539193930.330 [MassServo](DEBUG): Initializing MassServo. 2018-10-10T17:52:10.339Z,1539193930.339 [CBIT](INFO): Clearing failed state for component MassServo 2018-10-10T17:52:10.339Z,1539193930.339 [MassServo] No Fault, FailCount= 3 2018-10-10T17:52:10.719Z,1539193930.719 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:11.081Z,1539193931.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:11.188Z,1539193931.188 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-10T17:52:11.188Z,1539193931.188 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-10T17:52:11.189Z,1539193931.189 [BuoyancyServo] Communications Fault, FailCount= 5 2018-10-10T17:52:11.189Z,1539193931.189 [BuoyancyServo](ERROR): Communications Fault 2018-10-10T17:52:11.199Z,1539193931.199 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-10T17:52:11.459Z,1539193931.459 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-10-10T17:52:11.459Z,1539193931.459 [PNI_TCM] Data Fault, FailCount= 2 2018-10-10T17:52:11.459Z,1539193931.459 [PNI_TCM](ERROR): Data Fault 2018-10-10T17:52:11.485Z,1539193931.485 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:11.500Z,1539193931.500 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-10T17:52:11.512Z,1539193931.512 [BuoyancyServo](INFO): Powering down 2018-10-10T17:52:11.532Z,1539193931.532 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-10T17:52:11.851Z,1539193931.851 [PNI_TCM](INFO): Powering down 2018-10-10T17:52:11.890Z,1539193931.890 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:12.294Z,1539193932.294 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:12.693Z,1539193932.693 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:13.097Z,1539193933.097 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:13.115Z,1539193933.115 [BuoyancyServo](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated. 2018-10-10T17:52:13.115Z,1539193933.115 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-10-10T17:52:13.115Z,1539193933.115 [BuoyancyServo](ERROR): Hardware Fault 2018-10-10T17:52:13.125Z,1539193933.125 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-10-10T17:52:13.126Z,1539193933.126 [PNI_TCM] No Fault, FailCount= 2 2018-10-10T17:52:13.465Z,1539193933.465 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-10-10T17:52:13.796Z,1539193933.796 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-10-10T17:52:13.797Z,1539193933.797 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:52:13.797Z,1539193933.797 [MassServo] Communications Fault, FailCount= 4 2018-10-10T17:52:13.797Z,1539193933.797 [MassServo](ERROR): Communications Fault 2018-10-10T17:52:13.797Z,1539193933.797 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-10-10T17:52:13.797Z,1539193933.797 [MassServo] Hardware Fault, FailCount= 4 2018-10-10T17:52:13.797Z,1539193933.797 [MassServo](ERROR): Hardware Fault 2018-10-10T17:52:14.372Z,1539193934.372 [MassServo](ERROR): getPosition uart error serial timeout 2018-10-10T17:52:14.373Z,1539193934.373 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-10-10T17:52:14.373Z,1539193934.373 [MassServo] Communications Fault, FailCount= 4 2018-10-10T17:52:14.373Z