2018-11-14T17:59:18.442Z,1542218358.442 [Supervisor](DEBUG): Initializing supervisor. 2018-11-14T17:59:18.445Z,1542218358.445 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-14T17:59:18.445Z,1542218358.445 [SyncHandler](INFO): Protected caller Thread ID is 799 2018-11-14T17:59:18.446Z,1542218358.446 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-14T17:59:18.447Z,1542218358.447 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-14T17:59:18.447Z,1542218358.447 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800 2018-11-14T17:59:18.450Z,1542218358.450 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-14T17:59:18.462Z,1542218358.462 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-14T17:59:18.463Z,1542218358.463 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-14T17:59:18.463Z,1542218358.463 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801 2018-11-14T17:59:18.464Z,1542218358.464 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-14T17:59:18.465Z,1542218358.465 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-14T17:59:18.465Z,1542218358.465 [logger ThreadHandler](INFO): Protected caller Thread ID is 802 2018-11-14T17:59:18.467Z,1542218358.467 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-14T17:59:18.467Z,1542218358.467 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-14T17:59:18.471Z,1542218358.471 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-14T17:59:18.793Z,1542218358.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-14T17:59:18.794Z,1542218358.794 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-14T17:59:19.241Z,1542218359.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-14T17:59:19.243Z,1542218359.243 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-14T17:59:19.588Z,1542218359.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-14T17:59:19.589Z,1542218359.589 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-14T17:59:19.785Z,1542218359.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-14T17:59:19.786Z,1542218359.786 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-14T17:59:20.242Z,1542218360.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-14T17:59:20.243Z,1542218360.243 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-14T17:59:20.722Z,1542218360.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-14T17:59:20.723Z,1542218360.723 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-14T17:59:20.828Z,1542218360.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-14T17:59:20.828Z,1542218360.828 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-14T17:59:21.027Z,1542218361.027 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-14T17:59:21.028Z,1542218361.028 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-14T17:59:21.173Z,1542218361.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-14T17:59:21.173Z,1542218361.173 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-14T17:59:21.255Z,1542218361.255 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-14T17:59:21.474Z,1542218361.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-14T17:59:21.475Z,1542218361.475 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-14T17:59:21.575Z,1542218361.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-14T17:59:21.576Z,1542218361.576 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-14T17:59:21.673Z,1542218361.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-14T17:59:21.674Z,1542218361.674 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-14T17:59:21.827Z,1542218361.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-14T17:59:21.828Z,1542218361.828 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-14T17:59:22.768Z,1542218362.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-14T17:59:22.770Z,1542218362.770 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-11-14T17:59:22.774Z,1542218362.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-11-14T17:59:22.908Z,1542218362.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-11-14T17:59:23.173Z,1542218363.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-14T17:59:23.173Z,1542218363.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-11-14T17:59:23.350Z,1542218363.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-11-14T17:59:23.452Z,1542218363.452 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-11-14T17:59:23.551Z,1542218363.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-11-14T17:59:23.746Z,1542218363.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-11-14T17:59:23.832Z,1542218363.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-11-14T17:59:23.931Z,1542218363.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-11-14T17:59:24.026Z,1542218364.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-11-14T17:59:24.109Z,1542218364.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-11-14T17:59:24.219Z,1542218364.219 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-11-14T17:59:24.223Z,1542218364.223 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-14T17:59:24.376Z,1542218364.376 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-14T17:59:24.406Z,1542218364.406 [SBIT] Loaded 2018-11-14T17:59:24.406Z,1542218364.406 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-14T17:59:24.407Z,1542218364.407 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-14T17:59:24.434Z,1542218364.434 [IBIT] Loaded 2018-11-14T17:59:24.434Z,1542218364.434 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-14T17:59:24.437Z,1542218364.437 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-14T17:59:24.589Z,1542218364.589 [CBIT] Loaded 2018-11-14T17:59:24.589Z,1542218364.589 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-14T17:59:24.589Z,1542218364.589 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-14T17:59:24.590Z,1542218364.590 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-14T17:59:24.725Z,1542218364.725 [BuoyancyServo] Loaded 2018-11-14T17:59:24.725Z,1542218364.725 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-14T17:59:24.740Z,1542218364.740 [ElevatorServo] Loaded 2018-11-14T17:59:24.740Z,1542218364.740 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-14T17:59:24.754Z,1542218364.754 [MassServo] Loaded 2018-11-14T17:59:24.754Z,1542218364.754 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-14T17:59:24.769Z,1542218364.769 [RudderServo] Loaded 2018-11-14T17:59:24.770Z,1542218364.770 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-14T17:59:24.784Z,1542218364.784 [ThrusterServo] Loaded 2018-11-14T17:59:24.784Z,1542218364.784 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-14T17:59:24.785Z,1542218364.785 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-14T17:59:24.785Z,1542218364.785 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-14T17:59:24.899Z,1542218364.899 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-11-14T17:59:24.899Z,1542218364.899 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-11-14T17:59:24.952Z,1542218364.952 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-14T17:59:24.953Z,1542218364.953 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-14T17:59:24.969Z,1542218364.969 [NavChart] Loaded 2018-11-14T17:59:24.969Z,1542218364.969 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-14T17:59:24.973Z,1542218364.973 [UniversalFixResidualReporter] Loaded 2018-11-14T17:59:24.973Z,1542218364.973 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-14T17:59:24.974Z,1542218364.974 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-14T17:59:24.974Z,1542218364.974 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-14T17:59:24.998Z,1542218364.998 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-14T17:59:24.999Z,1542218364.999 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-14T17:59:25.280Z,1542218365.280 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-14T17:59:25.281Z,1542218365.281 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-14T17:59:25.901Z,1542218365.901 [AHRS_M2] Loaded 2018-11-14T17:59:25.901Z,1542218365.901 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-11-14T17:59:26.039Z,1542218366.039 [DataOverHttps] Loaded 2018-11-14T17:59:26.039Z,1542218366.039 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-14T17:59:26.052Z,1542218366.052 [Depth_Keller] Loaded 2018-11-14T17:59:26.052Z,1542218366.052 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-14T17:59:26.057Z,1542218366.057 [DropWeight] Loaded 2018-11-14T17:59:26.057Z,1542218366.057 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-14T17:59:26.146Z,1542218366.146 [NAL9602] Loaded 2018-11-14T17:59:26.146Z,1542218366.146 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-14T17:59:26.151Z,1542218366.151 [Onboard] Loaded 2018-11-14T17:59:26.151Z,1542218366.151 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-14T17:59:26.158Z,1542218366.158 [Radio_Surface] Loaded 2018-11-14T17:59:26.158Z,1542218366.158 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-14T17:59:26.159Z,1542218366.159 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0 2018-11-14T17:59:26.160Z,1542218366.160 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882 2018-11-14T17:59:26.187Z,1542218366.187 [RDI_Pathfinder] Loaded 2018-11-14T17:59:26.188Z,1542218366.188 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-11-14T17:59:26.209Z,1542218366.209 [RDI_PathfinderUp] Loaded 2018-11-14T17:59:26.209Z,1542218366.209 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-11-14T17:59:26.215Z,1542218366.215 [SCPI] Loaded 2018-11-14T17:59:26.216Z,1542218366.216 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-11-14T17:59:28.195Z,1542218368.195 [BPC1] Loaded 2018-11-14T17:59:28.196Z,1542218368.196 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-14T17:59:28.196Z,1542218368.196 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-14T17:59:28.197Z,1542218368.197 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-14T17:59:28.286Z,1542218368.286 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-14T17:59:28.371Z,1542218368.371 [VerticalControl] Loaded 2018-11-14T17:59:28.372Z,1542218368.372 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-14T17:59:28.372Z,1542218368.372 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-14T17:59:28.429Z,1542218368.429 [HorizontalControl] Loaded 2018-11-14T17:59:28.430Z,1542218368.430 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-14T17:59:28.430Z,1542218368.430 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-14T17:59:28.432Z,1542218368.432 [SpeedControl] Loaded 2018-11-14T17:59:28.433Z,1542218368.433 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-14T17:59:28.433Z,1542218368.433 [LoopControl](DEBUG): Construct LoopControl. 2018-11-14T17:59:28.434Z,1542218368.434 [LoopControl] Loaded 2018-11-14T17:59:28.434Z,1542218368.434 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-14T17:59:28.435Z,1542218368.435 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-14T17:59:28.435Z,1542218368.435 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-14T17:59:28.562Z,1542218368.562 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-14T17:59:28.563Z,1542218368.563 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-14T17:59:28.769Z,1542218368.769 [Aanderaa_O2] Loaded 2018-11-14T17:59:28.769Z,1542218368.769 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-11-14T17:59:28.853Z,1542218368.853 [CTD_NeilBrown] Loaded 2018-11-14T17:59:28.853Z,1542218368.853 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-11-14T17:59:28.854Z,1542218368.854 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409374E0 2018-11-14T17:59:28.855Z,1542218368.855 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 883 2018-11-14T17:59:28.898Z,1542218368.898 [WetLabsSeaOWL_UV_A] Loaded 2018-11-14T17:59:28.899Z,1542218368.899 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-11-14T17:59:28.900Z,1542218368.900 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409674E0 2018-11-14T17:59:28.900Z,1542218368.900 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 884 2018-11-14T17:59:28.901Z,1542218368.901 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-14T17:59:28.901Z,1542218368.901 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-14T17:59:29.003Z,1542218369.003 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-14T17:59:29.005Z,1542218369.005 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-14T17:59:29.061Z,1542218369.061 [DepthRateCalculator] Loaded 2018-11-14T17:59:29.061Z,1542218369.061 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-14T17:59:29.066Z,1542218369.066 [PitchRateCalculator] Loaded 2018-11-14T17:59:29.067Z,1542218369.067 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-14T17:59:29.078Z,1542218369.078 [SpeedCalculator] Loaded 2018-11-14T17:59:29.079Z,1542218369.079 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-14T17:59:29.099Z,1542218369.099 [TempGradientCalculator] Loaded 2018-11-14T17:59:29.100Z,1542218369.100 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-14T17:59:29.105Z,1542218369.105 [YawRateCalculator] Loaded 2018-11-14T17:59:29.105Z,1542218369.105 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-14T17:59:29.134Z,1542218369.134 [ElevatorOffsetCalculator] Loaded 2018-11-14T17:59:29.135Z,1542218369.135 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-14T17:59:29.135Z,1542218369.135 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-14T17:59:29.136Z,1542218369.136 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-14T17:59:29.173Z,1542218369.173 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-14T17:59:29.177Z,1542218369.177 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-14T17:59:29.178Z,1542218369.178 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-14T17:59:29.184Z,1542218369.184 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-14T17:59:29.185Z,1542218369.185 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0 2018-11-14T17:59:29.186Z,1542218369.186 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885 2018-11-14T17:59:29.190Z,1542218369.190 [Supervisor](INFO): Main Thread ID is 795 2018-11-14T17:59:29.190Z,1542218369.190 [Supervisor](DEBUG): Running supervisor. 2018-11-14T17:59:29.191Z,1542218369.191 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886 2018-11-14T17:59:29.193Z,1542218369.193 [controlThread ThreadHandler](INFO): Handler Thread ID is 887 2018-11-14T17:59:29.194Z,1542218369.194 [controlThread](DEBUG): Initializing ControlThread 2018-11-14T17:59:29.195Z,1542218369.195 [SBIT](INFO): Initialize SBIT Component. 2018-11-14T17:59:29.195Z,1542218369.195 [SBIT](IMPORTANT): git: 2018-11-05-2-g6a71760 2018-11-14T17:59:29.195Z,1542218369.195 [SBIT](INFO): git hash: 6a71760e9f7f683776be04bba2e56bc8c8a5e6e9 2018-11-14T17:59:29.196Z,1542218369.196 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-14T17:59:29.196Z,1542218369.196 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-14T17:59:29.197Z,1542218369.197 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-11-14T17:59:29.197Z,1542218369.197 [IBIT](INFO): Initialize IBIT Component. 2018-11-14T17:59:29.198Z,1542218369.198 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-14T17:59:29.199Z,1542218369.199 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-11-14T17:59:29.199Z,1542218369.199 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-11-14T17:59:29.200Z,1542218369.200 [logger ThreadHandler](INFO): Handler Thread ID is 888 2018-11-14T17:59:29.231Z,1542218369.231 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889 2018-11-14T17:59:29.236Z,1542218369.236 [Radio_Surface](INFO): Powering up 2018-11-14T17:59:29.243Z,1542218369.243 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 890 2018-11-14T17:59:29.244Z,1542218369.244 [CTD_NeilBrown](INFO): Powering down 2018-11-14T17:59:29.271Z,1542218369.271 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 891 2018-11-14T17:59:29.272Z,1542218369.272 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-11-14T17:59:29.303Z,1542218369.303 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892 2018-11-14T17:59:29.308Z,1542218369.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T17:59:29.308Z,1542218369.308 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T17:59:29.309Z,1542218369.309 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T17:59:29.309Z,1542218369.309 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T17:59:29.310Z,1542218369.310 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T17:59:29.310Z,1542218369.310 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T17:59:29.310Z,1542218369.310 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-14T17:59:29.311Z,1542218369.311 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-14T17:59:29.317Z,1542218369.317 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-14T17:59:29.319Z,1542218369.319 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-14T17:59:29.320Z,1542218369.320 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-14T17:59:29.321Z,1542218369.321 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-14T17:59:29.321Z,1542218369.321 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-14T17:59:29.322Z,1542218369.322 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-14T17:59:29.322Z,1542218369.322 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-14T17:59:29.323Z,1542218369.323 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-14T17:59:29.325Z,1542218369.325 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-14T17:59:29.325Z,1542218369.325 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-14T17:59:29.327Z,1542218369.327 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-14T17:59:29.340Z,1542218369.340 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-14T17:59:29.340Z,1542218369.340 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-14T17:59:29.341Z,1542218369.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-14T17:59:29.341Z,1542218369.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-14T17:59:29.341Z,1542218369.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-14T17:59:29.341Z,1542218369.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-14T17:59:29.341Z,1542218369.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-14T17:59:29.342Z,1542218369.342 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-14T17:59:29.342Z,1542218369.342 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-14T17:59:29.342Z,1542218369.342 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-14T17:59:29.342Z,1542218369.342 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-14T17:59:29.343Z,1542218369.343 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-14T17:59:29.343Z,1542218369.343 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-14T17:59:29.343Z,1542218369.343 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-14T17:59:29.343Z,1542218369.343 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-14T17:59:29.343Z,1542218369.343 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-14T17:59:29.373Z,1542218369.373 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-14T17:59:29.410Z,1542218369.410 [MissionManager](DEBUG): 2018-11-14T17:59:29.423Z,1542218369.423 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-14T17:59:29.504Z,1542218369.504 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-14T17:59:29.506Z,1542218369.506 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-14T17:59:29.513Z,1542218369.513 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-14T17:59:29.532Z,1542218369.532 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-14T17:59:29.567Z,1542218369.567 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-14T17:59:29.573Z,1542218369.573 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-14T17:59:29.592Z,1542218369.592 [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-14T17:59:29.598Z,1542218369.598 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,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-14T17:59:29.642Z,1542218369.642 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T17:59:29.770Z,1542218369.770 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-14T17:59:29.815Z,1542218369.815 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-11-14T17:59:30.150Z,1542218370.150 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-14T17:59:30.388Z,1542218370.388 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-14T17:59:30.395Z,1542218370.395 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-14T17:59:30.417Z,1542218370.417 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-14T17:59:30.423Z,1542218370.423 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-14T17:59:30.429Z,1542218370.429 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-14T17:59:30.435Z,1542218370.435 [MassServo](DEBUG): Initializing MassServo. 2018-11-14T17:59:30.441Z,1542218370.441 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-14T17:59:30.447Z,1542218370.447 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-14T17:59:30.463Z,1542218370.463 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-14T17:59:30.471Z,1542218370.471 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-14T17:59:30.621Z,1542218370.621 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-14T17:59:30.621Z,1542218370.621 [DropWeight] Hardware Fault, FailCount= 1 2018-11-14T17:59:30.621Z,1542218370.621 [DropWeight](ERROR): Hardware Fault 2018-11-14T17:59:30.714Z,1542218370.714 [RDI_PathfinderUp](INFO): Powering down 2018-11-14T17:59:30.771Z,1542218370.771 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-14T17:59:30.800Z,1542218370.800 [CommandLine](FAULT): Scheduling is paused 2018-11-14T17:59:30.800Z,1542218370.800 [CBIT](INFO): Critical error at 20181114T175930 2018-11-14T17:59:30.800Z,1542218370.800 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-14T17:59:30.803Z,1542218370.803 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-14T17:59:30.803Z,1542218370.803 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-14T17:59:30.995Z,1542218370.995 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-14T17:59:31.326Z,1542218371.326 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-14T17:59:31.327Z,1542218371.327 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-14T17:59:31.327Z,1542218371.327 [BuoyancyServo] Communications Fault, FailCount= 1 2018-11-14T17:59:31.327Z,1542218371.327 [BuoyancyServo](ERROR): Communications Fault 2018-11-14T17:59:31.532Z,1542218371.532 [CBIT](INFO): Critical error at 20181114T175930 2018-11-14T17:59:31.534Z,1542218371.534 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-11-14T17:59:33.607Z,1542218373.607 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T17:59:33.703Z,1542218373.703 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-11-14T17:59:33.704Z,1542218373.704 [BuoyancyServo] No Fault, FailCount= 1 2018-11-14T17:59:34.001Z,1542218374.001 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-14T17:59:34.119Z,1542218374.119 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-14T17:59:35.266Z,1542218375.266 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T17:59:39.583Z,1542218379.583 [CBIT](CRITICAL): Environmental Failure. Press:14.963887 PSI. Humidity:15%. Temp:25 C. ABORTING MISSION 2018-11-14T17:59:39.661Z,1542218379.661 [CBIT](INFO): Critical error at 20181114T175939 2018-11-14T17:59:40.428Z,1542218380.428 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T17:59:40.428Z,1542218380.428 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-14T17:59:40.428Z,1542218380.428 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T17:59:40.475Z,1542218380.475 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T17:59:40.947Z,1542218380.947 [Aanderaa_O2](INFO): Powering down 2018-11-14T17:59:41.647Z,1542218381.647 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T17:59:41.647Z,1542218381.647 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-14T17:59:43.206Z,1542218383.206 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T17:59:43.206Z,1542218383.206 [AHRS_M2] Data Fault, FailCount= 1 2018-11-14T17:59:43.207Z,1542218383.207 [AHRS_M2](ERROR): Data Fault 2018-11-14T17:59:43.300Z,1542218383.300 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T17:59:43.596Z,1542218383.596 [AHRS_M2](INFO): Powering down 2018-11-14T17:59:47.251Z,1542218387.251 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T17:59:47.251Z,1542218387.251 [AHRS_M2] No Fault, FailCount= 1 2018-11-14T17:59:47.596Z,1542218387.596 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T17:59:51.266Z,1542218391.266 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T17:59:52.027Z,1542218392.027 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T17:59:52.027Z,1542218392.027 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-11-14T17:59:52.027Z,1542218392.027 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T17:59:52.067Z,1542218392.067 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T17:59:52.498Z,1542218392.498 [RDI_Pathfinder](INFO): Powering down 2018-11-14T17:59:52.501Z,1542218392.501 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T17:59:52.501Z,1542218392.501 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-14T17:59:52.501Z,1542218392.501 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T17:59:52.534Z,1542218392.534 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T17:59:52.950Z,1542218392.950 [Aanderaa_O2](INFO): Powering down 2018-11-14T17:59:53.270Z,1542218393.270 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T17:59:53.270Z,1542218393.270 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-14T17:59:53.685Z,1542218393.685 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T17:59:53.686Z,1542218393.686 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-14T17:59:56.412Z,1542218396.412 [NAL9602](INFO): Powering up NAL9602 2018-11-14T17:59:57.656Z,1542218397.656 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-14T17:59:57.684Z,1542218397.684 [CBIT](IMPORTANT): Beginning ground fault scan 2018-11-14T17:59:59.658Z,1542218399.658 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:00.990Z,1542218400.990 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:00:00.990Z,1542218400.990 [AHRS_M2] Data Fault, FailCount= 2 2018-11-14T18:00:00.991Z,1542218400.991 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:00:01.160Z,1542218401.160 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:00:01.380Z,1542218401.380 [AHRS_M2](INFO): Powering down 2018-11-14T18:00:04.199Z,1542218404.199 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:00:04.199Z,1542218404.199 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-14T18:00:04.199Z,1542218404.199 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:00:04.314Z,1542218404.314 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:00:04.314Z,1542218404.314 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-14T18:00:04.718Z,1542218404.718 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:00:05.083Z,1542218405.083 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:00:05.083Z,1542218405.083 [AHRS_M2] No Fault, FailCount= 2 2018-11-14T18:00:05.379Z,1542218405.379 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:00:07.384Z,1542218407.384 [NAL9602](INFO): NAL9602 initialized 2018-11-14T18:00:08.890Z,1542218408.890 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:09.470Z,1542218409.470 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:09.603Z,1542218409.603 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.018437 CHAN A1 (24V): 0.007386 CHAN A2 (12V): -0.007577 CHAN A3 (5V): -0.002124 CHAN B0 (3.3V): 0.000273 CHAN B1 (3.15aV): 0.000086 CHAN B2 (3.15bV): -0.000092 CHAN B3 (GND): 0.002089 OPEN: 0.006580 Full Scale Calc: 4.765 mA, -1.589 mA 2018-11-14T18:00:10.082Z,1542218410.082 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:11.682Z,1542218411.682 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:12.082Z,1542218412.082 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:15.853Z,1542218415.853 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:00:15.854Z,1542218415.854 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-14T18:00:15.854Z,1542218415.854 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:00:15.000Z,1542218416.000 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:00:16.323Z,1542218416.323 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:00:17.164Z,1542218417.164 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:00:17.164Z,1542218417.164 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-14T18:00:19.818Z,1542218419.818 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:00:19.818Z,1542218419.818 [AHRS_M2] Data Fault, FailCount= 3 2018-11-14T18:00:19.818Z,1542218419.818 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:00:19.934Z,1542218419.934 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:00:20.207Z,1542218420.207 [AHRS_M2](INFO): Powering down 2018-11-14T18:00:23.904Z,1542218423.904 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:00:23.904Z,1542218423.904 [AHRS_M2] No Fault, FailCount= 3 2018-11-14T18:00:24.207Z,1542218424.207 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:00:27.498Z,1542218427.498 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:27.902Z,1542218427.902 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:28.306Z,1542218428.306 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:28.702Z,1542218428.702 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:29.102Z,1542218429.102 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:29.502Z,1542218429.502 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:29.902Z,1542218429.902 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:30.291Z,1542218430.291 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:30.702Z,1542218430.702 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:31.102Z,1542218431.102 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:31.878Z,1542218431.878 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:32.678Z,1542218432.678 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:33.878Z,1542218433.878 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:35.070Z,1542218435.070 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:36.270Z,1542218436.270 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:37.470Z,1542218437.470 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:39.875Z,1542218439.875 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:39.918Z,1542218439.918 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:00:39.918Z,1542218439.918 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-14T18:00:39.919Z,1542218439.919 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:00:40.367Z,1542218440.367 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:00:40.368Z,1542218440.368 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:00:40.438Z,1542218440.438 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:40.539Z,1542218440.539 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:00:40.678Z,1542218440.678 [CBIT](INFO): Critical error at 20181114T180040 2018-11-14T18:00:40.838Z,1542218440.838 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:41.238Z,1542218441.238 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:41.638Z,1542218441.638 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:41.652Z,1542218441.652 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:00:41.652Z,1542218441.652 [AHRS_M2] Data Fault, FailCount= 4 2018-11-14T18:00:41.652Z,1542218441.652 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:00:41.784Z,1542218441.784 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:00:41.975Z,1542218441.975 [AHRS_M2](INFO): Powering down 2018-11-14T18:00:45.672Z,1542218445.672 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:00:45.672Z,1542218445.672 [AHRS_M2] No Fault, FailCount= 4 2018-11-14T18:00:45.975Z,1542218445.975 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:00:49.250Z,1542218449.250 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:49.650Z,1542218449.650 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:50.050Z,1542218450.050 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:00:50.846Z,1542218450.846 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:51.246Z,1542218451.246 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:51.304Z,1542218451.304 [SBIT](IMPORTANT): SBIT PASSED 2018-11-14T18:00:51.367Z,1542218451.367 [CommandLine](IMPORTANT): got command configSet list 2018-11-14T18:00:51.367Z,1542218451.367 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-11-14T18:00:51.371Z,1542218451.371 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2018-11-14T18:00:51.371Z,1542218451.371 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2018-11-14T18:00:51.371Z,1542218451.371 [CommandLine](IMPORTANT): BuoyancyServo.powerOnTimeout=1 second; 2018-11-14T18:00:51.371Z,1542218451.371 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2018-11-14T18:00:51.371Z,1542218451.371 [CommandLine](IMPORTANT): ElevatorServo.powerOnTimeout=0.8 second; 2018-11-14T18:00:51.371Z,1542218451.371 [CommandLine](IMPORTANT): MassServo.powerOnTimeout=0.8 second; 2018-11-14T18:00:51.372Z,1542218451.372 [CommandLine](IMPORTANT): NavChart.loadAtStartup=0 bool; 2018-11-14T18:00:51.372Z,1542218451.372 [CommandLine](IMPORTANT): RudderServo.powerOnTimeout=0.8 second; 2018-11-14T18:00:51.372Z,1542218451.372 [CommandLine](IMPORTANT): SCPI.loadAtStartup=0 bool; 2018-11-14T18:00:51.372Z,1542218451.372 [CommandLine](IMPORTANT): ThrusterServo.powerOnTimeout=0.8 second; 2018-11-14T18:00:51.372Z,1542218451.372 [CommandLine](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup=0 bool; 2018-11-14T18:00:51.632Z,1542218451.632 [MissionManager](IMPORTANT): Started mission Startup 2018-11-14T18:00:51.633Z,1542218451.633 [Startup] Running Loop=1 2018-11-14T18:00:51.633Z,1542218451.633 [Startup](DEBUG): Aggregate::initialize Startup 2018-11-14T18:00:51.633Z,1542218451.633 [Startup:A.GoToSurface] Running Loop=1 2018-11-14T18:00:51.633Z,1542218451.633 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-14T18:00:51.634Z,1542218451.634 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-14T18:00:51.634Z,1542218451.634 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-14T18:00:51.635Z,1542218451.635 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-14T18:00:51.635Z,1542218451.635 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-14T18:00:51.635Z,1542218451.635 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-14T18:00:51.644Z,1542218451.644 [Startup:StartupSatComms] Running Loop=1 2018-11-14T18:00:51.644Z,1542218451.644 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-11-14T18:00:51.644Z,1542218451.644 [Startup:StartupSatComms:A] Running Loop=1 2018-11-14T18:00:52.040Z,1542218452.040 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-11-14T18:00:52.446Z,1542218452.446 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:53.646Z,1542218453.646 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:54.847Z,1542218454.847 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:56.046Z,1542218456.046 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:00:57.246Z,1542218457.246 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:01:00.390Z,1542218460.390 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:01:00.390Z,1542218460.390 [AHRS_M2] Data Fault, FailCount= 5 2018-11-14T18:01:00.390Z,1542218460.390 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:01:00.458Z,1542218460.458 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:01:00.463Z,1542218460.463 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2018-11-14T18:01:00.775Z,1542218460.775 [AHRS_M2](INFO): Powering down 2018-11-14T18:01:00.903Z,1542218460.903 [CBIT](INFO): Critical error at 20181114T180100 2018-11-14T18:01:29.627Z,1542218489.627 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:01:29.627Z,1542218489.627 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-11-14T18:01:29.627Z,1542218489.627 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:01:29.628Z,1542218489.628 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:01:29.628Z,1542218489.628 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-11-14T18:01:29.629Z,1542218489.629 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:01:29.650Z,1542218489.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:01:29.650Z,1542218489.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:01:30.038Z,1542218490.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:01:30.038Z,1542218490.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-11-14T18:01:30.039Z,1542218490.039 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:01:30.039Z,1542218490.039 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-11-14T18:01:30.412Z,1542218490.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:01:30.412Z,1542218490.412 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:01:30.412Z,1542218490.412 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:01:30.413Z,1542218490.413 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:01:30.413Z,1542218490.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:01:30.413Z,1542218490.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:01:52.017Z,1542218512.017 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-14T18:00:51.6Z 2018-11-14T18:01:52.018Z,1542218512.018 [Startup:StartupSatComms:A] Stopped 2018-11-14T18:01:52.018Z,1542218512.018 [Startup:StartupSatComms:B] Running Loop=1 2018-11-14T18:01:52.423Z,1542218512.423 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-14T18:02:25.932Z,1542218545.932 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004284 2018-11-14T18:02:29.982Z,1542218549.982 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20181114T174425/Courier0004.lzma 2018-11-14T18:02:30.781Z,1542218550.781 [DataOverHttps](INFO): Moved sent file to Logs/20181114T174425/Courier0004.lzma.bak 2018-11-14T18:02:30.781Z,1542218550.781 [DataOverHttps](INFO): SBD MOMSN=8870545 2018-11-14T18:02:34.828Z,1542218554.828 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:02:34.828Z,1542218554.828 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-14T18:02:39.595Z,1542218559.595 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20181114T175222/Courier0000.lzma 2018-11-14T18:02:40.392Z,1542218560.392 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175222/Courier0000.lzma.bak 2018-11-14T18:02:40.393Z,1542218560.393 [DataOverHttps](INFO): SBD MOMSN=8870548 2018-11-14T18:02:45.295Z,1542218565.295 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:02:45.295Z,1542218565.295 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-14T18:02:45.295Z,1542218565.295 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:02:45.388Z,1542218565.388 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:02:45.779Z,1542218565.779 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:02:49.241Z,1542218569.241 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:02:49.241Z,1542218569.241 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-14T18:02:49.285Z,1542218569.285 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181114T175222/Courier0004.lzma 2018-11-14T18:02:50.082Z,1542218570.082 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175222/Courier0004.lzma.bak 2018-11-14T18:02:50.084Z,1542218570.084 [DataOverHttps](INFO): SBD MOMSN=8870554 2018-11-14T18:02:54.022Z,1542218574.022 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-14T18:01:52.0Z 2018-11-14T18:02:54.022Z,1542218574.022 [Startup:StartupSatComms:B] Stopped 2018-11-14T18:02:54.022Z,1542218574.022 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-11-14T18:02:54.022Z,1542218574.022 [Startup:StartupSatComms] Stopped 2018-11-14T18:02:54.022Z,1542218574.022 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-11-14T18:02:54.023Z,1542218574.023 [Startup](INFO): Completed Startup 2018-11-14T18:02:54.024Z,1542218574.024 [MissionManager](INFO): Startup is completed. 2018-11-14T18:02:54.024Z,1542218574.024 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-14T18:02:54.024Z,1542218574.024 [Startup] Stopped 2018-11-14T18:02:54.024Z,1542218574.024 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-11-14T18:02:54.024Z,1542218574.024 [Startup:A.GoToSurface] Stopped 2018-11-14T18:02:54.024Z,1542218574.024 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-14T18:02:54.163Z,1542218574.163 [MissionManager](IMPORTANT): Started mission Default 2018-11-14T18:02:54.164Z,1542218574.164 [Default] Running Loop=1 2018-11-14T18:02:54.164Z,1542218574.164 [Default](DEBUG): Aggregate::initialize Default 2018-11-14T18:02:54.164Z,1542218574.164 [Default:B.GoToSurface] Running Loop=1 2018-11-14T18:02:54.164Z,1542218574.164 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-14T18:02:54.164Z,1542218574.164 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-14T18:02:54.164Z,1542218574.164 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-14T18:02:54.165Z,1542218574.165 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-14T18:02:54.165Z,1542218574.165 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-14T18:02:54.165Z,1542218574.165 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-14T18:02:54.166Z,1542218574.166 [Default:A.Wait] Running Loop=1 2018-11-14T18:02:54.166Z,1542218574.166 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-11-14T18:02:58.883Z,1542218578.883 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20181114T175918/Courier0000.lzma 2018-11-14T18:02:59.680Z,1542218579.680 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175918/Courier0000.lzma.bak 2018-11-14T18:02:59.681Z,1542218579.681 [DataOverHttps](INFO): SBD MOMSN=8870556 2018-11-14T18:02:59.739Z,1542218579.739 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:02:59.739Z,1542218579.739 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-14T18:02:59.739Z,1542218579.739 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:02:59.788Z,1542218579.788 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:03:00.219Z,1542218580.219 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:03:00.932Z,1542218580.932 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:03:00.932Z,1542218580.932 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-14T18:03:07.313Z,1542218587.313 [Default:A.Wait](INFO): Done Waiting. 2018-11-14T18:03:07.314Z,1542218587.314 [Default:A.Wait] Stopped 2018-11-14T18:03:07.314Z,1542218587.314 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-11-14T18:03:07.713Z,1542218587.713 [Default:CheckIn] Running Loop=1 2018-11-14T18:03:07.713Z,1542218587.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-11-14T18:03:07.713Z,1542218587.713 [Default:CheckIn:Read_GPS] Running Loop=1 2018-11-14T18:03:08.113Z,1542218588.113 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-11-14T18:03:11.704Z,1542218591.704 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:03:11.704Z,1542218591.704 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-14T18:03:11.704Z,1542218591.704 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:03:11.755Z,1542218591.755 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:03:11.755Z,1542218591.755 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-14T18:03:12.287Z,1542218592.287 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:03:30.518Z,1542218610.518 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:03:30.519Z,1542218610.519 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-11-14T18:03:30.519Z,1542218610.519 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:03:30.520Z,1542218610.520 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:03:30.520Z,1542218610.520 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-11-14T18:03:30.520Z,1542218610.520 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:03:30.533Z,1542218610.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:03:30.534Z,1542218610.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:03:30.962Z,1542218610.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:03:30.962Z,1542218610.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-11-14T18:03:30.963Z,1542218610.963 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:03:30.963Z,1542218610.963 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-11-14T18:03:34.100Z,1542218614.100 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:03:34.101Z,1542218614.101 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:03:34.101Z,1542218614.101 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:03:34.102Z,1542218614.102 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:03:34.102Z,1542218614.102 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:03:34.103Z,1542218614.103 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:03:40.192Z,1542218620.192 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:03:40.192Z,1542218620.192 [RDI_Pathfinder] No Fault, FailCount= 3 2018-11-14T18:03:53.343Z,1542218633.343 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2018-11-14T18:03:53.343Z,1542218633.343 [RDI_Pathfinder] Data Fault, FailCount= 1 2018-11-14T18:03:53.344Z,1542218633.344 [RDI_Pathfinder](ERROR): Data Fault 2018-11-14T18:03:53.406Z,1542218633.406 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2018-11-14T18:03:53.815Z,1542218633.815 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:03:54.588Z,1542218634.588 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:03:54.588Z,1542218634.588 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-14T18:04:19.350Z,1542218659.350 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:04:19.350Z,1542218659.350 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-14T18:04:19.350Z,1542218659.350 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:04:19.375Z,1542218659.375 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:04:19.831Z,1542218659.831 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:04:20.574Z,1542218660.574 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:04:20.575Z,1542218660.575 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-14T18:04:44.432Z,1542218684.432 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:04:44.432Z,1542218684.432 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-14T18:04:44.432Z,1542218684.432 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:04:44.480Z,1542218684.480 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:04:44.480Z,1542218684.480 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:04:44.615Z,1542218684.615 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:04:44.638Z,1542218684.638 [CBIT](INFO): Critical error at 20181114T180444 2018-11-14T18:04:49.776Z,1542218689.776 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2018-11-14T18:05:11.292Z,1542218711.292 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-11-14T18:05:34.130Z,1542218734.130 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:05:34.130Z,1542218734.130 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2018-11-14T18:05:34.130Z,1542218734.130 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:05:34.132Z,1542218734.132 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:05:34.132Z,1542218734.132 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-11-14T18:05:34.132Z,1542218734.132 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:05:34.168Z,1542218734.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:05:34.169Z,1542218734.169 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:05:34.569Z,1542218734.569 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:05:34.570Z,1542218734.570 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2018-11-14T18:05:34.570Z,1542218734.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:05:34.570Z,1542218734.570 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-11-14T18:05:34.958Z,1542218734.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:05:34.958Z,1542218734.958 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:05:34.959Z,1542218734.959 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:05:34.959Z,1542218734.959 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:05:34.960Z,1542218734.960 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:05:34.960Z,1542218734.960 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:05:41.740Z,1542218741.740 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:05:41.740Z,1542218741.740 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-14T18:05:54.823Z,1542218754.823 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:05:54.823Z,1542218754.823 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-14T18:05:54.823Z,1542218754.823 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:05:54.841Z,1542218754.841 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:05:55.067Z,1542218755.067 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:05:55.744Z,1542218755.744 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:05:55.744Z,1542218755.744 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-14T18:06:00.567Z,1542218760.567 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:06:00.567Z,1542218760.567 [AHRS_M2] No Fault, FailCount= 5 2018-11-14T18:06:00.851Z,1542218760.851 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:06:04.146Z,1542218764.146 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:04.546Z,1542218764.546 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:04.946Z,1542218764.946 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:05.346Z,1542218765.346 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:05.737Z,1542218765.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:06.136Z,1542218766.136 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:06.156Z,1542218766.156 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:06:06.157Z,1542218766.157 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-14T18:06:06.157Z,1542218766.157 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:06:06.179Z,1542218766.179 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:06:06.538Z,1542218766.538 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:06.691Z,1542218766.691 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:06:06.938Z,1542218766.938 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:07.338Z,1542218767.338 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:07.418Z,1542218767.418 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:06:07.419Z,1542218767.419 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-14T18:06:07.738Z,1542218767.738 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:08.138Z,1542218768.138 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:08.538Z,1542218768.538 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:09.322Z,1542218769.322 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:10.122Z,1542218770.122 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:11.322Z,1542218771.322 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:12.522Z,1542218772.522 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:13.722Z,1542218773.722 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:14.922Z,1542218774.922 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:16.122Z,1542218776.122 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:17.322Z,1542218777.322 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:18.092Z,1542218778.092 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:06:18.092Z,1542218778.092 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-14T18:06:18.092Z,1542218778.092 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:06:18.143Z,1542218778.143 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:06:18.143Z,1542218778.143 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-14T18:06:18.514Z,1542218778.514 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:18.691Z,1542218778.691 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:06:19.266Z,1542218779.266 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:06:19.266Z,1542218779.266 [AHRS_M2] Data Fault, FailCount= 1 2018-11-14T18:06:19.266Z,1542218779.266 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:06:19.343Z,1542218779.343 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:06:19.653Z,1542218779.653 [AHRS_M2](INFO): Powering down 2018-11-14T18:06:23.299Z,1542218783.299 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:06:23.299Z,1542218783.299 [AHRS_M2] No Fault, FailCount= 1 2018-11-14T18:06:23.651Z,1542218783.651 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:06:26.946Z,1542218786.946 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:27.326Z,1542218787.326 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:30.166Z,1542218790.166 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:30.586Z,1542218790.586 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:30.966Z,1542218790.966 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:31.386Z,1542218791.386 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:31.786Z,1542218791.786 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:32.186Z,1542218792.186 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:32.586Z,1542218792.586 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:32.986Z,1542218792.986 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:33.366Z,1542218793.366 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:33.786Z,1542218793.786 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:06:33.787Z,1542218793.787 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-11-14T18:06:33.787Z,1542218793.787 [AHRS_M2] Communications Fault, FailCount= 2 2018-11-14T18:06:33.787Z,1542218793.787 [AHRS_M2](ERROR): Communications Fault 2018-11-14T18:06:33.860Z,1542218793.860 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-11-14T18:06:34.091Z,1542218794.091 [AHRS_M2](INFO): Powering down 2018-11-14T18:06:37.747Z,1542218797.747 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:06:37.747Z,1542218797.747 [AHRS_M2] No Fault, FailCount= 2 2018-11-14T18:06:38.091Z,1542218798.091 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:06:41.762Z,1542218801.762 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:42.562Z,1542218802.562 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:43.762Z,1542218803.762 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:44.962Z,1542218804.962 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:46.162Z,1542218806.162 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:47.362Z,1542218807.362 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:48.562Z,1542218808.562 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:49.762Z,1542218809.762 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:50.962Z,1542218810.962 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:51.306Z,1542218811.306 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:06:51.306Z,1542218811.306 [AHRS_M2] Data Fault, FailCount= 3 2018-11-14T18:06:51.306Z,1542218811.306 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:06:51.384Z,1542218811.384 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:06:51.691Z,1542218811.691 [AHRS_M2](INFO): Powering down 2018-11-14T18:06:55.359Z,1542218815.359 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:06:55.359Z,1542218815.359 [AHRS_M2] No Fault, FailCount= 3 2018-11-14T18:06:55.691Z,1542218815.691 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:06:59.362Z,1542218819.362 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:06:59.762Z,1542218819.762 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:00.962Z,1542218820.962 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:02.162Z,1542218822.162 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:05.734Z,1542218825.734 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:06.934Z,1542218826.934 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:09.334Z,1542218829.334 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:09.346Z,1542218829.346 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:07:09.346Z,1542218829.346 [AHRS_M2] Data Fault, FailCount= 4 2018-11-14T18:07:09.346Z,1542218829.346 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:07:09.409Z,1542218829.409 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:07:09.663Z,1542218829.663 [AHRS_M2](INFO): Powering down 2018-11-14T18:07:13.331Z,1542218833.331 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:07:13.331Z,1542218833.331 [AHRS_M2] No Fault, FailCount= 4 2018-11-14T18:07:13.663Z,1542218833.663 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:07:18.135Z,1542218838.135 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:07:23.012Z,1542218843.012 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-11-14T18:07:27.274Z,1542218847.274 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:07:27.274Z,1542218847.274 [AHRS_M2] Data Fault, FailCount= 5 2018-11-14T18:07:27.274Z,1542218847.274 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:07:27.317Z,1542218847.317 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:07:27.317Z,1542218847.317 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2018-11-14T18:07:27.663Z,1542218847.663 [AHRS_M2](INFO): Powering down 2018-11-14T18:07:27.804Z,1542218847.804 [CBIT](INFO): Critical error at 20181114T180727 2018-11-14T18:07:35.303Z,1542218855.303 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:07:35.303Z,1542218855.303 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2018-11-14T18:07:35.303Z,1542218855.303 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:07:35.304Z,1542218855.304 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:07:35.304Z,1542218855.304 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-11-14T18:07:35.305Z,1542218855.305 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:07:35.317Z,1542218855.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:07:35.318Z,1542218855.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:07:35.710Z,1542218855.710 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:07:35.710Z,1542218855.710 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2018-11-14T18:07:35.710Z,1542218855.710 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:07:35.710Z,1542218855.710 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-11-14T18:07:36.093Z,1542218856.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:07:36.093Z,1542218856.093 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:07:36.094Z,1542218856.094 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:07:36.094Z,1542218856.094 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:07:36.095Z,1542218856.095 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:07:36.095Z,1542218856.095 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:07:44.471Z,1542218864.471 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:07:44.471Z,1542218864.471 [RDI_Pathfinder] No Fault, FailCount= 3 2018-11-14T18:07:57.631Z,1542218877.631 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2018-11-14T18:07:57.631Z,1542218877.631 [RDI_Pathfinder] Data Fault, FailCount= 1 2018-11-14T18:07:57.631Z,1542218877.631 [RDI_Pathfinder](ERROR): Data Fault 2018-11-14T18:07:57.680Z,1542218877.680 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2018-11-14T18:07:58.099Z,1542218878.099 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:07:58.883Z,1542218878.883 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:07:58.883Z,1542218878.883 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-14T18:08:08.045Z,1542218888.045 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-14T18:03:07.7Z 2018-11-14T18:08:08.045Z,1542218888.045 [Default:CheckIn:Read_GPS] Stopped 2018-11-14T18:08:08.045Z,1542218888.045 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-11-14T18:08:08.453Z,1542218888.453 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-14T18:08:12.350Z,1542218892.350 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20181114T175918/Courier0004.lzma 2018-11-14T18:08:13.148Z,1542218893.148 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175918/Courier0004.lzma.bak 2018-11-14T18:08:13.149Z,1542218893.149 [DataOverHttps](INFO): SBD MOMSN=8870634 2018-11-14T18:08:21.865Z,1542218901.865 [DataOverHttps](INFO): Sending 921 bytes from file Logs/20181114T174237/Express0001.lzma 2018-11-14T18:08:22.661Z,1542218902.661 [DataOverHttps](INFO): Moved sent file to Logs/20181114T174237/Express0001.lzma.bak 2018-11-14T18:08:22.661Z,1542218902.661 [DataOverHttps](INFO): SBD MOMSN=8870638 2018-11-14T18:08:22.713Z,1542218902.713 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:08:22.713Z,1542218902.713 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-14T18:08:22.713Z,1542218902.713 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:08:22.761Z,1542218902.761 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:08:23.479Z,1542218903.479 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:08:26.669Z,1542218906.669 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:08:26.669Z,1542218906.669 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-14T18:08:31.422Z,1542218911.422 [DataOverHttps](INFO): Sending 931 bytes from file Logs/20181114T174425/Express0001.lzma 2018-11-14T18:08:32.217Z,1542218912.217 [DataOverHttps](INFO): Moved sent file to Logs/20181114T174425/Express0001.lzma.bak 2018-11-14T18:08:32.217Z,1542218912.217 [DataOverHttps](INFO): SBD MOMSN=8870678 2018-11-14T18:08:40.885Z,1542218920.885 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20181114T174425/Express0005.lzma 2018-11-14T18:08:41.684Z,1542218921.684 [DataOverHttps](INFO): Moved sent file to Logs/20181114T174425/Express0005.lzma.bak 2018-11-14T18:08:41.685Z,1542218921.685 [DataOverHttps](INFO): SBD MOMSN=8870705 2018-11-14T18:08:50.482Z,1542218930.482 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:08:50.482Z,1542218930.482 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-14T18:08:50.494Z,1542218930.494 [DataOverHttps](INFO): Sending 900 bytes from file Logs/20181114T175222/Express0001.lzma 2018-11-14T18:08:50.925Z,1542218930.925 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:08:50.925Z,1542218930.925 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-14T18:08:50.925Z,1542218930.925 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:08:50.975Z,1542218930.975 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:08:50.976Z,1542218930.976 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:08:51.292Z,1542218931.292 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175222/Express0001.lzma.bak 2018-11-14T18:08:51.293Z,1542218931.293 [DataOverHttps](INFO): SBD MOMSN=8870715 2018-11-14T18:08:51.408Z,1542218931.408 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:08:51.433Z,1542218931.433 [CBIT](INFO): Critical error at 20181114T180850 2018-11-14T18:09:00.055Z,1542218940.055 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181114T175222/Express0005.lzma 2018-11-14T18:09:00.852Z,1542218940.852 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175222/Express0005.lzma.bak 2018-11-14T18:09:00.853Z,1542218940.853 [DataOverHttps](INFO): SBD MOMSN=8870746 2018-11-14T18:09:00.906Z,1542218940.906 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:09:00.906Z,1542218940.906 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-14T18:09:00.906Z,1542218940.906 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:09:00.952Z,1542218940.952 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:09:01.723Z,1542218941.723 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:09:04.898Z,1542218944.898 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:09:04.898Z,1542218944.898 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-14T18:09:09.661Z,1542218949.661 [DataOverHttps](INFO): Sending 1118 bytes from file Logs/20181114T175918/Express0001.lzma 2018-11-14T18:09:10.437Z,1542218950.437 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175918/Express0001.lzma.bak 2018-11-14T18:09:10.437Z,1542218950.437 [DataOverHttps](INFO): SBD MOMSN=8870748 2018-11-14T18:09:15.262Z,1542218955.262 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:09:15.262Z,1542218955.262 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-14T18:09:15.262Z,1542218955.262 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:09:15.281Z,1542218955.281 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:09:15.771Z,1542218955.771 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:09:19.170Z,1542218959.170 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:09:19.170Z,1542218959.170 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-14T18:09:19.181Z,1542218959.181 [DataOverHttps](INFO): Sending 464 bytes from file Logs/20181114T175918/Express0005.lzma 2018-11-14T18:09:19.981Z,1542218959.981 [DataOverHttps](INFO): Moved sent file to Logs/20181114T175918/Express0005.lzma.bak 2018-11-14T18:09:19.981Z,1542218959.981 [DataOverHttps](INFO): SBD MOMSN=8870786 2018-11-14T18:09:20.761Z,1542218960.761 [Default:CheckIn:Read_Iridium] Stopped 2018-11-14T18:09:20.761Z,1542218960.761 [Default:CheckIn:C.Wait] Running Loop=1 2018-11-14T18:09:20.761Z,1542218960.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-11-14T18:09:29.554Z,1542218969.554 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:09:29.554Z,1542218969.554 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-14T18:09:29.570Z,1542218969.570 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:09:29.588Z,1542218969.588 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:09:29.588Z,1542218969.588 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-14T18:09:30.079Z,1542218970.079 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:09:36.374Z,1542218976.374 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:09:36.375Z,1542218976.375 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2018-11-14T18:09:36.375Z,1542218976.375 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:09:36.376Z,1542218976.376 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:09:36.376Z,1542218976.376 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-11-14T18:09:36.376Z,1542218976.376 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:09:36.389Z,1542218976.389 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:09:36.389Z,1542218976.389 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:09:36.782Z,1542218976.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:09:36.782Z,1542218976.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2018-11-14T18:09:36.783Z,1542218976.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:09:36.783Z,1542218976.783 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-11-14T18:09:37.169Z,1542218977.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:09:37.170Z,1542218977.170 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:09:37.170Z,1542218977.170 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:09:37.171Z,1542218977.171 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:09:37.171Z,1542218977.171 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:09:37.171Z,1542218977.171 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:09:37.595Z,1542218977.595 [CommandLine](IMPORTANT): got command failComponent 2018-11-14T18:09:37.595Z,1542218977.595 [CommandLine](IMPORTANT): Failed components: 2018-11-14T18:09:37.596Z,1542218977.596 [CommandLine](IMPORTANT): AHRS_M2: Data Fault 2018-11-14T18:09:37.596Z,1542218977.596 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2018-11-14T18:09:37.596Z,1542218977.596 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault 2018-11-14T18:09:37.596Z,1542218977.596 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2018-11-14T18:10:10.696Z,1542219010.696 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-11-14T18:10:10.696Z,1542219010.696 [NAL9602] Data Fault, FailCount= 1 2018-11-14T18:10:10.696Z,1542219010.696 [NAL9602](ERROR): Data Fault 2018-11-14T18:10:10.750Z,1542219010.750 [CBIT](ERROR): Data Fault in component: NAL9602 2018-11-14T18:10:11.096Z,1542219011.096 [NAL9602](INFO): Powering down 2018-11-14T18:10:11.936Z,1542219011.936 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-14T18:10:11.936Z,1542219011.936 [NAL9602] No Fault, FailCount= 1 2018-11-14T18:10:41.160Z,1542219041.160 [NAL9602](INFO): Powering up NAL9602 2018-11-14T18:10:52.360Z,1542219052.360 [NAL9602](INFO): NAL9602 initialized 2018-11-14T18:11:37.530Z,1542219097.530 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:11:37.531Z,1542219097.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2018-11-14T18:11:37.531Z,1542219097.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:11:37.532Z,1542219097.532 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:11:37.532Z,1542219097.532 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-11-14T18:11:37.532Z,1542219097.532 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:11:37.578Z,1542219097.578 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:11:37.578Z,1542219097.578 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:11:37.942Z,1542219097.942 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:11:37.942Z,1542219097.942 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2018-11-14T18:11:37.975Z,1542219097.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:11:37.975Z,1542219097.975 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-11-14T18:11:38.320Z,1542219098.320 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:11:38.320Z,1542219098.320 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:11:38.321Z,1542219098.321 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:11:38.321Z,1542219098.321 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:11:38.322Z,1542219098.322 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:11:38.322Z,1542219098.322 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:11:51.088Z,1542219111.088 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:11:51.088Z,1542219111.088 [RDI_Pathfinder] No Fault, FailCount= 3 2018-11-14T18:11:59.903Z,1542219119.903 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:11:59.903Z,1542219119.903 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-14T18:12:04.261Z,1542219124.261 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2018-11-14T18:12:04.261Z,1542219124.261 [RDI_Pathfinder] Data Fault, FailCount= 1 2018-11-14T18:12:04.261Z,1542219124.261 [RDI_Pathfinder](ERROR): Data Fault 2018-11-14T18:12:04.320Z,1542219124.320 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2018-11-14T18:12:04.728Z,1542219124.728 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:12:05.499Z,1542219125.499 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:12:05.499Z,1542219125.499 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-14T18:12:10.270Z,1542219130.270 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:12:10.270Z,1542219130.270 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-14T18:12:10.270Z,1542219130.270 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:12:10.348Z,1542219130.348 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:12:10.771Z,1542219130.771 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:12:11.513Z,1542219131.513 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:12:11.513Z,1542219131.513 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-14T18:12:21.909Z,1542219141.909 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:12:21.909Z,1542219141.909 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-14T18:12:21.909Z,1542219141.909 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:12:21.931Z,1542219141.931 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:12:22.430Z,1542219142.430 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:12:23.139Z,1542219143.139 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-14T18:12:23.139Z,1542219143.139 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-14T18:12:27.539Z,1542219147.539 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:12:27.540Z,1542219147.540 [AHRS_M2] No Fault, FailCount= 5 2018-11-14T18:12:27.883Z,1542219147.883 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:12:30.314Z,1542219150.314 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:12:30.314Z,1542219150.314 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-14T18:12:30.315Z,1542219150.315 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:12:30.335Z,1542219150.335 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:12:30.778Z,1542219150.778 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:12:31.406Z,1542219151.406 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:31.814Z,1542219151.814 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:31.880Z,1542219151.880 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-14T18:12:31.880Z,1542219151.880 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-14T18:12:32.222Z,1542219152.222 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:32.634Z,1542219152.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:33.048Z,1542219153.048 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:33.442Z,1542219153.442 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:33.618Z,1542219153.618 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:33.645Z,1542219153.645 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-14T18:12:33.645Z,1542219153.645 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-14T18:12:33.645Z,1542219153.645 [Aanderaa_O2](ERROR): Communications Fault 2018-11-14T18:12:33.706Z,1542219153.706 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-14T18:12:33.707Z,1542219153.707 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-14T18:12:34.014Z,1542219154.014 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:34.183Z,1542219154.183 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:12:34.402Z,1542219154.402 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:35.042Z,1542219155.042 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:35.230Z,1542219155.230 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:35.630Z,1542219155.630 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:36.030Z,1542219156.030 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:36.418Z,1542219156.418 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:36.830Z,1542219156.830 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:37.458Z,1542219157.458 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:37.634Z,1542219157.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:38.030Z,1542219158.030 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:38.030Z,1542219158.030 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-11-14T18:12:38.031Z,1542219158.031 [AHRS_M2] Communications Fault, FailCount= 1 2018-11-14T18:12:38.031Z,1542219158.031 [AHRS_M2](ERROR): Communications Fault 2018-11-14T18:12:38.117Z,1542219158.117 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-11-14T18:12:38.335Z,1542219158.335 [AHRS_M2](INFO): Powering down 2018-11-14T18:12:41.987Z,1542219161.987 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:12:41.987Z,1542219161.987 [AHRS_M2] No Fault, FailCount= 1 2018-11-14T18:12:42.335Z,1542219162.335 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:12:45.622Z,1542219165.622 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:46.030Z,1542219166.030 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:12:46.806Z,1542219166.806 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:47.606Z,1542219167.606 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:48.798Z,1542219168.798 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:49.999Z,1542219169.999 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:51.198Z,1542219171.198 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:52.399Z,1542219172.399 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:55.728Z,1542219175.728 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-14T18:12:55.728Z,1542219175.728 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-14T18:12:55.728Z,1542219175.728 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-14T18:12:55.752Z,1542219175.752 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:12:55.752Z,1542219175.752 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-14T18:12:55.923Z,1542219175.923 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:12:55.956Z,1542219175.956 [CBIT](INFO): Critical error at 20181114T181255 2018-11-14T18:12:56.231Z,1542219176.231 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:12:56.570Z,1542219176.570 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:12:56.570Z,1542219176.570 [AHRS_M2] Data Fault, FailCount= 2 2018-11-14T18:12:56.570Z,1542219176.570 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:12:56.659Z,1542219176.659 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:12:56.959Z,1542219176.959 [AHRS_M2](INFO): Powering down 2018-11-14T18:13:00.623Z,1542219180.623 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:13:00.623Z,1542219180.623 [AHRS_M2] No Fault, FailCount= 2 2018-11-14T18:13:00.959Z,1542219180.959 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:13:04.234Z,1542219184.234 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:04.634Z,1542219184.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:05.034Z,1542219185.034 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:05.454Z,1542219185.454 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:05.854Z,1542219185.854 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:06.254Z,1542219186.254 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:06.643Z,1542219186.643 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:07.054Z,1542219187.054 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:07.434Z,1542219187.434 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:07.854Z,1542219187.854 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:08.254Z,1542219188.254 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:08.634Z,1542219188.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:09.054Z,1542219189.054 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:09.454Z,1542219189.454 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:09.834Z,1542219189.834 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:10.254Z,1542219190.254 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:10.643Z,1542219190.643 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:11.034Z,1542219191.034 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:11.034Z,1542219191.034 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-11-14T18:13:11.035Z,1542219191.035 [AHRS_M2] Communications Fault, FailCount= 3 2018-11-14T18:13:11.035Z,1542219191.035 [AHRS_M2](ERROR): Communications Fault 2018-11-14T18:13:11.123Z,1542219191.123 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-11-14T18:13:11.359Z,1542219191.359 [AHRS_M2](INFO): Powering down 2018-11-14T18:13:15.011Z,1542219195.011 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:13:15.011Z,1542219195.011 [AHRS_M2] No Fault, FailCount= 3 2018-11-14T18:13:15.359Z,1542219195.359 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:13:18.634Z,1542219198.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:19.034Z,1542219199.034 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:19.434Z,1542219199.434 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:19.834Z,1542219199.834 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:20.254Z,1542219200.254 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:20.643Z,1542219200.643 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:21.034Z,1542219201.034 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:21.458Z,1542219201.458 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:21.854Z,1542219201.854 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:22.254Z,1542219202.254 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:22.655Z,1542219202.655 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:23.054Z,1542219203.054 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:23.434Z,1542219203.434 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:23.854Z,1542219203.854 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:24.258Z,1542219204.258 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:24.634Z,1542219204.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:25.058Z,1542219205.058 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:25.454Z,1542219205.454 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:25.455Z,1542219205.455 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-11-14T18:13:25.455Z,1542219205.455 [AHRS_M2] Communications Fault, FailCount= 4 2018-11-14T18:13:25.455Z,1542219205.455 [AHRS_M2](ERROR): Communications Fault 2018-11-14T18:13:25.497Z,1542219205.497 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-11-14T18:13:25.759Z,1542219205.759 [AHRS_M2](INFO): Powering down 2018-11-14T18:13:31.035Z,1542219211.035 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-11-14T18:13:31.035Z,1542219211.035 [AHRS_M2] No Fault, FailCount= 4 2018-11-14T18:13:31.387Z,1542219211.387 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-11-14T18:13:34.662Z,1542219214.662 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:35.062Z,1542219215.062 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:35.462Z,1542219215.462 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:35.875Z,1542219215.875 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:36.262Z,1542219216.262 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:36.662Z,1542219216.662 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:37.062Z,1542219217.062 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:37.462Z,1542219217.462 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:37.862Z,1542219217.862 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:38.262Z,1542219218.262 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-11-14T18:13:38.638Z,1542219218.638 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:13:38.638Z,1542219218.638 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2018-11-14T18:13:38.638Z,1542219218.638 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-14T18:13:38.640Z,1542219218.640 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-14T18:13:38.640Z,1542219218.640 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2018-11-14T18:13:38.640Z,1542219218.640 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-14T18:13:38.653Z,1542219218.653 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-14T18:13:38.653Z,1542219218.653 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-14T18:13:39.058Z,1542219219.058 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:39.133Z,1542219219.133 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-14T18:13:39.133Z,1542219219.133 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2018-11-14T18:13:39.134Z,1542219219.134 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-14T18:13:39.134Z,1542219219.134 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2018-11-14T18:13:39.458Z,1542219219.458 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:39.502Z,1542219219.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-14T18:13:39.503Z,1542219219.503 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:13:39.503Z,1542219219.503 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:13:39.503Z,1542219219.503 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-14T18:13:39.504Z,1542219219.504 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-14T18:13:39.504Z,1542219219.504 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-14T18:13:40.658Z,1542219220.658 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:41.906Z,1542219221.906 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:43.058Z,1542219223.058 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:44.258Z,1542219224.258 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:45.458Z,1542219225.458 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:46.658Z,1542219226.658 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:47.850Z,1542219227.850 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:49.058Z,1542219229.058 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-11-14T18:13:49.070Z,1542219229.070 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-11-14T18:13:49.070Z,1542219229.070 [AHRS_M2] Data Fault, FailCount= 5 2018-11-14T18:13:49.070Z,1542219229.070 [AHRS_M2](ERROR): Data Fault 2018-11-14T18:13:49.152Z,1542219229.152 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-11-14T18:13:49.153Z,1542219229.153 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2018-11-14T18:13:49.387Z,1542219229.387 [AHRS_M2](INFO): Powering down 2018-11-14T18:13:49.501Z,1542219229.501 [CBIT](INFO): Critical error at 20181114T181349 2018-11-14T18:14:00.824Z,1542219240.824 [CommandLine](IMPORTANT): got command restart application 2018-11-14T18:14:01.833Z,1542219241.833 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:01.834Z,1542219241.834 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:01.958Z,1542219241.958 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-14T18:14:01.958Z,1542219241.958 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:01.959Z,1542219241.959 [CommandLine](INFO): Join timeout helper Thread ID is 980 2018-11-14T18:14:01.960Z,1542219241.960 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-14T18:14:01.960Z,1542219241.960 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:01.960Z,1542219241.960 [NavChartDb](INFO): Join timeout helper Thread ID is 981 2018-11-14T18:14:02.275Z,1542219242.275 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:02.275Z,1542219242.275 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.286Z,1542219242.286 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2018-11-14T18:14:02.286Z,1542219242.286 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.287Z,1542219242.287 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 982 2018-11-14T18:14:02.349Z,1542219242.349 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:02.349Z,1542219242.349 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-11-14T18:14:02.350Z,1542219242.350 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.366Z,1542219242.366 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2018-11-14T18:14:02.366Z,1542219242.366 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.367Z,1542219242.367 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 983 2018-11-14T18:14:02.496Z,1542219242.496 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:02.496Z,1542219242.496 [CTD_NeilBrown](INFO): Powering down 2018-11-14T18:14:02.510Z,1542219242.510 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.526Z,1542219242.526 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-14T18:14:02.526Z,1542219242.526 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.527Z,1542219242.527 [Radio_Surface](INFO): Join timeout helper Thread ID is 984 2018-11-14T18:14:02.727Z,1542219242.727 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:02.727Z,1542219242.727 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.735Z,1542219242.735 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-14T18:14:02.735Z,1542219242.735 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.736Z,1542219242.736 [logger](INFO): Join timeout helper Thread ID is 985 2018-11-14T18:14:02.746Z,1542219242.746 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:02.747Z,1542219242.747 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.755Z,1542219242.755 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-14T18:14:02.755Z,1542219242.755 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.755Z,1542219242.755 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-14T18:14:02.755Z,1542219242.755 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:02.756Z,1542219242.756 [controlThread](INFO): Join timeout helper Thread ID is 986 2018-11-14T18:14:02.982Z,1542219242.982 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-14T18:14:02.983Z,1542219242.983 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-14T18:14:02.983Z,1542219242.983 [AHRS_M2](INFO): Powering down 2018-11-14T18:14:02.985Z,1542219242.985 [NAL9602](INFO): Powering down 2018-11-14T18:14:02.986Z,1542219242.986 [RDI_Pathfinder](INFO): Powering down 2018-11-14T18:14:02.987Z,1542219242.987 [RDI_PathfinderUp](INFO): Powering down 2018-11-14T18:14:02.988Z,1542219242.988 [Aanderaa_O2](INFO): Powering down 2018-11-14T18:14:02.989Z,1542219242.989 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-14T18:14:02.990Z,1542219242.990 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-14T18:14:02.990Z,1542219242.990 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-14T18:14:02.991Z,1542219242.991 [MissionManager](INFO): Uninitializing Mission Default 2018-11-14T18:14:02.991Z,1542219242.991 [Default] Stopped 2018-11-14T18:14:02.991Z,1542219242.991 [Default](DEBUG): Aggregate::uninitialize Default 2018-11-14T18:14:02.991Z,1542219242.991 [Default:B.GoToSurface] Stopped 2018-11-14T18:14:02.991Z,1542219242.991 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-14T18:14:02.991Z,1542219242.991 [Default:CheckIn] Stopped 2018-11-14T18:14:02.991Z,1542219242.991 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-11-14T18:14:02.991Z,1542219242.991 [Default:CheckIn:C.Wait] Stopped 2018-11-14T18:14:02.991Z,1542219242.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-11-14T18:14:02.995Z,1542219242.995 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-14T18:14:02.995Z,1542219242.995 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-14T18:14:02.995Z,1542219242.995 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-14T18:14:02.995Z,1542219242.995 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-14T18:14:02.995Z,1542219242.995 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-14T18:14:02.996Z,1542219242.996 [BuoyancyServo](INFO): Powering down 2018-11-14T18:14:03.011Z,1542219243.011 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-14T18:14:03.011Z,1542219243.011 [ElevatorServo](INFO): Powering down 2018-11-14T18:14:03.012Z,1542219243.012 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-14T18:14:03.012Z,1542219243.012 [MassServo](INFO): Powering down 2018-11-14T18:14:03.012Z,1542219243.012 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-14T18:14:03.013Z,1542219243.013 [RudderServo](INFO): Powering down 2018-11-14T18:14:03.013Z,1542219243.013 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-14T18:14:03.013Z,1542219243.013 [ThrusterServo](INFO): Powering down 2018-11-14T18:14:03.014Z,1542219243.014 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-14T18:14:03.015Z,1542219243.015 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-14T18:14:03.015Z,1542219243.015 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-14T18:14:03.016Z,1542219243.016 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:03.121Z,1542219243.121 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:03.163Z,1542219243.163 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:03.165Z,1542219243.165 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:03.187Z,1542219243.187 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-14T18:14:03.230Z,1542219243.230 [logger ThreadHandler](INFO): Thread cancelled.