2018-11-08T23:27:29.171Z,1541719649.171 [Supervisor](DEBUG): Initializing supervisor. 2018-11-08T23:27:29.174Z,1541719649.174 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-08T23:27:29.175Z,1541719649.175 [SyncHandler](INFO): Protected caller Thread ID is 9815 2018-11-08T23:27:29.175Z,1541719649.175 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-08T23:27:29.176Z,1541719649.176 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-08T23:27:29.177Z,1541719649.177 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9816 2018-11-08T23:27:29.180Z,1541719649.180 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-08T23:27:29.192Z,1541719649.192 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-08T23:27:29.193Z,1541719649.193 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-08T23:27:29.193Z,1541719649.193 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9817 2018-11-08T23:27:29.194Z,1541719649.194 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-08T23:27:29.195Z,1541719649.195 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-08T23:27:29.195Z,1541719649.195 [logger ThreadHandler](INFO): Protected caller Thread ID is 9818 2018-11-08T23:27:29.197Z,1541719649.197 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-08T23:27:29.197Z,1541719649.197 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-08T23:27:29.199Z,1541719649.199 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-08T23:27:29.528Z,1541719649.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-08T23:27:29.528Z,1541719649.528 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-08T23:27:29.981Z,1541719649.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-08T23:27:29.982Z,1541719649.982 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-08T23:27:30.324Z,1541719650.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-08T23:27:30.324Z,1541719650.324 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-08T23:27:30.524Z,1541719650.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-08T23:27:30.525Z,1541719650.525 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-08T23:27:30.997Z,1541719650.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-08T23:27:30.997Z,1541719650.997 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-08T23:27:31.485Z,1541719651.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-08T23:27:31.486Z,1541719651.486 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-08T23:27:31.591Z,1541719651.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-08T23:27:31.591Z,1541719651.591 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-08T23:27:31.795Z,1541719651.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-08T23:27:31.795Z,1541719651.795 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-08T23:27:31.940Z,1541719651.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-08T23:27:31.941Z,1541719651.941 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-08T23:27:32.021Z,1541719652.021 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-08T23:27:32.237Z,1541719652.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-08T23:27:32.237Z,1541719652.237 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-08T23:27:32.337Z,1541719652.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-08T23:27:32.338Z,1541719652.338 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-08T23:27:32.435Z,1541719652.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-08T23:27:32.435Z,1541719652.435 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-08T23:27:32.588Z,1541719652.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-08T23:27:32.588Z,1541719652.588 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-08T23:27:32.912Z,1541719652.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-08T23:27:32.914Z,1541719652.914 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-11-08T23:27:32.914Z,1541719652.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-11-08T23:27:33.049Z,1541719653.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-11-08T23:27:33.305Z,1541719653.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-08T23:27:33.305Z,1541719653.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-11-08T23:27:33.925Z,1541719653.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-11-08T23:27:34.076Z,1541719654.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-11-08T23:27:34.315Z,1541719654.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-11-08T23:27:34.519Z,1541719654.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-11-08T23:27:34.603Z,1541719654.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-11-08T23:27:34.702Z,1541719654.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-11-08T23:27:34.796Z,1541719654.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-11-08T23:27:34.878Z,1541719654.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-11-08T23:27:34.989Z,1541719654.989 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2018-11-08T23:27:34.990Z,1541719654.990 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-11-08T23:27:34.998Z,1541719654.998 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-08T23:27:35.124Z,1541719655.124 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-08T23:27:35.154Z,1541719655.154 [SBIT] Loaded 2018-11-08T23:27:35.154Z,1541719655.154 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-08T23:27:35.155Z,1541719655.155 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-08T23:27:35.181Z,1541719655.181 [IBIT] Loaded 2018-11-08T23:27:35.182Z,1541719655.182 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-08T23:27:35.185Z,1541719655.185 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-08T23:27:35.337Z,1541719655.337 [CBIT] Loaded 2018-11-08T23:27:35.337Z,1541719655.337 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-08T23:27:35.338Z,1541719655.338 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-08T23:27:35.338Z,1541719655.338 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-08T23:27:35.451Z,1541719655.451 [BuoyancyServo] Loaded 2018-11-08T23:27:35.451Z,1541719655.451 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-08T23:27:35.466Z,1541719655.466 [ElevatorServo] Loaded 2018-11-08T23:27:35.466Z,1541719655.466 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-08T23:27:35.480Z,1541719655.480 [MassServo] Loaded 2018-11-08T23:27:35.481Z,1541719655.481 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-08T23:27:35.495Z,1541719655.495 [RudderServo] Loaded 2018-11-08T23:27:35.496Z,1541719655.496 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-08T23:27:35.510Z,1541719655.510 [ThrusterServo] Loaded 2018-11-08T23:27:35.510Z,1541719655.510 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-08T23:27:35.511Z,1541719655.511 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-08T23:27:35.511Z,1541719655.511 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-08T23:27:35.609Z,1541719655.609 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-11-08T23:27:35.609Z,1541719655.609 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-11-08T23:27:35.662Z,1541719655.662 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-08T23:27:35.662Z,1541719655.662 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-08T23:27:35.678Z,1541719655.678 [NavChart] Loaded 2018-11-08T23:27:35.678Z,1541719655.678 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-08T23:27:35.682Z,1541719655.682 [UniversalFixResidualReporter] Loaded 2018-11-08T23:27:35.683Z,1541719655.683 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-08T23:27:35.683Z,1541719655.683 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-08T23:27:35.684Z,1541719655.684 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-08T23:27:35.696Z,1541719655.696 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-08T23:27:35.697Z,1541719655.697 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-08T23:27:35.933Z,1541719655.933 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-08T23:27:35.934Z,1541719655.934 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-08T23:27:36.228Z,1541719656.228 [AHRS_M2] Loaded 2018-11-08T23:27:36.229Z,1541719656.229 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-11-08T23:27:36.301Z,1541719656.301 [DataOverHttps] Loaded 2018-11-08T23:27:36.301Z,1541719656.301 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-08T23:27:36.314Z,1541719656.314 [Depth_Keller] Loaded 2018-11-08T23:27:36.314Z,1541719656.314 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-08T23:27:36.319Z,1541719656.319 [DropWeight] Loaded 2018-11-08T23:27:36.319Z,1541719656.319 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-08T23:27:36.407Z,1541719656.407 [NAL9602] Loaded 2018-11-08T23:27:36.407Z,1541719656.407 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-08T23:27:36.412Z,1541719656.412 [Onboard] Loaded 2018-11-08T23:27:36.413Z,1541719656.413 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-08T23:27:36.419Z,1541719656.419 [Radio_Surface] Loaded 2018-11-08T23:27:36.420Z,1541719656.420 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-08T23:27:36.421Z,1541719656.421 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0 2018-11-08T23:27:36.421Z,1541719656.421 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9897 2018-11-08T23:27:36.449Z,1541719656.449 [RDI_Pathfinder] Loaded 2018-11-08T23:27:36.450Z,1541719656.450 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-11-08T23:27:36.471Z,1541719656.471 [RDI_PathfinderUp] Loaded 2018-11-08T23:27:36.471Z,1541719656.471 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-11-08T23:27:38.228Z,1541719658.228 [BPC1] Loaded 2018-11-08T23:27:38.228Z,1541719658.228 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-08T23:27:38.229Z,1541719658.229 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-08T23:27:38.229Z,1541719658.229 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-08T23:27:38.295Z,1541719658.295 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-08T23:27:38.381Z,1541719658.381 [VerticalControl] Loaded 2018-11-08T23:27:38.381Z,1541719658.381 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-08T23:27:38.382Z,1541719658.382 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-08T23:27:38.490Z,1541719658.490 [HorizontalControl] Loaded 2018-11-08T23:27:38.490Z,1541719658.490 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-08T23:27:38.491Z,1541719658.491 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-08T23:27:38.493Z,1541719658.493 [SpeedControl] Loaded 2018-11-08T23:27:38.493Z,1541719658.493 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-08T23:27:38.494Z,1541719658.494 [LoopControl](DEBUG): Construct LoopControl. 2018-11-08T23:27:38.494Z,1541719658.494 [LoopControl] Loaded 2018-11-08T23:27:38.495Z,1541719658.495 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-08T23:27:38.495Z,1541719658.495 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-08T23:27:38.496Z,1541719658.496 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-08T23:27:38.924Z,1541719658.924 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-08T23:27:38.924Z,1541719658.924 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-08T23:27:39.154Z,1541719659.154 [Aanderaa_O2] Loaded 2018-11-08T23:27:39.154Z,1541719659.154 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-11-08T23:27:39.156Z,1541719659.156 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-08T23:27:39.156Z,1541719659.156 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-08T23:27:39.219Z,1541719659.219 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-08T23:27:39.219Z,1541719659.219 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-08T23:27:39.259Z,1541719659.259 [DepthRateCalculator] Loaded 2018-11-08T23:27:39.259Z,1541719659.259 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-08T23:27:39.265Z,1541719659.265 [PitchRateCalculator] Loaded 2018-11-08T23:27:39.265Z,1541719659.265 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-08T23:27:39.277Z,1541719659.277 [SpeedCalculator] Loaded 2018-11-08T23:27:39.277Z,1541719659.277 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-08T23:27:39.298Z,1541719659.298 [TempGradientCalculator] Loaded 2018-11-08T23:27:39.299Z,1541719659.299 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-08T23:27:39.304Z,1541719659.304 [YawRateCalculator] Loaded 2018-11-08T23:27:39.304Z,1541719659.304 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-08T23:27:39.334Z,1541719659.334 [ElevatorOffsetCalculator] Loaded 2018-11-08T23:27:39.334Z,1541719659.334 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-08T23:27:39.334Z,1541719659.334 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-08T23:27:39.335Z,1541719659.335 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-08T23:27:39.359Z,1541719659.359 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-08T23:27:39.363Z,1541719659.363 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-08T23:27:39.364Z,1541719659.364 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-08T23:27:39.370Z,1541719659.370 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-08T23:27:39.371Z,1541719659.371 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409B54E0 2018-11-08T23:27:39.371Z,1541719659.371 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9898 2018-11-08T23:27:39.376Z,1541719659.376 [Supervisor](INFO): Main Thread ID is 9690 2018-11-08T23:27:39.376Z,1541719659.376 [Supervisor](DEBUG): Running supervisor. 2018-11-08T23:27:39.377Z,1541719659.377 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9899 2018-11-08T23:27:39.379Z,1541719659.379 [controlThread ThreadHandler](INFO): Handler Thread ID is 9900 2018-11-08T23:27:39.380Z,1541719659.380 [controlThread](DEBUG): Initializing ControlThread 2018-11-08T23:27:39.381Z,1541719659.381 [SBIT](INFO): Initialize SBIT Component. 2018-11-08T23:27:39.381Z,1541719659.381 [SBIT](IMPORTANT): git: 2018-10-09 2018-11-08T23:27:39.381Z,1541719659.381 [SBIT](INFO): git hash: e647870a3e94d94bcb418e3013bcadd95d09d9aa 2018-11-08T23:27:39.382Z,1541719659.382 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-08T23:27:39.382Z,1541719659.382 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-08T23:27:39.383Z,1541719659.383 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-11-08T23:27:39.384Z,1541719659.384 [IBIT](INFO): Initialize IBIT Component. 2018-11-08T23:27:39.385Z,1541719659.385 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-08T23:27:39.385Z,1541719659.385 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-11-08T23:27:39.386Z,1541719659.386 [logger ThreadHandler](INFO): Handler Thread ID is 9901 2018-11-08T23:27:39.413Z,1541719659.413 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9902 2018-11-08T23:27:39.419Z,1541719659.419 [Radio_Surface](INFO): Powering up 2018-11-08T23:27:39.437Z,1541719659.437 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9903 2018-11-08T23:27:39.440Z,1541719659.440 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-08T23:27:39.440Z,1541719659.440 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-08T23:27:39.440Z,1541719659.440 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-08T23:27:39.441Z,1541719659.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-08T23:27:39.441Z,1541719659.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-08T23:27:39.441Z,1541719659.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-08T23:27:39.441Z,1541719659.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-08T23:27:39.441Z,1541719659.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-08T23:27:39.442Z,1541719659.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-08T23:27:39.442Z,1541719659.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-08T23:27:39.442Z,1541719659.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-08T23:27:39.442Z,1541719659.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-08T23:27:39.442Z,1541719659.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-08T23:27:39.442Z,1541719659.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-08T23:27:39.443Z,1541719659.443 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-08T23:27:39.443Z,1541719659.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-08T23:27:39.494Z,1541719659.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-08T23:27:39.495Z,1541719659.495 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-08T23:27:39.495Z,1541719659.495 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-08T23:27:39.496Z,1541719659.496 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-08T23:27:39.496Z,1541719659.496 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-08T23:27:39.496Z,1541719659.496 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-08T23:27:39.497Z,1541719659.497 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-08T23:27:39.497Z,1541719659.497 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-08T23:27:39.504Z,1541719659.504 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-08T23:27:39.506Z,1541719659.506 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-08T23:27:39.506Z,1541719659.506 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-08T23:27:39.507Z,1541719659.507 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-08T23:27:39.508Z,1541719659.508 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-08T23:27:39.508Z,1541719659.508 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-08T23:27:39.508Z,1541719659.508 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-08T23:27:39.509Z,1541719659.509 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-08T23:27:39.510Z,1541719659.510 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-08T23:27:39.510Z,1541719659.510 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-08T23:27:39.511Z,1541719659.511 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-08T23:27:39.543Z,1541719659.543 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-08T23:27:39.586Z,1541719659.586 [MissionManager](DEBUG): 2018-11-08T23:27:39.587Z,1541719659.587 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-08T23:27:39.664Z,1541719659.664 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-08T23:27:39.685Z,1541719659.685 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-08T23:27:39.687Z,1541719659.687 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-08T23:27:39.720Z,1541719659.720 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-08T23:27:39.731Z,1541719659.731 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-08T23:27:39.736Z,1541719659.736 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-08T23:27:39.760Z,1541719659.760 [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-11-08T23:27:39.773Z,1541719659.773 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-11-08T23:27:39.797Z,1541719659.797 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-08T23:27:39.842Z,1541719659.842 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-08T23:27:39.858Z,1541719659.858 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-11-08T23:27:40.013Z,1541719660.013 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-08T23:27:40.085Z,1541719660.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:40.113Z,1541719660.113 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:40.217Z,1541719660.217 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:27:40.226Z,1541719660.226 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-08T23:27:40.240Z,1541719660.240 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:27:40.241Z,1541719660.241 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-08T23:27:40.272Z,1541719660.272 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:27:40.277Z,1541719660.277 [MassServo](DEBUG): Initializing MassServo. 2018-11-08T23:27:40.297Z,1541719660.297 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:27:40.305Z,1541719660.305 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-08T23:27:40.327Z,1541719660.327 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:27:40.333Z,1541719660.333 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-08T23:27:40.498Z,1541719660.498 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-08T23:27:40.498Z,1541719660.498 [DropWeight] Hardware Fault, FailCount= 1 2018-11-08T23:27:40.498Z,1541719660.498 [DropWeight](ERROR): Hardware Fault 2018-11-08T23:27:40.597Z,1541719660.597 [RDI_PathfinderUp](INFO): Powering down 2018-11-08T23:27:40.641Z,1541719660.641 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-08T23:27:40.644Z,1541719660.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:40.653Z,1541719660.653 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:40.678Z,1541719660.678 [CommandLine](FAULT): Scheduling is paused 2018-11-08T23:27:40.679Z,1541719660.679 [CBIT](INFO): Critical error at 20181108T232740 2018-11-08T23:27:40.679Z,1541719660.679 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-08T23:27:40.681Z,1541719660.681 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-08T23:27:40.682Z,1541719660.682 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-08T23:27:40.833Z,1541719660.833 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-08T23:27:40.836Z,1541719660.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:40.837Z,1541719660.837 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:40.855Z,1541719660.855 [CBIT](INFO): Critical error at 20181108T232740 2018-11-08T23:27:41.240Z,1541719661.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:41.241Z,1541719661.241 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:41.589Z,1541719661.589 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-08T23:27:41.589Z,1541719661.589 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-08T23:27:41.589Z,1541719661.589 [BuoyancyServo] Communications Fault, FailCount= 1 2018-11-08T23:27:41.589Z,1541719661.589 [BuoyancyServo](ERROR): Communications Fault 2018-11-08T23:27:41.901Z,1541719661.901 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-11-08T23:27:41.901Z,1541719661.901 [ElevatorServo](FAULT): Elevator failed to initialize 2018-11-08T23:27:41.901Z,1541719661.901 [ElevatorServo] Communications Fault, FailCount= 1 2018-11-08T23:27:41.901Z,1541719661.901 [ElevatorServo](ERROR): Communications Fault 2018-11-08T23:27:42.073Z,1541719662.073 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-11-08T23:27:42.074Z,1541719662.074 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-11-08T23:27:42.318Z,1541719662.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:42.340Z,1541719662.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:42.439Z,1541719662.439 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-08T23:27:42.457Z,1541719662.457 [BuoyancyServo](INFO): Powering down 2018-11-08T23:27:42.729Z,1541719662.729 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-08T23:27:42.729Z,1541719662.729 [ElevatorServo](INFO): Powering down 2018-11-08T23:27:42.856Z,1541719662.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:42.857Z,1541719662.857 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:43.465Z,1541719663.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:27:43.492Z,1541719663.492 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:43.494Z,1541719663.494 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:43.515Z,1541719663.515 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:27:43.634Z,1541719663.634 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-08T23:27:43.640Z,1541719663.640 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-11-08T23:27:43.640Z,1541719663.640 [ElevatorServo] No Fault, FailCount= 1 2018-11-08T23:27:43.749Z,1541719663.749 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:27:43.776Z,1541719663.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:43.777Z,1541719663.777 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:44.193Z,1541719664.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:27:44.220Z,1541719664.220 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:44.229Z,1541719664.229 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:44.549Z,1541719664.549 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:27:44.576Z,1541719664.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:44.577Z,1541719664.577 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:45.033Z,1541719665.033 [RudderServo](FAULT): Rudder uart error: serial timeout 2018-11-08T23:27:45.033Z,1541719665.033 [RudderServo] Communications Fault, FailCount= 1 2018-11-08T23:27:45.033Z,1541719665.033 [RudderServo](ERROR): Communications Fault 2018-11-08T23:27:45.071Z,1541719665.071 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-11-08T23:27:45.072Z,1541719665.072 [BuoyancyServo] No Fault, FailCount= 1 2018-11-08T23:27:45.086Z,1541719665.086 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-11-08T23:27:45.217Z,1541719665.217 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:27:45.244Z,1541719665.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:45.246Z,1541719665.246 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:27:45.252Z,1541719665.252 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:06.741Z,1541719686.741 [CommandLine](IMPORTANT): got command failComponent 2018-11-08T23:28:06.742Z,1541719686.742 [CommandLine](IMPORTANT): Failed components: 2018-11-08T23:28:06.742Z,1541719686.742 [CommandLine](IMPORTANT): RudderServo: Communications Fault 2018-11-08T23:28:06.742Z,1541719686.742 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2018-11-08T23:28:15.370Z,1541719695.370 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-08T23:28:15.470Z,1541719695.470 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-08T23:28:15.470Z,1541719695.470 [RudderServo](INFO): Powering down 2018-11-08T23:28:15.694Z,1541719695.694 [CBIT](CRITICAL): Environmental Failure. Press:14.817736 PSI. Humidity:31%. Temp:25 C. ABORTING MISSION 2018-11-08T23:28:16.033Z,1541719696.033 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:28:16.033Z,1541719696.033 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-11-08T23:28:16.033Z,1541719696.033 [AHRS_M2] Communications Fault, FailCount= 1 2018-11-08T23:28:16.033Z,1541719696.033 [AHRS_M2](ERROR): Communications Fault 2018-11-08T23:28:16.067Z,1541719696.067 [NAL9602](INFO): Powering up NAL9602 2018-11-08T23:28:16.088Z,1541719696.088 [Aanderaa_O2](FAULT): Timed out starting 2018-11-08T23:28:16.088Z,1541719696.088 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-08T23:28:16.088Z,1541719696.088 [Aanderaa_O2](ERROR): Communications Fault 2018-11-08T23:28:16.133Z,1541719696.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:16.140Z,1541719696.140 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:16.243Z,1541719696.243 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-08T23:28:16.245Z,1541719696.245 [CBIT](INFO): Critical error at 20181108T232815 2018-11-08T23:28:16.248Z,1541719696.248 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-11-08T23:28:16.248Z,1541719696.248 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-08T23:28:16.249Z,1541719696.249 [CBIT](IMPORTANT): Beginning ground fault scan 2018-11-08T23:28:16.256Z,1541719696.256 [AHRS_M2](INFO): Powering down 2018-11-08T23:28:16.569Z,1541719696.569 [Aanderaa_O2](INFO): Powering down 2018-11-08T23:28:16.640Z,1541719696.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:16.650Z,1541719696.650 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:16.997Z,1541719696.997 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-08T23:28:16.997Z,1541719696.997 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-08T23:28:16.997Z,1541719696.997 [BuoyancyServo] Communications Fault, FailCount= 2 2018-11-08T23:28:16.997Z,1541719696.997 [BuoyancyServo](ERROR): Communications Fault 2018-11-08T23:28:17.317Z,1541719697.317 [MassServo](ERROR): getPosition uart error serial timeout 2018-11-08T23:28:17.317Z,1541719697.317 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-11-08T23:28:17.317Z,1541719697.317 [MassServo] Communications Fault, FailCount= 1 2018-11-08T23:28:17.317Z,1541719697.317 [MassServo](ERROR): Communications Fault 2018-11-08T23:28:17.442Z,1541719697.442 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-11-08T23:28:17.442Z,1541719697.442 [CBIT](ERROR): Communications Fault in component: MassServo 2018-11-08T23:28:17.442Z,1541719697.442 [CBIT](INFO): Clearing failed state for component RudderServo 2018-11-08T23:28:17.442Z,1541719697.442 [RudderServo] No Fault, FailCount= 1 2018-11-08T23:28:17.584Z,1541719697.584 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:17.585Z,1541719697.585 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:17.607Z,1541719697.607 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-08T23:28:17.607Z,1541719697.607 [BuoyancyServo](INFO): Powering down 2018-11-08T23:28:17.925Z,1541719697.925 [ElevatorServo](ERROR): getPosition uart error serial timeout 2018-11-08T23:28:17.925Z,1541719697.925 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2018-11-08T23:28:17.925Z,1541719697.925 [ElevatorServo] Communications Fault, FailCount= 2 2018-11-08T23:28:17.925Z,1541719697.925 [ElevatorServo](ERROR): Communications Fault 2018-11-08T23:28:17.926Z,1541719697.926 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-08T23:28:17.926Z,1541719697.926 [MassServo](INFO): Powering down 2018-11-08T23:28:17.937Z,1541719697.937 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:17.945Z,1541719697.945 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-08T23:28:17.951Z,1541719697.951 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-11-08T23:28:18.053Z,1541719698.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:18.054Z,1541719698.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:18.069Z,1541719698.069 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-08T23:28:18.070Z,1541719698.070 [ElevatorServo](INFO): Powering down 2018-11-08T23:28:18.084Z,1541719698.084 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-08T23:28:18.084Z,1541719698.084 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-08T23:28:18.405Z,1541719698.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:18.406Z,1541719698.406 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:18.440Z,1541719698.440 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:18.558Z,1541719698.558 [MassServo](DEBUG): Initializing MassServo. 2018-11-08T23:28:18.563Z,1541719698.563 [CBIT](INFO): Clearing failed state for component MassServo 2018-11-08T23:28:18.564Z,1541719698.564 [MassServo] No Fault, FailCount= 1 2018-11-08T23:28:18.833Z,1541719698.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:18.834Z,1541719698.834 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:18.841Z,1541719698.841 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:18.962Z,1541719698.962 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-08T23:28:19.270Z,1541719699.270 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-11-08T23:28:19.270Z,1541719699.270 [RudderServo](FAULT): Rudder failed to initialize 2018-11-08T23:28:19.270Z,1541719699.270 [RudderServo] Communications Fault, FailCount= 2 2018-11-08T23:28:19.270Z,1541719699.270 [RudderServo](ERROR): Communications Fault 2018-11-08T23:28:19.275Z,1541719699.275 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-11-08T23:28:19.275Z,1541719699.275 [ElevatorServo] No Fault, FailCount= 2 2018-11-08T23:28:19.276Z,1541719699.276 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-11-08T23:28:19.365Z,1541719699.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:19.366Z,1541719699.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:19.392Z,1541719699.392 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-08T23:28:19.392Z,1541719699.392 [RudderServo](INFO): Powering down 2018-11-08T23:28:19.713Z,1541719699.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:19.714Z,1541719699.714 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:19.765Z,1541719699.765 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-11-08T23:28:19.765Z,1541719699.765 [BuoyancyServo] No Fault, FailCount= 2 2018-11-08T23:28:20.121Z,1541719700.121 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:20.122Z,1541719700.122 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:20.128Z,1541719700.128 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:47.622Z,1541719727.622 [CommandLine](IMPORTANT): got command failComponent 2018-11-08T23:28:47.622Z,1541719727.622 [CommandLine](IMPORTANT): Failed components: 2018-11-08T23:28:47.622Z,1541719727.622 [CommandLine](IMPORTANT): RudderServo: Communications Fault 2018-11-08T23:28:47.622Z,1541719727.622 [CommandLine](IMPORTANT): AHRS_M2: Communications Fault 2018-11-08T23:28:47.622Z,1541719727.622 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2018-11-08T23:28:50.246Z,1541719730.246 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-08T23:28:50.411Z,1541719730.411 [SBIT](ERROR): Could not read elevatorAngleReader_. 2018-11-08T23:28:50.411Z,1541719730.411 [SBIT](ERROR): Could not read rudderAngleReader_. 2018-11-08T23:28:50.411Z,1541719730.411 [SBIT](ERROR): Could not read massPosReader_. 2018-11-08T23:28:50.414Z,1541719730.414 [CBIT](INFO): Clearing failed state for component RudderServo 2018-11-08T23:28:50.415Z,1541719730.415 [RudderServo] No Fault, FailCount= 2 2018-11-08T23:28:50.415Z,1541719730.415 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-08T23:28:50.415Z,1541719730.415 [AHRS_M2] No Fault, FailCount= 1 2018-11-08T23:28:50.504Z,1541719730.504 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-08T23:28:50.540Z,1541719730.540 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-08T23:28:50.540Z,1541719730.540 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-11-08T23:28:50.540Z,1541719730.540 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-08T23:28:50.543Z,1541719730.543 [Aanderaa_O2](FAULT): Timed out starting 2018-11-08T23:28:50.543Z,1541719730.543 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-08T23:28:50.544Z,1541719730.544 [Aanderaa_O2](ERROR): Communications Fault 2018-11-08T23:28:50.561Z,1541719730.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:50.562Z,1541719730.562 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:50.618Z,1541719730.618 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:50.627Z,1541719730.627 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-08T23:28:50.634Z,1541719730.634 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-08T23:28:50.634Z,1541719730.634 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-08T23:28:51.009Z,1541719731.009 [RDI_Pathfinder](INFO): Powering down 2018-11-08T23:28:51.157Z,1541719731.157 [Aanderaa_O2](INFO): Powering down 2018-11-08T23:28:51.179Z,1541719731.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:51.180Z,1541719731.180 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:51.309Z,1541719731.309 [NAL9602](INFO): NAL9602 initialized 2018-11-08T23:28:51.375Z,1541719731.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:51.376Z,1541719731.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:51.713Z,1541719731.713 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-08T23:28:51.713Z,1541719731.713 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-08T23:28:51.713Z,1541719731.713 [BuoyancyServo] Communications Fault, FailCount= 3 2018-11-08T23:28:51.713Z,1541719731.713 [BuoyancyServo](ERROR): Communications Fault 2018-11-08T23:28:52.049Z,1541719732.049 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-11-08T23:28:52.049Z,1541719732.049 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-11-08T23:28:52.049Z,1541719732.049 [MassServo] Communications Fault, FailCount= 2 2018-11-08T23:28:52.049Z,1541719732.049 [MassServo](ERROR): Communications Fault 2018-11-08T23:28:52.050Z,1541719732.050 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-11-08T23:28:52.050Z,1541719732.050 [MassServo] Hardware Fault, FailCount= 2 2018-11-08T23:28:52.050Z,1541719732.050 [MassServo](ERROR): Hardware Fault 2018-11-08T23:28:52.373Z,1541719732.373 [MassServo](FAULT): Invalid EZ Servo response:"69" 2018-11-08T23:28:52.374Z,1541719732.374 [MassServo] Communications Fault, FailCount= 2 2018-11-08T23:28:52.374Z,1541719732.374 [MassServo](ERROR): Communications Fault 2018-11-08T23:28:52.681Z,1541719732.681 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-11-08T23:28:52.681Z,1541719732.681 [RudderServo](FAULT): Rudder failed to initialize 2018-11-08T23:28:52.681Z,1541719732.681 [RudderServo] Communications Fault, FailCount= 3 2018-11-08T23:28:52.681Z,1541719732.681 [RudderServo](ERROR): Communications Fault 2018-11-08T23:28:52.686Z,1541719732.686 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-11-08T23:28:52.686Z,1541719732.686 [CBIT](ERROR): Communications Fault in component: MassServo 2018-11-08T23:28:52.687Z,1541719732.687 [CBIT](ERROR): Communications Fault in component: RudderServo 2018-11-08T23:28:52.687Z,1541719732.687 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2018-11-08T23:28:52.851Z,1541719732.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:52.852Z,1541719732.852 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:52.860Z,1541719732.860 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-08T23:28:52.861Z,1541719732.861 [BuoyancyServo](INFO): Powering down 2018-11-08T23:28:53.177Z,1541719733.177 [ElevatorServo](FAULT): Elevator initialization uart error:serial timeout 2018-11-08T23:28:53.177Z,1541719733.177 [ElevatorServo] Communications Fault, FailCount= 3 2018-11-08T23:28:53.177Z,1541719733.177 [ElevatorServo](ERROR): Communications Fault 2018-11-08T23:28:53.178Z,1541719733.178 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-08T23:28:53.178Z,1541719733.178 [MassServo](INFO): Powering down 2018-11-08T23:28:53.179Z,1541719733.179 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-08T23:28:53.179Z,1541719733.179 [RudderServo](INFO): Powering down 2018-11-08T23:28:53.183Z,1541719733.183 [CBIT](INFO): Critical error at 20181108T232852 2018-11-08T23:28:53.185Z,1541719733.185 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-11-08T23:28:53.186Z,1541719733.186 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo 2018-11-08T23:28:53.186Z,1541719733.186 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-08T23:28:53.186Z,1541719733.186 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-08T23:28:53.187Z,1541719733.187 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-08T23:28:53.187Z,1541719733.187 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-08T23:28:53.269Z,1541719733.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:53.270Z,1541719733.270 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:53.290Z,1541719733.290 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-08T23:28:53.290Z,1541719733.290 [ElevatorServo](INFO): Powering down 2018-11-08T23:28:53.298Z,1541719733.298 [CBIT](INFO): Critical error at 20181108T232853 2018-11-08T23:28:53.921Z,1541719733.921 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-08T23:28:54.016Z,1541719734.016 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:54.017Z,1541719734.017 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-08T23:28:54.024Z,1541719734.024 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-08T23:28:54.138Z,1541719734.138 [MassServo](DEBUG): Initializing MassServo. 2018-11-08T23:28:54.139Z,1541719734.139 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-08T23: