2018-12-14T21:22:12.233Z,1544822532.233 [Supervisor](DEBUG): Initializing supervisor.
2018-12-14T21:22:12.236Z,1544822532.236 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-14T21:22:12.237Z,1544822532.237 [SyncHandler](INFO): Protected caller Thread ID is 8791
2018-12-14T21:22:12.237Z,1544822532.237 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-14T21:22:12.238Z,1544822532.238 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-14T21:22:12.238Z,1544822532.238 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8792
2018-12-14T21:22:12.242Z,1544822532.242 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-14T21:22:12.254Z,1544822532.254 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-14T21:22:12.255Z,1544822532.255 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-14T21:22:12.255Z,1544822532.255 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8793
2018-12-14T21:22:12.256Z,1544822532.256 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-14T21:22:12.257Z,1544822532.257 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-14T21:22:12.257Z,1544822532.257 [logger ThreadHandler](INFO): Protected caller Thread ID is 8794
2018-12-14T21:22:12.259Z,1544822532.259 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-14T21:22:12.259Z,1544822532.259 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-14T21:22:12.261Z,1544822532.261 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-14T21:22:12.420Z,1544822532.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-14T21:22:12.421Z,1544822532.421 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-14T21:22:12.521Z,1544822532.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-14T21:22:12.522Z,1544822532.522 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-14T21:22:12.710Z,1544822532.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-14T21:22:12.711Z,1544822532.711 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-14T21:22:13.103Z,1544822533.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-14T21:22:13.104Z,1544822533.104 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-14T21:22:13.463Z,1544822533.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-14T21:22:13.463Z,1544822533.463 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-14T21:22:13.900Z,1544822533.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-14T21:22:13.901Z,1544822533.901 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-14T21:22:14.363Z,1544822534.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-14T21:22:14.364Z,1544822534.364 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-14T21:22:14.802Z,1544822534.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-14T21:22:14.803Z,1544822534.803 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-14T21:22:14.882Z,1544822534.882 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-14T21:22:15.183Z,1544822535.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-14T21:22:15.183Z,1544822535.183 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-14T21:22:15.332Z,1544822535.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-14T21:22:15.333Z,1544822535.333 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-14T21:22:15.921Z,1544822535.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-14T21:22:15.922Z,1544822535.922 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-14T21:22:16.121Z,1544822536.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-14T21:22:16.122Z,1544822536.122 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-14T21:22:16.219Z,1544822536.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-14T21:22:16.219Z,1544822536.219 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-14T21:22:16.361Z,1544822536.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-14T21:22:16.363Z,1544822536.363 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-14T21:22:16.364Z,1544822536.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-14T21:22:16.591Z,1544822536.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-14T21:22:16.592Z,1544822536.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-14T21:22:16.694Z,1544822536.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-14T21:22:16.841Z,1544822536.841 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-14T21:22:16.932Z,1544822536.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-14T21:22:17.017Z,1544822537.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-14T21:22:17.166Z,1544822537.166 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-14T21:22:17.353Z,1544822537.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-14T21:22:17.450Z,1544822537.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-14T21:22:17.543Z,1544822537.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-14T21:22:17.639Z,1544822537.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-14T21:22:17.722Z,1544822537.722 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-12-14T21:22:17.723Z,1544822537.723 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-12-14T21:22:17.729Z,1544822537.729 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-14T21:22:17.857Z,1544822537.857 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-14T21:22:17.857Z,1544822537.857 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-14T21:22:17.881Z,1544822537.881 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-14T21:22:17.881Z,1544822537.881 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-14T21:22:17.937Z,1544822537.937 [DepthRateCalculator] Loaded
2018-12-14T21:22:17.938Z,1544822537.938 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-14T21:22:17.944Z,1544822537.944 [PitchRateCalculator] Loaded
2018-12-14T21:22:17.944Z,1544822537.944 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-14T21:22:17.959Z,1544822537.959 [SpeedCalculator] Loaded
2018-12-14T21:22:17.960Z,1544822537.960 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-14T21:22:17.980Z,1544822537.980 [TempGradientCalculator] Loaded
2018-12-14T21:22:17.980Z,1544822537.980 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-14T21:22:17.986Z,1544822537.986 [YawRateCalculator] Loaded
2018-12-14T21:22:17.986Z,1544822537.986 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-14T21:22:18.025Z,1544822538.025 [ElevatorOffsetCalculator] Loaded
2018-12-14T21:22:18.026Z,1544822538.026 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-14T21:22:18.026Z,1544822538.026 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-14T21:22:18.027Z,1544822538.027 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-14T21:22:18.067Z,1544822538.067 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-14T21:22:18.067Z,1544822538.067 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-14T21:22:18.548Z,1544822538.548 [AcousticModem_Benthos_ATM900] Loaded
2018-12-14T21:22:18.548Z,1544822538.548 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-12-14T21:22:18.637Z,1544822538.637 [DataOverHttps] Loaded
2018-12-14T21:22:18.637Z,1544822538.637 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-12-14T21:22:18.651Z,1544822538.651 [Depth_Keller] Loaded
2018-12-14T21:22:18.651Z,1544822538.651 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-12-14T21:22:18.748Z,1544822538.748 [NAL9602] Loaded
2018-12-14T21:22:18.748Z,1544822538.748 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-12-14T21:22:18.763Z,1544822538.763 [Onboard] Loaded
2018-12-14T21:22:18.764Z,1544822538.764 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-12-14T21:22:18.774Z,1544822538.774 [Radio_Surface] Loaded
2018-12-14T21:22:18.774Z,1544822538.774 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-12-14T21:22:18.775Z,1544822538.775 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407CE4E0
2018-12-14T21:22:18.775Z,1544822538.775 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8874
2018-12-14T21:22:18.928Z,1544822538.928 [PNI_TCM] Loaded
2018-12-14T21:22:18.928Z,1544822538.928 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-12-14T21:22:18.987Z,1544822538.987 [Rowe_600LCM] Loaded
2018-12-14T21:22:18.987Z,1544822538.987 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-12-14T21:22:18.988Z,1544822538.988 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407FE4E0
2018-12-14T21:22:18.989Z,1544822538.989 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 8875
2018-12-14T21:22:20.745Z,1544822540.745 [BPC1] Loaded
2018-12-14T21:22:20.745Z,1544822540.745 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-12-14T21:22:20.746Z,1544822540.746 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-12-14T21:22:20.746Z,1544822540.746 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-14T21:22:21.010Z,1544822541.010 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-14T21:22:21.032Z,1544822541.032 [SBIT] Loaded
2018-12-14T21:22:21.033Z,1544822541.033 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-14T21:22:21.033Z,1544822541.033 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-14T21:22:21.095Z,1544822541.095 [IBIT] Loaded
2018-12-14T21:22:21.095Z,1544822541.095 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-14T21:22:21.098Z,1544822541.098 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-14T21:22:21.376Z,1544822541.376 [CBIT] Loaded
2018-12-14T21:22:21.376Z,1544822541.376 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-14T21:22:21.376Z,1544822541.376 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-14T21:22:21.377Z,1544822541.377 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-14T21:22:21.673Z,1544822541.673 [CTD_Seabird] Loaded
2018-12-14T21:22:21.674Z,1544822541.674 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-14T21:22:21.675Z,1544822541.675 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0
2018-12-14T21:22:21.675Z,1544822541.675 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8876
2018-12-14T21:22:21.692Z,1544822541.692 [ESPComponent] Loaded
2018-12-14T21:22:21.692Z,1544822541.692 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-14T21:22:21.706Z,1544822541.706 [PAR_Licor] Loaded
2018-12-14T21:22:21.706Z,1544822541.706 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-14T21:22:21.754Z,1544822541.754 [WetLabsBB2FL] Loaded
2018-12-14T21:22:21.754Z,1544822541.754 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-14T21:22:21.755Z,1544822541.755 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2018-12-14T21:22:21.756Z,1544822541.756 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8877
2018-12-14T21:22:21.757Z,1544822541.757 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-14T21:22:21.757Z,1544822541.757 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-14T21:22:22.034Z,1544822542.034 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-14T21:22:22.034Z,1544822542.034 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-14T21:22:22.121Z,1544822542.121 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-14T21:22:22.215Z,1544822542.215 [VerticalControl] Loaded
2018-12-14T21:22:22.215Z,1544822542.215 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-14T21:22:22.216Z,1544822542.216 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-14T21:22:22.275Z,1544822542.275 [HorizontalControl] Loaded
2018-12-14T21:22:22.275Z,1544822542.275 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-14T21:22:22.276Z,1544822542.276 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-14T21:22:22.281Z,1544822542.281 [SpeedControl] Loaded
2018-12-14T21:22:22.281Z,1544822542.281 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-14T21:22:22.282Z,1544822542.282 [LoopControl](DEBUG): Construct LoopControl.
2018-12-14T21:22:22.282Z,1544822542.282 [LoopControl] Loaded
2018-12-14T21:22:22.283Z,1544822542.283 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-14T21:22:22.283Z,1544822542.283 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-14T21:22:22.284Z,1544822542.284 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-14T21:22:22.387Z,1544822542.387 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-14T21:22:22.387Z,1544822542.387 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-14T21:22:22.506Z,1544822542.506 [BuoyancyServo] Loaded
2018-12-14T21:22:22.507Z,1544822542.507 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-14T21:22:22.518Z,1544822542.518 [ElevatorServo] Loaded
2018-12-14T21:22:22.518Z,1544822542.518 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-14T21:22:22.528Z,1544822542.528 [MassServo] Loaded
2018-12-14T21:22:22.529Z,1544822542.529 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-14T21:22:22.540Z,1544822542.540 [RudderServo] Loaded
2018-12-14T21:22:22.540Z,1544822542.540 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-14T21:22:22.551Z,1544822542.551 [ThrusterServo] Loaded
2018-12-14T21:22:22.551Z,1544822542.551 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-14T21:22:22.551Z,1544822542.551 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-14T21:22:22.552Z,1544822542.552 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-14T21:22:22.664Z,1544822542.664 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-14T21:22:22.665Z,1544822542.665 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-14T21:22:22.681Z,1544822542.681 [NavChart] Loaded
2018-12-14T21:22:22.681Z,1544822542.681 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-14T21:22:22.685Z,1544822542.685 [UniversalFixResidualReporter] Loaded
2018-12-14T21:22:22.685Z,1544822542.685 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-14T21:22:22.685Z,1544822542.685 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-14T21:22:22.689Z,1544822542.689 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-14T21:22:22.690Z,1544822542.690 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-14T21:22:22.697Z,1544822542.697 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-14T21:22:22.698Z,1544822542.698 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A684E0
2018-12-14T21:22:22.698Z,1544822542.698 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8878
2018-12-14T21:22:22.703Z,1544822542.703 [Supervisor](INFO): Main Thread ID is 8790
2018-12-14T21:22:22.703Z,1544822542.703 [Supervisor](DEBUG): Running supervisor.
2018-12-14T21:22:22.704Z,1544822542.704 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8879
2018-12-14T21:22:22.706Z,1544822542.706 [controlThread ThreadHandler](INFO): Handler Thread ID is 8880
2018-12-14T21:22:22.707Z,1544822542.707 [controlThread](DEBUG): Initializing ControlThread
2018-12-14T21:22:22.707Z,1544822542.707 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-14T21:22:22.708Z,1544822542.708 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-14T21:22:22.708Z,1544822542.708 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-14T21:22:22.708Z,1544822542.708 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-14T21:22:22.709Z,1544822542.709 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-14T21:22:22.709Z,1544822542.709 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-14T21:22:22.714Z,1544822542.714 [SBIT](INFO): Initialize SBIT Component.
2018-12-14T21:22:22.714Z,1544822542.714 [SBIT](IMPORTANT): git: 2018-11-27-30-g8200dc4
2018-12-14T21:22:22.714Z,1544822542.714 [SBIT](INFO): git hash: 8200dc43e6ef01082c531ff0fab84136a371dc2d
2018-12-14T21:22:22.715Z,1544822542.715 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-14T21:22:22.715Z,1544822542.715 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-12-14T21:22:22.716Z,1544822542.716 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-14T21:22:22.717Z,1544822542.717 [IBIT](INFO): Initialize IBIT Component.
2018-12-14T21:22:22.718Z,1544822542.718 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-14T21:22:22.718Z,1544822542.718 [logger ThreadHandler](INFO): Handler Thread ID is 8881
2018-12-14T21:22:22.729Z,1544822542.729 [CBIT](DEBUG): Initialized mux pins.
2018-12-14T21:22:22.729Z,1544822542.729 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-14T21:22:22.737Z,1544822542.737 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8882
2018-12-14T21:22:22.743Z,1544822542.743 [Radio_Surface](INFO): Powering up
2018-12-14T21:22:22.749Z,1544822542.749 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 8883
2018-12-14T21:22:22.750Z,1544822542.750 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:22:22.751Z,1544822542.751 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:22:22.757Z,1544822542.757 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-12-14T21:22:22.757Z,1544822542.757 [CBIT](DEBUG): Initializing heartbeat.
2018-12-14T21:22:22.829Z,1544822542.829 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-14T21:22:22.829Z,1544822542.829 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-14T21:22:22.857Z,1544822542.857 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8884
2018-12-14T21:22:22.857Z,1544822542.857 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:22:22.860Z,1544822542.860 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:22:22.869Z,1544822542.869 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-14T21:22:22.871Z,1544822542.871 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-14T21:22:22.871Z,1544822542.871 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-14T21:22:22.872Z,1544822542.872 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-14T21:22:22.894Z,1544822542.894 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:22:22.894Z,1544822542.894 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:22:22.895Z,1544822542.895 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:22:22.895Z,1544822542.895 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-14T21:22:22.895Z,1544822542.895 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-14T21:22:22.901Z,1544822542.901 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-14T21:22:23.006Z,1544822543.006 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8886
2018-12-14T21:22:23.007Z,1544822543.007 [WetLabsBB2FL](INFO): Powering down
2018-12-14T21:22:23.049Z,1544822543.049 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8887
2018-12-14T21:22:23.055Z,1544822543.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-14T21:22:23.055Z,1544822543.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-14T21:22:23.056Z,1544822543.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-14T21:22:23.056Z,1544822543.056 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-14T21:22:23.056Z,1544822543.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-14T21:22:23.056Z,1544822543.056 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-14T21:22:23.056Z,1544822543.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-14T21:22:23.057Z,1544822543.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-14T21:22:23.057Z,1544822543.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-14T21:22:23.057Z,1544822543.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-14T21:22:23.057Z,1544822543.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-14T21:22:23.057Z,1544822543.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-14T21:22:23.058Z,1544822543.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-14T21:22:23.058Z,1544822543.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-14T21:22:23.058Z,1544822543.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-14T21:22:23.058Z,1544822543.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-14T21:22:23.082Z,1544822543.082 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-14T21:22:23.219Z,1544822543.219 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-12-14T21:22:23.229Z,1544822543.229 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-14T21:22:23.345Z,1544822543.345 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:22:23.345Z,1544822543.345 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:22:23.389Z,1544822543.389 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-14T21:22:23.390Z,1544822543.390 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-14T21:22:23.391Z,1544822543.391 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-14T21:22:23.459Z,1544822543.459 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-14T21:22:23.461Z,1544822543.461 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-14T21:22:23.507Z,1544822543.507 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-14T21:22:23.511Z,1544822543.511 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-12-14T21:22:23.534Z,1544822543.534 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,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-12-14T21:22:23.584Z,1544822543.584 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-12-14T21:22:23.584Z,1544822543.584 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-12-14T21:22:23.649Z,1544822543.649 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-12-14T21:22:23.958Z,1544822543.958 [ESPComponent](INFO): powering down ESP
2018-12-14T21:22:24.801Z,1544822544.801 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-14T21:22:24.813Z,1544822544.813 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-14T21:22:24.820Z,1544822544.820 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-14T21:22:24.841Z,1544822544.841 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-14T21:22:24.847Z,1544822544.847 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-14T21:22:24.857Z,1544822544.857 [MassServo](DEBUG): Initializing MassServo.
2018-12-14T21:22:24.870Z,1544822544.870 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-14T21:22:24.877Z,1544822544.877 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-14T21:22:24.883Z,1544822544.883 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-14T21:22:24.889Z,1544822544.889 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-14T21:22:24.922Z,1544822544.922 [CommandLine](FAULT): Scheduling is paused
2018-12-14T21:22:24.923Z,1544822544.923 [CBIT](INFO): Critical error at 20181214T212222
2018-12-14T21:22:24.923Z,1544822544.923 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-12-14T21:22:27.433Z,1544822547.433 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:22:27.580Z,1544822547.580 [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-12-14T21:22:27.727Z,1544822547.727 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:22:27.727Z,1544822547.727 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:22:27.728Z,1544822547.728 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:22:35.829Z,1544822555.829 [CBIT](CRITICAL): Environmental Failure. Press:14.814690 PSI. Humidity:40%. Temp:25 C. ABORTING MISSION
2018-12-14T21:22:35.837Z,1544822555.837 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-12-14T21:22:35.839Z,1544822555.839 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-12-14T21:22:35.839Z,1544822555.839 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-12-14T21:22:35.840Z,1544822555.840 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-12-14T21:22:35.845Z,1544822555.845 [AcousticModem_Benthos_ATM900](DEBUG): Nov 13 2018 06:49:08 
2018-12-14T21:22:35.846Z,1544822555.846 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-12-14T21:22:35.930Z,1544822555.930 [CBIT](INFO): Critical error at 20181214T212235
2018-12-14T21:22:36.638Z,1544822556.638 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-12-14T21:22:36.645Z,1544822556.645 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003923
2018-12-14T21:22:36.905Z,1544822556.905 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:22:36.905Z,1544822556.905 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:22:36.905Z,1544822556.905 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:22:37.038Z,1544822557.038 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-12-14T21:22:37.039Z,1544822557.039 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-12-14T21:22:37.039Z,1544822557.039 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-12-14T21:22:37.237Z,1544822557.237 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:22:37.309Z,1544822557.309 [CTD_Seabird](INFO): Powering down
2018-12-14T21:22:37.438Z,1544822557.438 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-12-14T21:22:37.838Z,1544822557.838 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-12-14T21:22:37.839Z,1544822557.839 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-12-14T21:22:38.239Z,1544822558.239 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-12-14T21:22:39.530Z,1544822559.530 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:22:39.530Z,1544822559.530 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:22:40.157Z,1544822560.157 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:22:40.158Z,1544822560.158 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:22:41.457Z,1544822561.457 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:22:41.457Z,1544822561.457 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:22:41.457Z,1544822561.457 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:22:41.574Z,1544822561.574 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:22:41.861Z,1544822561.861 [PNI_TCM](INFO): Powering down
2018-12-14T21:22:43.144Z,1544822563.144 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:22:43.144Z,1544822563.144 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:22:47.117Z,1544822567.117 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-14T21:22:47.122Z,1544822567.122 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-14T21:22:49.054Z,1544822569.054 [NAL9602](INFO): Powering up NAL9602
2018-12-14T21:22:50.773Z,1544822570.773 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:22:50.773Z,1544822570.773 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:22:50.773Z,1544822570.773 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:22:50.943Z,1544822570.943 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:22:51.174Z,1544822571.174 [PNI_TCM](INFO): Powering down
2018-12-14T21:22:52.459Z,1544822572.459 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:22:52.459Z,1544822572.459 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:22:54.209Z,1544822574.209 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:22:54.209Z,1544822574.209 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:22:54.209Z,1544822574.209 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:22:54.451Z,1544822574.451 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:22:54.613Z,1544822574.613 [CTD_Seabird](INFO): Powering down
2018-12-14T21:22:56.859Z,1544822576.859 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:22:56.859Z,1544822576.859 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:22:57.561Z,1544822577.561 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:22:57.564Z,1544822577.564 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:22:58.154Z,1544822578.154 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007555
CHAN A1 (24V): -0.013244
CHAN A2 (12V): -0.003804
CHAN A3 (5V): -0.002113
CHAN B0 (3.3V): -0.001285
CHAN B1 (3.15aV): -0.001328
CHAN B2 (3.15bV): -0.000963
CHAN B3 (GND): 0.000135
OPEN: 0.004379
Full Scale Calc: 4.765 mA, -1.589 mA
2018-12-14T21:22:59.958Z,1544822579.958 [NAL9602](INFO): NAL9602 initialized
2018-12-14T21:22:59.973Z,1544822579.973 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:22:59.973Z,1544822579.973 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:22:59.973Z,1544822579.973 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:23:00.087Z,1544822580.087 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:23:00.399Z,1544822580.399 [PNI_TCM](INFO): Powering down
2018-12-14T21:23:01.674Z,1544822581.674 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:23:01.674Z,1544822581.674 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:23:09.380Z,1544822589.380 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:23:09.380Z,1544822589.380 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:23:09.380Z,1544822589.380 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:23:09.463Z,1544822589.463 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:23:09.769Z,1544822589.769 [PNI_TCM](INFO): Powering down
2018-12-14T21:23:11.047Z,1544822591.047 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:23:11.047Z,1544822591.047 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:23:11.605Z,1544822591.605 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:23:11.605Z,1544822591.605 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:23:11.605Z,1544822591.605 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:23:11.846Z,1544822591.846 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:23:11.847Z,1544822591.847 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:23:12.009Z,1544822592.009 [CTD_Seabird](INFO): Powering down
2018-12-14T21:23:18.584Z,1544822598.584 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:23:18.584Z,1544822598.584 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:23:18.584Z,1544822598.584 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:23:18.637Z,1544822598.637 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:23:18.637Z,1544822598.637 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:23:18.979Z,1544822598.979 [PNI_TCM](INFO): Powering down
2018-12-14T21:23:19.143Z,1544822599.143 [CBIT](INFO): Critical error at 20181214T212318
2018-12-14T21:23:31.274Z,1544822611.274 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-12-14T21:23:31.274Z,1544822611.274 [BPC1] Data Fault, FailCount= 1
2018-12-14T21:23:31.274Z,1544822611.274 [BPC1](ERROR): Data Fault
2018-12-14T21:23:31.346Z,1544822611.346 [CBIT](ERROR): Data Fault in component: BPC1
2018-12-14T21:23:44.601Z,1544822624.601 [SBIT](IMPORTANT): SBIT PASSED
2018-12-14T21:23:44.690Z,1544822624.690 [CommandLine](IMPORTANT): got command configSet list
2018-12-14T21:23:44.690Z,1544822624.690 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-12-14T21:23:44.691Z,1544822624.691 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-12-14T21:23:44.691Z,1544822624.691 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2018-12-14T21:23:44.691Z,1544822624.691 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2018-12-14T21:23:44.987Z,1544822624.987 [MissionManager](IMPORTANT): Started mission Startup
2018-12-14T21:23:44.988Z,1544822624.988 [Startup] Running Loop=1
2018-12-14T21:23:44.988Z,1544822624.988 [Startup](DEBUG): Aggregate::initialize Startup
2018-12-14T21:23:44.988Z,1544822624.988 [Startup:A.GoToSurface] Running Loop=1
2018-12-14T21:23:44.988Z,1544822624.988 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-12-14T21:23:44.989Z,1544822624.989 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-12-14T21:23:44.989Z,1544822624.989 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-12-14T21:23:44.990Z,1544822624.990 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-12-14T21:23:44.990Z,1544822624.990 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-12-14T21:23:44.990Z,1544822624.990 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-12-14T21:23:44.996Z,1544822624.996 [Startup:StartupSatComms] Running Loop=1
2018-12-14T21:23:44.997Z,1544822624.997 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-12-14T21:23:44.997Z,1544822624.997 [Startup:StartupSatComms:A] Running Loop=1
2018-12-14T21:23:45.394Z,1544822625.394 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-12-14T21:24:21.458Z,1544822661.458 [CommandLine](IMPORTANT): got command 
2018-12-14T21:24:21.458Z,1544822661.458 [CommandLine](FAULT): Incomplete syntax. Try: help
2018-12-14T21:24:22.445Z,1544822662.445 [CommandLine](IMPORTANT): got command failComponent
2018-12-14T21:24:22.445Z,1544822662.445 [CommandLine](IMPORTANT): Failed components:
2018-12-14T21:24:22.446Z,1544822662.446 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2018-12-14T21:24:22.446Z,1544822662.446 [CommandLine](IMPORTANT): BPC1: Data Fault
2018-12-14T21:24:22.446Z,1544822662.446 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2018-12-14T21:24:23.003Z,1544822663.003 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:24:23.003Z,1544822663.003 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-12-14T21:24:23.003Z,1544822663.003 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:24:23.016Z,1544822663.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:24:23.417Z,1544822663.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:24:23.417Z,1544822663.417 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-12-14T21:24:23.433Z,1544822663.433 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:24:23.433Z,1544822663.433 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-14T21:24:23.433Z,1544822663.433 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:24:23.809Z,1544822663.809 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:24:23.809Z,1544822663.809 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:24:23.809Z,1544822663.809 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:24:23.822Z,1544822663.822 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:24:23.837Z,1544822663.837 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:24:23.968Z,1544822663.968 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:24:27.264Z,1544822667.264 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:24:27.265Z,1544822667.265 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-14T21:24:27.605Z,1544822667.605 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:24:27.605Z,1544822667.605 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:24:27.606Z,1544822667.606 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:24:27.606Z,1544822667.606 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:24:31.823Z,1544822671.823 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:24:31.884Z,1544822671.884 [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-12-14T21:24:31.894Z,1544822671.894 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:24:31.894Z,1544822671.894 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:24:31.895Z,1544822671.895 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:24:33.283Z,1544822673.283 [CommandLine](IMPORTANT): got command show variable nal9602.sig
2018-12-14T21:24:33.481Z,1544822673.481 [CommandLine](IMPORTANT): NAL9602.sigQuality (count)
2018-12-14T21:24:39.021Z,1544822679.021 [CommandLine](IMPORTANT): got command report touch NAL9602.sigQuality
2018-12-14T21:24:43.331Z,1544822683.331 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:24:44.131Z,1544822684.131 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:24:45.307Z,1544822685.307 [Startup:StartupSatComms:A](INFO): Timed out from 2018-12-14T21:23:44.0Z
2018-12-14T21:24:45.307Z,1544822685.307 [Startup:StartupSatComms:A] Stopped
2018-12-14T21:24:45.307Z,1544822685.307 [Startup:StartupSatComms:B] Running Loop=1
2018-12-14T21:24:45.716Z,1544822685.716 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-12-14T21:24:50.483Z,1544822690.483 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:24:50.520Z,1544822690.520 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20181214T170242/Courier0064.lzma
2018-12-14T21:24:50.967Z,1544822690.967 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:24:51.292Z,1544822691.292 [DataOverHttps](INFO): Moved sent file to Logs/20181214T170242/Courier0064.lzma.bak
2018-12-14T21:24:51.292Z,1544822691.292 [DataOverHttps](INFO): SBD MOMSN=9058378
2018-12-14T21:24:56.082Z,1544822696.082 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:24:56.547Z,1544822696.547 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:24:56.781Z,1544822696.781 [CommandLine](IMPORTANT): got command show variable nal9602.
2018-12-14T21:24:56.827Z,1544822696.827 [CommandLine](IMPORTANT): NAL9602.loadAtStartup (bool)
2018-12-14T21:24:56.827Z,1544822696.827 [CommandLine](IMPORTANT): NAL9602.simulateHardware (bool)
2018-12-14T21:24:56.828Z,1544822696.828 [CommandLine](IMPORTANT): NAL9602.gpsFailTimeout (minute)
2018-12-14T21:24:56.828Z,1544822696.828 [CommandLine](IMPORTANT): NAL9602.iridiumMTQueueTimeout (minute)
2018-12-14T21:24:56.828Z,1544822696.828 [CommandLine](IMPORTANT): NAL9602.power (watt)
2018-12-14T21:24:56.829Z,1544822696.829 [CommandLine](IMPORTANT): NAL9602.power_platform_communications (watt)
2018-12-14T21:24:56.830Z,1544822696.830 [CommandLine](IMPORTANT): NAL9602.requestGGA (bool)
2018-12-14T21:24:56.830Z,1544822696.830 [CommandLine](IMPORTANT): NAL9602.fastGPSFix (bool)
2018-12-14T21:24:56.838Z,1544822696.838 [CommandLine](IMPORTANT): NAL9602.loadControl (none)
2018-12-14T21:24:56.838Z,1544822696.838 [CommandLine](IMPORTANT): NAL9602.uart (none)
2018-12-14T21:24:56.838Z,1544822696.838 [CommandLine](IMPORTANT): NAL9602.baud (bit_per_second)
2018-12-14T21:24:56.906Z,1544822696.906 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_0 (count)
2018-12-14T21:24:56.907Z,1544822696.907 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_1 (count)
2018-12-14T21:24:56.907Z,1544822696.907 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_2 (count)
2018-12-14T21:24:56.907Z,1544822696.907 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_3 (count)
2018-12-14T21:24:56.908Z,1544822696.908 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_4 (count)
2018-12-14T21:24:56.908Z,1544822696.908 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_5 (count)
2018-12-14T21:24:56.908Z,1544822696.908 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_6 (count)
2018-12-14T21:24:56.919Z,1544822696.919 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_7 (count)
2018-12-14T21:24:56.945Z,1544822696.945 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_8 (count)
2018-12-14T21:24:56.945Z,1544822696.945 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_9 (count)
2018-12-14T21:24:56.945Z,1544822696.945 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_10 (count)
2018-12-14T21:24:56.946Z,1544822696.946 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_11 (count)
2018-12-14T21:24:56.946Z,1544822696.946 [CommandLine](IMPORTANT): NAL9602.goodFix (bool)
2018-12-14T21:24:56.946Z,1544822696.946 [CommandLine](IMPORTANT): NAL9602.numSatellites (count)
2018-12-14T21:24:56.947Z,1544822696.947 [CommandLine](IMPORTANT): NAL9602.sigQuality (count)
2018-12-14T21:24:56.947Z,1544822696.947 [CommandLine](IMPORTANT): NAL9602.SOG (knot)
2018-12-14T21:24:56.947Z,1544822696.947 [CommandLine](IMPORTANT): NAL9602.COG (degree)
2018-12-14T21:24:56.948Z,1544822696.948 [CommandLine](IMPORTANT): NAL9602.time_fix (second)
2018-12-14T21:24:56.948Z,1544822696.948 [CommandLine](IMPORTANT): NAL9602.latitude_fix (degree)
2018-12-14T21:24:56.948Z,1544822696.948 [CommandLine](IMPORTANT): NAL9602.longitude_fix (degree)
2018-12-14T21:24:56.949Z,1544822696.949 [CommandLine](IMPORTANT): NAL9602.location_fix (degree)
2018-12-14T21:24:56.949Z,1544822696.949 [CommandLine](IMPORTANT): NAL9602.platform_communications (bool)
2018-12-14T21:24:57.038Z,1544822697.038 [CommandLine](IMPORTANT): NAL9602.durationOfLastRun (second)
2018-12-14T21:24:57.059Z,1544822697.059 [CommandLine](IMPORTANT): NAL9602.component_voltage (volt)
2018-12-14T21:24:57.060Z,1544822697.060 [CommandLine](IMPORTANT): NAL9602.component_avgVoltage (volt)
2018-12-14T21:24:57.060Z,1544822697.060 [CommandLine](IMPORTANT): NAL9602.component_current (milliampere)
2018-12-14T21:24:57.067Z,1544822697.067 [CommandLine](IMPORTANT): NAL9602.component_avgCurrent (milliampere)
2018-12-14T21:25:01.649Z,1544822701.649 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:01.660Z,1544822701.660 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20181214T212212/Courier0000.lzma
2018-12-14T21:25:02.119Z,1544822702.119 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:02.459Z,1544822702.459 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Courier0000.lzma.bak
2018-12-14T21:25:02.459Z,1544822702.459 [DataOverHttps](INFO): SBD MOMSN=9058383
2018-12-14T21:25:08.406Z,1544822708.406 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:08.867Z,1544822708.867 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:13.152Z,1544822713.152 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:13.164Z,1544822713.164 [DataOverHttps](INFO): Sending 587 bytes from file Logs/20181214T170242/Express0065.lzma
2018-12-14T21:25:13.615Z,1544822713.615 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:13.964Z,1544822713.964 [DataOverHttps](INFO): Moved sent file to Logs/20181214T170242/Express0065.lzma.bak
2018-12-14T21:25:13.964Z,1544822713.964 [DataOverHttps](INFO): SBD MOMSN=9058389
2018-12-14T21:25:19.962Z,1544822719.962 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:20.443Z,1544822720.443 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:25.295Z,1544822725.295 [CommandLine](IMPORTANT): got command report touch NAL9602.numSatellites
2018-12-14T21:25:26.100Z,1544822726.100 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:26.119Z,1544822726.119 [DataOverHttps](INFO): Sending 1201 bytes from file Logs/20181214T212212/Express0001.lzma
2018-12-14T21:25:26.571Z,1544822726.571 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:26.911Z,1544822726.911 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Express0001.lzma.bak
2018-12-14T21:25:26.912Z,1544822726.912 [DataOverHttps](INFO): SBD MOMSN=9058435
2018-12-14T21:25:29.759Z,1544822729.759 [Startup:StartupSatComms:B] Stopped
2018-12-14T21:25:29.759Z,1544822729.759 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-12-14T21:25:29.759Z,1544822729.759 [Startup:StartupSatComms] Stopped
2018-12-14T21:25:29.760Z,1544822729.760 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-12-14T21:25:29.760Z,1544822729.760 [Startup](INFO): Completed Startup
2018-12-14T21:25:29.761Z,1544822729.761 [MissionManager](INFO): Startup is completed.
2018-12-14T21:25:29.761Z,1544822729.761 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-14T21:25:29.761Z,1544822729.761 [Startup] Stopped
2018-12-14T21:25:29.761Z,1544822729.761 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-12-14T21:25:29.761Z,1544822729.761 [Startup:A.GoToSurface] Stopped
2018-12-14T21:25:29.761Z,1544822729.761 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-12-14T21:25:29.879Z,1544822729.879 [MissionManager](IMPORTANT): Started mission Default
2018-12-14T21:25:29.879Z,1544822729.879 [Default] Running Loop=1
2018-12-14T21:25:29.879Z,1544822729.879 [Default](DEBUG): Aggregate::initialize Default
2018-12-14T21:25:29.879Z,1544822729.879 [Default:B.GoToSurface] Running Loop=1
2018-12-14T21:25:29.880Z,1544822729.880 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-12-14T21:25:29.880Z,1544822729.880 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-12-14T21:25:29.880Z,1544822729.880 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-12-14T21:25:29.880Z,1544822729.880 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-12-14T21:25:29.885Z,1544822729.885 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-12-14T21:25:29.885Z,1544822729.885 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-12-14T21:25:29.886Z,1544822729.886 [Default:A.Wait] Running Loop=1
2018-12-14T21:25:29.886Z,1544822729.886 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-12-14T21:25:33.067Z,1544822733.067 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:33.535Z,1544822733.535 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:37.932Z,1544822737.932 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:38.733Z,1544822738.733 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:41.921Z,1544822741.921 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:25:41.921Z,1544822741.921 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:25:42.057Z,1544822742.057 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:25:42.059Z,1544822742.059 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:25:43.135Z,1544822743.135 [Default:A.Wait](INFO): Done Waiting.
2018-12-14T21:25:43.135Z,1544822743.135 [Default:A.Wait] Stopped
2018-12-14T21:25:43.135Z,1544822743.135 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-12-14T21:25:43.171Z,1544822743.171 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:43.531Z,1544822743.531 [Default:CheckIn] Running Loop=1
2018-12-14T21:25:43.531Z,1544822743.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-14T21:25:43.531Z,1544822743.531 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-14T21:25:43.931Z,1544822743.931 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-12-14T21:25:43.965Z,1544822743.965 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:47.193Z,1544822747.193 [CommandLine](IMPORTANT): got command show variable nal9602
2018-12-14T21:25:47.246Z,1544822747.246 [CommandLine](IMPORTANT): NAL9602.loadAtStartup (bool)
2018-12-14T21:25:47.246Z,1544822747.246 [CommandLine](IMPORTANT): NAL9602.simulateHardware (bool)
2018-12-14T21:25:47.246Z,1544822747.246 [CommandLine](IMPORTANT): NAL9602.gpsFailTimeout (minute)
2018-12-14T21:25:47.247Z,1544822747.247 [CommandLine](IMPORTANT): NAL9602.iridiumMTQueueTimeout (minute)
2018-12-14T21:25:47.247Z,1544822747.247 [CommandLine](IMPORTANT): NAL9602.power (watt)
2018-12-14T21:25:47.247Z,1544822747.247 [CommandLine](IMPORTANT): NAL9602.power_platform_communications (watt)
2018-12-14T21:25:47.248Z,1544822747.248 [CommandLine](IMPORTANT): NAL9602.requestGGA (bool)
2018-12-14T21:25:47.248Z,1544822747.248 [CommandLine](IMPORTANT): NAL9602.fastGPSFix (bool)
2018-12-14T21:25:47.283Z,1544822747.283 [CommandLine](IMPORTANT): NAL9602.loadControl (none)
2018-12-14T21:25:47.284Z,1544822747.284 [CommandLine](IMPORTANT): NAL9602.uart (none)
2018-12-14T21:25:47.284Z,1544822747.284 [CommandLine](IMPORTANT): NAL9602.baud (bit_per_second)
2018-12-14T21:25:47.378Z,1544822747.378 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_0 (count)
2018-12-14T21:25:47.378Z,1544822747.378 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_1 (count)
2018-12-14T21:25:47.379Z,1544822747.379 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_2 (count)
2018-12-14T21:25:47.379Z,1544822747.379 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_3 (count)
2018-12-14T21:25:47.379Z,1544822747.379 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_4 (count)
2018-12-14T21:25:47.380Z,1544822747.380 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_5 (count)
2018-12-14T21:25:47.380Z,1544822747.380 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_6 (count)
2018-12-14T21:25:47.380Z,1544822747.380 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_7 (count)
2018-12-14T21:25:47.385Z,1544822747.385 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_8 (count)
2018-12-14T21:25:47.385Z,1544822747.385 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_9 (count)
2018-12-14T21:25:47.386Z,1544822747.386 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_10 (count)
2018-12-14T21:25:47.386Z,1544822747.386 [CommandLine](IMPORTANT): NAL9602.SNRSatellite_11 (count)
2018-12-14T21:25:47.386Z,1544822747.386 [CommandLine](IMPORTANT): NAL9602.goodFix (bool)
2018-12-14T21:25:47.387Z,1544822747.387 [CommandLine](IMPORTANT): NAL9602.numSatellites (count)
2018-12-14T21:25:47.387Z,1544822747.387 [CommandLine](IMPORTANT): NAL9602.sigQuality (count)
2018-12-14T21:25:47.387Z,1544822747.387 [CommandLine](IMPORTANT): NAL9602.SOG (knot)
2018-12-14T21:25:47.388Z,1544822747.388 [CommandLine](IMPORTANT): NAL9602.COG (degree)
2018-12-14T21:25:47.388Z,1544822747.388 [CommandLine](IMPORTANT): NAL9602.time_fix (second)
2018-12-14T21:25:47.388Z,1544822747.388 [CommandLine](IMPORTANT): NAL9602.latitude_fix (degree)
2018-12-14T21:25:47.401Z,1544822747.401 [CommandLine](IMPORTANT): NAL9602.longitude_fix (degree)
2018-12-14T21:25:47.401Z,1544822747.401 [CommandLine](IMPORTANT): NAL9602.location_fix (degree)
2018-12-14T21:25:47.402Z,1544822747.402 [CommandLine](IMPORTANT): NAL9602.platform_communications (bool)
2018-12-14T21:25:47.468Z,1544822747.468 [CommandLine](IMPORTANT): NAL9602.durationOfLastRun (second)
2018-12-14T21:25:47.535Z,1544822747.535 [CommandLine](IMPORTANT): NAL9602.component_voltage (volt)
2018-12-14T21:25:47.536Z,1544822747.536 [CommandLine](IMPORTANT): NAL9602.component_avgVoltage (volt)
2018-12-14T21:25:47.536Z,1544822747.536 [CommandLine](IMPORTANT): NAL9602.component_current (milliampere)
2018-12-14T21:25:47.536Z,1544822747.536 [CommandLine](IMPORTANT): NAL9602.component_avgCurrent (milliampere)
2018-12-14T21:25:48.369Z,1544822748.369 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:49.146Z,1544822749.146 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:53.616Z,1544822753.616 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:54.375Z,1544822754.375 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:56.121Z,1544822756.121 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:25:56.121Z,1544822756.121 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:25:56.121Z,1544822756.121 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:25:56.375Z,1544822756.375 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:25:56.525Z,1544822756.525 [CTD_Seabird](INFO): Powering down
2018-12-14T21:25:58.755Z,1544822758.755 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:25:58.755Z,1544822758.755 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:25:58.761Z,1544822758.761 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:25:59.473Z,1544822759.473 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:25:59.476Z,1544822759.476 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:25:59.610Z,1544822759.610 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:26:04.006Z,1544822764.006 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:26:04.811Z,1544822764.811 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:26:09.162Z,1544822769.162 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:26:10.004Z,1544822770.004 [Reporter](INFO): NAL9602.sigQuality 0 count
2018-12-14T21:26:12.970Z,1544822772.970 [CommandLine](IMPORTANT): got command report clear
2018-12-14T21:26:13.525Z,1544822773.525 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:26:13.525Z,1544822773.525 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:26:13.525Z,1544822773.525 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:26:13.586Z,1544822773.586 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:26:13.929Z,1544822773.929 [CTD_Seabird](INFO): Powering down
2018-12-14T21:26:15.921Z,1544822775.921 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:26:15.922Z,1544822775.922 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:26:16.877Z,1544822776.877 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:26:16.879Z,1544822776.879 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:26:23.917Z,1544822783.917 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:26:23.917Z,1544822783.917 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-12-14T21:26:23.917Z,1544822783.917 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:26:23.942Z,1544822783.942 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:26:24.359Z,1544822784.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:26:24.359Z,1544822784.359 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-12-14T21:26:24.721Z,1544822784.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:26:24.721Z,1544822784.721 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:26:24.722Z,1544822784.722 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:26:28.269Z,1544822788.269 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:26:28.269Z,1544822788.269 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-14T21:26:28.269Z,1544822788.269 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:26:28.339Z,1544822788.339 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:26:28.673Z,1544822788.673 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:26:28.842Z,1544822788.842 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:26:30.338Z,1544822790.338 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:26:30.338Z,1544822790.338 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-14T21:26:30.457Z,1544822790.457 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:26:30.457Z,1544822790.457 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:26:30.457Z,1544822790.457 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:26:30.458Z,1544822790.458 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:26:30.925Z,1544822790.925 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:26:30.925Z,1544822790.925 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:26:30.925Z,1544822790.925 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:26:31.329Z,1544822791.329 [CTD_Seabird](INFO): Powering down
2018-12-14T21:26:33.431Z,1544822793.431 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:26:33.432Z,1544822793.432 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:26:34.575Z,1544822794.575 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:26:34.633Z,1544822794.633 [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-12-14T21:26:34.634Z,1544822794.634 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:26:34.635Z,1544822794.635 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:26:34.635Z,1544822794.635 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:28:03.422Z,1544822883.422 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-12-14T21:28:18.662Z,1544822898.662 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:28:18.662Z,1544822898.662 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T21:28:25.066Z,1544822905.066 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:28:25.066Z,1544822905.066 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-12-14T21:28:25.066Z,1544822905.066 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:28:25.080Z,1544822905.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:28:25.471Z,1544822905.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:28:25.471Z,1544822905.471 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-12-14T21:28:25.864Z,1544822905.864 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:28:25.864Z,1544822905.864 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:28:25.865Z,1544822905.865 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:28:26.244Z,1544822906.244 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:28:26.244Z,1544822906.244 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:28:26.244Z,1544822906.244 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:28:26.270Z,1544822906.270 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:28:26.633Z,1544822906.633 [PNI_TCM](INFO): Powering down
2018-12-14T21:28:27.869Z,1544822907.869 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:28:27.869Z,1544822907.869 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:28:31.009Z,1544822911.009 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:28:31.009Z,1544822911.009 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-14T21:28:31.009Z,1544822911.009 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:28:31.106Z,1544822911.106 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:28:31.413Z,1544822911.413 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:28:31.590Z,1544822911.590 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:28:35.311Z,1544822915.311 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:28:35.311Z,1544822915.311 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:28:35.311Z,1544822915.311 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:28:35.337Z,1544822915.337 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:28:35.338Z,1544822915.338 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:28:35.338Z,1544822915.338 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-14T21:28:35.425Z,1544822915.425 [PNI_TCM](INFO): Powering down
2018-12-14T21:28:35.629Z,1544822915.629 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:28:35.629Z,1544822915.629 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:28:35.629Z,1544822915.629 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:28:35.630Z,1544822915.630 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:28:36.622Z,1544822916.622 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:28:36.622Z,1544822916.622 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:28:39.791Z,1544822919.791 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:28:39.891Z,1544822919.891 [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-12-14T21:28:39.892Z,1544822919.892 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:28:39.893Z,1544822919.893 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:28:39.893Z,1544822919.893 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:28:44.168Z,1544822924.168 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:28:44.168Z,1544822924.168 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:28:44.168Z,1544822924.168 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:28:44.200Z,1544822924.200 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:28:44.569Z,1544822924.569 [PNI_TCM](INFO): Powering down
2018-12-14T21:28:45.827Z,1544822925.827 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:28:45.827Z,1544822925.827 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:28:53.379Z,1544822933.379 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:28:53.379Z,1544822933.379 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:28:53.380Z,1544822933.380 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:28:53.430Z,1544822933.430 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:28:53.775Z,1544822933.775 [PNI_TCM](INFO): Powering down
2018-12-14T21:28:55.002Z,1544822935.002 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:28:55.002Z,1544822935.002 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:29:01.006Z,1544822941.006 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:29:01.006Z,1544822941.006 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:29:01.405Z,1544822941.405 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:29:01.407Z,1544822941.407 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:29:02.566Z,1544822942.566 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:29:02.566Z,1544822942.566 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:29:02.566Z,1544822942.566 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:29:02.637Z,1544822942.637 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:29:02.638Z,1544822942.638 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:29:02.977Z,1544822942.977 [PNI_TCM](INFO): Powering down
2018-12-14T21:29:03.033Z,1544822943.033 [CBIT](INFO): Critical error at 20181214T212902
2018-12-14T21:29:15.449Z,1544822955.449 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:29:15.449Z,1544822955.449 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:29:15.449Z,1544822955.449 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:29:15.810Z,1544822955.810 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:29:15.853Z,1544822955.853 [CTD_Seabird](INFO): Powering down
2018-12-14T21:29:17.810Z,1544822957.810 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:29:17.811Z,1544822957.811 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:29:18.801Z,1544822958.801 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:29:18.803Z,1544822958.803 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:29:32.845Z,1544822972.845 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:29:32.845Z,1544822972.845 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:29:32.845Z,1544822972.845 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:29:33.249Z,1544822973.249 [CTD_Seabird](INFO): Powering down
2018-12-14T21:29:35.290Z,1544822975.290 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:29:35.290Z,1544822975.290 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:29:35.290Z,1544822975.290 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:29:36.197Z,1544822976.197 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:29:36.199Z,1544822976.199 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:29:50.241Z,1544822990.241 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:29:50.241Z,1544822990.241 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:29:50.241Z,1544822990.241 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:29:50.565Z,1544822990.565 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:29:50.565Z,1544822990.565 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:29:50.645Z,1544822990.645 [CTD_Seabird](INFO): Powering down
2018-12-14T21:30:26.146Z,1544823026.146 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:30:26.146Z,1544823026.146 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-12-14T21:30:26.146Z,1544823026.146 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:30:26.158Z,1544823026.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:30:26.562Z,1544823026.562 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:30:26.562Z,1544823026.562 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-12-14T21:30:26.947Z,1544823026.947 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:30:26.947Z,1544823026.947 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:30:26.947Z,1544823026.947 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:30:35.673Z,1544823035.673 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:30:35.673Z,1544823035.673 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-14T21:30:35.673Z,1544823035.673 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:30:35.745Z,1544823035.745 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:30:36.078Z,1544823036.078 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:30:36.236Z,1544823036.236 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:30:37.714Z,1544823037.714 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:30:37.714Z,1544823037.714 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-14T21:30:37.853Z,1544823037.853 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:30:37.853Z,1544823037.853 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:30:37.853Z,1544823037.853 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:30:37.854Z,1544823037.854 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:30:41.971Z,1544823041.971 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:30:42.036Z,1544823042.036 [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-12-14T21:30:42.037Z,1544823042.037 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:30:42.037Z,1544823042.037 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:30:42.038Z,1544823042.038 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:30:43.715Z,1544823043.715 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-14T21:25:43.5Z
2018-12-14T21:30:43.715Z,1544823043.715 [Default:CheckIn:Read_GPS] Stopped
2018-12-14T21:30:43.715Z,1544823043.715 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-14T21:30:44.115Z,1544823044.115 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-12-14T21:31:08.032Z,1544823068.032 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181214T212212/Courier0004.lzma
2018-12-14T21:31:08.819Z,1544823068.819 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Courier0004.lzma.bak
2018-12-14T21:31:08.819Z,1544823068.819 [DataOverHttps](INFO): SBD MOMSN=9058482
2018-12-14T21:32:21.279Z,1544823141.279 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:32:21.279Z,1544823141.279 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:32:21.497Z,1544823141.497 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:32:21.498Z,1544823141.498 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:32:30.656Z,1544823150.656 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:32:30.656Z,1544823150.656 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-12-14T21:32:30.657Z,1544823150.657 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:32:30.678Z,1544823150.678 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:32:30.770Z,1544823150.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:32:30.770Z,1544823150.770 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-12-14T21:32:31.158Z,1544823151.158 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:32:31.158Z,1544823151.158 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:32:31.159Z,1544823151.159 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:32:35.541Z,1544823155.541 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:32:35.541Z,1544823155.541 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:32:35.541Z,1544823155.541 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:32:35.877Z,1544823155.877 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:32:35.945Z,1544823155.945 [CTD_Seabird](INFO): Powering down
2018-12-14T21:32:38.281Z,1544823158.281 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:32:38.281Z,1544823158.281 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-14T21:32:38.281Z,1544823158.281 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:32:38.685Z,1544823158.685 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:32:38.826Z,1544823158.826 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:32:40.256Z,1544823160.256 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:32:40.256Z,1544823160.256 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-14T21:32:40.256Z,1544823160.256 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:32:40.256Z,1544823160.256 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:32:40.509Z,1544823160.509 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:32:40.511Z,1544823160.511 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:32:54.553Z,1544823174.553 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:32:54.553Z,1544823174.553 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:32:54.553Z,1544823174.553 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:32:54.591Z,1544823174.591 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:32:54.957Z,1544823174.957 [CTD_Seabird](INFO): Powering down
2018-12-14T21:32:59.224Z,1544823179.224 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:32:59.224Z,1544823179.224 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:32:59.521Z,1544823179.521 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:32:59.522Z,1544823179.522 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:33:08.665Z,1544823188.665 [DataOverHttps](INFO): Sending 1086 bytes from file Logs/20181214T212212/Express0005.lzma
2018-12-14T21:33:09.059Z,1544823189.059 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-14T21:33:09.059Z,1544823189.059 [NAL9602] Data Fault, FailCount= 1
2018-12-14T21:33:09.059Z,1544823189.059 [NAL9602](ERROR): Data Fault
2018-12-14T21:33:09.121Z,1544823189.121 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-14T21:33:09.455Z,1544823189.455 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Express0005.lzma.bak
2018-12-14T21:33:09.456Z,1544823189.456 [DataOverHttps](INFO): SBD MOMSN=9058504
2018-12-14T21:33:09.463Z,1544823189.463 [NAL9602](INFO): Powering down
2018-12-14T21:33:12.255Z,1544823192.255 [Default:CheckIn:Read_Iridium] Stopped
2018-12-14T21:33:12.255Z,1544823192.255 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-14T21:33:12.255Z,1544823192.255 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-14T21:33:12.391Z,1544823192.391 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-14T21:33:12.391Z,1544823192.391 [NAL9602] No Fault, FailCount= 1
2018-12-14T21:33:13.573Z,1544823193.573 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:33:13.573Z,1544823193.573 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:33:13.573Z,1544823193.573 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:33:13.977Z,1544823193.977 [CTD_Seabird](INFO): Powering down
2018-12-14T21:33:15.404Z,1544823195.404 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:33:15.405Z,1544823195.405 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:33:32.261Z,1544823212.261 [CBIT](INFO): Clearing failed state for component BPC1
2018-12-14T21:33:32.261Z,1544823212.261 [BPC1] No Fault, FailCount= 1
2018-12-14T21:33:39.826Z,1544823219.826 [NAL9602](INFO): Powering up NAL9602
2018-12-14T21:33:51.018Z,1544823231.018 [NAL9602](INFO): NAL9602 initialized
2018-12-14T21:34:02.662Z,1544823242.662 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:34:02.662Z,1544823242.662 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T21:34:10.245Z,1544823250.245 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:34:10.246Z,1544823250.246 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:34:10.246Z,1544823250.246 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:34:10.298Z,1544823250.298 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:34:10.633Z,1544823250.633 [PNI_TCM](INFO): Powering down
2018-12-14T21:34:11.866Z,1544823251.866 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:34:11.866Z,1544823251.866 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:34:22.206Z,1544823262.206 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:34:22.206Z,1544823262.206 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:34:22.206Z,1544823262.206 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:34:22.260Z,1544823262.260 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:34:22.617Z,1544823262.617 [PNI_TCM](INFO): Powering down
2018-12-14T21:34:23.822Z,1544823263.822 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:34:23.822Z,1544823263.822 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:34:31.393Z,1544823271.393 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:34:31.393Z,1544823271.393 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:34:31.394Z,1544823271.394 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:34:31.414Z,1544823271.414 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:34:31.414Z,1544823271.414 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-12-14T21:34:31.414Z,1544823271.414 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:34:31.435Z,1544823271.435 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:34:31.436Z,1544823271.436 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:34:31.792Z,1544823271.792 [PNI_TCM](INFO): Powering down
2018-12-14T21:34:31.895Z,1544823271.895 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:34:31.895Z,1544823271.895 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-12-14T21:34:32.229Z,1544823272.229 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:34:32.230Z,1544823272.230 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:34:32.230Z,1544823272.230 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:34:33.033Z,1544823273.033 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:34:33.033Z,1544823273.033 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:34:37.813Z,1544823277.813 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-12-14T21:34:37.813Z,1544823277.813 [BPC1] Data Fault, FailCount= 2
2018-12-14T21:34:37.813Z,1544823277.813 [BPC1](ERROR): Data Fault
2018-12-14T21:34:37.837Z,1544823277.837 [CBIT](ERROR): Data Fault in component: BPC1
2018-12-14T21:34:40.615Z,1544823280.615 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:34:40.615Z,1544823280.615 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:34:40.615Z,1544823280.615 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:34:40.633Z,1544823280.633 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:34:41.010Z,1544823281.010 [PNI_TCM](INFO): Powering down
2018-12-14T21:34:42.226Z,1544823282.226 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:34:42.226Z,1544823282.226 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:34:49.811Z,1544823289.811 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:34:49.811Z,1544823289.811 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:34:49.811Z,1544823289.811 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:34:49.859Z,1544823289.859 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:34:49.860Z,1544823289.860 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:34:50.211Z,1544823290.211 [PNI_TCM](INFO): Powering down
2018-12-14T21:34:50.322Z,1544823290.322 [CBIT](INFO): Critical error at 20181214T213449
2018-12-14T21:35:43.766Z,1544823343.766 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:35:43.766Z,1544823343.766 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:35:44.017Z,1544823344.017 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:35:44.018Z,1544823344.018 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:35:58.077Z,1544823358.077 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:35:58.077Z,1544823358.077 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:35:58.077Z,1544823358.077 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:35:58.199Z,1544823358.199 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:35:58.481Z,1544823358.481 [CTD_Seabird](INFO): Powering down
2018-12-14T21:36:00.586Z,1544823360.586 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:36:00.586Z,1544823360.586 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:36:01.429Z,1544823361.429 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:36:01.437Z,1544823361.437 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:36:15.481Z,1544823375.481 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:36:15.481Z,1544823375.481 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:36:15.481Z,1544823375.481 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:36:15.885Z,1544823375.885 [CTD_Seabird](INFO): Powering down
2018-12-14T21:36:17.637Z,1544823377.637 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:36:18.099Z,1544823378.099 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:36:18.099Z,1544823378.099 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:36:18.833Z,1544823378.833 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:36:18.835Z,1544823378.835 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:36:32.497Z,1544823392.497 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:36:32.497Z,1544823392.497 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-12-14T21:36:32.497Z,1544823392.497 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:36:32.510Z,1544823392.510 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:36:32.885Z,1544823392.885 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:36:32.885Z,1544823392.885 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:36:32.885Z,1544823392.885 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:36:32.909Z,1544823392.909 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:36:32.909Z,1544823392.909 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:36:32.909Z,1544823392.909 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:36:32.910Z,1544823392.910 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-12-14T21:36:33.289Z,1544823393.289 [CTD_Seabird](INFO): Powering down
2018-12-14T21:36:33.301Z,1544823393.301 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:36:33.301Z,1544823393.301 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:36:33.302Z,1544823393.302 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:38:12.411Z,1544823492.411 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-14T21:38:12.411Z,1544823492.411 [Default:CheckIn:C.Wait] Stopped
2018-12-14T21:38:12.411Z,1544823492.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-14T21:38:12.412Z,1544823492.411 [Default:CheckIn:D] Running Loop=1
2018-12-14T21:38:12.809Z,1544823492.809 [Default:CheckIn:D] Stopped
2018-12-14T21:38:12.809Z,1544823492.809 [Default:CheckIn:E] Running Loop=1
2018-12-14T21:38:13.207Z,1544823493.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.715465 min 
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn:E] Stopped
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn] Stopped
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn](INFO): Running loop #2
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn] Running Loop=2
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-14T21:38:13.210Z,1544823493.210 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-14T21:38:33.558Z,1544823513.558 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:38:33.558Z,1544823513.558 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-12-14T21:38:33.558Z,1544823513.558 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:38:33.571Z,1544823513.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:38:33.961Z,1544823513.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:38:33.961Z,1544823513.961 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-12-14T21:38:34.344Z,1544823514.344 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:38:34.345Z,1544823514.345 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:38:34.345Z,1544823514.345 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:39:03.174Z,1544823543.174 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:39:03.174Z,1544823543.174 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:39:03.361Z,1544823543.361 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:39:03.363Z,1544823543.363 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:39:17.413Z,1544823557.413 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:39:17.413Z,1544823557.413 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:39:17.413Z,1544823557.413 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:39:17.817Z,1544823557.817 [CTD_Seabird](INFO): Powering down
2018-12-14T21:39:18.638Z,1544823558.638 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:39:19.922Z,1544823559.922 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:39:19.922Z,1544823559.922 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:39:20.765Z,1544823560.765 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:39:20.767Z,1544823560.767 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:39:34.809Z,1544823574.809 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:39:34.809Z,1544823574.809 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:39:34.809Z,1544823574.809 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:39:35.111Z,1544823575.111 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:39:35.214Z,1544823575.214 [CTD_Seabird](INFO): Powering down
2018-12-14T21:39:37.505Z,1544823577.505 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:39:37.505Z,1544823577.505 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:39:38.161Z,1544823578.161 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:39:38.163Z,1544823578.163 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:39:49.910Z,1544823589.910 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:39:49.910Z,1544823589.910 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T21:39:52.209Z,1544823592.209 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:39:52.209Z,1544823592.209 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:39:52.209Z,1544823592.209 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:39:52.325Z,1544823592.325 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:39:52.325Z,1544823592.325 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:39:52.613Z,1544823592.613 [CTD_Seabird](INFO): Powering down
2018-12-14T21:39:57.465Z,1544823597.465 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:39:57.465Z,1544823597.465 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:39:57.465Z,1544823597.465 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:39:57.522Z,1544823597.522 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:39:57.875Z,1544823597.875 [PNI_TCM](INFO): Powering down
2018-12-14T21:39:59.113Z,1544823599.113 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:39:59.113Z,1544823599.113 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:40:06.669Z,1544823606.669 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:40:06.669Z,1544823606.669 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:40:06.669Z,1544823606.669 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:40:06.722Z,1544823606.722 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:40:07.083Z,1544823607.083 [PNI_TCM](INFO): Powering down
2018-12-14T21:40:08.318Z,1544823608.318 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:40:08.318Z,1544823608.318 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:40:15.879Z,1544823615.879 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:40:15.879Z,1544823615.879 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:40:15.879Z,1544823615.879 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:40:15.922Z,1544823615.922 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:40:18.951Z,1544823618.951 [PNI_TCM](INFO): Powering down
2018-12-14T21:40:20.325Z,1544823620.325 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:40:20.325Z,1544823620.325 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:40:27.913Z,1544823627.913 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:40:27.913Z,1544823627.913 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:40:27.913Z,1544823627.913 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:40:27.938Z,1544823627.938 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:40:28.299Z,1544823628.299 [PNI_TCM](INFO): Powering down
2018-12-14T21:40:29.530Z,1544823629.530 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:40:29.530Z,1544823629.530 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:40:34.714Z,1544823634.714 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:40:34.714Z,1544823634.714 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-12-14T21:40:34.714Z,1544823634.714 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:40:34.726Z,1544823634.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:40:35.119Z,1544823635.119 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:40:35.119Z,1544823635.119 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-12-14T21:40:35.514Z,1544823635.514 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:40:35.514Z,1544823635.514 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:40:35.514Z,1544823635.514 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:40:37.093Z,1544823637.093 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:40:37.093Z,1544823637.093 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:40:37.093Z,1544823637.093 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:40:37.139Z,1544823637.139 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:40:37.139Z,1544823637.139 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:40:37.499Z,1544823637.499 [PNI_TCM](INFO): Powering down
2018-12-14T21:40:37.613Z,1544823637.613 [CBIT](INFO): Critical error at 20181214T214037
2018-12-14T21:42:22.570Z,1544823742.570 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:42:22.570Z,1544823742.570 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:42:22.705Z,1544823742.705 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:42:22.706Z,1544823742.706 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:42:35.771Z,1544823755.771 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:42:35.771Z,1544823755.771 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-12-14T21:42:35.771Z,1544823755.771 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:42:35.784Z,1544823755.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:42:36.174Z,1544823756.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:42:36.174Z,1544823756.174 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-12-14T21:42:36.561Z,1544823756.561 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:42:36.562Z,1544823756.562 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:42:36.562Z,1544823756.562 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:42:36.753Z,1544823756.753 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:42:36.753Z,1544823756.753 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:42:36.753Z,1544823756.753 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:42:36.984Z,1544823756.984 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:42:37.157Z,1544823757.157 [CTD_Seabird](INFO): Powering down
2018-12-14T21:42:38.570Z,1544823758.570 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:42:38.570Z,1544823758.570 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-14T21:42:38.657Z,1544823758.657 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:42:38.657Z,1544823758.657 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:42:38.657Z,1544823758.657 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:42:38.657Z,1544823758.657 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:42:39.386Z,1544823759.386 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:42:39.386Z,1544823759.386 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:42:40.109Z,1544823760.109 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:42:40.111Z,1544823760.111 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:42:42.956Z,1544823762.956 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:42:43.112Z,1544823763.112 [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-12-14T21:42:43.113Z,1544823763.113 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:42:43.114Z,1544823763.114 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:42:43.115Z,1544823763.115 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:42:54.153Z,1544823774.153 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:42:54.153Z,1544823774.153 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:42:54.153Z,1544823774.153 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:42:54.202Z,1544823774.202 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:42:54.557Z,1544823774.557 [CTD_Seabird](INFO): Powering down
2018-12-14T21:42:56.562Z,1544823776.562 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:42:56.562Z,1544823776.562 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:42:57.385Z,1544823777.385 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:42:57.386Z,1544823777.386 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:43:11.429Z,1544823791.429 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:43:11.429Z,1544823791.429 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:43:11.429Z,1544823791.429 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:43:11.780Z,1544823791.780 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:43:11.780Z,1544823791.780 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:43:11.833Z,1544823791.833 [CTD_Seabird](INFO): Powering down
2018-12-14T21:43:13.375Z,1544823793.375 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-14T21:38:13.2Z
2018-12-14T21:43:13.375Z,1544823793.375 [Default:CheckIn:Read_GPS] Stopped
2018-12-14T21:43:13.375Z,1544823793.375 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-14T21:43:55.670Z,1544823835.670 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-14T21:43:55.670Z,1544823835.670 [NAL9602] Data Fault, FailCount= 2
2018-12-14T21:43:55.670Z,1544823835.670 [NAL9602](ERROR): Data Fault
2018-12-14T21:43:55.710Z,1544823835.710 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-14T21:43:56.022Z,1544823836.022 [NAL9602](INFO): Powering down
2018-12-14T21:43:56.858Z,1544823836.858 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-14T21:43:56.858Z,1544823836.858 [NAL9602] No Fault, FailCount= 2
2018-12-14T21:44:28.779Z,1544823868.779 [NAL9602](INFO): Powering up NAL9602
2018-12-14T21:44:39.105Z,1544823879.105 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:44:39.105Z,1544823879.105 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-12-14T21:44:39.105Z,1544823879.105 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:44:39.149Z,1544823879.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:44:39.220Z,1544823879.220 [CBIT](INFO): Clearing failed state for component BPC1
2018-12-14T21:44:39.220Z,1544823879.220 [BPC1] No Fault, FailCount= 2
2018-12-14T21:44:39.245Z,1544823879.245 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:44:39.245Z,1544823879.245 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-12-14T21:44:39.353Z,1544823879.353 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:44:39.353Z,1544823879.353 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-14T21:44:39.353Z,1544823879.353 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:44:39.562Z,1544823879.562 [NAL9602](INFO): NAL9602 initialized
2018-12-14T21:44:39.630Z,1544823879.630 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:44:39.630Z,1544823879.630 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:44:39.631Z,1544823879.631 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:44:39.643Z,1544823879.643 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:44:39.757Z,1544823879.757 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:44:39.846Z,1544823879.846 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:44:41.218Z,1544823881.218 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:44:41.218Z,1544823881.218 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-14T21:44:41.461Z,1544823881.461 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:44:41.461Z,1544823881.461 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:44:41.461Z,1544823881.461 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:44:41.462Z,1544823881.462 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:44:45.666Z,1544823885.666 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:44:45.755Z,1544823885.755 [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-12-14T21:44:45.756Z,1544823885.756 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:44:45.757Z,1544823885.757 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:44:45.757Z,1544823885.757 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:45:10.075Z,1544823910.075 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20181214T212212/Courier0007.lzma
2018-12-14T21:45:10.876Z,1544823910.876 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Courier0007.lzma.bak
2018-12-14T21:45:10.876Z,1544823910.876 [DataOverHttps](INFO): SBD MOMSN=9058658
2018-12-14T21:45:21.616Z,1544823921.616 [DataOverHttps](INFO): Sending 605 bytes from file Logs/20181214T212212/Express0008.lzma
2018-12-14T21:45:22.415Z,1544823922.415 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Express0008.lzma.bak
2018-12-14T21:45:22.416Z,1544823922.416 [DataOverHttps](INFO): SBD MOMSN=9058667
2018-12-14T21:45:25.203Z,1544823925.203 [Default:CheckIn:Read_Iridium] Stopped
2018-12-14T21:45:25.203Z,1544823925.203 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-14T21:45:25.204Z,1544823925.204 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-14T21:45:37.278Z,1544823937.278 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:45:37.278Z,1544823937.278 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T21:45:41.675Z,1544823941.675 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:45:41.675Z,1544823941.675 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:45:41.853Z,1544823941.853 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:45:41.854Z,1544823941.854 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:45:44.837Z,1544823944.837 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:45:44.837Z,1544823944.837 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:45:44.837Z,1544823944.837 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:45:44.839Z,1544823944.839 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-12-14T21:45:44.839Z,1544823944.839 [BPC1] Data Fault, FailCount= 3
2018-12-14T21:45:44.839Z,1544823944.839 [BPC1](ERROR): Data Fault
2018-12-14T21:45:44.890Z,1544823944.890 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:45:44.890Z,1544823944.890 [CBIT](ERROR): Data Fault in component: BPC1
2018-12-14T21:45:45.247Z,1544823945.247 [PNI_TCM](INFO): Powering down
2018-12-14T21:45:46.504Z,1544823946.504 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:45:46.504Z,1544823946.504 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:45:54.054Z,1544823954.054 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:45:54.055Z,1544823954.055 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:45:54.055Z,1544823954.055 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:45:54.110Z,1544823954.110 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:45:54.449Z,1544823954.449 [PNI_TCM](INFO): Powering down
2018-12-14T21:45:55.716Z,1544823955.716 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:45:55.716Z,1544823955.716 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:45:55.905Z,1544823955.905 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:45:55.905Z,1544823955.905 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:45:55.905Z,1544823955.905 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:45:56.126Z,1544823956.126 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:45:56.309Z,1544823956.309 [CTD_Seabird](INFO): Powering down
2018-12-14T21:45:58.490Z,1544823958.490 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:45:58.490Z,1544823958.490 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:45:59.261Z,1544823959.261 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:45:59.263Z,1544823959.263 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:46:03.249Z,1544823963.249 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:46:03.249Z,1544823963.249 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:46:03.249Z,1544823963.249 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:46:03.314Z,1544823963.314 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:46:03.653Z,1544823963.653 [PNI_TCM](INFO): Powering down
2018-12-14T21:46:04.937Z,1544823964.937 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:46:04.937Z,1544823964.937 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:46:12.444Z,1544823972.444 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:46:12.444Z,1544823972.444 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:46:12.444Z,1544823972.444 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:46:12.515Z,1544823972.515 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:46:12.838Z,1544823972.838 [PNI_TCM](INFO): Powering down
2018-12-14T21:46:13.305Z,1544823973.305 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:46:13.305Z,1544823973.305 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:46:13.305Z,1544823973.305 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:46:13.313Z,1544823973.313 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:46:13.709Z,1544823973.709 [CTD_Seabird](INFO): Powering down
2018-12-14T21:46:14.078Z,1544823974.078 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:46:14.113Z,1544823974.113 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:46:15.711Z,1544823975.711 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:46:15.711Z,1544823975.711 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:46:16.658Z,1544823976.658 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:46:16.665Z,1544823976.665 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:46:21.652Z,1544823981.652 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:46:21.652Z,1544823981.652 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:46:21.652Z,1544823981.652 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:46:21.743Z,1544823981.743 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:46:21.744Z,1544823981.744 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:46:22.048Z,1544823982.048 [PNI_TCM](INFO): Powering down
2018-12-14T21:46:22.190Z,1544823982.190 [CBIT](INFO): Critical error at 20181214T214621
2018-12-14T21:46:30.705Z,1544823990.705 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:46:30.705Z,1544823990.705 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:46:30.705Z,1544823990.705 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:46:30.818Z,1544823990.818 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:46:30.818Z,1544823990.818 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:46:31.109Z,1544823991.109 [CTD_Seabird](INFO): Powering down
2018-12-14T21:46:39.995Z,1544823999.995 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:46:39.995Z,1544823999.995 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-12-14T21:46:39.995Z,1544823999.995 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:46:40.008Z,1544824000.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:46:40.405Z,1544824000.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:46:40.405Z,1544824000.405 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-12-14T21:46:40.799Z,1544824000.799 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:46:40.800Z,1544824000.800 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:46:40.800Z,1544824000.800 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:46:41.973Z,1544824001.973 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:46:41.973Z,1544824001.973 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-14T21:46:41.973Z,1544824001.973 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:46:42.039Z,1544824002.039 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:46:42.382Z,1544824002.382 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:46:42.536Z,1544824002.536 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:46:44.010Z,1544824004.010 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:46:44.010Z,1544824004.010 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-14T21:46:44.153Z,1544824004.153 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:46:44.153Z,1544824004.153 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:46:44.154Z,1544824004.154 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:46:44.154Z,1544824004.154 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:46:48.270Z,1544824008.270 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:46:48.334Z,1544824008.334 [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-12-14T21:46:48.335Z,1544824008.335 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:46:48.335Z,1544824008.335 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:46:48.336Z,1544824008.336 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:48:41.102Z,1544824121.102 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:48:41.102Z,1544824121.102 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-12-14T21:48:41.102Z,1544824121.102 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:48:41.128Z,1544824121.128 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:48:41.525Z,1544824121.525 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:48:41.526Z,1544824121.526 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-12-14T21:48:41.918Z,1544824121.918 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:48:41.919Z,1544824121.919 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:48:41.919Z,1544824121.919 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:48:44.709Z,1544824124.709 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:48:44.709Z,1544824124.709 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-14T21:48:44.709Z,1544824124.709 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:48:44.715Z,1544824124.715 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:48:45.113Z,1544824125.113 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:48:45.235Z,1544824125.235 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:48:46.717Z,1544824126.717 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:48:46.718Z,1544824126.718 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-14T21:48:46.849Z,1544824126.849 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:48:46.849Z,1544824126.849 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:48:46.849Z,1544824126.849 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:48:46.850Z,1544824126.850 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:48:50.971Z,1544824130.971 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:48:51.028Z,1544824131.028 [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-12-14T21:48:51.029Z,1544824131.029 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:48:51.029Z,1544824131.029 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:48:51.030Z,1544824131.030 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:49:00.718Z,1544824140.718 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:49:00.718Z,1544824140.718 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:49:00.789Z,1544824140.789 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:49:00.791Z,1544824140.791 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:49:14.833Z,1544824154.833 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:49:14.833Z,1544824154.833 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:49:14.833Z,1544824154.833 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:49:15.130Z,1544824155.130 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:49:15.237Z,1544824155.237 [CTD_Seabird](INFO): Powering down
2018-12-14T21:49:17.507Z,1544824157.507 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:49:17.507Z,1544824157.507 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:49:18.185Z,1544824158.185 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:49:18.186Z,1544824158.186 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:49:32.237Z,1544824172.237 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:49:32.237Z,1544824172.237 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:49:32.237Z,1544824172.237 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:49:32.571Z,1544824172.571 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:49:32.641Z,1544824172.641 [CTD_Seabird](INFO): Powering down
2018-12-14T21:49:34.594Z,1544824174.593 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:49:34.594Z,1544824174.594 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:49:35.589Z,1544824175.589 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:49:35.590Z,1544824175.590 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:49:49.641Z,1544824189.641 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:49:49.641Z,1544824189.641 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:49:49.641Z,1544824189.641 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:49:49.790Z,1544824189.790 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:49:49.790Z,1544824189.790 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:49:50.045Z,1544824190.045 [CTD_Seabird](INFO): Powering down
2018-12-14T21:50:25.363Z,1544824225.363 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-14T21:50:25.363Z,1544824225.363 [Default:CheckIn:C.Wait] Stopped
2018-12-14T21:50:25.363Z,1544824225.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-14T21:50:25.363Z,1544824225.363 [Default:CheckIn:D] Running Loop=1
2018-12-14T21:50:25.763Z,1544824225.763 [Default:CheckIn:D] Stopped
2018-12-14T21:50:25.763Z,1544824225.763 [Default:CheckIn:E] Running Loop=1
2018-12-14T21:50:26.147Z,1544824226.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.931399 min 
2018-12-14T21:50:26.147Z,1544824226.147 [Default:CheckIn:E] Stopped
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn] Stopped
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn](INFO): Running loop #3
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn] Running Loop=3
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-14T21:50:26.148Z,1544824226.148 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-14T21:50:42.146Z,1544824242.146 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:50:42.146Z,1544824242.146 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-12-14T21:50:42.146Z,1544824242.146 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:50:42.159Z,1544824242.159 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:50:42.562Z,1544824242.562 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:50:42.562Z,1544824242.562 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-12-14T21:50:42.937Z,1544824242.937 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:50:42.938Z,1544824242.938 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:50:42.938Z,1544824242.938 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:50:47.293Z,1544824247.293 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:50:47.293Z,1544824247.293 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-14T21:50:47.293Z,1544824247.293 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:50:47.365Z,1544824247.365 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:50:47.697Z,1544824247.697 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:50:47.861Z,1544824247.861 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:50:49.346Z,1544824249.346 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T21:50:49.346Z,1544824249.346 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-14T21:50:49.477Z,1544824249.477 [Rowe_600LCM](INFO): Initializing
2018-12-14T21:50:49.477Z,1544824249.477 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T21:50:49.478Z,1544824249.478 [Rowe_600LCM](INFO): LCM OK
2018-12-14T21:50:49.478Z,1544824249.478 [Rowe_600LCM](INFO): Powering up
2018-12-14T21:50:53.645Z,1544824253.645 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T21:50:53.863Z,1544824253.863 [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-12-14T21:50:53.864Z,1544824253.864 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T21:50:53.865Z,1544824253.865 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T21:50:53.866Z,1544824253.866 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T21:51:21.778Z,1544824281.778 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:51:21.778Z,1544824281.778 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T21:51:31.227Z,1544824291.227 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:51:31.227Z,1544824291.227 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:51:31.227Z,1544824291.227 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:51:31.278Z,1544824291.278 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:51:31.329Z,1544824291.329 [PNI_TCM](INFO): Powering down
2018-12-14T21:51:32.538Z,1544824292.538 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:51:32.539Z,1544824292.539 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:51:40.111Z,1544824300.111 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:51:40.112Z,1544824300.112 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:51:40.112Z,1544824300.112 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:51:40.138Z,1544824300.138 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:51:40.512Z,1544824300.512 [PNI_TCM](INFO): Powering down
2018-12-14T21:51:41.733Z,1544824301.733 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:51:41.733Z,1544824301.733 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:51:49.316Z,1544824309.316 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:51:49.316Z,1544824309.316 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:51:49.316Z,1544824309.316 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:51:49.342Z,1544824309.342 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:51:49.709Z,1544824309.709 [PNI_TCM](INFO): Powering down
2018-12-14T21:51:50.946Z,1544824310.946 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:51:50.946Z,1544824310.946 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:51:58.505Z,1544824318.505 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:51:58.505Z,1544824318.505 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:51:58.505Z,1544824318.505 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:51:58.554Z,1544824318.554 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:51:58.909Z,1544824318.909 [PNI_TCM](INFO): Powering down
2018-12-14T21:52:00.146Z,1544824320.146 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:52:00.147Z,1544824320.147 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:52:07.705Z,1544824327.705 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:52:07.705Z,1544824327.705 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:52:07.706Z,1544824327.706 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:52:07.758Z,1544824327.758 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:52:07.758Z,1544824327.758 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:52:08.106Z,1544824328.106 [PNI_TCM](INFO): Powering down
2018-12-14T21:52:08.215Z,1544824328.215 [CBIT](INFO): Critical error at 20181214T215207
2018-12-14T21:52:19.749Z,1544824339.749 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:52:19.749Z,1544824339.749 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:52:20.117Z,1544824340.117 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:52:20.119Z,1544824340.119 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:52:34.161Z,1544824354.161 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:52:34.161Z,1544824354.161 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:52:34.161Z,1544824354.161 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:52:34.209Z,1544824354.209 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:52:34.565Z,1544824354.565 [CTD_Seabird](INFO): Powering down
2018-12-14T21:52:36.579Z,1544824356.579 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:52:36.579Z,1544824356.579 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:52:37.513Z,1544824357.513 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:52:37.515Z,1544824357.515 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:52:42.962Z,1544824362.962 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:52:42.962Z,1544824362.962 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-12-14T21:52:42.962Z,1544824362.962 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:52:42.999Z,1544824362.999 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:52:43.434Z,1544824363.434 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:52:43.434Z,1544824363.434 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-12-14T21:52:43.765Z,1544824363.765 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:52:43.766Z,1544824363.766 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:52:43.766Z,1544824363.766 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:52:49.577Z,1544824369.577 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-14T21:52:49.577Z,1544824369.577 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-14T21:52:49.577Z,1544824369.577 [Rowe_600LCM](ERROR): Communications Fault
2018-12-14T21:52:49.784Z,1544824369.784 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-14T21:52:49.785Z,1544824369.785 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-14T21:52:49.981Z,1544824369.981 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-14T21:52:50.064Z,1544824370.064 [Rowe_600LCM](INFO): Powering down
2018-12-14T21:52:51.561Z,1544824371.561 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:52:51.561Z,1544824371.561 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:52:51.561Z,1544824371.561 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:52:51.783Z,1544824371.783 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:52:51.965Z,1544824371.965 [CTD_Seabird](INFO): Powering down
2018-12-14T21:52:54.182Z,1544824374.182 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:52:54.183Z,1544824374.183 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:52:54.913Z,1544824374.913 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:52:54.915Z,1544824374.915 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:53:08.961Z,1544824388.961 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:53:08.961Z,1544824388.961 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:53:08.961Z,1544824388.961 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:53:08.989Z,1544824388.989 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:53:08.989Z,1544824388.989 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:53:09.365Z,1544824389.365 [CTD_Seabird](INFO): Powering down
2018-12-14T21:54:40.378Z,1544824480.378 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-14T21:54:40.378Z,1544824480.378 [NAL9602] Data Fault, FailCount= 3
2018-12-14T21:54:40.378Z,1544824480.378 [NAL9602](ERROR): Data Fault
2018-12-14T21:54:40.411Z,1544824480.411 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-14T21:54:40.781Z,1544824480.781 [NAL9602](INFO): Powering down
2018-12-14T21:54:41.614Z,1544824481.614 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-14T21:54:41.614Z,1544824481.614 [NAL9602] No Fault, FailCount= 3
2018-12-14T21:54:43.998Z,1544824483.998 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:54:43.998Z,1544824483.998 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-12-14T21:54:43.998Z,1544824483.998 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:54:44.011Z,1544824484.011 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:54:44.417Z,1544824484.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:54:44.417Z,1544824484.417 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-12-14T21:54:44.798Z,1544824484.798 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:54:44.798Z,1544824484.798 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:54:44.798Z,1544824484.798 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:55:11.172Z,1544824511.172 [NAL9602](INFO): Powering up NAL9602
2018-12-14T21:55:21.983Z,1544824521.983 [NAL9602](INFO): NAL9602 initialized
2018-12-14T21:55:26.403Z,1544824526.403 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-14T21:50:26.1Z
2018-12-14T21:55:26.403Z,1544824526.403 [Default:CheckIn:Read_GPS] Stopped
2018-12-14T21:55:26.403Z,1544824526.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-14T21:55:31.561Z,1544824531.561 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20181214T212212/Courier0010.lzma
2018-12-14T21:55:32.651Z,1544824532.651 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Courier0010.lzma.bak
2018-12-14T21:55:32.651Z,1544824532.651 [DataOverHttps](INFO): SBD MOMSN=9058757
2018-12-14T21:55:39.166Z,1544824539.166 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:55:39.166Z,1544824539.166 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:55:39.445Z,1544824539.445 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:55:39.446Z,1544824539.446 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:55:48.695Z,1544824548.695 [DataOverHttps](INFO): Sending 584 bytes from file Logs/20181214T212212/Express0011.lzma
2018-12-14T21:55:48.802Z,1544824548.802 [CBIT](INFO): Clearing failed state for component BPC1
2018-12-14T21:55:48.802Z,1544824548.802 [BPC1] No Fault, FailCount= 3
2018-12-14T21:55:49.496Z,1544824549.496 [DataOverHttps](INFO): Moved sent file to Logs/20181214T212212/Express0011.lzma.bak
2018-12-14T21:55:49.496Z,1544824549.496 [DataOverHttps](INFO): SBD MOMSN=9058792
2018-12-14T21:55:53.509Z,1544824553.509 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:55:53.509Z,1544824553.509 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:55:53.509Z,1544824553.509 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:55:53.913Z,1544824553.913 [CTD_Seabird](INFO): Powering down
2018-12-14T21:55:56.500Z,1544824556.500 [Default:CheckIn:Read_Iridium] Stopped
2018-12-14T21:55:56.500Z,1544824556.500 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-14T21:55:56.500Z,1544824556.500 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-14T21:55:56.532Z,1544824556.532 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:55:56.532Z,1544824556.532 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:55:56.532Z,1544824556.532 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:55:56.861Z,1544824556.861 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:55:56.866Z,1544824556.866 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:56:10.933Z,1544824570.933 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:56:10.933Z,1544824570.933 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:56:10.933Z,1544824570.933 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:56:11.026Z,1544824571.026 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:56:11.337Z,1544824571.337 [CTD_Seabird](INFO): Powering down
2018-12-14T21:56:13.469Z,1544824573.469 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:56:13.469Z,1544824573.469 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:56:14.285Z,1544824574.285 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:56:14.289Z,1544824574.289 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:56:28.329Z,1544824588.329 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:56:28.329Z,1544824588.329 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:56:28.329Z,1544824588.329 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:56:28.656Z,1544824588.656 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:56:28.657Z,1544824588.657 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:56:28.734Z,1544824588.734 [CTD_Seabird](INFO): Powering down
2018-12-14T21:56:45.034Z,1544824605.034 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:56:45.034Z,1544824605.034 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-12-14T21:56:45.034Z,1544824605.034 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:56:45.067Z,1544824605.067 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:56:45.438Z,1544824605.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:56:45.438Z,1544824605.438 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-12-14T21:56:45.824Z,1544824605.824 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:56:45.825Z,1544824605.825 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:56:45.825Z,1544824605.825 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:56:54.630Z,1544824614.630 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-12-14T21:56:54.630Z,1544824614.630 [BPC1] Data Fault, FailCount= 4
2018-12-14T21:56:54.630Z,1544824614.630 [BPC1](ERROR): Data Fault
2018-12-14T21:56:54.682Z,1544824614.682 [CBIT](ERROR): Data Fault in component: BPC1
2018-12-14T21:57:07.793Z,1544824627.793 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:57:07.793Z,1544824627.793 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T21:57:15.360Z,1544824635.360 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:57:15.360Z,1544824635.360 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T21:57:15.360Z,1544824635.360 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:57:15.386Z,1544824635.386 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:57:15.752Z,1544824635.752 [PNI_TCM](INFO): Powering down
2018-12-14T21:57:16.993Z,1544824636.993 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:57:16.993Z,1544824636.993 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T21:57:24.557Z,1544824644.557 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:57:24.557Z,1544824644.557 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T21:57:24.558Z,1544824644.558 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:57:24.598Z,1544824644.598 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:57:24.960Z,1544824644.960 [PNI_TCM](INFO): Powering down
2018-12-14T21:57:26.197Z,1544824646.197 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:57:26.198Z,1544824646.198 [PNI_TCM] No Fault, FailCount= 2
2018-12-14T21:57:33.763Z,1544824653.763 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:57:33.763Z,1544824653.763 [PNI_TCM] Data Fault, FailCount= 3
2018-12-14T21:57:33.763Z,1544824653.763 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:57:33.790Z,1544824653.790 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:57:34.160Z,1544824654.160 [PNI_TCM](INFO): Powering down
2018-12-14T21:57:35.401Z,1544824655.401 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:57:35.401Z,1544824655.401 [PNI_TCM] No Fault, FailCount= 3
2018-12-14T21:57:42.960Z,1544824662.960 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:57:42.960Z,1544824662.960 [PNI_TCM] Data Fault, FailCount= 4
2018-12-14T21:57:42.960Z,1544824662.960 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:57:42.985Z,1544824662.985 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:57:43.360Z,1544824663.360 [PNI_TCM](INFO): Powering down
2018-12-14T21:57:44.594Z,1544824664.594 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T21:57:44.594Z,1544824664.594 [PNI_TCM] No Fault, FailCount= 4
2018-12-14T21:57:52.157Z,1544824672.157 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T21:57:52.157Z,1544824672.157 [PNI_TCM] Data Fault, FailCount= 5
2018-12-14T21:57:52.158Z,1544824672.158 [PNI_TCM](ERROR): Data Fault
2018-12-14T21:57:52.194Z,1544824672.194 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T21:57:52.194Z,1544824672.194 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-14T21:57:52.552Z,1544824672.552 [PNI_TCM](INFO): Powering down
2018-12-14T21:57:52.669Z,1544824672.669 [CBIT](INFO): Critical error at 20181214T215752
2018-12-14T21:58:46.113Z,1544824726.113 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T21:58:46.113Z,1544824726.113 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-12-14T21:58:46.113Z,1544824726.113 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T21:58:46.134Z,1544824726.134 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T21:58:46.545Z,1544824726.545 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T21:58:46.545Z,1544824726.545 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-12-14T21:58:46.926Z,1544824726.926 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T21:58:46.926Z,1544824726.926 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T21:58:46.926Z,1544824726.926 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T21:59:00.803Z,1544824740.803 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:59:00.803Z,1544824740.803 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T21:59:00.881Z,1544824740.881 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:59:00.882Z,1544824740.882 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:59:14.925Z,1544824754.925 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:59:14.925Z,1544824754.925 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T21:59:14.925Z,1544824754.925 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:59:15.277Z,1544824755.277 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:59:15.329Z,1544824755.329 [CTD_Seabird](INFO): Powering down
2018-12-14T21:59:17.278Z,1544824757.278 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:59:17.278Z,1544824757.278 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T21:59:18.277Z,1544824758.277 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:59:18.278Z,1544824758.278 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:59:32.321Z,1544824772.321 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:59:32.321Z,1544824772.321 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T21:59:32.321Z,1544824772.321 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:59:32.481Z,1544824772.481 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:59:32.725Z,1544824772.725 [CTD_Seabird](INFO): Powering down
2018-12-14T21:59:34.875Z,1544824774.875 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T21:59:34.875Z,1544824774.875 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T21:59:35.677Z,1544824775.677 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T21:59:35.679Z,1544824775.679 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T21:59:49.721Z,1544824789.721 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T21:59:49.721Z,1544824789.721 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T21:59:49.721Z,1544824789.721 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T21:59:50.083Z,1544824790.083 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T21:59:50.083Z,1544824790.083 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T21:59:50.125Z,1544824790.125 [CTD_Seabird](INFO): Powering down
2018-12-14T22:00:47.200Z,1544824847.200 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T22:00:47.201Z,1544824847.201 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-12-14T22:00:47.201Z,1544824847.201 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T22:00:47.254Z,1544824847.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T22:00:47.602Z,1544824847.602 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T22:00:47.602Z,1544824847.602 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-12-14T22:00:48.005Z,1544824848.005 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T22:00:48.005Z,1544824848.005 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T22:00:48.006Z,1544824848.006 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T22:00:56.792Z,1544824856.792 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-14T22:00:56.792Z,1544824856.792 [Default:CheckIn:C.Wait] Stopped
2018-12-14T22:00:56.792Z,1544824856.792 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-14T22:00:56.792Z,1544824856.792 [Default:CheckIn:D] Running Loop=1
2018-12-14T22:00:57.211Z,1544824857.211 [Default:CheckIn:D] Stopped
2018-12-14T22:00:57.211Z,1544824857.211 [Default:CheckIn:E] Running Loop=1
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.455522 min 
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn:E] Stopped
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn] Stopped
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn](INFO): Running loop #4
2018-12-14T22:00:57.581Z,1544824857.581 [Default:CheckIn] Running Loop=4
2018-12-14T22:00:57.582Z,1544824857.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-14T22:00:57.582Z,1544824857.582 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-14T22:02:20.102Z,1544824940.102 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T22:02:20.102Z,1544824940.102 [CTD_Seabird] No Fault, FailCount= 3
2018-12-14T22:02:20.205Z,1544824940.205 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T22:02:20.206Z,1544824940.206 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T22:02:34.261Z,1544824954.261 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T22:02:34.261Z,1544824954.261 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-14T22:02:34.261Z,1544824954.261 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T22:02:34.502Z,1544824954.502 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T22:02:34.669Z,1544824954.669 [CTD_Seabird](INFO): Powering down
2018-12-14T22:02:36.906Z,1544824956.906 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T22:02:36.906Z,1544824956.906 [CTD_Seabird] No Fault, FailCount= 1
2018-12-14T22:02:37.617Z,1544824957.617 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T22:02:37.619Z,1544824957.619 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T22:02:48.082Z,1544824968.082 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-14T22:02:48.082Z,1544824968.082 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-12-14T22:02:48.082Z,1544824968.082 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-14T22:02:48.094Z,1544824968.094 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-14T22:02:48.526Z,1544824968.526 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-14T22:02:48.526Z,1544824968.526 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-12-14T22:02:48.894Z,1544824968.894 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-14T22:02:48.894Z,1544824968.894 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-14T22:02:48.894Z,1544824968.894 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-14T22:02:49.699Z,1544824969.699 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-14T22:02:49.699Z,1544824969.699 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-14T22:02:49.929Z,1544824969.929 [Rowe_600LCM](INFO): Initializing
2018-12-14T22:02:49.930Z,1544824969.930 [Rowe_600LCM](INFO): Checking LCM
2018-12-14T22:02:49.930Z,1544824969.930 [Rowe_600LCM](INFO): LCM OK
2018-12-14T22:02:49.930Z,1544824969.930 [Rowe_600LCM](INFO): Powering up
2018-12-14T22:02:51.673Z,1544824971.673 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T22:02:51.673Z,1544824971.673 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-14T22:02:51.673Z,1544824971.673 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T22:02:51.726Z,1544824971.726 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T22:02:52.077Z,1544824972.077 [CTD_Seabird](INFO): Powering down
2018-12-14T22:02:52.494Z,1544824972.494 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T22:02:52.494Z,1544824972.494 [PNI_TCM] No Fault, FailCount= 5
2018-12-14T22:02:54.094Z,1544824974.094 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-14T22:02:54.173Z,1544824974.173 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-14T22:02:54.173Z,1544824974.173 [CTD_Seabird] No Fault, FailCount= 2
2018-12-14T22:02:54.199Z,1544824974.199 [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-12-14T22:02:54.200Z,1544824974.200 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-14T22:02:54.201Z,1544824974.201 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-14T22:02:54.202Z,1544824974.202 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-14T22:02:55.021Z,1544824975.021 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-14T22:02:55.023Z,1544824975.023 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-14T22:03:05.585Z,1544824985.585 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T22:03:05.585Z,1544824985.585 [PNI_TCM] Data Fault, FailCount= 1
2018-12-14T22:03:05.585Z,1544824985.585 [PNI_TCM](ERROR): Data Fault
2018-12-14T22:03:05.604Z,1544824985.604 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-14T22:03:05.681Z,1544824985.681 [PNI_TCM](INFO): Powering down
2018-12-14T22:03:06.878Z,1544824986.878 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-14T22:03:06.878Z,1544824986.878 [PNI_TCM] No Fault, FailCount= 1
2018-12-14T22:03:09.065Z,1544824989.065 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-14T22:03:09.065Z,1544824989.065 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-14T22:03:09.065Z,1544824989.065 [CTD_Seabird](ERROR): Communications Fault
2018-12-14T22:03:09.305Z,1544824989.305 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-14T22:03:09.306Z,1544824989.306 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-14T22:03:09.469Z,1544824989.469 [CTD_Seabird](INFO): Powering down
2018-12-14T22:03:14.433Z,1544824994.433 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-14T22:03:14.433Z,1544824994.433 [PNI_TCM] Data Fault, FailCount= 2
2018-12-14T22:03:14.434Z,1544824994.434 [PNI_TCM](ERROR): Data Fault
2018-