2018-11-28T23:43:18.864Z,1543448598.864 [Supervisor](DEBUG): Initializing supervisor. 2018-11-28T23:43:18.867Z,1543448598.867 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-28T23:43:18.868Z,1543448598.868 [SyncHandler](INFO): Protected caller Thread ID is 799 2018-11-28T23:43:18.869Z,1543448598.869 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-28T23:43:18.871Z,1543448598.871 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-28T23:43:18.872Z,1543448598.872 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800 2018-11-28T23:43:18.879Z,1543448598.879 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-28T23:43:18.902Z,1543448598.902 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-28T23:43:18.904Z,1543448598.904 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-28T23:43:18.904Z,1543448598.904 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801 2018-11-28T23:43:18.907Z,1543448598.907 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-28T23:43:18.909Z,1543448598.909 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-28T23:43:18.910Z,1543448598.910 [logger ThreadHandler](INFO): Protected caller Thread ID is 802 2018-11-28T23:43:18.912Z,1543448598.912 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-28T23:43:18.912Z,1543448598.912 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-28T23:43:18.916Z,1543448598.916 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-28T23:43:19.040Z,1543448599.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-28T23:43:19.042Z,1543448599.042 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-28T23:43:19.155Z,1543448599.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-28T23:43:19.157Z,1543448599.157 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-28T23:43:19.410Z,1543448599.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-28T23:43:19.410Z,1543448599.410 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-28T23:43:19.824Z,1543448599.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-28T23:43:19.826Z,1543448599.826 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-28T23:43:20.233Z,1543448600.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-28T23:43:20.234Z,1543448600.234 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-28T23:43:20.794Z,1543448600.794 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-28T23:43:20.796Z,1543448600.796 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-28T23:43:21.390Z,1543448601.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-28T23:43:21.393Z,1543448601.393 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-28T23:43:21.999Z,1543448601.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-28T23:43:22.001Z,1543448602.001 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-28T23:43:22.084Z,1543448602.084 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-28T23:43:22.428Z,1543448602.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-28T23:43:22.430Z,1543448602.430 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-28T23:43:22.586Z,1543448602.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-28T23:43:22.586Z,1543448602.586 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-28T23:43:22.815Z,1543448602.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-28T23:43:22.816Z,1543448602.816 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-28T23:43:23.034Z,1543448603.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-28T23:43:23.035Z,1543448603.035 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-28T23:43:23.136Z,1543448603.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-28T23:43:23.138Z,1543448603.138 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-28T23:43:23.290Z,1543448603.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-28T23:43:23.292Z,1543448603.292 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-11-28T23:43:23.294Z,1543448603.294 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-11-28T23:43:23.534Z,1543448603.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-28T23:43:23.535Z,1543448603.535 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-11-28T23:43:23.644Z,1543448603.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-11-28T23:43:23.803Z,1543448603.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-11-28T23:43:23.899Z,1543448603.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-11-28T23:43:23.986Z,1543448603.986 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-11-28T23:43:24.151Z,1543448604.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-11-28T23:43:24.361Z,1543448604.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-11-28T23:43:24.463Z,1543448604.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-11-28T23:43:24.561Z,1543448604.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-11-28T23:43:24.665Z,1543448604.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-11-28T23:43:24.749Z,1543448604.749 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-11-28T23:43:24.752Z,1543448604.751 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-28T23:43:24.889Z,1543448604.889 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-28T23:43:24.891Z,1543448604.891 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-28T23:43:24.916Z,1543448604.916 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-28T23:43:24.918Z,1543448604.918 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-28T23:43:24.976Z,1543448604.976 [DepthRateCalculator] Loaded 2018-11-28T23:43:24.977Z,1543448604.977 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-28T23:43:24.983Z,1543448604.983 [PitchRateCalculator] Loaded 2018-11-28T23:43:24.983Z,1543448604.983 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-28T23:43:25.000Z,1543448605.000 [SpeedCalculator] Loaded 2018-11-28T23:43:25.001Z,1543448605.001 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-28T23:43:25.023Z,1543448605.023 [TempGradientCalculator] Loaded 2018-11-28T23:43:25.023Z,1543448605.023 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-28T23:43:25.029Z,1543448605.029 [YawRateCalculator] Loaded 2018-11-28T23:43:25.030Z,1543448605.030 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-28T23:43:25.072Z,1543448605.072 [ElevatorOffsetCalculator] Loaded 2018-11-28T23:43:25.072Z,1543448605.072 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-28T23:43:25.073Z,1543448605.073 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-28T23:43:25.074Z,1543448605.074 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-28T23:43:25.113Z,1543448605.113 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-28T23:43:25.114Z,1543448605.114 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-28T23:43:25.614Z,1543448605.614 [AcousticModem_Benthos_ATM900] Loaded 2018-11-28T23:43:25.614Z,1543448605.614 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-11-28T23:43:25.754Z,1543448605.754 [DataOverHttps] Loaded 2018-11-28T23:43:25.754Z,1543448605.754 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-28T23:43:25.769Z,1543448605.769 [Depth_Keller] Loaded 2018-11-28T23:43:25.769Z,1543448605.769 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-28T23:43:25.774Z,1543448605.774 [DropWeight] Loaded 2018-11-28T23:43:25.774Z,1543448605.774 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-28T23:43:25.879Z,1543448605.879 [NAL9602] Loaded 2018-11-28T23:43:25.879Z,1543448605.879 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-28T23:43:25.896Z,1543448605.896 [Onboard] Loaded 2018-11-28T23:43:25.896Z,1543448605.896 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-28T23:43:25.907Z,1543448605.907 [Radio_Surface] Loaded 2018-11-28T23:43:25.907Z,1543448605.907 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-28T23:43:25.908Z,1543448605.908 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-11-28T23:43:25.909Z,1543448605.909 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 881 2018-11-28T23:43:26.065Z,1543448606.065 [PNI_TCM] Loaded 2018-11-28T23:43:26.065Z,1543448606.065 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-11-28T23:43:26.131Z,1543448606.131 [Rowe_600LCM] Loaded 2018-11-28T23:43:26.131Z,1543448606.131 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-11-28T23:43:26.132Z,1543448606.132 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-11-28T23:43:26.133Z,1543448606.133 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 882 2018-11-28T23:43:28.387Z,1543448608.387 [BPC1] Loaded 2018-11-28T23:43:28.387Z,1543448608.387 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-28T23:43:28.388Z,1543448608.388 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-28T23:43:28.388Z,1543448608.388 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-28T23:43:28.557Z,1543448608.557 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-28T23:43:28.581Z,1543448608.581 [SBIT] Loaded 2018-11-28T23:43:28.581Z,1543448608.581 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-28T23:43:28.582Z,1543448608.582 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-28T23:43:28.594Z,1543448608.594 [IBIT] Loaded 2018-11-28T23:43:28.594Z,1543448608.594 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-28T23:43:28.597Z,1543448608.597 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-28T23:43:28.756Z,1543448608.756 [CBIT] Loaded 2018-11-28T23:43:28.756Z,1543448608.756 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-28T23:43:28.757Z,1543448608.757 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-28T23:43:28.758Z,1543448608.758 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-28T23:43:28.979Z,1543448608.979 [Aanderaa_O2] Loaded 2018-11-28T23:43:28.979Z,1543448608.979 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-11-28T23:43:29.040Z,1543448609.040 [CTD_Seabird] Loaded 2018-11-28T23:43:29.040Z,1543448609.040 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-11-28T23:43:29.042Z,1543448609.042 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0 2018-11-28T23:43:29.042Z,1543448609.042 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 883 2018-11-28T23:43:29.061Z,1543448609.061 [ESPComponent] Loaded 2018-11-28T23:43:29.061Z,1543448609.061 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-11-28T23:43:29.076Z,1543448609.076 [PAR_Licor] Loaded 2018-11-28T23:43:29.076Z,1543448609.076 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-11-28T23:43:29.129Z,1543448609.129 [WetLabsBB2FL] Loaded 2018-11-28T23:43:29.129Z,1543448609.129 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-11-28T23:43:29.130Z,1543448609.130 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0 2018-11-28T23:43:29.130Z,1543448609.130 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 884 2018-11-28T23:43:29.131Z,1543448609.131 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-28T23:43:29.133Z,1543448609.133 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-28T23:43:29.435Z,1543448609.435 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-28T23:43:29.435Z,1543448609.435 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-28T23:43:29.531Z,1543448609.531 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-28T23:43:29.632Z,1543448609.632 [VerticalControl] Loaded 2018-11-28T23:43:29.632Z,1543448609.632 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-28T23:43:29.633Z,1543448609.633 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-28T23:43:29.697Z,1543448609.697 [HorizontalControl] Loaded 2018-11-28T23:43:29.698Z,1543448609.698 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-28T23:43:29.698Z,1543448609.698 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-28T23:43:29.704Z,1543448609.704 [SpeedControl] Loaded 2018-11-28T23:43:29.704Z,1543448609.704 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-28T23:43:29.705Z,1543448609.705 [LoopControl](DEBUG): Construct LoopControl. 2018-11-28T23:43:29.705Z,1543448609.705 [LoopControl] Loaded 2018-11-28T23:43:29.706Z,1543448609.706 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-28T23:43:29.706Z,1543448609.706 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-28T23:43:29.708Z,1543448609.708 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-28T23:43:29.816Z,1543448609.816 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-28T23:43:29.816Z,1543448609.816 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-28T23:43:29.943Z,1543448609.943 [BuoyancyServo] Loaded 2018-11-28T23:43:29.943Z,1543448609.943 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-28T23:43:29.955Z,1543448609.955 [ElevatorServo] Loaded 2018-11-28T23:43:29.955Z,1543448609.955 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-28T23:43:29.967Z,1543448609.967 [MassServo] Loaded 2018-11-28T23:43:29.967Z,1543448609.967 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-28T23:43:29.979Z,1543448609.979 [RudderServo] Loaded 2018-11-28T23:43:29.980Z,1543448609.980 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-28T23:43:29.991Z,1543448609.991 [ThrusterServo] Loaded 2018-11-28T23:43:29.991Z,1543448609.991 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-28T23:43:29.992Z,1543448609.992 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-28T23:43:29.992Z,1543448609.992 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-28T23:43:30.105Z,1543448610.105 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-28T23:43:30.106Z,1543448610.106 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-28T23:43:30.123Z,1543448610.123 [NavChart] Loaded 2018-11-28T23:43:30.123Z,1543448610.123 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-28T23:43:30.128Z,1543448610.128 [UniversalFixResidualReporter] Loaded 2018-11-28T23:43:30.128Z,1543448610.128 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-28T23:43:30.128Z,1543448610.128 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-28T23:43:30.132Z,1543448610.132 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-28T23:43:30.133Z,1543448610.133 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-28T23:43:30.140Z,1543448610.140 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-28T23:43:30.141Z,1543448610.141 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A504E0 2018-11-28T23:43:30.141Z,1543448610.141 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885 2018-11-28T23:43:30.146Z,1543448610.146 [Supervisor](INFO): Main Thread ID is 795 2018-11-28T23:43:30.146Z,1543448610.146 [Supervisor](DEBUG): Running supervisor. 2018-11-28T23:43:30.146Z,1543448610.146 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886 2018-11-28T23:43:30.149Z,1543448610.149 [controlThread ThreadHandler](INFO): Handler Thread ID is 887 2018-11-28T23:43:30.149Z,1543448610.149 [controlThread](DEBUG): Initializing ControlThread 2018-11-28T23:43:30.150Z,1543448610.150 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-28T23:43:30.150Z,1543448610.150 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-28T23:43:30.151Z,1543448610.151 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-28T23:43:30.151Z,1543448610.151 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-28T23:43:30.152Z,1543448610.152 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-28T23:43:30.152Z,1543448610.152 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-28T23:43:30.156Z,1543448610.156 [SBIT](INFO): Initialize SBIT Component. 2018-11-28T23:43:30.157Z,1543448610.157 [SBIT](IMPORTANT): git: 2018-11-05-3-g424fd37 2018-11-28T23:43:30.157Z,1543448610.157 [SBIT](INFO): git hash: 424fd377e53a63ada65ca308b07209cf4fd40d4d 2018-11-28T23:43:30.157Z,1543448610.157 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-28T23:43:30.158Z,1543448610.158 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-28T23:43:30.159Z,1543448610.159 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-11-28T23:43:30.159Z,1543448610.159 [IBIT](INFO): Initialize IBIT Component. 2018-11-28T23:43:30.160Z,1543448610.160 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-28T23:43:30.160Z,1543448610.160 [CBIT](DEBUG): Initialized mux pins. 2018-11-28T23:43:30.160Z,1543448610.160 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-11-28T23:43:30.160Z,1543448610.160 [CBIT](DEBUG): Initializing the watchdog timer. 2018-11-28T23:43:30.161Z,1543448610.161 [logger ThreadHandler](INFO): Handler Thread ID is 888 2018-11-28T23:43:30.178Z,1543448610.178 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889 2018-11-28T23:43:30.185Z,1543448610.185 [Radio_Surface](INFO): Powering up 2018-11-28T23:43:30.186Z,1543448610.186 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-11-28T23:43:30.186Z,1543448610.186 [CBIT](DEBUG): Initializing heartbeat. 2018-11-28T23:43:30.194Z,1543448610.194 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 890 2018-11-28T23:43:30.195Z,1543448610.195 [Rowe_600LCM](INFO): Initializing 2018-11-28T23:43:30.196Z,1543448610.196 [Rowe_600LCM](INFO): Checking LCM 2018-11-28T23:43:30.279Z,1543448610.279 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 891 2018-11-28T23:43:30.280Z,1543448610.280 [CBIT](DEBUG): Deactivating GF circuits. 2018-11-28T23:43:30.280Z,1543448610.280 [CBIT](DEBUG): Deactivating emergency mode. 2018-11-28T23:43:30.280Z,1543448610.280 [CTD_Seabird](INFO): Initializing 2018-11-28T23:43:30.281Z,1543448610.281 [CTD_Seabird](INFO): Checking LCM 2018-11-28T23:43:30.282Z,1543448610.282 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 892 2018-11-28T23:43:30.291Z,1543448610.291 [WetLabsBB2FL](INFO): Powering down 2018-11-28T23:43:30.309Z,1543448610.309 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893 2018-11-28T23:43:30.316Z,1543448610.316 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-28T23:43:30.317Z,1543448610.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-28T23:43:30.317Z,1543448610.317 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-28T23:43:30.317Z,1543448610.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-28T23:43:30.317Z,1543448610.317 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-28T23:43:30.317Z,1543448610.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-28T23:43:30.318Z,1543448610.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-28T23:43:30.318Z,1543448610.318 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-28T23:43:30.318Z,1543448610.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-28T23:43:30.318Z,1543448610.318 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-28T23:43:30.318Z,1543448610.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-28T23:43:30.319Z,1543448610.319 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-28T23:43:30.319Z,1543448610.319 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-28T23:43:30.319Z,1543448610.319 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-28T23:43:30.319Z,1543448610.319 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-28T23:43:30.319Z,1543448610.319 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-28T23:43:30.350Z,1543448610.350 [CBIT](DEBUG): Backplane powered. 2018-11-28T23:43:30.430Z,1543448610.430 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-28T23:43:30.432Z,1543448610.432 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-28T23:43:30.433Z,1543448610.433 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-28T23:43:30.433Z,1543448610.433 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-28T23:43:30.435Z,1543448610.435 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:43:30.436Z,1543448610.436 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:43:30.436Z,1543448610.436 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:43:30.436Z,1543448610.436 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-28T23:43:30.437Z,1543448610.437 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-28T23:43:30.440Z,1543448610.440 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-28T23:43:30.524Z,1543448610.524 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-28T23:43:30.605Z,1543448610.605 [MissionManager](DEBUG): 2018-11-28T23:43:30.606Z,1543448610.606 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-28T23:43:30.617Z,1543448610.617 [CTD_Seabird](INFO): LCM OK 2018-11-28T23:43:30.618Z,1543448610.618 [CTD_Seabird](INFO): Powering up 2018-11-28T23:43:30.618Z,1543448610.618 [Rowe_600LCM](INFO): LCM OK 2018-11-28T23:43:30.618Z,1543448610.618 [Rowe_600LCM](INFO): Powering up 2018-11-28T23:43:30.707Z,1543448610.707 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-28T23:43:30.708Z,1543448610.708 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-28T23:43:30.710Z,1543448610.710 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-28T23:43:30.747Z,1543448610.747 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-28T23:43:30.751Z,1543448610.751 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-28T23:43:30.773Z,1543448610.773 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-28T23:43:30.777Z,1543448610.777 [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-28T23:43:30.798Z,1543448610.798 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-11-28T23:43:30.817Z,1543448610.817 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-11-28T23:43:30.817Z,1543448610.817 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-11-28T23:43:30.853Z,1543448610.853 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-28T23:43:31.148Z,1543448611.148 [ESPComponent](INFO): powering down ESP 2018-11-28T23:43:31.732Z,1543448611.732 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-28T23:43:31.738Z,1543448611.738 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-28T23:43:31.770Z,1543448611.770 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-28T23:43:31.778Z,1543448611.778 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-28T23:43:31.784Z,1543448611.784 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-28T23:43:31.790Z,1543448611.790 [MassServo](DEBUG): Initializing MassServo. 2018-11-28T23:43:31.808Z,1543448611.808 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-28T23:43:31.814Z,1543448611.814 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-28T23:43:31.837Z,1543448611.837 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-28T23:43:31.842Z,1543448611.842 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-28T23:43:32.002Z,1543448612.002 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-28T23:43:32.002Z,1543448612.002 [DropWeight] Hardware Fault, FailCount= 1 2018-11-28T23:43:32.003Z,1543448612.003 [DropWeight](ERROR): Hardware Fault 2018-11-28T23:43:32.103Z,1543448612.103 [CommandLine](FAULT): Scheduling is paused 2018-11-28T23:43:32.104Z,1543448612.104 [CBIT](INFO): Critical error at 20181128T234332 2018-11-28T23:43:32.104Z,1543448612.104 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-28T23:43:32.106Z,1543448612.106 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-28T23:43:32.107Z,1543448612.107 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-28T23:43:32.702Z,1543448612.702 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-28T23:43:32.702Z,1543448612.702 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-28T23:43:32.702Z,1543448612.702 [BuoyancyServo] Communications Fault, FailCount= 1 2018-11-28T23:43:32.702Z,1543448612.702 [BuoyancyServo](ERROR): Communications Fault 2018-11-28T23:43:32.907Z,1543448612.907 [CBIT](INFO): Critical error at 20181128T234332 2018-11-28T23:43:32.910Z,1543448612.910 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-11-28T23:43:34.459Z,1543448614.459 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-28T23:43:34.557Z,1543448614.557 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-28T23:43:34.747Z,1543448614.747 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-28T23:43:34.747Z,1543448614.747 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-28T23:43:34.748Z,1543448614.748 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-28T23:43:35.115Z,1543448615.115 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-11-28T23:43:35.115Z,1543448615.115 [BuoyancyServo] No Fault, FailCount= 1 2018-11-28T23:43:35.380Z,1543448615.380 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-28T23:43:35.380Z,1543448615.380 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-28T23:43:35.839Z,1543448615.839 [Radio_Surface](INFO): Powering down 2018-11-28T23:43:35.879Z,1543448615.879 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-11-28T23:43:36.053Z,1543448616.053 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-11-28T23:43:36.054Z,1543448616.054 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-11-28T23:43:36.412Z,1543448616.412 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-11-28T23:43:36.412Z,1543448616.412 [CTD_Seabird](INFO): Powering down 2018-11-28T23:43:38.519Z,1543448618.519 [AcousticModem_Benthos_ATM900](DEBUG): 2018-11-28T23:43:38.538Z,1543448618.538 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:43:38.538Z,1543448618.538 [PNI_TCM] Data Fault, FailCount= 1 2018-11-28T23:43:38.538Z,1543448618.538 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:43:38.735Z,1543448618.735 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:43:38.937Z,1543448618.937 [PNI_TCM](INFO): Powering down 2018-11-28T23:43:39.722Z,1543448619.722 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-11-28T23:43:39.723Z,1543448619.723 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-11-28T23:43:40.120Z,1543448620.120 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-11-28T23:43:40.120Z,1543448620.120 [AcousticModem_Benthos_ATM900](DEBUG): Nov 11 2018 07:23:49 2018-11-28T23:43:40.251Z,1543448620.251 [CBIT](CRITICAL): Environmental Failure. Press:14.647570 PSI. Humidity:47%. Temp:26 C. ABORTING MISSION 2018-11-28T23:43:40.252Z,1543448620.252 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:43:40.252Z,1543448620.252 [PNI_TCM] No Fault, FailCount= 1 2018-11-28T23:43:40.519Z,1543448620.519 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-11-28T23:43:40.670Z,1543448620.670 [CBIT](INFO): Critical error at 20181128T234340 2018-11-28T23:43:41.319Z,1543448621.319 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-11-28T23:43:41.351Z,1543448621.351 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:43:41.351Z,1543448621.351 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-28T23:43:41.351Z,1543448621.351 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:43:41.428Z,1543448621.428 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:43:41.719Z,1543448621.719 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-11-28T23:43:41.720Z,1543448621.720 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-11-28T23:43:41.720Z,1543448621.720 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-11-28T23:43:41.739Z,1543448621.739 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:43:42.119Z,1543448622.119 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-11-28T23:43:42.519Z,1543448622.519 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-11-28T23:43:42.520Z,1543448622.520 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-11-28T23:43:42.640Z,1543448622.640 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:43:42.640Z,1543448622.640 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-28T23:43:42.920Z,1543448622.920 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-11-28T23:43:47.738Z,1543448627.738 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:43:47.738Z,1543448627.738 [PNI_TCM] Data Fault, FailCount= 2 2018-11-28T23:43:47.738Z,1543448627.738 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:43:47.788Z,1543448627.788 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:43:48.138Z,1543448628.138 [PNI_TCM](INFO): Powering down 2018-11-28T23:43:49.377Z,1543448629.377 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:43:49.377Z,1543448629.377 [PNI_TCM] No Fault, FailCount= 2 2018-11-28T23:43:53.338Z,1543448633.338 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:43:53.339Z,1543448633.339 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-28T23:43:53.339Z,1543448633.339 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:43:53.408Z,1543448633.408 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:43:53.740Z,1543448633.740 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:43:54.617Z,1543448634.617 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-28T23:43:54.629Z,1543448634.629 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:43:54.629Z,1543448634.629 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-28T23:43:54.630Z,1543448634.630 [CBIT](IMPORTANT): Beginning ground fault scan 2018-11-28T23:43:56.124Z,1543448636.124 [NAL9602](INFO): Powering up NAL9602 2018-11-28T23:43:57.070Z,1543448637.070 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:43:57.071Z,1543448637.071 [PNI_TCM] Data Fault, FailCount= 3 2018-11-28T23:43:57.071Z,1543448637.071 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:43:57.248Z,1543448637.248 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:43:57.467Z,1543448637.467 [PNI_TCM](INFO): Powering down 2018-11-28T23:43:58.487Z,1543448638.487 [Radio_Surface](INFO): Powering up 2018-11-28T23:43:58.806Z,1543448638.806 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:43:58.807Z,1543448638.807 [PNI_TCM] No Fault, FailCount= 3 2018-11-28T23:44:05.068Z,1543448645.068 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:44:05.068Z,1543448645.068 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-28T23:44:05.069Z,1543448645.069 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:44:05.195Z,1543448645.195 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:44:05.196Z,1543448645.196 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-28T23:44:05.586Z,1543448645.586 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:44:05.679Z,1543448645.679 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005964 CHAN A1 (24V): -0.010584 CHAN A2 (12V): -0.004628 CHAN A3 (5V): -0.002421 CHAN B0 (3.3V): -0.001101 CHAN B1 (3.15aV): -0.001365 CHAN B2 (3.15bV): -0.001294 CHAN B3 (GND): -0.000356 OPEN: 0.004093 Full Scale Calc: 4.765 mA, -1.589 mA 2018-11-28T23:44:06.163Z,1543448646.163 [Radio_Surface](INFO): Powering down 2018-11-28T23:44:06.269Z,1543448646.269 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:44:06.269Z,1543448646.269 [PNI_TCM] Data Fault, FailCount= 4 2018-11-28T23:44:06.269Z,1543448646.269 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:44:06.392Z,1543448646.392 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:44:06.675Z,1543448646.675 [PNI_TCM](INFO): Powering down 2018-11-28T23:44:07.047Z,1543448647.047 [NAL9602](INFO): NAL9602 initialized 2018-11-28T23:44:08.044Z,1543448648.044 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:44:08.044Z,1543448648.044 [PNI_TCM] No Fault, FailCount= 4 2018-11-28T23:44:15.689Z,1543448655.689 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:44:15.689Z,1543448655.690 [PNI_TCM] Data Fault, FailCount= 5 2018-11-28T23:44:15.690Z,1543448655.690 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:44:15.911Z,1543448655.911 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:44:15.911Z,1543448655.911 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-28T23:44:16.092Z,1543448656.092 [PNI_TCM](INFO): Powering down 2018-11-28T23:44:16.233Z,1543448656.233 [CBIT](INFO): Critical error at 20181128T234415 2018-11-28T23:44:36.495Z,1543448676.495 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-11-28T23:44:36.495Z,1543448676.495 [BPC1] Data Fault, FailCount= 1 2018-11-28T23:44:36.495Z,1543448676.495 [BPC1](ERROR): Data Fault 2018-11-28T23:44:36.691Z,1543448676.691 [CBIT](ERROR): Data Fault in component: BPC1 2018-11-28T23:44:48.612Z,1543448688.612 [SBIT](IMPORTANT): SBIT PASSED 2018-11-28T23:44:48.703Z,1543448688.703 [CommandLine](IMPORTANT): got command configSet list 2018-11-28T23:44:48.703Z,1543448688.703 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-11-28T23:44:48.707Z,1543448688.707 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2018-11-28T23:44:49.001Z,1543448689.001 [MissionManager](IMPORTANT): Started mission Startup 2018-11-28T23:44:49.001Z,1543448689.001 [Startup] Running Loop=1 2018-11-28T23:44:49.001Z,1543448689.001 [Startup](DEBUG): Aggregate::initialize Startup 2018-11-28T23:44:49.001Z,1543448689.001 [Startup:A.GoToSurface] Running Loop=1 2018-11-28T23:44:49.001Z,1543448689.001 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-28T23:44:49.002Z,1543448689.002 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-28T23:44:49.003Z,1543448689.003 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-28T23:44:49.003Z,1543448689.003 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-28T23:44:49.003Z,1543448689.003 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-28T23:44:49.004Z,1543448689.004 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-28T23:44:49.019Z,1543448689.019 [Radio_Surface](INFO): Powering up 2018-11-28T23:45:30.647Z,1543448730.647 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:45:30.647Z,1543448730.647 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-11-28T23:45:30.647Z,1543448730.647 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:45:30.662Z,1543448730.662 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:45:31.046Z,1543448731.046 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-28T23:45:31.046Z,1543448731.046 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-11-28T23:45:31.046Z,1543448731.046 [Rowe_600LCM](ERROR): Communications Fault 2018-11-28T23:45:31.080Z,1543448731.080 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-28T23:45:31.081Z,1543448731.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:45:31.081Z,1543448731.081 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-11-28T23:45:31.448Z,1543448731.448 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:45:31.448Z,1543448731.448 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:45:31.448Z,1543448731.448 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:45:31.450Z,1543448731.450 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-28T23:45:31.593Z,1543448731.593 [Rowe_600LCM](INFO): Powering down 2018-11-28T23:45:33.069Z,1543448733.069 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-28T23:45:33.069Z,1543448733.069 [Rowe_600LCM] No Fault, FailCount= 1 2018-11-28T23:45:33.210Z,1543448733.210 [Rowe_600LCM](INFO): Initializing 2018-11-28T23:45:33.211Z,1543448733.211 [Rowe_600LCM](INFO): Checking LCM 2018-11-28T23:45:33.211Z,1543448733.211 [Rowe_600LCM](INFO): LCM OK 2018-11-28T23:45:33.211Z,1543448733.211 [Rowe_600LCM](INFO): Powering up 2018-11-28T23:45:37.326Z,1543448737.326 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-28T23:45:37.385Z,1543448737.385 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-28T23:45:37.391Z,1543448737.391 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-28T23:45:37.392Z,1543448737.392 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-28T23:45:37.392Z,1543448737.392 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-28T23:45:48.262Z,1543448748.262 [VerticalControl](CRITICAL): Excessive depth excursion=10.935417 m, failToGoUpDepth_=29.661358 m, depthRate=0.192534 m/s, pitch=nan deg. 2018-11-28T23:45:48.272Z,1543448748.272 [CBIT](INFO): Critical error at 20181128T234548 2018-11-28T23:46:35.069Z,1543448795.069 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:46:35.069Z,1543448795.069 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-28T23:46:45.836Z,1543448805.836 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:46:45.836Z,1543448805.836 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-28T23:46:45.836Z,1543448805.836 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:46:45.881Z,1543448805.881 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:46:46.355Z,1543448806.355 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:46:47.085Z,1543448807.085 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:46:47.085Z,1543448807.085 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-28T23:46:57.437Z,1543448817.437 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:46:57.437Z,1543448817.437 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-28T23:46:57.437Z,1543448817.437 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:46:57.496Z,1543448817.496 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:46:57.942Z,1543448817.942 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:46:58.650Z,1543448818.650 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:46:58.650Z,1543448818.650 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-28T23:47:09.030Z,1543448829.030 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:47:09.030Z,1543448829.030 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-28T23:47:09.030Z,1543448829.030 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:47:09.050Z,1543448829.050 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:47:09.051Z,1543448829.051 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-28T23:47:09.546Z,1543448829.546 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:47:11.447Z,1543448831.447 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=20.408993, failToGoUpDepth_=29.661358, depthRate=0.124112 m/s, pitch=nan deg. 2018-11-28T23:47:11.464Z,1543448831.464 [CBIT](INFO): Critical error at 20181128T234711 2018-11-28T23:47:31.844Z,1543448851.844 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:47:31.845Z,1543448851.845 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-11-28T23:47:31.845Z,1543448851.845 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:47:31.859Z,1543448851.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:47:32.261Z,1543448852.261 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:47:32.261Z,1543448852.261 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-11-28T23:47:32.655Z,1543448852.655 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:47:32.656Z,1543448852.656 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:47:32.656Z,1543448852.656 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:47:33.798Z,1543448853.798 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-28T23:47:33.798Z,1543448853.798 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-11-28T23:47:33.798Z,1543448853.798 [Rowe_600LCM](ERROR): Communications Fault 2018-11-28T23:47:33.884Z,1543448853.884 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-28T23:47:34.202Z,1543448854.202 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-28T23:47:34.376Z,1543448854.376 [Rowe_600LCM](INFO): Powering down 2018-11-28T23:47:35.867Z,1543448855.867 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-28T23:47:35.868Z,1543448855.868 [Rowe_600LCM] No Fault, FailCount= 2 2018-11-28T23:47:35.990Z,1543448855.990 [Rowe_600LCM](INFO): Initializing 2018-11-28T23:47:35.991Z,1543448855.991 [Rowe_600LCM](INFO): Checking LCM 2018-11-28T23:47:35.991Z,1543448855.991 [Rowe_600LCM](INFO): LCM OK 2018-11-28T23:47:35.991Z,1543448855.991 [Rowe_600LCM](INFO): Powering up 2018-11-28T23:47:40.112Z,1543448860.112 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-28T23:47:40.173Z,1543448860.173 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-28T23:47:40.174Z,1543448860.174 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-28T23:47:40.174Z,1543448860.174 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-28T23:47:40.175Z,1543448860.175 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-28T23:49:10.608Z,1543448950.608 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-11-28T23:49:15.868Z,1543448955.868 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:49:15.868Z,1543448955.868 [PNI_TCM] No Fault, FailCount= 5 2018-11-28T23:49:23.433Z,1543448963.433 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:49:23.433Z,1543448963.433 [PNI_TCM] Data Fault, FailCount= 1 2018-11-28T23:49:23.433Z,1543448963.433 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:49:23.480Z,1543448963.480 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:49:23.822Z,1543448963.822 [PNI_TCM](INFO): Powering down 2018-11-28T23:49:25.073Z,1543448965.073 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:49:25.073Z,1543448965.073 [PNI_TCM] No Fault, FailCount= 1 2018-11-28T23:49:32.638Z,1543448972.638 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:49:32.638Z,1543448972.638 [PNI_TCM] Data Fault, FailCount= 2 2018-11-28T23:49:32.638Z,1543448972.638 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:49:32.681Z,1543448972.681 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:49:33.030Z,1543448973.030 [PNI_TCM](INFO): Powering down 2018-11-28T23:49:33.134Z,1543448973.134 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:49:33.134Z,1543448973.134 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-11-28T23:49:33.134Z,1543448973.134 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:49:33.148Z,1543448973.148 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:49:33.452Z,1543448973.452 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:49:33.452Z,1543448973.452 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-11-28T23:49:33.848Z,1543448973.848 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:49:33.849Z,1543448973.849 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:49:33.849Z,1543448973.849 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:49:34.264Z,1543448974.264 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:49:34.264Z,1543448974.264 [PNI_TCM] No Fault, FailCount= 2 2018-11-28T23:49:36.594Z,1543448976.594 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-28T23:49:36.594Z,1543448976.594 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-11-28T23:49:36.594Z,1543448976.594 [Rowe_600LCM](ERROR): Communications Fault 2018-11-28T23:49:36.679Z,1543448976.679 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-28T23:49:37.006Z,1543448977.006 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-28T23:49:37.168Z,1543448977.168 [Rowe_600LCM](INFO): Powering down 2018-11-28T23:49:38.669Z,1543448978.669 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-28T23:49:38.669Z,1543448978.669 [Rowe_600LCM] No Fault, FailCount= 3 2018-11-28T23:49:38.782Z,1543448978.782 [Rowe_600LCM](INFO): Initializing 2018-11-28T23:49:38.783Z,1543448978.783 [Rowe_600LCM](INFO): Checking LCM 2018-11-28T23:49:38.783Z,1543448978.783 [Rowe_600LCM](INFO): LCM OK 2018-11-28T23:49:38.783Z,1543448978.783 [Rowe_600LCM](INFO): Powering up 2018-11-28T23:49:39.062Z,1543448979.062 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:49:39.062Z,1543448979.062 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-28T23:49:41.822Z,1543448981.822 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:49:41.822Z,1543448981.822 [PNI_TCM] Data Fault, FailCount= 3 2018-11-28T23:49:41.822Z,1543448981.822 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:49:41.873Z,1543448981.873 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:49:42.220Z,1543448982.220 [PNI_TCM](INFO): Powering down 2018-11-28T23:49:42.903Z,1543448982.903 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-28T23:49:42.960Z,1543448982.960 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-28T23:49:42.961Z,1543448982.961 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-28T23:49:42.963Z,1543448982.963 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-28T23:49:42.963Z,1543448982.963 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-28T23:49:43.473Z,1543448983.473 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:49:43.473Z,1543448983.473 [PNI_TCM] No Fault, FailCount= 3 2018-11-28T23:49:49.428Z,1543448989.428 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:49:49.428Z,1543448989.428 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-28T23:49:49.429Z,1543448989.429 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:49:49.470Z,1543448989.470 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:49:49.838Z,1543448989.838 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:49:50.673Z,1543448990.673 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:49:50.673Z,1543448990.673 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-28T23:49:51.027Z,1543448991.027 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:49:51.027Z,1543448991.027 [PNI_TCM] Data Fault, FailCount= 4 2018-11-28T23:49:51.027Z,1543448991.027 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:49:51.068Z,1543448991.068 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:49:51.427Z,1543448991.427 [PNI_TCM](INFO): Powering down 2018-11-28T23:49:52.679Z,1543448992.679 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:49:52.679Z,1543448992.679 [PNI_TCM] No Fault, FailCount= 4 2018-11-28T23:50:00.221Z,1543449000.221 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:50:00.221Z,1543449000.221 [PNI_TCM] Data Fault, FailCount= 5 2018-11-28T23:50:00.221Z,1543449000.221 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:50:00.243Z,1543449000.243 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:50:00.243Z,1543449000.243 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-28T23:50:00.634Z,1543449000.634 [PNI_TCM](INFO): Powering down 2018-11-28T23:50:00.742Z,1543449000.742 [CBIT](INFO): Critical error at 20181128T235000 2018-11-28T23:50:01.032Z,1543449001.032 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:50:01.032Z,1543449001.032 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-28T23:50:01.032Z,1543449001.032 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:50:01.056Z,1543449001.056 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:50:01.427Z,1543449001.427 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:50:02.280Z,1543449002.280 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:50:02.280Z,1543449002.280 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-28T23:50:13.028Z,1543449013.028 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:50:13.028Z,1543449013.028 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-28T23:50:13.028Z,1543449013.028 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:50:13.052Z,1543449013.052 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:50:13.052Z,1543449013.052 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-28T23:50:13.542Z,1543449013.542 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:51:34.250Z,1543449094.250 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:51:34.250Z,1543449094.250 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-11-28T23:51:34.250Z,1543449094.250 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:51:34.264Z,1543449094.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:51:34.664Z,1543449094.664 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:51:34.664Z,1543449094.664 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-11-28T23:51:35.055Z,1543449095.055 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:51:35.056Z,1543449095.056 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:51:35.056Z,1543449095.056 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:51:39.394Z,1543449099.394 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-28T23:51:39.394Z,1543449099.394 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-11-28T23:51:39.394Z,1543449099.394 [Rowe_600LCM](ERROR): Communications Fault 2018-11-28T23:51:39.471Z,1543449099.471 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-28T23:51:39.806Z,1543449099.806 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-28T23:51:39.982Z,1543449099.982 [Rowe_600LCM](INFO): Powering down 2018-11-28T23:51:41.469Z,1543449101.469 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-28T23:51:41.469Z,1543449101.469 [Rowe_600LCM] No Fault, FailCount= 4 2018-11-28T23:51:41.598Z,1543449101.598 [Rowe_600LCM](INFO): Initializing 2018-11-28T23:51:41.599Z,1543449101.599 [Rowe_600LCM](INFO): Checking LCM 2018-11-28T23:51:41.599Z,1543449101.599 [Rowe_600LCM](INFO): LCM OK 2018-11-28T23:51:41.599Z,1543449101.599 [Rowe_600LCM](INFO): Powering up 2018-11-28T23:51:45.716Z,1543449105.716 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-28T23:51:45.777Z,1543449105.777 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-28T23:51:45.778Z,1543449105.778 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-28T23:51:45.778Z,1543449105.778 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-28T23:51:45.779Z,1543449105.779 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-28T23:52:43.062Z,1543449163.062 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:52:43.062Z,1543449163.062 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-28T23:52:53.433Z,1543449173.433 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:52:53.433Z,1543449173.433 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-28T23:52:53.433Z,1543449173.433 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:52:53.477Z,1543449173.477 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:52:53.938Z,1543449173.938 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:52:54.662Z,1543449174.662 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:52:54.662Z,1543449174.662 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-28T23:53:05.037Z,1543449185.037 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:53:05.037Z,1543449185.037 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-28T23:53:05.037Z,1543449185.037 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:53:05.085Z,1543449185.085 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:53:05.546Z,1543449185.546 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:53:06.268Z,1543449186.268 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:53:06.269Z,1543449186.269 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-28T23:53:16.633Z,1543449196.633 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:53:16.633Z,1543449196.633 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-28T23:53:16.634Z,1543449196.634 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:53:16.679Z,1543449196.679 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:53:16.680Z,1543449196.680 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-28T23:53:17.150Z,1543449197.150 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:53:35.061Z,1543449215.061 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:53:35.061Z,1543449215.061 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-11-28T23:53:35.061Z,1543449215.061 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:53:35.075Z,1543449215.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:53:35.473Z,1543449215.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:53:35.473Z,1543449215.473 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-11-28T23:53:35.835Z,1543449215.835 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:53:35.835Z,1543449215.835 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:53:35.836Z,1543449215.836 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:53:42.198Z,1543449222.198 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-28T23:53:42.198Z,1543449222.198 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-11-28T23:53:42.198Z,1543449222.198 [Rowe_600LCM](ERROR): Communications Fault 2018-11-28T23:53:42.268Z,1543449222.268 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-28T23:53:42.269Z,1543449222.269 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-11-28T23:53:42.602Z,1543449222.602 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-28T23:53:42.779Z,1543449222.779 [Rowe_600LCM](INFO): Powering down 2018-11-28T23:54:08.211Z,1543449248.211 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-11-28T23:54:08.211Z,1543449248.211 [NAL9602] Data Fault, FailCount= 1 2018-11-28T23:54:08.212Z,1543449248.212 [NAL9602](ERROR): Data Fault 2018-11-28T23:54:08.268Z,1543449248.268 [CBIT](ERROR): Data Fault in component: NAL9602 2018-11-28T23:54:08.610Z,1543449248.610 [NAL9602](INFO): Powering down 2018-11-28T23:54:09.476Z,1543449249.476 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-28T23:54:09.476Z,1543449249.476 [NAL9602] No Fault, FailCount= 1 2018-11-28T23:54:37.451Z,1543449277.451 [CBIT](INFO): Clearing failed state for component BPC1 2018-11-28T23:54:37.451Z,1543449277.451 [BPC1] No Fault, FailCount= 1 2018-11-28T23:54:39.011Z,1543449279.011 [NAL9602](INFO): Powering up NAL9602 2018-11-28T23:54:50.215Z,1543449290.215 [NAL9602](INFO): NAL9602 initialized 2018-11-28T23:55:00.281Z,1543449300.281 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:55:00.281Z,1543449300.281 [PNI_TCM] No Fault, FailCount= 5 2018-11-28T23:55:07.827Z,1543449307.827 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:55:07.827Z,1543449307.827 [PNI_TCM] Data Fault, FailCount= 1 2018-11-28T23:55:07.827Z,1543449307.827 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:55:07.847Z,1543449307.847 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:55:08.227Z,1543449308.227 [PNI_TCM](INFO): Powering down 2018-11-28T23:55:09.488Z,1543449309.488 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:55:09.489Z,1543449309.489 [PNI_TCM] No Fault, FailCount= 1 2018-11-28T23:55:17.029Z,1543449317.029 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:55:17.030Z,1543449317.030 [PNI_TCM] Data Fault, FailCount= 2 2018-11-28T23:55:17.030Z,1543449317.030 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:55:17.050Z,1543449317.050 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:55:17.440Z,1543449317.440 [PNI_TCM](INFO): Powering down 2018-11-28T23:55:18.644Z,1543449318.644 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:55:18.644Z,1543449318.644 [PNI_TCM] No Fault, FailCount= 2 2018-11-28T23:55:26.227Z,1543449326.227 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:55:26.227Z,1543449326.227 [PNI_TCM] Data Fault, FailCount= 3 2018-11-28T23:55:26.227Z,1543449326.227 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:55:26.253Z,1543449326.253 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:55:26.641Z,1543449326.641 [PNI_TCM](INFO): Powering down 2018-11-28T23:55:27.846Z,1543449327.846 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:55:27.846Z,1543449327.846 [PNI_TCM] No Fault, FailCount= 3 2018-11-28T23:55:35.437Z,1543449335.437 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:55:35.437Z,1543449335.437 [PNI_TCM] Data Fault, FailCount= 4 2018-11-28T23:55:35.437Z,1543449335.437 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:55:35.477Z,1543449335.477 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:55:35.837Z,1543449335.837 [PNI_TCM](INFO): Powering down 2018-11-28T23:55:35.936Z,1543449335.936 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:55:35.936Z,1543449335.936 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-11-28T23:55:35.936Z,1543449335.936 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:55:35.950Z,1543449335.950 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:55:36.260Z,1543449336.260 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:55:36.260Z,1543449336.260 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-11-28T23:55:36.636Z,1543449336.636 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:55:36.636Z,1543449336.636 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:55:36.637Z,1543449336.637 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:55:37.054Z,1543449337.054 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-28T23:55:37.055Z,1543449337.055 [PNI_TCM] No Fault, FailCount= 4 2018-11-28T23:55:43.429Z,1543449343.429 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-11-28T23:55:43.429Z,1543449343.429 [BPC1] Data Fault, FailCount= 2 2018-11-28T23:55:43.430Z,1543449343.430 [BPC1](ERROR): Data Fault 2018-11-28T23:55:43.477Z,1543449343.477 [CBIT](ERROR): Data Fault in component: BPC1 2018-11-28T23:55:44.640Z,1543449344.640 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-28T23:55:44.640Z,1543449344.640 [PNI_TCM] Data Fault, FailCount= 5 2018-11-28T23:55:44.640Z,1543449344.640 [PNI_TCM](ERROR): Data Fault 2018-11-28T23:55:44.664Z,1543449344.664 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-28T23:55:44.665Z,1543449344.665 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-28T23:55:45.041Z,1543449345.041 [PNI_TCM](INFO): Powering down 2018-11-28T23:55:45.150Z,1543449345.150 [CBIT](INFO): Critical error at 20181128T235544 2018-11-28T23:55:46.657Z,1543449346.657 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:55:46.657Z,1543449346.657 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-28T23:55:57.043Z,1543449357.043 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:55:57.043Z,1543449357.043 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-28T23:55:57.043Z,1543449357.043 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:55:57.068Z,1543449357.068 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:55:57.554Z,1543449357.554 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:55:58.257Z,1543449358.257 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:55:58.257Z,1543449358.257 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-28T23:56:08.636Z,1543449368.636 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:56:08.636Z,1543449368.636 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-28T23:56:08.636Z,1543449368.636 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:56:08.684Z,1543449368.684 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:56:09.162Z,1543449369.162 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:56:09.861Z,1543449369.861 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:56:09.861Z,1543449369.861 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-28T23:56:20.227Z,1543449380.227 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:56:20.227Z,1543449380.227 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-28T23:56:20.227Z,1543449380.227 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:56:20.271Z,1543449380.271 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:56:20.272Z,1543449380.272 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-28T23:56:20.746Z,1543449380.746 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:57:37.035Z,1543449457.035 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:57:37.036Z,1543449457.036 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2018-11-28T23:57:37.036Z,1543449457.036 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:57:37.082Z,1543449457.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:57:37.468Z,1543449457.468 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:57:37.468Z,1543449457.468 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2018-11-28T23:57:37.835Z,1543449457.835 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:57:37.836Z,1543449457.836 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:57:37.836Z,1543449457.836 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-28T23:58:32.248Z,1543449512.248 [CBIT](INFO): Clearing failed state for component DropWeight 2018-11-28T23:58:32.248Z,1543449512.248 [DropWeight] No Fault, FailCount= 1 2018-11-28T23:58:33.811Z,1543449513.811 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-28T23:58:33.811Z,1543449513.811 [DropWeight] Hardware Fault, FailCount= 1 2018-11-28T23:58:33.811Z,1543449513.811 [DropWeight](ERROR): Hardware Fault 2018-11-28T23:58:33.870Z,1543449513.870 [CBIT](INFO): Critical error at 20181128T235833 2018-11-28T23:58:33.872Z,1543449513.872 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-28T23:58:33.873Z,1543449513.873 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-28T23:58:34.253Z,1543449514.253 [CBIT](INFO): Critical error at 20181128T235833 2018-11-28T23:58:50.246Z,1543449530.246 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:58:50.246Z,1543449530.246 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-28T23:59:01.027Z,1543449541.027 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:59:01.027Z,1543449541.027 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-28T23:59:01.027Z,1543449541.027 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:59:01.048Z,1543449541.048 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:59:01.546Z,1543449541.546 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:59:02.251Z,1543449542.251 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:59:02.252Z,1543449542.252 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-28T23:59:13.033Z,1543449553.033 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:59:13.033Z,1543449553.033 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-28T23:59:13.033Z,1543449553.033 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:59:13.061Z,1543449553.061 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:59:13.546Z,1543449553.546 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:59:14.248Z,1543449554.248 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-28T23:59:14.248Z,1543449554.248 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-28T23:59:25.027Z,1543449565.027 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-28T23:59:25.028Z,1543449565.028 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-28T23:59:25.028Z,1543449565.028 [Aanderaa_O2](ERROR): Communications Fault 2018-11-28T23:59:25.073Z,1543449565.073 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-28T23:59:25.073Z,1543449565.073 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-28T23:59:25.546Z,1543449565.546 [Aanderaa_O2](INFO): Powering down 2018-11-28T23:59:37.849Z,1543449577.849 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-28T23:59:37.849Z,1543449577.849 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2018-11-28T23:59:37.850Z,1543449577.850 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-28T23:59:37.863Z,1543449577.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-28T23:59:38.261Z,1543449578.261 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-28T23:59:38.261Z,1543449578.261 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2018-11-28T23:59:38.655Z,1543449578.655 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-28T23:59:38.656Z,1543449578.656 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-28T23:59:38.656Z,1543449578.656 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:00:44.664Z,1543449644.664 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:00:44.664Z,1543449644.664 [PNI_TCM] No Fault, FailCount= 5 2018-11-29T00:00:52.222Z,1543449652.222 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:00:52.222Z,1543449652.222 [PNI_TCM] Data Fault, FailCount= 1 2018-11-29T00:00:52.222Z,1543449652.222 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:00:52.272Z,1543449652.272 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:00:52.624Z,1543449652.624 [PNI_TCM](INFO): Powering down 2018-11-29T00:00:53.865Z,1543449653.865 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:00:53.866Z,1543449653.866 [PNI_TCM] No Fault, FailCount= 1 2018-11-29T00:01:01.423Z,1543449661.423 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:01:01.423Z,1543449661.423 [PNI_TCM] Data Fault, FailCount= 2 2018-11-29T00:01:01.423Z,1543449661.423 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:01:01.481Z,1543449661.481 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:01:01.823Z,1543449661.823 [PNI_TCM](INFO): Powering down 2018-11-29T00:01:03.068Z,1543449663.068 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:01:03.068Z,1543449663.068 [PNI_TCM] No Fault, FailCount= 2 2018-11-29T00:01:10.628Z,1543449670.628 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:01:10.628Z,1543449670.628 [PNI_TCM] Data Fault, FailCount= 3 2018-11-29T00:01:10.628Z,1543449670.628 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:01:10.676Z,1543449670.676 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:01:11.023Z,1543449671.023 [PNI_TCM](INFO): Powering down 2018-11-29T00:01:12.268Z,1543449672.268 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:01:12.268Z,1543449672.268 [PNI_TCM] No Fault, FailCount= 3 2018-11-29T00:01:19.824Z,1543449679.824 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:01:19.824Z,1543449679.824 [PNI_TCM] Data Fault, FailCount= 4 2018-11-29T00:01:19.824Z,1543449679.824 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:01:19.861Z,1543449679.861 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:01:20.222Z,1543449680.222 [PNI_TCM](INFO): Powering down 2018-11-29T00:01:21.475Z,1543449681.475 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:01:21.475Z,1543449681.475 [PNI_TCM] No Fault, FailCount= 4 2018-11-29T00:01:29.023Z,1543449689.023 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:01:29.023Z,1543449689.023 [PNI_TCM] Data Fault, FailCount= 5 2018-11-29T00:01:29.023Z,1543449689.023 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:01:29.037Z,1543449689.037 [Startup:A.GoToSurface](CRITICAL): Surfacing timeout. Returning calc satisfied. 2018-11-29T00:01:29.038Z,1543449689.038 [Startup:StartupSatComms] Running Loop=1 2018-11-29T00:01:29.038Z,1543449689.038 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-11-29T00:01:29.038Z,1543449689.038 [Startup:StartupSatComms:A] Running Loop=1 2018-11-29T00:01:29.063Z,1543449689.063 [CBIT](INFO): Critical error at 20181129T000129 2018-11-29T00:01:29.065Z,1543449689.065 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:01:29.070Z,1543449689.070 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-29T00:01:29.440Z,1543449689.440 [PNI_TCM](INFO): Powering down 2018-11-29T00:01:29.537Z,1543449689.537 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-11-29T00:01:29.549Z,1543449689.549 [CBIT](INFO): Critical error at 20181129T000129 2018-11-29T00:01:38.714Z,1543449698.714 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:01:38.714Z,1543449698.714 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2018-11-29T00:01:38.714Z,1543449698.714 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:01:38.771Z,1543449698.771 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:01:39.147Z,1543449699.147 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:01:39.147Z,1543449699.147 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2018-11-29T00:01:39.488Z,1543449699.488 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:01:39.488Z,1543449699.488 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:01:39.489Z,1543449699.489 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:01:55.093Z,1543449715.093 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:01:55.094Z,1543449715.094 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:02:05.484Z,1543449725.484 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:02:05.484Z,1543449725.484 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:02:05.484Z,1543449725.484 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:02:05.508Z,1543449725.508 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:02:06.006Z,1543449726.006 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:02:06.700Z,1543449726.700 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:02:06.700Z,1543449726.700 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:02:17.486Z,1543449737.486 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:02:17.486Z,1543449737.486 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:02:17.486Z,1543449737.486 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:02:17.512Z,1543449737.512 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:02:18.006Z,1543449738.006 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:02:18.697Z,1543449738.697 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:02:18.697Z,1543449738.697 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:02:29.088Z,1543449749.088 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-29T00:01:29.0Z 2018-11-29T00:02:29.088Z,1543449749.088 [Startup:StartupSatComms:A] Stopped 2018-11-29T00:02:29.088Z,1543449749.088 [Startup:StartupSatComms:B] Running Loop=1 2018-11-29T00:02:29.483Z,1543449749.483 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:02:29.483Z,1543449749.483 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:02:29.484Z,1543449749.484 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:02:29.497Z,1543449749.497 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-29T00:02:29.510Z,1543449749.510 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:02:29.511Z,1543449749.511 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:02:29.994Z,1543449749.994 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:03:29.369Z,1543449809.369 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-29T00:02:29.1Z 2018-11-29T00:03:29.369Z,1543449809.369 [Startup:StartupSatComms:B] Stopped 2018-11-29T00:03:29.369Z,1543449809.369 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-11-29T00:03:29.369Z,1543449809.369 [Startup:StartupSatComms] Stopped 2018-11-29T00:03:29.370Z,1543449809.370 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-11-29T00:03:29.371Z,1543449809.371 [Startup](INFO): Completed Startup 2018-11-29T00:03:29.371Z,1543449809.371 [MissionManager](INFO): Startup is completed. 2018-11-29T00:03:29.371Z,1543449809.371 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-29T00:03:29.371Z,1543449809.371 [Startup] Stopped 2018-11-29T00:03:29.371Z,1543449809.371 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-11-29T00:03:29.371Z,1543449809.371 [Startup:A.GoToSurface] Stopped 2018-11-29T00:03:29.371Z,1543449809.371 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-29T00:03:29.769Z,1543449809.769 [MissionManager](IMPORTANT): Started mission Default 2018-11-29T00:03:29.769Z,1543449809.769 [Default] Running Loop=1 2018-11-29T00:03:29.769Z,1543449809.769 [Default](DEBUG): Aggregate::initialize Default 2018-11-29T00:03:29.769Z,1543449809.769 [Default:B.GoToSurface] Running Loop=1 2018-11-29T00:03:29.769Z,1543449809.769 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-29T00:03:29.770Z,1543449809.770 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-29T00:03:29.770Z,1543449809.770 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-29T00:03:29.770Z,1543449809.770 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-29T00:03:29.771Z,1543449809.771 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-29T00:03:29.771Z,1543449809.771 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-29T00:03:29.771Z,1543449809.771 [Default:A.Wait] Running Loop=1 2018-11-29T00:03:29.772Z,1543449809.772 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-11-29T00:03:39.818Z,1543449819.818 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:03:39.819Z,1543449819.819 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2018-11-29T00:03:39.819Z,1543449819.819 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:03:39.883Z,1543449819.883 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:03:40.267Z,1543449820.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:03:40.267Z,1543449820.267 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2018-11-29T00:03:40.627Z,1543449820.627 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:03:40.627Z,1543449820.627 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:03:40.628Z,1543449820.628 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:03:42.279Z,1543449822.279 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-29T00:03:42.279Z,1543449822.279 [Rowe_600LCM] No Fault, FailCount= 5 2018-11-29T00:03:42.682Z,1543449822.682 [Rowe_600LCM](INFO): Initializing 2018-11-29T00:03:42.683Z,1543449822.683 [Rowe_600LCM](INFO): Checking LCM 2018-11-29T00:03:42.683Z,1543449822.683 [Rowe_600LCM](INFO): LCM OK 2018-11-29T00:03:42.683Z,1543449822.683 [Rowe_600LCM](INFO): Powering up 2018-11-29T00:03:43.026Z,1543449823.026 [Default:A.Wait](INFO): Done Waiting. 2018-11-29T00:03:43.026Z,1543449823.026 [Default:A.Wait] Stopped 2018-11-29T00:03:43.026Z,1543449823.026 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-11-29T00:03:46.802Z,1543449826.802 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-29T00:03:46.872Z,1543449826.872 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-29T00:03:46.873Z,1543449826.873 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-29T00:03:46.874Z,1543449826.874 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-29T00:03:46.875Z,1543449826.875 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-29T00:04:51.379Z,1543449891.379 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-11-29T00:04:51.379Z,1543449891.379 [NAL9602] Data Fault, FailCount= 2 2018-11-29T00:04:51.379Z,1543449891.379 [NAL9602](ERROR): Data Fault 2018-11-29T00:04:51.446Z,1543449891.446 [CBIT](ERROR): Data Fault in component: NAL9602 2018-11-29T00:04:51.775Z,1543449891.775 [NAL9602](INFO): Powering down 2018-11-29T00:04:52.620Z,1543449892.620 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-29T00:04:52.620Z,1543449892.620 [NAL9602] No Fault, FailCount= 2 2018-11-29T00:04:59.820Z,1543449899.820 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:04:59.820Z,1543449899.820 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:05:10.598Z,1543449910.598 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:05:10.598Z,1543449910.598 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:05:10.598Z,1543449910.598 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:05:10.635Z,1543449910.635 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:05:11.118Z,1543449911.118 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:05:11.824Z,1543449911.824 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:05:11.824Z,1543449911.824 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:05:21.779Z,1543449921.779 [NAL9602](INFO): Powering up NAL9602 2018-11-29T00:05:22.589Z,1543449922.589 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:05:22.590Z,1543449922.590 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:05:22.590Z,1543449922.590 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:05:22.632Z,1543449922.632 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:05:23.118Z,1543449923.118 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:05:23.820Z,1543449923.820 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:05:23.820Z,1543449923.820 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:05:24.627Z,1543449924.627 [Radio_Surface](INFO): Powering down 2018-11-29T00:05:30.699Z,1543449930.699 [Radio_Surface](INFO): Powering up 2018-11-29T00:05:32.975Z,1543449932.975 [NAL9602](INFO): NAL9602 initialized 2018-11-29T00:05:34.203Z,1543449934.203 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:05:34.203Z,1543449934.203 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:05:34.203Z,1543449934.203 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:05:34.260Z,1543449934.260 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:05:34.260Z,1543449934.260 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:05:34.722Z,1543449934.722 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:05:41.013Z,1543449941.013 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:05:41.013Z,1543449941.013 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2018-11-29T00:05:41.013Z,1543449941.013 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:05:41.027Z,1543449941.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:05:41.424Z,1543449941.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:05:41.424Z,1543449941.424 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2018-11-29T00:05:41.810Z,1543449941.810 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:05:41.810Z,1543449941.810 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:05:41.811Z,1543449941.811 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:05:43.418Z,1543449943.418 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-29T00:05:43.418Z,1543449943.418 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-11-29T00:05:43.418Z,1543449943.418 [Rowe_600LCM](ERROR): Communications Fault 2018-11-29T00:05:43.425Z,1543449943.425 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-29T00:05:43.823Z,1543449943.823 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-29T00:05:43.943Z,1543449943.943 [Rowe_600LCM](INFO): Powering down 2018-11-29T00:05:44.632Z,1543449944.632 [CBIT](INFO): Clearing failed state for component BPC1 2018-11-29T00:05:44.632Z,1543449944.632 [BPC1] No Fault, FailCount= 2 2018-11-29T00:05:45.437Z,1543449945.437 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-29T00:05:45.437Z,1543449945.437 [Rowe_600LCM] No Fault, FailCount= 1 2018-11-29T00:05:45.558Z,1543449945.558 [Rowe_600LCM](INFO): Initializing 2018-11-29T00:05:45.559Z,1543449945.559 [Rowe_600LCM](INFO): Checking LCM 2018-11-29T00:05:45.559Z,1543449945.559 [Rowe_600LCM](INFO): LCM OK 2018-11-29T00:05:45.559Z,1543449945.559 [Rowe_600LCM](INFO): Powering up 2018-11-29T00:05:49.677Z,1543449949.677 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-29T00:05:49.733Z,1543449949.733 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-29T00:05:49.734Z,1543449949.734 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-29T00:05:49.735Z,1543449949.735 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-29T00:05:49.735Z,1543449949.735 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-29T00:06:29.029Z,1543449989.029 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:06:29.029Z,1543449989.029 [PNI_TCM] No Fault, FailCount= 5 2018-11-29T00:06:36.608Z,1543449996.608 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:06:36.608Z,1543449996.608 [PNI_TCM] Data Fault, FailCount= 1 2018-11-29T00:06:36.608Z,1543449996.608 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:06:36.656Z,1543449996.656 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:06:37.000Z,1543449997.000 [PNI_TCM](INFO): Powering down 2018-11-29T00:06:38.240Z,1543449998.240 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:06:38.241Z,1543449998.241 [PNI_TCM] No Fault, FailCount= 1 2018-11-29T00:06:45.804Z,1543450005.804 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:06:45.804Z,1543450005.804 [PNI_TCM] Data Fault, FailCount= 2 2018-11-29T00:06:45.804Z,1543450005.804 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:06:45.853Z,1543450005.853 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:06:46.203Z,1543450006.203 [PNI_TCM](INFO): Powering down 2018-11-29T00:06:47.437Z,1543450007.437 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:06:47.437Z,1543450007.437 [PNI_TCM] No Fault, FailCount= 2 2018-11-29T00:06:50.593Z,1543450010.593 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-11-29T00:06:50.593Z,1543450010.593 [BPC1] Data Fault, FailCount= 3 2018-11-29T00:06:50.593Z,1543450010.593 [BPC1](ERROR): Data Fault 2018-11-29T00:06:50.660Z,1543450010.660 [CBIT](ERROR): Data Fault in component: BPC1 2018-11-29T00:06:55.005Z,1543450015.005 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:06:55.005Z,1543450015.005 [PNI_TCM] Data Fault, FailCount= 3 2018-11-29T00:06:55.005Z,1543450015.005 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:06:55.051Z,1543450015.051 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:06:55.390Z,1543450015.390 [PNI_TCM](INFO): Powering down 2018-11-29T00:06:56.649Z,1543450016.649 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:06:56.650Z,1543450016.650 [PNI_TCM] No Fault, FailCount= 3 2018-11-29T00:07:04.205Z,1543450024.205 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:07:04.205Z,1543450024.205 [PNI_TCM] Data Fault, FailCount= 4 2018-11-29T00:07:04.206Z,1543450024.206 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:07:04.252Z,1543450024.252 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:07:04.593Z,1543450024.593 [PNI_TCM](INFO): Powering down 2018-11-29T00:07:05.830Z,1543450025.830 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:07:05.831Z,1543450025.831 [PNI_TCM] No Fault, FailCount= 4 2018-11-29T00:07:13.396Z,1543450033.396 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:07:13.396Z,1543450033.396 [PNI_TCM] Data Fault, FailCount= 5 2018-11-29T00:07:13.396Z,1543450033.396 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:07:13.444Z,1543450033.444 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:07:13.444Z,1543450033.444 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-29T00:07:13.794Z,1543450033.794 [PNI_TCM](INFO): Powering down 2018-11-29T00:07:13.902Z,1543450033.902 [CBIT](INFO): Critical error at 20181129T000713 2018-11-29T00:07:42.211Z,1543450062.211 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:07:42.212Z,1543450062.212 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2018-11-29T00:07:42.212Z,1543450062.212 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:07:42.250Z,1543450062.250 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:07:42.656Z,1543450062.656 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:07:42.656Z,1543450062.656 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2018-11-29T00:07:43.018Z,1543450063.018 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:07:43.019Z,1543450063.019 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:07:43.019Z,1543450063.019 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:07:46.162Z,1543450066.162 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-29T00:07:46.162Z,1543450066.162 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-11-29T00:07:46.162Z,1543450066.162 [Rowe_600LCM](ERROR): Communications Fault 2018-11-29T00:07:46.224Z,1543450066.224 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-29T00:07:46.578Z,1543450066.578 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-29T00:07:46.751Z,1543450066.751 [Rowe_600LCM](INFO): Powering down 2018-11-29T00:07:48.225Z,1543450068.225 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-29T00:07:48.225Z,1543450068.225 [Rowe_600LCM] No Fault, FailCount= 2 2018-11-29T00:07:48.366Z,1543450068.366 [Rowe_600LCM](INFO): Initializing 2018-11-29T00:07:48.367Z,1543450068.367 [Rowe_600LCM](INFO): Checking LCM 2018-11-29T00:07:48.367Z,1543450068.367 [Rowe_600LCM](INFO): LCM OK 2018-11-29T00:07:48.367Z,1543450068.367 [Rowe_600LCM](INFO): Powering up 2018-11-29T00:07:52.488Z,1543450072.488 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-29T00:07:52.545Z,1543450072.545 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-29T00:07:52.546Z,1543450072.546 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-29T00:07:52.547Z,1543450072.547 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-29T00:07:52.548Z,1543450072.548 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-29T00:08:04.233Z,1543450084.233 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:08:04.234Z,1543450084.234 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:08:14.997Z,1543450094.997 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:08:14.997Z,1543450094.997 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:08:14.997Z,1543450094.997 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:08:15.020Z,1543450095.020 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:08:15.522Z,1543450095.522 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:08:16.233Z,1543450096.233 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:08:16.233Z,1543450096.233 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:08:26.601Z,1543450106.601 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:08:26.602Z,1543450106.602 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:08:26.602Z,1543450106.602 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:08:26.621Z,1543450106.621 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:08:27.110Z,1543450107.110 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:08:27.839Z,1543450107.839 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:08:27.839Z,1543450107.839 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:08:38.591Z,1543450118.591 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:08:38.591Z,1543450118.591 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:08:38.591Z,1543450118.591 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:08:38.632Z,1543450118.632 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:08:38.633Z,1543450118.633 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:08:39.106Z,1543450119.106 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:09:43.028Z,1543450183.028 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:09:43.028Z,1543450183.028 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2018-11-29T00:09:43.028Z,1543450183.028 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:09:43.042Z,1543450183.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:09:43.435Z,1543450183.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:09:43.435Z,1543450183.435 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2018-11-29T00:09:43.796Z,1543450183.796 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:09:43.797Z,1543450183.797 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:09:43.797Z,1543450183.797 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:09:48.966Z,1543450188.966 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-29T00:09:48.966Z,1543450188.966 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-11-29T00:09:48.966Z,1543450188.966 [Rowe_600LCM](ERROR): Communications Fault 2018-11-29T00:09:49.052Z,1543450189.052 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-29T00:09:49.370Z,1543450189.370 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-29T00:09:49.589Z,1543450189.589 [Rowe_600LCM](INFO): Powering down 2018-11-29T00:09:51.033Z,1543450191.033 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-29T00:09:51.033Z,1543450191.033 [Rowe_600LCM] No Fault, FailCount= 3 2018-11-29T00:09:51.206Z,1543450191.206 [Rowe_600LCM](INFO): Initializing 2018-11-29T00:09:51.207Z,1543450191.207 [Rowe_600LCM](INFO): Checking LCM 2018-11-29T00:09:51.207Z,1543450191.207 [Rowe_600LCM](INFO): LCM OK 2018-11-29T00:09:51.207Z,1543450191.207 [Rowe_600LCM](INFO): Powering up 2018-11-29T00:09:55.325Z,1543450195.325 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-29T00:09:55.452Z,1543450195.452 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-29T00:09:55.453Z,1543450195.453 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-29T00:09:55.454Z,1543450195.454 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-29T00:09:55.454Z,1543450195.454 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-29T00:11:08.639Z,1543450268.639 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:11:08.639Z,1543450268.639 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:11:18.000Z,1543450279.000 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:11:18.000Z,1543450279.000 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:11:19.000Z,1543450279.000 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:11:19.048Z,1543450279.048 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:11:19.510Z,1543450279.510 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:11:20.237Z,1543450280.237 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:11:20.237Z,1543450280.237 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:11:30.604Z,1543450290.604 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:11:30.605Z,1543450290.605 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:11:30.605Z,1543450290.605 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:11:30.653Z,1543450290.653 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:11:31.122Z,1543450291.122 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:11:31.847Z,1543450291.847 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:11:31.847Z,1543450291.847 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:11:42.205Z,1543450302.205 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:11:42.205Z,1543450302.205 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:11:42.205Z,1543450302.205 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:11:42.256Z,1543450302.256 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:11:42.256Z,1543450302.256 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:11:42.723Z,1543450302.723 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:11:43.807Z,1543450303.807 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:11:43.808Z,1543450303.808 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2018-11-29T00:11:43.808Z,1543450303.808 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:11:43.846Z,1543450303.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:11:44.220Z,1543450304.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:11:44.220Z,1543450304.220 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2018-11-29T00:11:44.607Z,1543450304.607 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:11:44.608Z,1543450304.608 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:11:44.608Z,1543450304.608 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:11:51.366Z,1543450311.366 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-29T00:11:51.366Z,1543450311.366 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-11-29T00:11:51.366Z,1543450311.366 [Rowe_600LCM](ERROR): Communications Fault 2018-11-29T00:11:51.453Z,1543450311.453 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-29T00:11:51.770Z,1543450311.770 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-29T00:11:51.932Z,1543450311.932 [Rowe_600LCM](INFO): Powering down 2018-11-29T00:11:53.422Z,1543450313.422 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-29T00:11:53.422Z,1543450313.422 [Rowe_600LCM] No Fault, FailCount= 4 2018-11-29T00:11:53.546Z,1543450313.546 [Rowe_600LCM](INFO): Initializing 2018-11-29T00:11:53.547Z,1543450313.547 [Rowe_600LCM](INFO): Checking LCM 2018-11-29T00:11:53.547Z,1543450313.547 [Rowe_600LCM](INFO): LCM OK 2018-11-29T00:11:53.547Z,1543450313.547 [Rowe_600LCM](INFO): Powering up 2018-11-29T00:11:57.668Z,1543450317.668 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-29T00:11:57.738Z,1543450317.738 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-29T00:11:57.739Z,1543450317.739 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-29T00:11:57.740Z,1543450317.740 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-29T00:11:57.740Z,1543450317.740 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-29T00:12:13.441Z,1543450333.441 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:12:13.441Z,1543450333.441 [PNI_TCM] No Fault, FailCount= 5 2018-11-29T00:12:20.990Z,1543450340.990 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:12:20.990Z,1543450340.990 [PNI_TCM] Data Fault, FailCount= 1 2018-11-29T00:12:20.990Z,1543450340.990 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:12:21.045Z,1543450341.045 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:12:21.400Z,1543450341.400 [PNI_TCM](INFO): Powering down 2018-11-29T00:12:22.629Z,1543450342.629 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:12:22.630Z,1543450342.630 [PNI_TCM] No Fault, FailCount= 1 2018-11-29T00:12:30.200Z,1543450350.200 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:12:30.201Z,1543450350.201 [PNI_TCM] Data Fault, FailCount= 2 2018-11-29T00:12:30.201Z,1543450350.201 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:12:30.258Z,1543450350.258 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:12:30.608Z,1543450350.608 [PNI_TCM](INFO): Powering down 2018-11-29T00:12:31.830Z,1543450351.830 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:12:31.830Z,1543450351.830 [PNI_TCM] No Fault, FailCount= 2 2018-11-29T00:12:39.400Z,1543450359.400 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:12:39.401Z,1543450359.401 [PNI_TCM] Data Fault, FailCount= 3 2018-11-29T00:12:39.401Z,1543450359.401 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:12:39.447Z,1543450359.447 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:12:39.804Z,1543450359.804 [PNI_TCM](INFO): Powering down 2018-11-29T00:12:41.037Z,1543450361.037 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:12:41.038Z,1543450361.038 [PNI_TCM] No Fault, FailCount= 3 2018-11-29T00:12:48.616Z,1543450368.616 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:12:48.616Z,1543450368.616 [PNI_TCM] Data Fault, FailCount= 4 2018-11-29T00:12:48.616Z,1543450368.616 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:12:48.641Z,1543450368.641 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:12:49.013Z,1543450369.013 [PNI_TCM](INFO): Powering down 2018-11-29T00:12:50.237Z,1543450370.237 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:12:50.237Z,1543450370.237 [PNI_TCM] No Fault, FailCount= 4 2018-11-29T00:12:57.804Z,1543450377.804 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:12:57.804Z,1543450377.804 [PNI_TCM] Data Fault, FailCount= 5 2018-11-29T00:12:57.804Z,1543450377.804 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:12:57.847Z,1543450377.847 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:12:57.848Z,1543450377.848 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-29T00:12:58.205Z,1543450378.205 [PNI_TCM](INFO): Powering down 2018-11-29T00:12:58.326Z,1543450378.326 [CBIT](INFO): Critical error at 20181129T001257 2018-11-29T00:13:33.837Z,1543450413.837 [CBIT](INFO): Clearing failed state for component DropWeight 2018-11-29T00:13:33.837Z,1543450413.837 [DropWeight] No Fault, FailCount= 1 2018-11-29T00:13:35.379Z,1543450415.379 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-29T00:13:35.379Z,1543450415.379 [DropWeight] Hardware Fault, FailCount= 1 2018-11-29T00:13:35.379Z,1543450415.379 [DropWeight](ERROR): Hardware Fault 2018-11-29T00:13:35.450Z,1543450415.450 [CBIT](INFO): Critical error at 20181129T001335 2018-11-29T00:13:35.453Z,1543450415.453 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-29T00:13:35.453Z,1543450415.453 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-29T00:13:35.834Z,1543450415.834 [CBIT](INFO): Critical error at 20181129T001335 2018-11-29T00:13:45.004Z,1543450425.004 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:13:45.004Z,1543450425.004 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2018-11-29T00:13:45.004Z,1543450425.004 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:13:45.047Z,1543450425.047 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:13:45.449Z,1543450425.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:13:45.449Z,1543450425.449 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2018-11-29T00:13:45.837Z,1543450425.837 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:13:45.838Z,1543450425.838 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:13:45.838Z,1543450425.838 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:13:54.170Z,1543450434.170 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-29T00:13:54.170Z,1543450434.170 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-11-29T00:13:54.170Z,1543450434.170 [Rowe_600LCM](ERROR): Communications Fault 2018-11-29T00:13:54.253Z,1543450434.253 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-29T00:13:54.253Z,1543450434.253 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-11-29T00:13:54.574Z,1543450434.574 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-29T00:13:54.749Z,1543450434.749 [Rowe_600LCM](INFO): Powering down 2018-11-29T00:14:12.229Z,1543450452.229 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:14:12.229Z,1543450452.229 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:14:22.998Z,1543450462.998 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:14:22.998Z,1543450462.998 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:14:22.998Z,1543450462.998 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:14:23.021Z,1543450463.021 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:14:23.514Z,1543450463.514 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:14:24.223Z,1543450464.223 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:14:24.223Z,1543450464.223 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:14:34.999Z,1543450474.999 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:14:34.999Z,1543450474.999 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:14:34.999Z,1543450474.999 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:14:35.024Z,1543450475.024 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:14:35.514Z,1543450475.514 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:14:36.228Z,1543450476.228 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:14:36.228Z,1543450476.228 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:14:46.997Z,1543450486.997 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:14:46.998Z,1543450486.998 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:14:46.998Z,1543450486.998 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:14:47.017Z,1543450487.017 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:14:47.017Z,1543450487.017 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:14:47.518Z,1543450487.518 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:15:33.776Z,1543450533.776 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-11-29T00:15:33.776Z,1543450533.776 [NAL9602] Data Fault, FailCount= 3 2018-11-29T00:15:33.776Z,1543450533.776 [NAL9602](ERROR): Data Fault 2018-11-29T00:15:33.832Z,1543450533.832 [CBIT](ERROR): Data Fault in component: NAL9602 2018-11-29T00:15:34.173Z,1543450534.173 [NAL9602](INFO): Powering down 2018-11-29T00:15:35.020Z,1543450535.020 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-29T00:15:35.020Z,1543450535.020 [NAL9602] No Fault, FailCount= 3 2018-11-29T00:15:46.207Z,1543450546.207 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:15:46.207Z,1543450546.207 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2018-11-29T00:15:46.207Z,1543450546.207 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:15:46.221Z,1543450546.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:15:46.623Z,1543450546.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:15:46.623Z,1543450546.623 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2018-11-29T00:15:47.007Z,1543450547.007 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:15:47.008Z,1543450547.008 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:15:47.008Z,1543450547.008 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:16:04.175Z,1543450564.175 [NAL9602](INFO): Powering up NAL9602 2018-11-29T00:16:15.375Z,1543450575.375 [NAL9602](INFO): NAL9602 initialized 2018-11-29T00:16:51.834Z,1543450611.834 [CBIT](INFO): Clearing failed state for component BPC1 2018-11-29T00:16:51.834Z,1543450611.834 [BPC1] No Fault, FailCount= 3 2018-11-29T00:17:01.423Z,1543450621.423 [Radio_Surface](INFO): Powering down 2018-11-29T00:17:07.508Z,1543450627.508 [Radio_Surface](INFO): Powering up 2018-11-29T00:17:17.036Z,1543450637.036 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:17:17.036Z,1543450637.036 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:17:27.390Z,1543450647.390 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:17:27.390Z,1543450647.390 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:17:27.390Z,1543450647.390 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:17:27.448Z,1543450647.448 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:17:27.914Z,1543450647.914 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:17:28.636Z,1543450648.636 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:17:28.636Z,1543450648.636 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:17:39.390Z,1543450659.390 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:17:39.390Z,1543450659.390 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:17:39.390Z,1543450659.390 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:17:39.453Z,1543450659.453 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:17:39.914Z,1543450659.914 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:17:40.639Z,1543450660.639 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:17:40.639Z,1543450660.639 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:17:47.400Z,1543450667.400 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:17:47.400Z,1543450667.400 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2018-11-29T00:17:47.400Z,1543450667.400 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:17:47.443Z,1543450667.443 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:17:47.840Z,1543450667.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:17:47.840Z,1543450667.840 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2018-11-29T00:17:48.201Z,1543450668.201 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:17:48.201Z,1543450668.201 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:17:48.202Z,1543450668.202 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:17:50.994Z,1543450670.994 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:17:50.994Z,1543450670.994 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:17:50.994Z,1543450670.994 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:17:51.045Z,1543450671.045 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:17:51.045Z,1543450671.045 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:17:51.506Z,1543450671.506 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:17:57.391Z,1543450677.391 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-11-29T00:17:57.391Z,1543450677.391 [BPC1] Data Fault, FailCount= 4 2018-11-29T00:17:57.391Z,1543450677.391 [BPC1](ERROR): Data Fault 2018-11-29T00:17:57.448Z,1543450677.448 [CBIT](ERROR): Data Fault in component: BPC1 2018-11-29T00:17:57.845Z,1543450677.845 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:17:57.845Z,1543450677.845 [PNI_TCM] No Fault, FailCount= 5 2018-11-29T00:18:05.395Z,1543450685.395 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:18:05.395Z,1543450685.395 [PNI_TCM] Data Fault, FailCount= 1 2018-11-29T00:18:05.395Z,1543450685.395 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:18:05.415Z,1543450685.415 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:18:05.795Z,1543450685.795 [PNI_TCM](INFO): Powering down 2018-11-29T00:18:07.045Z,1543450687.045 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:18:07.045Z,1543450687.045 [PNI_TCM] No Fault, FailCount= 1 2018-11-29T00:18:14.591Z,1543450694.591 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:18:14.591Z,1543450694.591 [PNI_TCM] Data Fault, FailCount= 2 2018-11-29T00:18:14.591Z,1543450694.591 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:18:14.615Z,1543450694.615 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:18:14.992Z,1543450694.992 [PNI_TCM](INFO): Powering down 2018-11-29T00:18:16.247Z,1543450696.247 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:18:16.248Z,1543450696.248 [PNI_TCM] No Fault, FailCount= 2 2018-11-29T00:18:23.795Z,1543450703.795 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:18:23.795Z,1543450703.795 [PNI_TCM] Data Fault, FailCount= 3 2018-11-29T00:18:23.795Z,1543450703.795 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:18:23.844Z,1543450703.844 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:18:24.194Z,1543450704.194 [PNI_TCM](INFO): Powering down 2018-11-29T00:18:25.452Z,1543450705.452 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:18:25.452Z,1543450705.452 [PNI_TCM] No Fault, FailCount= 3 2018-11-29T00:18:32.995Z,1543450712.995 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:18:32.995Z,1543450712.995 [PNI_TCM] Data Fault, FailCount= 4 2018-11-29T00:18:32.995Z,1543450712.995 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:18:33.015Z,1543450713.015 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:18:33.397Z,1543450713.397 [PNI_TCM](INFO): Powering down 2018-11-29T00:18:34.656Z,1543450714.656 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:18:34.657Z,1543450714.657 [PNI_TCM] No Fault, FailCount= 4 2018-11-29T00:18:42.197Z,1543450722.197 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:18:42.197Z,1543450722.197 [PNI_TCM] Data Fault, FailCount= 5 2018-11-29T00:18:42.197Z,1543450722.197 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:18:42.224Z,1543450722.224 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:18:42.224Z,1543450722.224 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-29T00:18:42.600Z,1543450722.600 [PNI_TCM](INFO): Powering down 2018-11-29T00:18:42.710Z,1543450722.710 [CBIT](INFO): Critical error at 20181129T001842 2018-11-29T00:19:48.616Z,1543450788.616 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:19:48.616Z,1543450788.616 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2018-11-29T00:19:48.616Z,1543450788.616 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:19:48.630Z,1543450788.630 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:19:49.028Z,1543450789.028 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:19:49.028Z,1543450789.028 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2018-11-29T00:19:49.408Z,1543450789.408 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:19:49.409Z,1543450789.409 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:19:49.409Z,1543450789.409 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:20:09.822Z,1543450809.822 [Default:B.GoToSurface](CRITICAL): Surfacing timeout. Returning calc satisfied. 2018-11-29T00:20:09.823Z,1543450809.823 [Default:CheckIn] Running Loop=1 2018-11-29T00:20:09.823Z,1543450809.823 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-11-29T00:20:09.823Z,1543450809.823 [Default:CheckIn:Read_GPS] Running Loop=1 2018-11-29T00:20:09.832Z,1543450809.832 [CBIT](INFO): Critical error at 20181129T002009 2018-11-29T00:20:10.217Z,1543450810.217 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-11-29T00:20:21.099Z,1543450821.099 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:20:21.099Z,1543450821.099 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:20:31.443Z,1543450831.443 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:20:31.443Z,1543450831.443 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:20:31.443Z,1543450831.443 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:20:31.486Z,1543450831.486 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:20:31.946Z,1543450831.946 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:20:32.666Z,1543450832.666 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:20:32.666Z,1543450832.666 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:20:43.050Z,1543450843.050 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:20:43.051Z,1543450843.051 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:20:43.051Z,1543450843.051 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:20:43.097Z,1543450843.097 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:20:43.558Z,1543450843.558 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:20:44.269Z,1543450844.269 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:20:44.269Z,1543450844.269 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:20:54.641Z,1543450854.641 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:20:54.641Z,1543450854.641 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:20:54.641Z,1543450854.641 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:20:54.689Z,1543450854.689 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:20:54.689Z,1543450854.689 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:20:55.166Z,1543450855.166 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:21:49.447Z,1543450909.447 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:21:49.447Z,1543450909.447 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2018-11-29T00:21:49.447Z,1543450909.447 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:21:49.477Z,1543450909.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:21:49.872Z,1543450909.872 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:21:49.872Z,1543450909.872 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2018-11-29T00:21:50.243Z,1543450910.243 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:21:50.243Z,1543450910.243 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:21:50.244Z,1543450910.244 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:23:24.665Z,1543451004.665 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:23:24.665Z,1543451004.665 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-29T00:23:35.434Z,1543451015.434 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:23:35.434Z,1543451015.434 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-29T00:23:35.434Z,1543451015.434 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:23:35.483Z,1543451015.483 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:23:35.954Z,1543451015.954 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:23:36.669Z,1543451016.669 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:23:36.669Z,1543451016.669 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-29T00:23:42.255Z,1543451022.255 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:23:42.256Z,1543451022.256 [PNI_TCM] No Fault, FailCount= 5 2018-11-29T00:23:47.441Z,1543451027.441 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:23:47.441Z,1543451027.441 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-29T00:23:47.441Z,1543451027.441 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:23:47.461Z,1543451027.461 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:23:47.958Z,1543451027.958 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:23:48.672Z,1543451028.672 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-29T00:23:48.672Z,1543451028.672 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-29T00:23:49.835Z,1543451029.835 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:23:49.835Z,1543451029.835 [PNI_TCM] Data Fault, FailCount= 1 2018-11-29T00:23:49.835Z,1543451029.835 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:23:49.881Z,1543451029.881 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:23:50.240Z,1543451030.240 [PNI_TCM](INFO): Powering down 2018-11-29T00:23:50.342Z,1543451030.342 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-29T00:23:50.342Z,1543451030.342 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2018-11-29T00:23:50.342Z,1543451030.342 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-29T00:23:50.355Z,1543451030.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-29T00:23:50.660Z,1543451030.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-29T00:23:50.660Z,1543451030.660 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20 2018-11-29T00:23:51.052Z,1543451031.052 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-29T00:23:51.052Z,1543451031.052 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-29T00:23:51.053Z,1543451031.053 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-29T00:23:51.468Z,1543451031.468 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:23:51.468Z,1543451031.468 [PNI_TCM] No Fault, FailCount= 1 2018-11-29T00:23:54.267Z,1543451034.267 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-29T00:23:54.267Z,1543451034.267 [Rowe_600LCM] No Fault, FailCount= 5 2018-11-29T00:23:54.662Z,1543451034.662 [Rowe_600LCM](INFO): Initializing 2018-11-29T00:23:54.663Z,1543451034.663 [Rowe_600LCM](INFO): Checking LCM 2018-11-29T00:23:54.663Z,1543451034.663 [Rowe_600LCM](INFO): LCM OK 2018-11-29T00:23:54.663Z,1543451034.663 [Rowe_600LCM](INFO): Powering up 2018-11-29T00:23:58.802Z,1543451038.802 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-29T00:23:58.862Z,1543451038.862 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-29T00:23:58.863Z,1543451038.863 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-29T00:23:58.864Z,1543451038.864 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-29T00:23:58.864Z,1543451038.864 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-29T00:23:59.037Z,1543451039.037 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:23:59.037Z,1543451039.037 [PNI_TCM] Data Fault, FailCount= 2 2018-11-29T00:23:59.037Z,1543451039.037 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:23:59.098Z,1543451039.098 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:23:59.436Z,1543451039.436 [PNI_TCM](INFO): Powering down 2018-11-29T00:23:59.438Z,1543451039.438 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-29T00:23:59.438Z,1543451039.438 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-29T00:23:59.438Z,1543451039.438 [Aanderaa_O2](ERROR): Communications Fault 2018-11-29T00:23:59.465Z,1543451039.465 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-29T00:23:59.465Z,1543451039.465 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-29T00:23:59.840Z,1543451039.840 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:24:00.690Z,1543451040.690 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:24:00.690Z,1543451040.690 [PNI_TCM] No Fault, FailCount= 2 2018-11-29T00:24:08.235Z,1543451048.235 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:24:08.236Z,1543451048.236 [PNI_TCM] Data Fault, FailCount= 3 2018-11-29T00:24:08.236Z,1543451048.236 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:24:08.285Z,1543451048.285 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:24:08.639Z,1543451048.639 [PNI_TCM](INFO): Powering down 2018-11-29T00:24:09.872Z,1543451049.872 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:24:09.872Z,1543451049.872 [PNI_TCM] No Fault, FailCount= 3 2018-11-29T00:24:17.429Z,1543451057.429 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:24:17.429Z,1543451057.429 [PNI_TCM] Data Fault, FailCount= 4 2018-11-29T00:24:17.430Z,1543451057.430 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:24:17.471Z,1543451057.471 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:24:17.842Z,1543451057.842 [PNI_TCM](INFO): Powering down 2018-11-29T00:24:19.076Z,1543451059.076 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-29T00:24:19.076Z,1543451059.076 [PNI_TCM] No Fault, FailCount= 4 2018-11-29T00:24:26.630Z,1543451066.630 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-29T00:24:26.630Z,1543451066.630 [PNI_TCM] Data Fault, FailCount= 5 2018-11-29T00:24:26.631Z,1543451066.631 [PNI_TCM](ERROR): Data Fault 2018-11-29T00:24:26.675Z,1543451066.675 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-29T00:24:26.675Z,1543451066.675 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-29T00:24:27.032Z,1543451067.032 [PNI_TCM](INFO): Powering down 2018-11-29T00:24:27.159Z,1543451067.159 [CBIT](INFO): Critical error at 20181129T002426 2018-11-29T00:25:08.264Z,1543451108.264 [CommandLine](IMPORTANT): got command quit 2018-11-29T00:25:09.273Z,1543451109.273 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:09.274Z,1543451109.274 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.322Z,1543451109.322 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-29T00:25:09.322Z,1543451109.322 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.323Z,1543451109.323 [CommandLine](INFO): Join timeout helper Thread ID is 1129 2018-11-29T00:25:09.323Z,1543451109.323 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-29T00:25:09.323Z,1543451109.323 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.324Z,1543451109.324 [NavChartDb](INFO): Join timeout helper Thread ID is 1130 2018-11-29T00:25:09.424Z,1543451109.424 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:09.425Z,1543451109.425 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.434Z,1543451109.434 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-11-29T00:25:09.434Z,1543451109.434 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.435Z,1543451109.435 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1131 2018-11-29T00:25:09.754Z,1543451109.754 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:09.754Z,1543451109.754 [WetLabsBB2FL](INFO): Powering down 2018-11-29T00:25:09.755Z,1543451109.755 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.758Z,1543451109.758 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2018-11-29T00:25:09.758Z,1543451109.758 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:09.758Z,1543451109.758 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1132 2018-11-29T00:25:09.863Z,1543451109.863 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-29T00:20:09.8Z 2018-11-29T00:25:09.863Z,1543451109.863 [Default:CheckIn:Read_GPS] Stopped 2018-11-29T00:25:09.863Z,1543451109.863 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-11-29T00:25:10.050Z,1543451110.050 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:10.131Z,1543451110.131 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-11-29T00:25:10.132Z,1543451110.132 [CTD_Seabird](INFO): Powering down 2018-11-29T00:25:10.133Z,1543451110.133 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.142Z,1543451110.142 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2018-11-29T00:25:10.142Z,1543451110.142 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.143Z,1543451110.143 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1134 2018-11-29T00:25:10.214Z,1543451110.214 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:10.215Z,1543451110.215 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-29T00:25:10.319Z,1543451110.319 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-29T00:25:10.403Z,1543451110.403 [Rowe_600LCM](INFO): Powering down 2018-11-29T00:25:10.404Z,1543451110.404 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.408Z,1543451110.408 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-29T00:25:10.408Z,1543451110.408 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.408Z,1543451110.408 [Radio_Surface](INFO): Join timeout helper Thread ID is 1136 2018-11-29T00:25:10.496Z,1543451110.496 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:10.497Z,1543451110.497 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.503Z,1543451110.503 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-29T00:25:10.503Z,1543451110.503 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.503Z,1543451110.503 [logger](INFO): Join timeout helper Thread ID is 1137 2018-11-29T00:25:10.542Z,1543451110.542 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:10.542Z,1543451110.542 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.551Z,1543451110.551 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-29T00:25:10.551Z,1543451110.551 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.551Z,1543451110.551 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-29T00:25:10.551Z,1543451110.551 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.552Z,1543451110.552 [controlThread](INFO): Join timeout helper Thread ID is 1138 2018-11-29T00:25:10.627Z,1543451110.627 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-29T00:25:10.627Z,1543451110.627 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-29T00:25:10.627Z,1543451110.627 [AcousticModem_Benthos_ATM900](INFO): Powering down 2018-11-29T00:25:10.747Z,1543451110.747 [NAL9602](INFO): Powering down 2018-11-29T00:25:10.749Z,1543451110.749 [PNI_TCM](INFO): Powering down 2018-11-29T00:25:10.750Z,1543451110.750 [Aanderaa_O2](INFO): Powering down 2018-11-29T00:25:10.753Z,1543451110.753 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-29T00:25:10.754Z,1543451110.754 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-29T00:25:10.754Z,1543451110.754 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-29T00:25:10.755Z,1543451110.755 [MissionManager](INFO): Uninitializing Mission Default 2018-11-29T00:25:10.755Z,1543451110.755 [Default] Stopped 2018-11-29T00:25:10.755Z,1543451110.755 [Default](DEBUG): Aggregate::uninitialize Default 2018-11-29T00:25:10.755Z,1543451110.755 [Default:B.GoToSurface] Stopped 2018-11-29T00:25:10.755Z,1543451110.755 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-29T00:25:10.755Z,1543451110.755 [Default:CheckIn] Stopped 2018-11-29T00:25:10.755Z,1543451110.755 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-11-29T00:25:10.755Z,1543451110.755 [Default:CheckIn:Read_Iridium] Stopped 2018-11-29T00:25:10.759Z,1543451110.759 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-29T00:25:10.759Z,1543451110.759 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-29T00:25:10.760Z,1543451110.760 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-29T00:25:10.760Z,1543451110.760 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-29T00:25:10.760Z,1543451110.760 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-29T00:25:10.761Z,1543451110.761 [BuoyancyServo](INFO): Powering down 2018-11-29T00:25:10.774Z,1543451110.774 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-29T00:25:10.774Z,1543451110.774 [ElevatorServo](INFO): Powering down 2018-11-29T00:25:10.775Z,1543451110.775 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-29T00:25:10.775Z,1543451110.775 [MassServo](INFO): Powering down 2018-11-29T00:25:10.776Z,1543451110.776 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-29T00:25:10.776Z,1543451110.776 [RudderServo](INFO): Powering down 2018-11-29T00:25:10.777Z,1543451110.777 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-29T00:25:10.777Z,1543451110.777 [ThrusterServo](INFO): Powering down 2018-11-29T00:25:10.778Z,1543451110.778 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-29T00:25:10.778Z,1543451110.778 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-29T00:25:10.779Z,1543451110.779 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-29T00:25:10.779Z,1543451110.779 [CBIT](DEBUG): Powering off loads. 2018-11-29T00:25:10.790Z,1543451110.790 [CBIT](DEBUG): Disabling WDT. 2018-11-29T00:25:10.802Z,1543451110.802 [CBIT](DEBUG): Opening all GF detection circuits. 2018-11-29T00:25:10.803Z,1543451110.803 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.899Z,1543451110.899 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.906Z,1543451110.906 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.945Z,1543451110.945 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.949Z,1543451110.949 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:10.998Z,1543451110.998 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-29T00:25:11.047Z,1543451111.047 [logger ThreadHandler](INFO): Thread cancelled.