2018-11-28T00:24:36.205Z,1543364676.205 [Supervisor](DEBUG): Initializing supervisor.
2018-11-28T00:24:36.208Z,1543364676.208 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-28T00:24:36.208Z,1543364676.208 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-11-28T00:24:36.209Z,1543364676.209 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-28T00:24:36.210Z,1543364676.210 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-28T00:24:36.210Z,1543364676.210 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-11-28T00:24:36.213Z,1543364676.213 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-28T00:24:36.224Z,1543364676.224 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-28T00:24:36.225Z,1543364676.225 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-28T00:24:36.225Z,1543364676.225 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-11-28T00:24:36.226Z,1543364676.226 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-28T00:24:36.227Z,1543364676.227 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-28T00:24:36.227Z,1543364676.227 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-11-28T00:24:36.229Z,1543364676.229 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-28T00:24:36.230Z,1543364676.230 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-28T00:24:36.234Z,1543364676.234 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-28T00:24:36.554Z,1543364676.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-28T00:24:36.584Z,1543364676.584 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-28T00:24:36.772Z,1543364676.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-28T00:24:36.773Z,1543364676.773 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-28T00:24:37.057Z,1543364677.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-28T00:24:37.058Z,1543364677.058 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-28T00:24:37.200Z,1543364677.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-28T00:24:37.201Z,1543364677.201 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-28T00:24:37.300Z,1543364677.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-28T00:24:37.301Z,1543364677.301 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-28T00:24:37.758Z,1543364677.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-28T00:24:37.760Z,1543364677.760 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-28T00:24:38.215Z,1543364678.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-28T00:24:38.216Z,1543364678.216 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-28T00:24:38.669Z,1543364678.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-28T00:24:38.670Z,1543364678.670 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-28T00:24:38.772Z,1543364678.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-28T00:24:38.774Z,1543364678.774 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-28T00:24:38.870Z,1543364678.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-28T00:24:38.871Z,1543364678.871 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-28T00:24:39.085Z,1543364679.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-28T00:24:39.086Z,1543364679.086 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-28T00:24:39.285Z,1543364679.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-28T00:24:39.287Z,1543364679.287 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-28T00:24:39.648Z,1543364679.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-28T00:24:39.648Z,1543364679.648 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-28T00:24:39.791Z,1543364679.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-28T00:24:39.792Z,1543364679.792 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-28T00:24:39.881Z,1543364679.881 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-11-28T00:24:39.910Z,1543364679.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-11-28T00:24:40.277Z,1543364680.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-11-28T00:24:40.945Z,1543364680.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-11-28T00:24:41.040Z,1543364681.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-11-28T00:24:41.126Z,1543364681.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-11-28T00:24:41.288Z,1543364681.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-11-28T00:24:41.469Z,1543364681.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-11-28T00:24:41.545Z,1543364681.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-11-28T00:24:41.772Z,1543364681.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-28T00:24:41.772Z,1543364681.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-11-28T00:24:41.855Z,1543364681.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-11-28T00:24:41.945Z,1543364681.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-11-28T00:24:42.043Z,1543364682.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-11-28T00:24:42.166Z,1543364682.166 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-11-28T00:24:42.170Z,1543364682.170 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-28T00:24:42.445Z,1543364682.445 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-28T00:24:42.447Z,1543364682.447 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-28T00:24:42.503Z,1543364682.503 [DepthRateCalculator] Loaded
2018-11-28T00:24:42.503Z,1543364682.503 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-28T00:24:42.509Z,1543364682.509 [PitchRateCalculator] Loaded
2018-11-28T00:24:42.509Z,1543364682.509 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-28T00:24:42.525Z,1543364682.525 [SpeedCalculator] Loaded
2018-11-28T00:24:42.525Z,1543364682.525 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-28T00:24:42.546Z,1543364682.546 [TempGradientCalculator] Loaded
2018-11-28T00:24:42.546Z,1543364682.546 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-28T00:24:42.551Z,1543364682.551 [YawRateCalculator] Loaded
2018-11-28T00:24:42.552Z,1543364682.552 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-28T00:24:42.590Z,1543364682.590 [ElevatorOffsetCalculator] Loaded
2018-11-28T00:24:42.590Z,1543364682.590 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-28T00:24:42.591Z,1543364682.591 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-28T00:24:42.591Z,1543364682.591 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-28T00:24:42.693Z,1543364682.693 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-28T00:24:42.694Z,1543364682.694 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-28T00:24:42.838Z,1543364682.838 [BuoyancyServo] Loaded
2018-11-28T00:24:42.838Z,1543364682.838 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-28T00:24:42.853Z,1543364682.853 [ElevatorServo] Loaded
2018-11-28T00:24:42.853Z,1543364682.853 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-28T00:24:42.867Z,1543364682.867 [MassServo] Loaded
2018-11-28T00:24:42.868Z,1543364682.868 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-28T00:24:42.882Z,1543364682.882 [RudderServo] Loaded
2018-11-28T00:24:42.883Z,1543364682.883 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-28T00:24:42.896Z,1543364682.896 [ThrusterServo] Loaded
2018-11-28T00:24:42.896Z,1543364682.896 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-28T00:24:42.897Z,1543364682.897 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-28T00:24:42.898Z,1543364682.898 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-28T00:24:42.921Z,1543364682.921 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-28T00:24:42.922Z,1543364682.922 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-28T00:24:43.046Z,1543364683.046 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-28T00:24:43.048Z,1543364683.048 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-28T00:24:43.613Z,1543364683.613 [DataOverHttps] Loaded
2018-11-28T00:24:43.613Z,1543364683.613 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-28T00:24:43.627Z,1543364683.627 [Depth_Keller] Loaded
2018-11-28T00:24:43.627Z,1543364683.627 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-28T00:24:43.632Z,1543364683.632 [DropWeight] Loaded
2018-11-28T00:24:43.632Z,1543364683.632 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-28T00:24:43.729Z,1543364683.729 [NAL9602] Loaded
2018-11-28T00:24:43.729Z,1543364683.729 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-28T00:24:43.744Z,1543364683.744 [Onboard] Loaded
2018-11-28T00:24:43.745Z,1543364683.745 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-28T00:24:43.755Z,1543364683.755 [Radio_Surface] Loaded
2018-11-28T00:24:43.755Z,1543364683.755 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-28T00:24:43.756Z,1543364683.756 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-11-28T00:24:43.756Z,1543364683.756 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-11-28T00:24:43.908Z,1543364683.908 [PNI_TCM] Loaded
2018-11-28T00:24:43.908Z,1543364683.908 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-28T00:24:43.967Z,1543364683.967 [Rowe_600LCM] Loaded
2018-11-28T00:24:43.968Z,1543364683.968 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-28T00:24:43.969Z,1543364683.969 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-11-28T00:24:43.969Z,1543364683.969 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 885
2018-11-28T00:24:43.970Z,1543364683.970 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-28T00:24:43.972Z,1543364683.972 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-28T00:24:44.128Z,1543364684.128 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-28T00:24:44.150Z,1543364684.150 [SBIT] Loaded
2018-11-28T00:24:44.150Z,1543364684.150 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-28T00:24:44.151Z,1543364684.151 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-28T00:24:44.163Z,1543364684.163 [IBIT] Loaded
2018-11-28T00:24:44.163Z,1543364684.163 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-28T00:24:44.166Z,1543364684.166 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-28T00:24:44.542Z,1543364684.542 [CBIT] Loaded
2018-11-28T00:24:44.542Z,1543364684.542 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-28T00:24:44.542Z,1543364684.542 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-28T00:24:44.543Z,1543364684.543 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-28T00:24:44.855Z,1543364684.855 [Aanderaa_O2] Loaded
2018-11-28T00:24:44.855Z,1543364684.855 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-28T00:24:44.937Z,1543364684.937 [CTD_NeilBrown] Loaded
2018-11-28T00:24:44.937Z,1543364684.937 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-28T00:24:44.938Z,1543364684.938 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0
2018-11-28T00:24:44.939Z,1543364684.939 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 887
2018-11-28T00:24:45.011Z,1543364685.011 [CTD_Seabird] Loaded
2018-11-28T00:24:45.011Z,1543364685.011 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-28T00:24:45.012Z,1543364685.012 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0
2018-11-28T00:24:45.012Z,1543364685.012 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888
2018-11-28T00:24:45.026Z,1543364685.026 [PAR_Licor] Loaded
2018-11-28T00:24:45.027Z,1543364685.027 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-28T00:24:45.074Z,1543364685.074 [WetLabsBB2FL] Loaded
2018-11-28T00:24:45.074Z,1543364685.074 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-28T00:24:45.075Z,1543364685.075 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0
2018-11-28T00:24:45.076Z,1543364685.076 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889
2018-11-28T00:24:45.076Z,1543364685.076 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-28T00:24:45.077Z,1543364685.077 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-28T00:24:45.363Z,1543364685.363 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-28T00:24:45.444Z,1543364685.444 [VerticalControl] Loaded
2018-11-28T00:24:45.444Z,1543364685.444 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-28T00:24:45.445Z,1543364685.445 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-28T00:24:45.576Z,1543364685.576 [HorizontalControl] Loaded
2018-11-28T00:24:45.577Z,1543364685.577 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-28T00:24:45.578Z,1543364685.578 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-28T00:24:45.579Z,1543364685.579 [SpeedControl] Loaded
2018-11-28T00:24:45.579Z,1543364685.579 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-28T00:24:45.580Z,1543364685.580 [LoopControl](DEBUG): Construct LoopControl.
2018-11-28T00:24:45.580Z,1543364685.580 [LoopControl] Loaded
2018-11-28T00:24:45.581Z,1543364685.581 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-28T00:24:45.581Z,1543364685.581 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-28T00:24:45.582Z,1543364685.582 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-28T00:24:45.619Z,1543364685.619 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-28T00:24:45.619Z,1543364685.619 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-28T00:24:45.724Z,1543364685.724 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-28T00:24:45.724Z,1543364685.724 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-28T00:24:45.740Z,1543364685.740 [NavChart] Loaded
2018-11-28T00:24:45.740Z,1543364685.740 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-28T00:24:45.744Z,1543364685.744 [UniversalFixResidualReporter] Loaded
2018-11-28T00:24:45.745Z,1543364685.745 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-28T00:24:45.745Z,1543364685.745 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-28T00:24:45.749Z,1543364685.749 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-28T00:24:45.750Z,1543364685.750 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-28T00:24:45.756Z,1543364685.756 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-28T00:24:45.757Z,1543364685.757 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2018-11-28T00:24:45.757Z,1543364685.757 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890
2018-11-28T00:24:45.762Z,1543364685.762 [Supervisor](INFO): Main Thread ID is 798
2018-11-28T00:24:45.762Z,1543364685.762 [Supervisor](DEBUG): Running supervisor.
2018-11-28T00:24:45.763Z,1543364685.763 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891
2018-11-28T00:24:45.765Z,1543364685.765 [controlThread ThreadHandler](INFO): Handler Thread ID is 892
2018-11-28T00:24:45.766Z,1543364685.766 [controlThread](DEBUG): Initializing ControlThread
2018-11-28T00:24:45.766Z,1543364685.766 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-28T00:24:45.767Z,1543364685.767 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-28T00:24:45.767Z,1543364685.767 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-28T00:24:45.767Z,1543364685.767 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-28T00:24:45.768Z,1543364685.768 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-28T00:24:45.768Z,1543364685.768 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-28T00:24:45.775Z,1543364685.775 [SBIT](INFO): Initialize SBIT Component.
2018-11-28T00:24:45.775Z,1543364685.775 [SBIT](IMPORTANT): git: 2018-11-27
2018-11-28T00:24:45.776Z,1543364685.776 [SBIT](INFO): git hash: 0274396627bfc50a79ae05a137f3c61385881def
2018-11-28T00:24:45.776Z,1543364685.776 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-28T00:24:45.776Z,1543364685.776 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-28T00:24:45.777Z,1543364685.777 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-11-28T00:24:45.778Z,1543364685.778 [IBIT](INFO): Initialize IBIT Component.
2018-11-28T00:24:45.779Z,1543364685.779 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-28T00:24:45.780Z,1543364685.780 [logger ThreadHandler](INFO): Handler Thread ID is 893
2018-11-28T00:24:45.793Z,1543364685.793 [CBIT](DEBUG): Initialized mux pins.
2018-11-28T00:24:45.793Z,1543364685.793 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-11-28T00:24:45.793Z,1543364685.793 [CBIT](DEBUG): Initializing the watchdog timer.
2018-11-28T00:24:45.797Z,1543364685.797 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894
2018-11-28T00:24:45.803Z,1543364685.803 [Radio_Surface](INFO): Powering up
2018-11-28T00:24:45.813Z,1543364685.813 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 895
2018-11-28T00:24:45.814Z,1543364685.814 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:24:45.814Z,1543364685.814 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:24:45.817Z,1543364685.817 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-28T00:24:45.817Z,1543364685.817 [CBIT](DEBUG): Initializing heartbeat.
2018-11-28T00:24:45.889Z,1543364685.889 [CBIT](DEBUG): Deactivating GF circuits.
2018-11-28T00:24:45.889Z,1543364685.889 [CBIT](DEBUG): Deactivating emergency mode.
2018-11-28T00:24:45.927Z,1543364685.927 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896
2018-11-28T00:24:45.928Z,1543364685.928 [CTD_NeilBrown](INFO): Powering down
2018-11-28T00:24:45.955Z,1543364685.955 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-28T00:24:45.957Z,1543364685.957 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-28T00:24:45.958Z,1543364685.958 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-28T00:24:45.958Z,1543364685.958 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-28T00:24:45.959Z,1543364685.959 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:24:45.959Z,1543364685.959 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:24:45.960Z,1543364685.960 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:24:45.960Z,1543364685.960 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-28T00:24:45.962Z,1543364685.962 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-28T00:24:46.058Z,1543364686.058 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-28T00:24:46.068Z,1543364686.068 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897
2018-11-28T00:24:46.069Z,1543364686.069 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:24:46.071Z,1543364686.071 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:24:46.072Z,1543364686.072 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 899
2018-11-28T00:24:46.074Z,1543364686.074 [WetLabsBB2FL](INFO): Powering down
2018-11-28T00:24:46.093Z,1543364686.093 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 900
2018-11-28T00:24:46.170Z,1543364686.170 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-28T00:24:46.171Z,1543364686.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-28T00:24:46.171Z,1543364686.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-28T00:24:46.171Z,1543364686.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-28T00:24:46.171Z,1543364686.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-28T00:24:46.171Z,1543364686.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-28T00:24:46.172Z,1543364686.172 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-28T00:24:46.172Z,1543364686.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-28T00:24:46.172Z,1543364686.172 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-28T00:24:46.172Z,1543364686.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-28T00:24:46.181Z,1543364686.181 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-28T00:24:46.181Z,1543364686.181 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-28T00:24:46.181Z,1543364686.181 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-28T00:24:46.181Z,1543364686.181 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-28T00:24:46.182Z,1543364686.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-28T00:24:46.182Z,1543364686.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-28T00:24:46.195Z,1543364686.195 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-28T00:24:46.280Z,1543364686.280 [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-11-28T00:24:46.292Z,1543364686.292 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:24:46.292Z,1543364686.292 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:24:46.297Z,1543364686.297 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-28T00:24:46.388Z,1543364686.388 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-28T00:24:46.389Z,1543364686.389 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-28T00:24:46.391Z,1543364686.391 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-28T00:24:46.442Z,1543364686.442 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-28T00:24:46.444Z,1543364686.444 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-28T00:24:46.463Z,1543364686.463 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-28T00:24:46.481Z,1543364686.481 [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-11-28T00:24:46.497Z,1543364686.497 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-28T00:24:46.525Z,1543364686.525 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-28T00:24:46.548Z,1543364686.548 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-11-28T00:24:46.729Z,1543364686.729 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-28T00:24:47.014Z,1543364687.014 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-28T00:24:47.021Z,1543364687.021 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-28T00:24:47.035Z,1543364687.035 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-28T00:24:47.041Z,1543364687.041 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-28T00:24:47.055Z,1543364687.055 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-28T00:24:47.061Z,1543364687.061 [MassServo](DEBUG): Initializing MassServo.
2018-11-28T00:24:47.083Z,1543364687.083 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-28T00:24:47.089Z,1543364687.089 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-28T00:24:47.102Z,1543364687.102 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-28T00:24:47.109Z,1543364687.109 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-28T00:24:47.335Z,1543364687.335 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-28T00:24:47.335Z,1543364687.335 [DropWeight] Hardware Fault, FailCount= 1
2018-11-28T00:24:47.335Z,1543364687.335 [DropWeight](ERROR): Hardware Fault
2018-11-28T00:24:47.416Z,1543364687.416 [CommandLine](FAULT): Scheduling is paused
2018-11-28T00:24:47.429Z,1543364687.429 [CBIT](INFO): Critical error at 20181128T002447
2018-11-28T00:24:47.429Z,1543364687.429 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-28T00:24:47.432Z,1543364687.432 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-28T00:24:47.432Z,1543364687.432 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-28T00:24:47.969Z,1543364687.969 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-28T00:24:47.969Z,1543364687.969 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-28T00:24:47.969Z,1543364687.969 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-28T00:24:47.969Z,1543364687.969 [BuoyancyServo](ERROR): Communications Fault
2018-11-28T00:24:48.175Z,1543364688.175 [CBIT](INFO): Critical error at 20181128T002447
2018-11-28T00:24:48.177Z,1543364688.177 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-28T00:24:50.138Z,1543364690.138 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:24:50.244Z,1543364690.244 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:24:50.318Z,1543364690.318 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-28T00:24:50.318Z,1543364690.318 [BuoyancyServo] No Fault, FailCount= 1
2018-11-28T00:24:50.408Z,1543364690.408 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:24:50.409Z,1543364690.409 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:24:50.409Z,1543364690.409 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:24:50.643Z,1543364690.643 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-28T00:24:50.644Z,1543364690.644 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-28T00:24:53.117Z,1543364693.117 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:24:53.117Z,1543364693.117 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:24:53.117Z,1543364693.117 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:24:53.506Z,1543364693.506 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:24:53.521Z,1543364693.521 [CTD_Seabird](INFO): Powering down
2018-11-28T00:24:53.805Z,1543364693.805 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:24:53.805Z,1543364693.805 [PNI_TCM] Data Fault, FailCount= 1
2018-11-28T00:24:53.805Z,1543364693.805 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:24:53.970Z,1543364693.970 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:24:54.205Z,1543364694.205 [PNI_TCM](INFO): Powering down
2018-11-28T00:24:55.546Z,1543364695.546 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:24:55.546Z,1543364695.546 [PNI_TCM] No Fault, FailCount= 1
2018-11-28T00:24:55.546Z,1543364695.546 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:24:55.546Z,1543364695.546 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:24:55.946Z,1543364695.946 [CBIT](CRITICAL): Environmental Failure. Press:14.758461 PSI. Humidity:26%. Temp:24 C. ABORTING MISSION
2018-11-28T00:24:56.284Z,1543364696.284 [CBIT](INFO): Critical error at 20181128T002455
2018-11-28T00:24:56.369Z,1543364696.369 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:24:56.370Z,1543364696.370 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:24:57.005Z,1543364697.005 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:24:57.005Z,1543364697.005 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:24:57.006Z,1543364697.006 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:24:57.106Z,1543364697.106 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:24:57.406Z,1543364697.406 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:24:58.305Z,1543364698.305 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:24:58.305Z,1543364698.305 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:25:03.041Z,1543364703.041 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:25:03.041Z,1543364703.041 [PNI_TCM] Data Fault, FailCount= 2
2018-11-28T00:25:03.041Z,1543364703.041 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:25:03.174Z,1543364703.174 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:25:03.405Z,1543364703.405 [PNI_TCM](INFO): Powering down
2018-11-28T00:25:03.413Z,1543364703.413 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:25:03.413Z,1543364703.413 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:25:03.413Z,1543364703.413 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:25:03.530Z,1543364703.530 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:25:03.817Z,1543364703.817 [CTD_Seabird](INFO): Powering down
2018-11-28T00:25:04.678Z,1543364704.678 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:25:04.679Z,1543364704.679 [PNI_TCM] No Fault, FailCount= 2
2018-11-28T00:25:05.904Z,1543364705.904 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:25:05.904Z,1543364705.904 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:25:06.646Z,1543364706.646 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:25:06.647Z,1543364706.647 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:25:09.005Z,1543364709.005 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:25:09.005Z,1543364709.005 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:25:09.005Z,1543364709.005 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:25:09.074Z,1543364709.074 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:25:09.417Z,1543364709.417 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:25:09.453Z,1543364709.453 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-28T00:25:09.457Z,1543364709.457 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-28T00:25:10.283Z,1543364710.283 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:25:10.283Z,1543364710.283 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:25:12.305Z,1543364712.305 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:25:12.305Z,1543364712.305 [PNI_TCM] Data Fault, FailCount= 3
2018-11-28T00:25:12.305Z,1543364712.305 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:25:12.446Z,1543364712.446 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:25:12.708Z,1543364712.708 [PNI_TCM](INFO): Powering down
2018-11-28T00:25:13.693Z,1543364713.693 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:25:13.693Z,1543364713.693 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:25:13.693Z,1543364713.693 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:25:13.894Z,1543364713.894 [NAL9602](INFO): Powering up NAL9602
2018-11-28T00:25:14.086Z,1543364714.086 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:25:14.086Z,1543364714.086 [PNI_TCM] No Fault, FailCount= 3
2018-11-28T00:25:14.086Z,1543364714.086 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:25:14.087Z,1543364714.087 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:25:14.097Z,1543364714.097 [CTD_Seabird](INFO): Powering down
2018-11-28T00:25:20.364Z,1543364720.364 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001202
CHAN A1 (24V): -0.005871
CHAN A2 (12V): -0.002338
CHAN A3 (5V): -0.001104
CHAN B0 (3.3V): 0.000403
CHAN B1 (3.15aV): -0.000064
CHAN B2 (3.15bV): -0.000057
CHAN B3 (GND): -0.000150
OPEN: -0.000554
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-28T00:25:20.711Z,1543364720.711 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:25:20.711Z,1543364720.711 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:25:20.711Z,1543364720.711 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:25:20.745Z,1543364720.745 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:25:20.746Z,1543364720.746 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:25:21.233Z,1543364721.233 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:25:21.511Z,1543364721.511 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:25:21.512Z,1543364721.512 [PNI_TCM] Data Fault, FailCount= 4
2018-11-28T00:25:21.512Z,1543364721.512 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:25:21.621Z,1543364721.621 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:25:21.915Z,1543364721.915 [PNI_TCM](INFO): Powering down
2018-11-28T00:25:23.153Z,1543364723.153 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:25:23.153Z,1543364723.153 [PNI_TCM] No Fault, FailCount= 4
2018-11-28T00:25:25.311Z,1543364725.311 [NAL9602](INFO): NAL9602 initialized
2018-11-28T00:25:30.909Z,1543364730.909 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:25:30.909Z,1543364730.909 [PNI_TCM] Data Fault, FailCount= 5
2018-11-28T00:25:30.909Z,1543364730.909 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:25:31.006Z,1543364731.006 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:25:31.007Z,1543364731.007 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-28T00:25:31.307Z,1543364731.307 [PNI_TCM](INFO): Powering down
2018-11-28T00:25:31.505Z,1543364731.505 [CBIT](INFO): Critical error at 20181128T002531
2018-11-28T00:26:03.426Z,1543364763.426 [SBIT](IMPORTANT): SBIT PASSED
2018-11-28T00:26:03.518Z,1543364763.518 [CommandLine](IMPORTANT): got command configSet list
2018-11-28T00:26:03.518Z,1543364763.518 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-28T00:26:03.522Z,1543364763.522 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-11-28T00:26:03.522Z,1543364763.522 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count;

2018-11-28T00:26:03.522Z,1543364763.522 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-11-28T00:26:03.522Z,1543364763.522 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2018-11-28T00:26:03.522Z,1543364763.522 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2018-11-28T00:26:03.522Z,1543364763.522 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature 0.150000 kelvin;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): Express linearApproximation CTD_NeilBrown.bin_median_sea_water_salinity 0.020000 practical_salinity_unit;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): Express linearApproximation CTD_NeilBrown.bin_median_sea_water_temperature 0.150000 kelvin;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-11-28T00:26:03.523Z,1543364763.523 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-11-28T00:26:03.821Z,1543364763.821 [MissionManager](IMPORTANT): Started mission Startup
2018-11-28T00:26:03.821Z,1543364763.821 [Startup] Running Loop=1
2018-11-28T00:26:03.821Z,1543364763.821 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-28T00:26:03.821Z,1543364763.821 [Startup:A.GoToSurface] Running Loop=1
2018-11-28T00:26:03.821Z,1543364763.821 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-28T00:26:03.822Z,1543364763.822 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-28T00:26:03.822Z,1543364763.822 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-28T00:26:03.823Z,1543364763.823 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-28T00:26:03.823Z,1543364763.823 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-28T00:26:03.823Z,1543364763.823 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-28T00:26:03.830Z,1543364763.830 [Startup:StartupSatComms] Running Loop=1
2018-11-28T00:26:03.830Z,1543364763.830 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-28T00:26:03.831Z,1543364763.831 [Startup:StartupSatComms:A] Running Loop=1
2018-11-28T00:26:04.233Z,1543364764.233 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-28T00:26:46.241Z,1543364806.241 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:26:46.241Z,1543364806.241 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-28T00:26:46.241Z,1543364806.241 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:26:46.282Z,1543364806.282 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:26:46.641Z,1543364806.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:26:46.641Z,1543364806.641 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-28T00:26:46.697Z,1543364806.697 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:26:46.697Z,1543364806.697 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-11-28T00:26:46.697Z,1543364806.697 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:26:47.030Z,1543364807.030 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:26:47.030Z,1543364807.030 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:26:47.030Z,1543364807.030 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:26:47.042Z,1543364807.042 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:26:47.101Z,1543364807.101 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:26:47.197Z,1543364807.197 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:26:48.647Z,1543364808.647 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:26:48.647Z,1543364808.647 [Rowe_600LCM] No Fault, FailCount= 1
2018-11-28T00:26:48.813Z,1543364808.813 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:26:48.813Z,1543364808.813 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:26:48.813Z,1543364808.813 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:26:48.814Z,1543364808.814 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:26:52.936Z,1543364812.936 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:26:53.012Z,1543364813.012 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:26:53.014Z,1543364813.014 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:26:53.014Z,1543364813.014 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:26:53.015Z,1543364813.015 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:27:04.224Z,1543364824.224 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-28T00:26:03.8Z
2018-11-28T00:27:04.224Z,1543364824.224 [Startup:StartupSatComms:A] Stopped
2018-11-28T00:27:04.224Z,1543364824.224 [Startup:StartupSatComms:B] Running Loop=1
2018-11-28T00:27:04.628Z,1543364824.628 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-28T00:27:18.599Z,1543364838.599 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004331
2018-11-28T00:27:22.554Z,1543364842.554 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181127T064204/Courier0196.lzma
2018-11-28T00:27:23.356Z,1543364843.356 [DataOverHttps](INFO): Moved sent file to Logs/20181127T064204/Courier0196.lzma.bak
2018-11-28T00:27:23.356Z,1543364843.356 [DataOverHttps](INFO): SBD MOMSN=8929083
2018-11-28T00:27:32.104Z,1543364852.104 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20181128T002436/Courier0000.lzma
2018-11-28T00:27:32.871Z,1543364852.871 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Courier0000.lzma.bak
2018-11-28T00:27:32.871Z,1543364852.871 [DataOverHttps](INFO): SBD MOMSN=8929085
2018-11-28T00:27:41.678Z,1543364861.678 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181127T064204/Express0197.lzma
2018-11-28T00:27:42.467Z,1543364862.467 [DataOverHttps](INFO): Moved sent file to Logs/20181127T064204/Express0197.lzma.bak
2018-11-28T00:27:42.467Z,1543364862.467 [DataOverHttps](INFO): SBD MOMSN=8929092
2018-11-28T00:27:46.438Z,1543364866.438 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:27:46.438Z,1543364866.438 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:27:46.465Z,1543364866.465 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:27:46.466Z,1543364866.466 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:27:51.238Z,1543364871.238 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:27:51.238Z,1543364871.238 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:27:51.293Z,1543364871.293 [DataOverHttps](INFO): Sending 1373 bytes from file Logs/20181128T002436/Express0001.lzma
2018-11-28T00:27:52.047Z,1543364872.047 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Express0001.lzma.bak
2018-11-28T00:27:52.047Z,1543364872.047 [DataOverHttps](INFO): SBD MOMSN=8929094
2018-11-28T00:27:52.632Z,1543364872.632 [Startup:StartupSatComms:B] Stopped
2018-11-28T00:27:52.632Z,1543364872.632 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-28T00:27:52.632Z,1543364872.632 [Startup:StartupSatComms] Stopped
2018-11-28T00:27:52.633Z,1543364872.633 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-28T00:27:52.634Z,1543364872.634 [Startup](INFO): Completed Startup
2018-11-28T00:27:52.634Z,1543364872.634 [MissionManager](INFO): Startup is completed.
2018-11-28T00:27:52.634Z,1543364872.634 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-28T00:27:52.634Z,1543364872.634 [Startup] Stopped
2018-11-28T00:27:52.634Z,1543364872.634 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-28T00:27:52.634Z,1543364872.634 [Startup:A.GoToSurface] Stopped
2018-11-28T00:27:52.634Z,1543364872.634 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-28T00:27:52.883Z,1543364872.883 [MissionManager](IMPORTANT): Started mission Default
2018-11-28T00:27:52.884Z,1543364872.884 [Default] Running Loop=1
2018-11-28T00:27:52.884Z,1543364872.884 [Default](DEBUG): Aggregate::initialize Default
2018-11-28T00:27:52.884Z,1543364872.884 [Default:B.GoToSurface] Running Loop=1
2018-11-28T00:27:52.884Z,1543364872.884 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-28T00:27:52.884Z,1543364872.884 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-28T00:27:52.884Z,1543364872.884 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-28T00:27:52.885Z,1543364872.885 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-28T00:27:52.885Z,1543364872.885 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-28T00:27:52.886Z,1543364872.886 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-28T00:27:52.886Z,1543364872.886 [Default:A.Wait] Running Loop=1
2018-11-28T00:27:52.886Z,1543364872.886 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-28T00:27:53.509Z,1543364873.509 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:27:53.509Z,1543364873.509 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:27:53.509Z,1543364873.509 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:27:53.913Z,1543364873.913 [CTD_Seabird](INFO): Powering down
2018-11-28T00:27:56.659Z,1543364876.659 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:27:56.659Z,1543364876.659 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:27:56.660Z,1543364876.660 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:27:56.861Z,1543364876.861 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:27:56.863Z,1543364876.863 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:28:01.507Z,1543364881.507 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:28:01.508Z,1543364881.508 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:28:01.508Z,1543364881.508 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:28:01.530Z,1543364881.530 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:28:02.025Z,1543364882.025 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:28:02.734Z,1543364882.734 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:28:02.734Z,1543364882.734 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:28:03.913Z,1543364883.913 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:28:03.913Z,1543364883.913 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:28:03.913Z,1543364883.913 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:28:03.923Z,1543364883.923 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:28:04.317Z,1543364884.317 [CTD_Seabird](INFO): Powering down
2018-11-28T00:28:05.900Z,1543364885.900 [Default:A.Wait](INFO): Done Waiting.
2018-11-28T00:28:05.901Z,1543364885.901 [Default:A.Wait] Stopped
2018-11-28T00:28:05.901Z,1543364885.901 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-28T00:28:06.296Z,1543364886.296 [Default:CheckIn] Running Loop=1
2018-11-28T00:28:06.297Z,1543364886.297 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-28T00:28:06.297Z,1543364886.297 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-28T00:28:06.307Z,1543364886.307 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:28:06.307Z,1543364886.307 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:28:06.712Z,1543364886.712 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-28T00:28:07.267Z,1543364887.267 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:28:07.269Z,1543364887.269 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:28:13.107Z,1543364893.107 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:28:13.107Z,1543364893.107 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:28:13.107Z,1543364893.107 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:28:13.161Z,1543364893.161 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:28:13.609Z,1543364893.609 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:28:14.309Z,1543364894.309 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:28:14.309Z,1543364894.309 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:28:14.309Z,1543364894.309 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:28:14.319Z,1543364894.319 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:28:14.319Z,1543364894.319 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:28:14.319Z,1543364894.319 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:28:14.319Z,1543364894.319 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:28:14.713Z,1543364894.713 [CTD_Seabird](INFO): Powering down
2018-11-28T00:28:25.103Z,1543364905.103 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:28:25.104Z,1543364905.104 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:28:25.104Z,1543364905.104 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:28:25.122Z,1543364905.122 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:28:25.122Z,1543364905.122 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:28:25.605Z,1543364905.605 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:28:47.101Z,1543364927.101 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:28:47.102Z,1543364927.102 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-28T00:28:47.102Z,1543364927.102 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:28:47.114Z,1543364927.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:28:47.519Z,1543364927.519 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:28:47.519Z,1543364927.519 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-28T00:28:47.902Z,1543364927.902 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:28:47.902Z,1543364927.902 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:28:47.902Z,1543364927.902 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:28:49.461Z,1543364929.461 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:28:49.461Z,1543364929.461 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-11-28T00:28:49.461Z,1543364929.461 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:28:49.590Z,1543364929.590 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:28:49.865Z,1543364929.865 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:28:50.119Z,1543364930.119 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:28:51.592Z,1543364931.592 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:28:51.592Z,1543364931.592 [Rowe_600LCM] No Fault, FailCount= 2
2018-11-28T00:28:51.733Z,1543364931.733 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:28:51.734Z,1543364931.734 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:28:51.734Z,1543364931.734 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:28:51.734Z,1543364931.734 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:28:56.015Z,1543364936.015 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:28:56.164Z,1543364936.164 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:28:56.165Z,1543364936.165 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:28:56.166Z,1543364936.166 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:28:56.166Z,1543364936.166 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:30:28.306Z,1543365028.306 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-11-28T00:30:31.156Z,1543365031.156 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:30:31.156Z,1543365031.156 [PNI_TCM] No Fault, FailCount= 5
2018-11-28T00:30:38.722Z,1543365038.722 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:30:38.722Z,1543365038.722 [PNI_TCM] Data Fault, FailCount= 1
2018-11-28T00:30:38.722Z,1543365038.722 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:30:38.774Z,1543365038.774 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:30:39.121Z,1543365039.121 [PNI_TCM](INFO): Powering down
2018-11-28T00:30:40.370Z,1543365040.370 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:30:40.370Z,1543365040.370 [PNI_TCM] No Fault, FailCount= 1
2018-11-28T00:30:44.378Z,1543365044.378 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:30:44.378Z,1543365044.378 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:30:44.398Z,1543365044.398 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:30:44.399Z,1543365044.399 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:30:47.921Z,1543365047.921 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:30:47.921Z,1543365047.921 [PNI_TCM] Data Fault, FailCount= 2
2018-11-28T00:30:47.921Z,1543365047.921 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:30:47.927Z,1543365047.927 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:30:47.927Z,1543365047.927 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-28T00:30:47.927Z,1543365047.927 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:30:47.940Z,1543365047.940 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:30:47.940Z,1543365047.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:30:48.321Z,1543365048.321 [PNI_TCM](INFO): Powering down
2018-11-28T00:30:48.434Z,1543365048.434 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:30:48.434Z,1543365048.434 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-28T00:30:48.743Z,1543365048.743 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:30:48.744Z,1543365048.744 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:30:48.744Z,1543365048.744 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:30:49.557Z,1543365049.557 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:30:49.557Z,1543365049.557 [PNI_TCM] No Fault, FailCount= 2
2018-11-28T00:30:51.441Z,1543365051.441 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:30:51.441Z,1543365051.441 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:30:51.441Z,1543365051.441 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:30:51.551Z,1543365051.551 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:30:51.845Z,1543365051.845 [CTD_Seabird](INFO): Powering down
2018-11-28T00:30:51.893Z,1543365051.893 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:30:51.893Z,1543365051.893 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-11-28T00:30:51.893Z,1543365051.893 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:30:51.987Z,1543365051.987 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:30:52.305Z,1543365052.305 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:30:52.484Z,1543365052.484 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:30:56.655Z,1543365056.655 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:30:56.655Z,1543365056.655 [Rowe_600LCM] No Fault, FailCount= 3
2018-11-28T00:30:56.656Z,1543365056.656 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:30:56.656Z,1543365056.656 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:30:56.656Z,1543365056.656 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:30:56.656Z,1543365056.656 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:30:56.809Z,1543365056.809 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:30:56.817Z,1543365056.817 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:30:56.929Z,1543365056.929 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:30:56.929Z,1543365056.929 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:30:56.930Z,1543365056.930 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:30:56.930Z,1543365056.930 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:30:57.109Z,1543365057.109 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:30:57.109Z,1543365057.109 [PNI_TCM] Data Fault, FailCount= 3
2018-11-28T00:30:57.109Z,1543365057.109 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:30:57.170Z,1543365057.170 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:30:57.514Z,1543365057.514 [PNI_TCM](INFO): Powering down
2018-11-28T00:30:58.723Z,1543365058.723 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:30:58.723Z,1543365058.723 [PNI_TCM] No Fault, FailCount= 3
2018-11-28T00:31:01.052Z,1543365061.052 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:31:01.191Z,1543365061.191 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:31:01.191Z,1543365061.191 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:31:01.192Z,1543365061.192 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:31:01.197Z,1543365061.197 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:31:03.857Z,1543365063.857 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:31:03.857Z,1543365063.857 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:31:03.857Z,1543365063.857 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:31:03.939Z,1543365063.939 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:31:04.261Z,1543365064.261 [CTD_Seabird](INFO): Powering down
2018-11-28T00:31:06.311Z,1543365066.311 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:31:06.311Z,1543365066.311 [PNI_TCM] Data Fault, FailCount= 4
2018-11-28T00:31:06.312Z,1543365066.312 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:31:06.342Z,1543365066.342 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:31:06.342Z,1543365066.342 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:31:06.342Z,1543365066.342 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:31:06.710Z,1543365066.710 [PNI_TCM](INFO): Powering down
2018-11-28T00:31:07.089Z,1543365067.089 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:31:07.090Z,1543365067.090 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:31:07.114Z,1543365067.114 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:31:07.114Z,1543365067.114 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:31:07.114Z,1543365067.114 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:31:07.172Z,1543365067.172 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:31:07.491Z,1543365067.491 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:31:07.941Z,1543365067.941 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:31:07.941Z,1543365067.941 [PNI_TCM] No Fault, FailCount= 4
2018-11-28T00:31:08.331Z,1543365068.331 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:31:08.331Z,1543365068.331 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:31:14.129Z,1543365074.129 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:31:14.129Z,1543365074.129 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:31:14.129Z,1543365074.129 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:31:14.354Z,1543365074.354 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:31:14.355Z,1543365074.355 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:31:14.533Z,1543365074.533 [CTD_Seabird](INFO): Powering down
2018-11-28T00:31:15.493Z,1543365075.493 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:31:15.493Z,1543365075.493 [PNI_TCM] Data Fault, FailCount= 5
2018-11-28T00:31:15.493Z,1543365075.493 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:31:15.517Z,1543365075.517 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:31:15.518Z,1543365075.518 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-28T00:31:15.899Z,1543365075.899 [PNI_TCM](INFO): Powering down
2018-11-28T00:31:15.952Z,1543365075.952 [CBIT](INFO): Critical error at 20181128T003115
2018-11-28T00:31:18.711Z,1543365078.711 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:31:18.712Z,1543365078.712 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:31:18.712Z,1543365078.712 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:31:18.734Z,1543365078.734 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:31:19.106Z,1543365079.106 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:31:19.943Z,1543365079.943 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:31:19.943Z,1543365079.943 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:31:30.307Z,1543365090.307 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:31:30.308Z,1543365090.308 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:31:30.308Z,1543365090.308 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:31:30.350Z,1543365090.350 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:31:30.350Z,1543365090.350 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:31:30.821Z,1543365090.821 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:32:49.106Z,1543365169.106 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:32:49.106Z,1543365169.106 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-11-28T00:32:49.106Z,1543365169.106 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:32:49.118Z,1543365169.118 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:32:49.516Z,1543365169.516 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:32:49.516Z,1543365169.516 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-11-28T00:32:49.901Z,1543365169.901 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:32:49.901Z,1543365169.901 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:32:49.902Z,1543365169.902 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:32:57.045Z,1543365177.045 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:32:57.045Z,1543365177.045 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-11-28T00:32:57.045Z,1543365177.045 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:32:57.449Z,1543365177.449 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:32:57.534Z,1543365177.534 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:32:57.666Z,1543365177.666 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:32:59.184Z,1543365179.184 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:32:59.184Z,1543365179.184 [Rowe_600LCM] No Fault, FailCount= 4
2018-11-28T00:32:59.281Z,1543365179.281 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:32:59.281Z,1543365179.281 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:32:59.281Z,1543365179.281 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:32:59.282Z,1543365179.282 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:33:03.403Z,1543365183.403 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:33:03.460Z,1543365183.460 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:33:03.462Z,1543365183.462 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:33:03.462Z,1543365183.462 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:33:03.463Z,1543365183.463 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:33:06.388Z,1543365186.388 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-28T00:28:06.3Z
2018-11-28T00:33:06.388Z,1543365186.388 [Default:CheckIn:Read_GPS] Stopped
2018-11-28T00:33:06.388Z,1543365186.388 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-28T00:33:06.776Z,1543365186.776 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-28T00:33:10.729Z,1543365190.729 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181128T002436/Courier0004.lzma
2018-11-28T00:33:11.507Z,1543365191.507 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Courier0004.lzma.bak
2018-11-28T00:33:11.507Z,1543365191.507 [DataOverHttps](INFO): SBD MOMSN=8929183
2018-11-28T00:33:20.272Z,1543365200.272 [DataOverHttps](INFO): Sending 541 bytes from file Logs/20181128T002436/Express0005.lzma
2018-11-28T00:33:21.063Z,1543365201.063 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Express0005.lzma.bak
2018-11-28T00:33:21.063Z,1543365201.063 [DataOverHttps](INFO): SBD MOMSN=8929186
2018-11-28T00:33:21.663Z,1543365201.663 [Default:CheckIn:Read_Iridium] Stopped
2018-11-28T00:33:21.664Z,1543365201.664 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-28T00:33:21.664Z,1543365201.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-28T00:33:44.283Z,1543365224.283 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:33:44.283Z,1543365224.283 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:33:44.525Z,1543365224.525 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:33:44.526Z,1543365224.526 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:33:51.569Z,1543365231.569 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:33:51.569Z,1543365231.569 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:33:51.569Z,1543365231.569 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:33:51.882Z,1543365231.882 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:33:51.973Z,1543365231.973 [CTD_Seabird](INFO): Powering down
2018-11-28T00:33:53.921Z,1543365233.921 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:33:53.921Z,1543365233.921 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:33:54.921Z,1543365234.921 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:33:54.923Z,1543365234.923 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:34:00.677Z,1543365240.677 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:34:00.677Z,1543365240.677 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:34:01.965Z,1543365241.965 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:34:01.965Z,1543365241.965 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:34:01.965Z,1543365241.965 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:34:02.287Z,1543365242.287 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:34:02.369Z,1543365242.369 [CTD_Seabird](INFO): Powering down
2018-11-28T00:34:04.686Z,1543365244.686 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:34:04.686Z,1543365244.686 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:34:05.321Z,1543365245.321 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:34:05.322Z,1543365245.322 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:34:11.070Z,1543365251.070 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:34:11.070Z,1543365251.070 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:34:11.070Z,1543365251.070 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:34:11.094Z,1543365251.094 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:34:11.589Z,1543365251.589 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:34:12.282Z,1543365252.282 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:34:12.282Z,1543365252.282 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:34:12.365Z,1543365252.365 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:34:12.365Z,1543365252.365 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:34:12.365Z,1543365252.365 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:34:12.682Z,1543365252.682 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:34:12.682Z,1543365252.682 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:34:12.769Z,1543365252.769 [CTD_Seabird](INFO): Powering down
2018-11-28T00:34:25.378Z,1543365265.378 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:34:25.378Z,1543365265.378 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:34:25.378Z,1543365265.378 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:34:25.406Z,1543365265.406 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:34:25.617Z,1543365265.617 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:34:26.285Z,1543365266.285 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:34:26.285Z,1543365266.285 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:34:36.642Z,1543365276.642 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:34:36.642Z,1543365276.642 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:34:36.642Z,1543365276.642 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:34:36.659Z,1543365276.659 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:34:36.660Z,1543365276.660 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:34:37.157Z,1543365277.157 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:34:50.262Z,1543365290.262 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:34:50.262Z,1543365290.262 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-11-28T00:34:50.262Z,1543365290.262 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:34:50.274Z,1543365290.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:34:50.661Z,1543365290.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:34:50.661Z,1543365290.661 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-11-28T00:34:51.057Z,1543365291.057 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:34:51.057Z,1543365291.057 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:34:51.058Z,1543365291.058 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:34:59.809Z,1543365299.809 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:34:59.809Z,1543365299.809 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-11-28T00:34:59.809Z,1543365299.809 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:34:59.873Z,1543365299.873 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:34:59.873Z,1543365299.873 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-11-28T00:35:00.213Z,1543365300.213 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:35:00.388Z,1543365300.388 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:35:27.378Z,1543365327.378 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-11-28T00:35:27.378Z,1543365327.378 [NAL9602] Data Fault, FailCount= 1
2018-11-28T00:35:27.378Z,1543365327.378 [NAL9602](ERROR): Data Fault
2018-11-28T00:35:27.445Z,1543365327.445 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-28T00:35:27.783Z,1543365327.783 [NAL9602](INFO): Powering down
2018-11-28T00:35:28.617Z,1543365328.617 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-28T00:35:28.617Z,1543365328.617 [NAL9602] No Fault, FailCount= 1
2018-11-28T00:35:57.786Z,1543365357.786 [NAL9602](INFO): Powering up NAL9602
2018-11-28T00:36:08.978Z,1543365368.978 [NAL9602](INFO): NAL9602 initialized
2018-11-28T00:36:15.834Z,1543365375.834 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:36:15.834Z,1543365375.834 [PNI_TCM] No Fault, FailCount= 5
2018-11-28T00:36:26.087Z,1543365386.087 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:36:26.088Z,1543365386.088 [PNI_TCM] Data Fault, FailCount= 1
2018-11-28T00:36:26.088Z,1543365386.088 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:36:26.137Z,1543365386.137 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:36:26.197Z,1543365386.197 [PNI_TCM](INFO): Powering down
2018-11-28T00:36:27.399Z,1543365387.399 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:36:27.399Z,1543365387.399 [PNI_TCM] No Fault, FailCount= 1
2018-11-28T00:36:34.973Z,1543365394.973 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:36:34.973Z,1543365394.973 [PNI_TCM] Data Fault, FailCount= 2
2018-11-28T00:36:34.974Z,1543365394.974 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:36:34.992Z,1543365394.992 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:36:35.375Z,1543365395.375 [PNI_TCM](INFO): Powering down
2018-11-28T00:36:36.594Z,1543365396.594 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:36:36.594Z,1543365396.594 [PNI_TCM] No Fault, FailCount= 2
2018-11-28T00:36:42.594Z,1543365402.594 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:36:42.594Z,1543365402.594 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:36:42.893Z,1543365402.893 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:36:42.894Z,1543365402.894 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:36:44.180Z,1543365404.180 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:36:44.180Z,1543365404.180 [PNI_TCM] Data Fault, FailCount= 3
2018-11-28T00:36:44.180Z,1543365404.180 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:36:44.238Z,1543365404.238 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:36:44.584Z,1543365404.584 [PNI_TCM](INFO): Powering down
2018-11-28T00:36:45.799Z,1543365405.799 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:36:45.799Z,1543365405.799 [PNI_TCM] No Fault, FailCount= 3
2018-11-28T00:36:49.937Z,1543365409.937 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:36:49.937Z,1543365409.937 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:36:49.937Z,1543365409.937 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:36:50.208Z,1543365410.208 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:36:50.341Z,1543365410.341 [CTD_Seabird](INFO): Powering down
2018-11-28T00:36:51.376Z,1543365411.376 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:36:51.376Z,1543365411.376 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-11-28T00:36:51.376Z,1543365411.376 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:36:51.433Z,1543365411.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:36:51.819Z,1543365411.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:36:51.819Z,1543365411.819 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-11-28T00:36:52.204Z,1543365412.204 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:36:52.204Z,1543365412.204 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:36:52.204Z,1543365412.204 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:36:52.611Z,1543365412.611 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:36:52.611Z,1543365412.611 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:36:53.287Z,1543365413.287 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:36:53.288Z,1543365413.288 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:36:53.370Z,1543365413.370 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:36:53.370Z,1543365413.370 [PNI_TCM] Data Fault, FailCount= 4
2018-11-28T00:36:53.370Z,1543365413.370 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:36:53.415Z,1543365413.415 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:36:53.765Z,1543365413.765 [PNI_TCM](INFO): Powering down
2018-11-28T00:36:54.986Z,1543365414.986 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:36:54.986Z,1543365414.986 [PNI_TCM] No Fault, FailCount= 4
2018-11-28T00:37:00.329Z,1543365420.329 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:37:00.329Z,1543365420.329 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:37:00.329Z,1543365420.329 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:37:00.610Z,1543365420.610 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:37:00.733Z,1543365420.733 [CTD_Seabird](INFO): Powering down
2018-11-28T00:37:02.571Z,1543365422.571 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:37:02.571Z,1543365422.571 [PNI_TCM] Data Fault, FailCount= 5
2018-11-28T00:37:02.571Z,1543365422.571 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:37:02.593Z,1543365422.593 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:37:02.594Z,1543365422.594 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-28T00:37:02.976Z,1543365422.976 [PNI_TCM](INFO): Powering down
2018-11-28T00:37:03.077Z,1543365423.077 [CBIT](INFO): Critical error at 20181128T003702
2018-11-28T00:37:03.080Z,1543365423.080 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:37:03.080Z,1543365423.080 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:37:03.681Z,1543365423.681 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:37:03.682Z,1543365423.682 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:37:06.997Z,1543365426.997 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:37:06.997Z,1543365426.997 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:37:10.725Z,1543365430.725 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:37:10.725Z,1543365430.725 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:37:10.725Z,1543365430.725 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:37:11.005Z,1543365431.005 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:37:11.006Z,1543365431.006 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:37:11.129Z,1543365431.129 [CTD_Seabird](INFO): Powering down
2018-11-28T00:37:17.764Z,1543365437.764 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:37:17.764Z,1543365437.764 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:37:17.765Z,1543365437.765 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:37:17.811Z,1543365437.811 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:37:18.293Z,1543365438.293 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:37:19.000Z,1543365439.000 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:37:19.000Z,1543365439.000 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:37:29.437Z,1543365449.437 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:37:29.437Z,1543365449.437 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:37:29.437Z,1543365449.437 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:37:29.462Z,1543365449.462 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:37:29.957Z,1543365449.957 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:37:30.659Z,1543365450.659 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:37:30.659Z,1543365450.659 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:37:36.859Z,1543365456.859 [CommandLine](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2018-11-28T00:37:36.859Z,1543365456.859 [CommandLine](IMPORTANT): Onboard.Pressure 9.831496 psi
2018-11-28T00:37:41.431Z,1543365461.431 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:37:41.431Z,1543365461.431 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:37:41.431Z,1543365461.431 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:37:41.488Z,1543365461.488 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:37:41.488Z,1543365461.488 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:37:41.949Z,1543365461.949 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:38:17.806Z,1543365497.806 [CommandLine](IMPORTANT): got command get Onboard.Temperature 
2018-11-28T00:38:17.807Z,1543365497.807 [CommandLine](IMPORTANT): Onboard.Temperature 23.332941 degC
2018-11-28T00:38:22.239Z,1543365502.239 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-28T00:38:22.239Z,1543365502.239 [Default:CheckIn:C.Wait] Stopped
2018-11-28T00:38:22.239Z,1543365502.239 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-28T00:38:22.239Z,1543365502.239 [Default:CheckIn:D] Running Loop=1
2018-11-28T00:38:22.638Z,1543365502.638 [Default:CheckIn:D] Stopped
2018-11-28T00:38:22.638Z,1543365502.638 [Default:CheckIn:E] Running Loop=1
2018-11-28T00:38:23.044Z,1543365503.044 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.495866 min 
2018-11-28T00:38:23.046Z,1543365503.046 [Default:CheckIn:E] Stopped
2018-11-28T00:38:23.046Z,1543365503.046 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-28T00:38:23.046Z,1543365503.046 [Default:CheckIn] Stopped
2018-11-28T00:38:23.046Z,1543365503.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-28T00:38:23.046Z,1543365503.046 [Default:CheckIn](INFO): Running loop #2
2018-11-28T00:38:23.046Z,1543365503.046 [Default:CheckIn] Running Loop=2
2018-11-28T00:38:23.047Z,1543365503.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-28T00:38:23.047Z,1543365503.047 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-28T00:38:45.523Z,1543365525.523 [CommandLine](IMPORTANT): got command get Onboard.Humidity
2018-11-28T00:38:45.523Z,1543365525.523 [CommandLine](IMPORTANT): Onboard.Humidity 14.961544 %
2018-11-28T00:38:52.265Z,1543365532.265 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:38:52.265Z,1543365532.265 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-11-28T00:38:52.265Z,1543365532.265 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:38:52.348Z,1543365532.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:38:52.728Z,1543365532.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:38:52.728Z,1543365532.728 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-11-28T00:38:53.029Z,1543365533.029 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:38:53.030Z,1543365533.030 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:38:53.030Z,1543365533.030 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:39:41.010Z,1543365581.010 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:39:41.010Z,1543365581.010 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:39:41.237Z,1543365581.237 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:39:41.238Z,1543365581.238 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:39:47.374Z,1543365587.374 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-28T00:39:47.374Z,1543365587.374 [DropWeight] No Fault, FailCount= 1
2018-11-28T00:39:48.281Z,1543365588.281 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:39:48.281Z,1543365588.281 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:39:48.281Z,1543365588.281 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:39:48.594Z,1543365588.594 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:39:48.685Z,1543365588.685 [CTD_Seabird](INFO): Powering down
2018-11-28T00:39:50.979Z,1543365590.979 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:39:50.979Z,1543365590.979 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:39:51.631Z,1543365591.631 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:39:51.633Z,1543365591.633 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:39:58.673Z,1543365598.673 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:39:58.673Z,1543365598.673 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:39:58.673Z,1543365598.673 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:39:58.978Z,1543365598.978 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:39:59.077Z,1543365599.077 [CTD_Seabird](INFO): Powering down
2018-11-28T00:40:01.373Z,1543365601.373 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:40:01.374Z,1543365601.374 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:40:02.025Z,1543365602.025 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:40:02.027Z,1543365602.027 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:40:09.069Z,1543365609.069 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:40:09.069Z,1543365609.069 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:40:09.069Z,1543365609.069 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:40:09.373Z,1543365609.373 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:40:09.374Z,1543365609.374 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:40:09.473Z,1543365609.473 [CTD_Seabird](INFO): Powering down
2018-11-28T00:40:11.769Z,1543365611.769 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:40:11.769Z,1543365611.769 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:40:22.158Z,1543365622.158 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:40:22.158Z,1543365622.158 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:40:22.158Z,1543365622.158 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:40:22.182Z,1543365622.182 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:40:22.673Z,1543365622.673 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:40:23.376Z,1543365623.376 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:40:23.376Z,1543365623.376 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:40:34.127Z,1543365634.127 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:40:34.127Z,1543365634.127 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:40:34.127Z,1543365634.127 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:40:34.190Z,1543365634.190 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:40:34.648Z,1543365634.648 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:40:35.338Z,1543365635.338 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:40:35.338Z,1543365635.338 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:40:45.716Z,1543365645.716 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:40:45.717Z,1543365645.717 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:40:45.717Z,1543365645.717 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:40:45.742Z,1543365645.742 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:40:45.742Z,1543365645.742 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:40:46.245Z,1543365646.245 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:40:53.330Z,1543365653.330 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:40:53.330Z,1543365653.330 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-11-28T00:40:53.330Z,1543365653.330 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:40:53.343Z,1543365653.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:40:53.741Z,1543365653.741 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:40:53.741Z,1543365653.741 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-11-28T00:40:54.138Z,1543365654.138 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:40:54.139Z,1543365654.139 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:40:54.139Z,1543365654.139 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:42:02.944Z,1543365722.944 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:42:02.944Z,1543365722.944 [PNI_TCM] No Fault, FailCount= 5
2018-11-28T00:42:10.501Z,1543365730.501 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:42:10.502Z,1543365730.502 [PNI_TCM] Data Fault, FailCount= 1
2018-11-28T00:42:10.502Z,1543365730.502 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:42:10.547Z,1543365730.547 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:42:10.893Z,1543365730.893 [PNI_TCM](INFO): Powering down
2018-11-28T00:42:12.150Z,1543365732.150 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:42:12.150Z,1543365732.150 [PNI_TCM] No Fault, FailCount= 1
2018-11-28T00:42:19.694Z,1543365739.694 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:42:19.694Z,1543365739.694 [PNI_TCM] Data Fault, FailCount= 2
2018-11-28T00:42:19.694Z,1543365739.694 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:42:19.756Z,1543365739.756 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:42:20.093Z,1543365740.093 [PNI_TCM](INFO): Powering down
2018-11-28T00:42:21.333Z,1543365741.333 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:42:21.333Z,1543365741.333 [PNI_TCM] No Fault, FailCount= 2
2018-11-28T00:42:28.886Z,1543365748.886 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:42:28.886Z,1543365748.886 [PNI_TCM] Data Fault, FailCount= 3
2018-11-28T00:42:28.886Z,1543365748.886 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:42:28.903Z,1543365748.903 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:42:29.289Z,1543365749.289 [PNI_TCM](INFO): Powering down
2018-11-28T00:42:30.501Z,1543365750.501 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:42:30.501Z,1543365750.501 [PNI_TCM] No Fault, FailCount= 3
2018-11-28T00:42:38.079Z,1543365758.079 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:42:38.079Z,1543365758.079 [PNI_TCM] Data Fault, FailCount= 4
2018-11-28T00:42:38.079Z,1543365758.079 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:42:38.146Z,1543365758.146 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:42:38.469Z,1543365758.469 [PNI_TCM](INFO): Powering down
2018-11-28T00:42:39.316Z,1543365759.316 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:42:39.316Z,1543365759.316 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:42:39.537Z,1543365759.537 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:42:39.538Z,1543365759.538 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:42:39.719Z,1543365759.719 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:42:39.719Z,1543365759.719 [PNI_TCM] No Fault, FailCount= 4
2018-11-28T00:42:46.581Z,1543365766.581 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:42:46.581Z,1543365766.581 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:42:46.581Z,1543365766.581 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:42:46.912Z,1543365766.912 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:42:46.986Z,1543365766.986 [CTD_Seabird](INFO): Powering down
2018-11-28T00:42:47.293Z,1543365767.293 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-28T00:42:47.294Z,1543365767.294 [PNI_TCM] Data Fault, FailCount= 5
2018-11-28T00:42:47.294Z,1543365767.294 [PNI_TCM](ERROR): Data Fault
2018-11-28T00:42:47.321Z,1543365767.321 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-28T00:42:47.321Z,1543365767.321 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-28T00:42:47.670Z,1543365767.670 [PNI_TCM](INFO): Powering down
2018-11-28T00:42:47.772Z,1543365767.772 [CBIT](INFO): Critical error at 20181128T004247
2018-11-28T00:42:49.313Z,1543365769.313 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:42:49.313Z,1543365769.313 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:42:49.933Z,1543365769.933 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:42:49.934Z,1543365769.934 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:42:54.491Z,1543365774.491 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:42:54.491Z,1543365774.491 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-11-28T00:42:54.491Z,1543365774.491 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:42:54.504Z,1543365774.504 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:42:54.901Z,1543365774.901 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:42:54.901Z,1543365774.901 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-11-28T00:42:55.298Z,1543365775.298 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:42:55.298Z,1543365775.298 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:42:55.298Z,1543365775.298 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:42:56.981Z,1543365776.981 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:42:56.981Z,1543365776.981 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:42:56.981Z,1543365776.981 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:42:57.306Z,1543365777.306 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:42:57.385Z,1543365777.385 [CTD_Seabird](INFO): Powering down
2018-11-28T00:42:59.698Z,1543365779.698 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:42:59.698Z,1543365779.698 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:43:00.357Z,1543365780.357 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:43:00.358Z,1543365780.358 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:43:07.405Z,1543365787.405 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:43:07.405Z,1543365787.405 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:43:07.405Z,1543365787.405 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:43:07.716Z,1543365787.716 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:43:07.717Z,1543365787.717 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:43:07.809Z,1543365787.809 [CTD_Seabird](INFO): Powering down
2018-11-28T00:43:15.719Z,1543365795.719 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:43:15.719Z,1543365795.719 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:43:23.304Z,1543365803.304 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-28T00:38:23.0Z
2018-11-28T00:43:23.305Z,1543365803.305 [Default:CheckIn:Read_GPS] Stopped
2018-11-28T00:43:23.305Z,1543365803.305 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-28T00:43:26.038Z,1543365806.038 [CommandLine](IMPORTANT): got command burn 10.000000
2018-11-28T00:43:27.588Z,1543365807.588 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:43:27.588Z,1543365807.588 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:43:27.588Z,1543365807.588 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:43:27.639Z,1543365807.639 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:43:27.673Z,1543365807.673 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20181128T002436/Courier0007.lzma
2018-11-28T00:43:27.825Z,1543365807.825 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:43:28.051Z,1543365808.051 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-28T00:43:28.051Z,1543365808.051 [DropWeight] Hardware Fault, FailCount= 1
2018-11-28T00:43:28.051Z,1543365808.051 [DropWeight](ERROR): Hardware Fault
2018-11-28T00:43:28.135Z,1543365808.135 [CBIT](INFO): Critical error at 20181128T004328
2018-11-28T00:43:28.137Z,1543365808.137 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-28T00:43:28.138Z,1543365808.138 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-28T00:43:28.447Z,1543365808.447 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Courier0007.lzma.bak
2018-11-28T00:43:28.447Z,1543365808.447 [DataOverHttps](INFO): SBD MOMSN=8929273
2018-11-28T00:43:28.532Z,1543365808.532 [CBIT](INFO): Critical error at 20181128T004328
2018-11-28T00:43:28.538Z,1543365808.538 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:43:28.538Z,1543365808.538 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:43:36.057Z,1543365816.057 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-28T00:43:37.219Z,1543365817.219 [DataOverHttps](INFO): Sending 782 bytes from file Logs/20181128T002436/Express0008.lzma
2018-11-28T00:43:37.983Z,1543365817.983 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Express0008.lzma.bak
2018-11-28T00:43:37.983Z,1543365817.983 [DataOverHttps](INFO): SBD MOMSN=8929277
2018-11-28T00:43:38.571Z,1543365818.571 [Default:CheckIn:Read_Iridium] Stopped
2018-11-28T00:43:38.571Z,1543365818.571 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-28T00:43:38.572Z,1543365818.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-28T00:43:41.902Z,1543365821.902 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:43:41.902Z,1543365821.902 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:43:41.902Z,1543365821.902 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:43:41.920Z,1543365821.920 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:43:42.143Z,1543365822.143 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:43:42.785Z,1543365822.785 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:43:42.786Z,1543365822.786 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:43:53.570Z,1543365833.570 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:43:53.570Z,1543365833.570 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:43:53.571Z,1543365833.571 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:43:53.588Z,1543365833.588 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:43:53.589Z,1543365833.589 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:43:54.085Z,1543365834.085 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:44:55.542Z,1543365895.542 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:44:55.542Z,1543365895.542 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-11-28T00:44:55.542Z,1543365895.542 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:44:55.597Z,1543365895.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:44:55.987Z,1543365895.987 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:44:55.988Z,1543365895.988 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-11-28T00:44:56.334Z,1543365896.334 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:44:56.334Z,1543365896.334 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:44:56.334Z,1543365896.334 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:44:59.947Z,1543365899.947 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:44:59.947Z,1543365899.947 [Rowe_600LCM] No Fault, FailCount= 5
2018-11-28T00:45:00.173Z,1543365900.173 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:45:00.173Z,1543365900.173 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:45:00.173Z,1543365900.173 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:45:00.173Z,1543365900.173 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:45:04.346Z,1543365904.346 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:45:04.473Z,1543365904.473 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:45:04.474Z,1543365904.474 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:45:04.474Z,1543365904.474 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:45:04.475Z,1543365904.475 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:45:37.579Z,1543365937.579 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:45:37.579Z,1543365937.579 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:45:37.917Z,1543365937.917 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:45:37.918Z,1543365937.918 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:45:44.961Z,1543365944.961 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:45:44.961Z,1543365944.961 [CTD_Seabird] Communications Fault, FailCount= 1
2018-11-28T00:45:44.961Z,1543365944.961 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:45:45.140Z,1543365945.140 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:45:45.365Z,1543365945.365 [CTD_Seabird](INFO): Powering down
2018-11-28T00:45:47.542Z,1543365947.542 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:45:47.542Z,1543365947.542 [CTD_Seabird] No Fault, FailCount= 1
2018-11-28T00:45:48.321Z,1543365948.321 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:45:48.329Z,1543365948.329 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:45:55.369Z,1543365955.369 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:45:55.369Z,1543365955.369 [CTD_Seabird] Communications Fault, FailCount= 2
2018-11-28T00:45:55.369Z,1543365955.369 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:45:55.577Z,1543365955.577 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:45:55.773Z,1543365955.773 [CTD_Seabird](INFO): Powering down
2018-11-28T00:45:57.937Z,1543365957.937 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:45:57.938Z,1543365957.938 [CTD_Seabird] No Fault, FailCount= 2
2018-11-28T00:45:58.717Z,1543365958.717 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:45:58.719Z,1543365958.719 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:46:05.761Z,1543365965.761 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-11-28T00:46:05.761Z,1543365965.761 [CTD_Seabird] Communications Fault, FailCount= 3
2018-11-28T00:46:05.761Z,1543365965.761 [CTD_Seabird](ERROR): Communications Fault
2018-11-28T00:46:05.938Z,1543365965.938 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-11-28T00:46:05.938Z,1543365965.938 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-11-28T00:46:06.165Z,1543365966.165 [CTD_Seabird](INFO): Powering down
2018-11-28T00:46:11.094Z,1543365971.094 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-11-28T00:46:11.094Z,1543365971.094 [NAL9602] Data Fault, FailCount= 2
2018-11-28T00:46:11.094Z,1543365971.094 [NAL9602](ERROR): Data Fault
2018-11-28T00:46:11.164Z,1543365971.164 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-28T00:46:11.490Z,1543365971.490 [NAL9602](INFO): Powering down
2018-11-28T00:46:12.333Z,1543365972.333 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-28T00:46:12.334Z,1543365972.334 [NAL9602] No Fault, FailCount= 2
2018-11-28T00:46:23.920Z,1543365983.920 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:46:23.920Z,1543365983.920 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:46:34.705Z,1543365994.705 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:46:34.705Z,1543365994.705 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-28T00:46:34.705Z,1543365994.705 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:46:34.758Z,1543365994.758 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:46:35.225Z,1543365995.225 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:46:35.923Z,1543365995.923 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:46:35.923Z,1543365995.923 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-28T00:46:43.002Z,1543366003.002 [NAL9602](INFO): Powering up NAL9602
2018-11-28T00:46:46.671Z,1543366006.671 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:46:46.671Z,1543366006.671 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-28T00:46:46.671Z,1543366006.671 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:46:46.689Z,1543366006.689 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:46:47.181Z,1543366007.181 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:46:47.894Z,1543366007.894 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:46:47.894Z,1543366007.894 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-28T00:46:53.854Z,1543366013.854 [NAL9602](INFO): NAL9602 initialized
2018-11-28T00:46:56.688Z,1543366016.688 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-28T00:46:56.688Z,1543366016.688 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-11-28T00:46:56.688Z,1543366016.688 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:46:56.702Z,1543366016.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:46:57.115Z,1543366017.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:46:57.115Z,1543366017.115 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-11-28T00:46:57.494Z,1543366017.494 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:46:57.494Z,1543366017.494 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:46:57.494Z,1543366017.494 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:46:58.276Z,1543366018.276 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-28T00:46:58.276Z,1543366018.276 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-28T00:46:58.276Z,1543366018.276 [Aanderaa_O2](ERROR): Communications Fault
2018-11-28T00:46:58.346Z,1543366018.346 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-28T00:46:58.346Z,1543366018.346 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-28T00:46:58.789Z,1543366018.789 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:47:00.189Z,1543366020.189 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:47:00.189Z,1543366020.189 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-11-28T00:47:00.189Z,1543366020.189 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:47:00.318Z,1543366020.318 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:47:00.594Z,1543366020.594 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:47:00.739Z,1543366020.739 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:47:02.290Z,1543366022.290 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:47:02.290Z,1543366022.290 [Rowe_600LCM] No Fault, FailCount= 1
2018-11-28T00:47:02.353Z,1543366022.353 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:47:02.353Z,1543366022.353 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:47:02.353Z,1543366022.353 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:47:02.354Z,1543366022.354 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:47:06.476Z,1543366026.476 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:47:06.541Z,1543366026.541 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:47:06.542Z,1543366026.542 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:47:06.543Z,1543366026.543 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:47:06.543Z,1543366026.543 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:47:47.442Z,1543366067.442 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-28T00:47:47.442Z,1543366067.442 [PNI_TCM] No Fault, FailCount= 5
2018-11-28T00:47:51.400Z,1543366071.400 [PNI_TCM](INFO): Failure count cleared after critical for PNI_TCM
2018-11-28T00:48:35.848Z,1543366115.848 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-11-28T00:48:35.848Z,1543366115.848 [CTD_Seabird] No Fault, FailCount= 3
2018-11-28T00:48:35.849Z,1543366115.849 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-11-28T00:48:35.858Z,1543366115.858 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-11-28T00:48:38.946Z,1543366118.946 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2018-11-28T00:48:39.008Z,1543366119.008 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-28T00:48:39.008Z,1543366119.008 [Default:CheckIn:C.Wait] Stopped
2018-11-28T00:48:39.008Z,1543366119.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-28T00:48:39.008Z,1543366119.008 [Default:CheckIn:D] Running Loop=1
2018-11-28T00:48:39.420Z,1543366119.420 [Default:CheckIn:D] Stopped
2018-11-28T00:48:39.420Z,1543366119.420 [Default:CheckIn:E] Running Loop=1
2018-11-28T00:48:39.818Z,1543366119.818 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.775596 min 
2018-11-28T00:48:39.818Z,1543366119.818 [Default:CheckIn:E] Stopped
2018-11-28T00:48:39.818Z,1543366119.818 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-28T00:48:39.818Z,1543366119.818 [Default:CheckIn] Stopped
2018-11-28T00:48:39.818Z,1543366119.818 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-28T00:48:39.819Z,1543366119.819 [Default:CheckIn](INFO): Running loop #3
2018-11-28T00:48:39.819Z,1543366119.819 [Default:CheckIn] Running Loop=3
2018-11-28T00:48:39.819Z,1543366119.819 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-28T00:48:39.819Z,1543366119.819 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-28T00:49:28.451Z,1543366168.451 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-28T00:49:28.451Z,1543366168.451 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-28T00:49:32.161Z,1543366172.161 [Aanderaa_O2](INFO): Powering down
2018-11-28T00:49:57.619Z,1543366197.619 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T00:49:57.619Z,1543366197.619 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-11-28T00:49:57.619Z,1543366197.619 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:49:57.644Z,1543366197.644 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:49:58.072Z,1543366198.072 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:49:58.072Z,1543366198.072 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-11-28T00:49:58.411Z,1543366198.411 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:49:58.412Z,1543366198.412 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:49:58.412Z,1543366198.412 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:51:35.965Z,1543366295.965 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:51:35.965Z,1543366295.965 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-11-28T00:51:35.965Z,1543366295.965 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:51:36.101Z,1543366296.101 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:51:36.379Z,1543366296.379 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:51:36.592Z,1543366296.592 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:51:38.048Z,1543366298.048 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:51:38.048Z,1543366298.048 [Rowe_600LCM] No Fault, FailCount= 2
2018-11-28T00:51:38.209Z,1543366298.209 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:51:38.209Z,1543366298.209 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:51:38.209Z,1543366298.209 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:51:38.209Z,1543366298.209 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:51:42.397Z,1543366302.397 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:51:42.497Z,1543366302.497 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:51:42.498Z,1543366302.498 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:51:42.498Z,1543366302.498 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:51:42.499Z,1543366302.499 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:52:58.682Z,1543366378.682 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T00:52:58.682Z,1543366378.682 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-11-28T00:52:58.682Z,1543366378.682 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:52:58.695Z,1543366378.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:52:59.087Z,1543366379.087 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:52:59.087Z,1543366379.087 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-11-28T00:52:59.452Z,1543366379.452 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:52:59.452Z,1543366379.452 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:52:59.453Z,1543366379.453 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:53:39.894Z,1543366419.894 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-28T00:48:39.8Z
2018-11-28T00:53:39.894Z,1543366419.894 [Default:CheckIn:Read_GPS] Stopped
2018-11-28T00:53:39.894Z,1543366419.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-28T00:53:44.604Z,1543366424.604 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20181128T002436/Courier0010.lzma
2018-11-28T00:53:45.407Z,1543366425.407 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Courier0010.lzma.bak
2018-11-28T00:53:45.407Z,1543366425.407 [DataOverHttps](INFO): SBD MOMSN=8929373
2018-11-28T00:53:55.253Z,1543366435.253 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20181128T002436/Express0011.lzma
2018-11-28T00:53:56.051Z,1543366436.051 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Express0011.lzma.bak
2018-11-28T00:53:56.052Z,1543366436.052 [DataOverHttps](INFO): SBD MOMSN=8929377
2018-11-28T00:53:56.149Z,1543366436.149 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:53:56.149Z,1543366436.149 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-11-28T00:53:56.149Z,1543366436.149 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:53:56.157Z,1543366436.157 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:53:56.553Z,1543366436.553 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:53:56.706Z,1543366436.706 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:53:56.771Z,1543366436.771 [Default:CheckIn:Read_Iridium] Stopped
2018-11-28T00:53:56.771Z,1543366436.771 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-28T00:53:56.771Z,1543366436.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-28T00:54:00.072Z,1543366440.072 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:54:00.072Z,1543366440.072 [Rowe_600LCM] No Fault, FailCount= 3
2018-11-28T00:54:00.341Z,1543366440.341 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:54:00.341Z,1543366440.341 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:54:00.341Z,1543366440.341 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:54:00.342Z,1543366440.342 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:54:04.465Z,1543366444.465 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:54:04.601Z,1543366444.601 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:54:04.602Z,1543366444.602 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:54:04.603Z,1543366444.603 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:54:04.604Z,1543366444.604 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:56:01.305Z,1543366561.305 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T00:56:01.305Z,1543366561.305 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-11-28T00:56:01.305Z,1543366561.305 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:56:01.317Z,1543366561.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:56:01.466Z,1543366561.466 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:56:01.466Z,1543366561.466 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-11-28T00:56:01.786Z,1543366561.786 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:56:01.786Z,1543366561.786 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:56:01.786Z,1543366561.786 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T00:56:55.330Z,1543366615.330 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-11-28T00:56:55.330Z,1543366615.330 [NAL9602] Data Fault, FailCount= 3
2018-11-28T00:56:55.330Z,1543366615.330 [NAL9602](ERROR): Data Fault
2018-11-28T00:56:55.416Z,1543366615.416 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-28T00:56:55.742Z,1543366615.742 [NAL9602](INFO): Powering down
2018-11-28T00:56:56.625Z,1543366616.625 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-28T00:56:56.625Z,1543366616.625 [NAL9602] No Fault, FailCount= 3
2018-11-28T00:57:25.866Z,1543366645.866 [NAL9602](INFO): Powering up NAL9602
2018-11-28T00:57:30.061Z,1543366650.061 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T00:57:30.061Z,1543366650.061 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-11-28T00:57:30.061Z,1543366650.061 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T00:57:30.322Z,1543366650.322 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T00:57:30.465Z,1543366650.465 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T00:57:30.557Z,1543366650.557 [Rowe_600LCM](INFO): Powering down
2018-11-28T00:57:31.917Z,1543366651.917 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-28T00:57:31.917Z,1543366651.917 [Rowe_600LCM] No Fault, FailCount= 4
2018-11-28T00:57:32.173Z,1543366652.173 [Rowe_600LCM](INFO): Initializing
2018-11-28T00:57:32.173Z,1543366652.173 [Rowe_600LCM](INFO): Checking LCM
2018-11-28T00:57:32.174Z,1543366652.174 [Rowe_600LCM](INFO): LCM OK
2018-11-28T00:57:32.174Z,1543366652.174 [Rowe_600LCM](INFO): Powering up
2018-11-28T00:57:36.410Z,1543366656.410 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-28T00:57:36.475Z,1543366656.475 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-28T00:57:36.476Z,1543366656.476 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-28T00:57:36.477Z,1543366656.477 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-28T00:57:36.478Z,1543366656.478 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-28T00:57:37.067Z,1543366657.067 [NAL9602](INFO): NAL9602 initialized
2018-11-28T00:58:28.108Z,1543366708.108 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-28T00:58:28.108Z,1543366708.108 [DropWeight] No Fault, FailCount= 1
2018-11-28T00:58:57.299Z,1543366737.299 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-28T00:58:57.299Z,1543366737.299 [Default:CheckIn:C.Wait] Stopped
2018-11-28T00:58:57.299Z,1543366737.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-28T00:58:57.299Z,1543366737.299 [Default:CheckIn:D] Running Loop=1
2018-11-28T00:58:57.701Z,1543366737.701 [Default:CheckIn:D] Stopped
2018-11-28T00:58:57.701Z,1543366737.701 [Default:CheckIn:E] Running Loop=1
2018-11-28T00:58:58.095Z,1543366738.095 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.080294 min 
2018-11-28T00:58:58.095Z,1543366738.095 [Default:CheckIn:E] Stopped
2018-11-28T00:58:58.095Z,1543366738.095 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-28T00:58:58.095Z,1543366738.095 [Default:CheckIn] Stopped
2018-11-28T00:58:58.095Z,1543366738.095 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-28T00:58:58.096Z,1543366738.096 [Default:CheckIn](INFO): Running loop #4
2018-11-28T00:58:58.096Z,1543366738.096 [Default:CheckIn] Running Loop=4
2018-11-28T00:58:58.096Z,1543366738.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-28T00:58:58.096Z,1543366738.096 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-28T00:59:01.925Z,1543366741.925 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T00:59:01.925Z,1543366741.925 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-11-28T00:59:01.925Z,1543366741.925 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T00:59:01.985Z,1543366741.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T00:59:02.090Z,1543366742.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T00:59:02.090Z,1543366742.090 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-11-28T00:59:02.472Z,1543366742.472 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T00:59:02.472Z,1543366742.472 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T00:59:02.473Z,1543366742.473 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T01:01:14.305Z,1543366874.305 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-28T01:01:14.305Z,1543366874.305 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-11-28T01:01:14.305Z,1543366874.305 [Rowe_600LCM](ERROR): Communications Fault
2018-11-28T01:01:14.400Z,1543366874.400 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-28T01:01:14.405Z,1543366874.405 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-11-28T01:01:14.717Z,1543366874.717 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-28T01:01:14.920Z,1543366874.920 [Rowe_600LCM](INFO): Powering down
2018-11-28T01:02:02.775Z,1543366922.775 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T01:02:02.775Z,1543366922.775 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-11-28T01:02:02.775Z,1543366922.775 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T01:02:02.812Z,1543366922.812 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T01:02:03.181Z,1543366923.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T01:02:03.181Z,1543366923.181 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-11-28T01:02:03.578Z,1543366923.578 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T01:02:03.579Z,1543366923.579 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T01:02:03.579Z,1543366923.579 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T01:03:58.362Z,1543367038.362 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-28T00:58:58.1Z
2018-11-28T01:03:58.362Z,1543367038.362 [Default:CheckIn:Read_GPS] Stopped
2018-11-28T01:03:58.363Z,1543367038.363 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-28T01:04:02.742Z,1543367042.741 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181128T002436/Courier0013.lzma
2018-11-28T01:04:03.527Z,1543367043.527 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Courier0013.lzma.bak
2018-11-28T01:04:03.527Z,1543367043.527 [DataOverHttps](INFO): SBD MOMSN=8929454
2018-11-28T01:04:12.341Z,1543367052.341 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20181128T002436/Express0014.lzma
2018-11-28T01:04:13.139Z,1543367053.139 [DataOverHttps](INFO): Moved sent file to Logs/20181128T002436/Express0014.lzma.bak
2018-11-28T01:04:13.139Z,1543367053.139 [DataOverHttps](INFO): SBD MOMSN=8929456
2018-11-28T01:04:13.732Z,1543367053.732 [Default:CheckIn:Read_Iridium] Stopped
2018-11-28T01:04:13.732Z,1543367053.732 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-28T01:04:13.732Z,1543367053.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-28T01:05:03.595Z,1543367103.595 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T01:05:03.595Z,1543367103.595 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-11-28T01:05:03.595Z,1543367103.595 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T01:05:03.607Z,1543367103.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T01:05:04.018Z,1543367104.018 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T01:05:04.018Z,1543367104.018 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-11-28T01:05:04.404Z,1543367104.404 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T01:05:04.405Z,1543367104.405 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T01:05:04.405Z,1543367104.405 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T01:07:39.522Z,1543367259.522 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-11-28T01:07:39.523Z,1543367259.523 [NAL9602] Data Fault, FailCount= 4
2018-11-28T01:07:39.523Z,1543367259.523 [NAL9602](ERROR): Data Fault
2018-11-28T01:07:39.600Z,1543367259.600 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-28T01:07:39.922Z,1543367259.922 [NAL9602](INFO): Powering down
2018-11-28T01:07:40.779Z,1543367260.779 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-28T01:07:40.780Z,1543367260.780 [NAL9602] No Fault, FailCount= 4
2018-11-28T01:08:04.760Z,1543367284.760 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-28T01:08:04.761Z,1543367284.761 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-11-28T01:08:04.761Z,1543367284.761 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-28T01:08:04.812Z,1543367284.812 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-28T01:08:05.170Z,1543367285.170 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-28T01:08:05.171Z,1543367285.171 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-11-28T01:08:05.569Z,1543367285.569 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-28T01:08:05.570Z,1543367285.570 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-28T01:08:05.570Z,1543367285.570 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-28T01:08:10.322Z,1543367290.322 [NAL9602](INFO): Powering up NAL9602
2018-11-28T01:08:21.518Z,1543367301.518 [NAL9602](INFO): NAL9602 initialized
2018-11-28T01:09:14.364Z,1543367354.364 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-28T01:09:14.364Z,1543367354.364 [Default:CheckIn:C.Wait] Stopped
2018-11-28T01:09:14.364Z,1543367354.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-28T01:09:14.364Z,1543367354.364 [Default:CheckIn:D] Running Loop=1
2018-11-28T01:09:14.758Z,1543367354.758 [Default:CheckIn:D] Stopped
2018-11-28T01:09:14.758Z,1543367354.758 [Default:CheckIn:E] Running Loop=1
2018-11-28T01:09:15.163Z,1543367355.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.364563 min 
2018-11-28T01:09:15.164Z,1543367355.164 [Default:CheckIn:E] Stopped
2018-11-28T01:09:15.164Z,1543367355.164 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-28T01:09:15.164Z,1543367355.164 [Default:CheckIn] Stopped
2018-11-28T01:09:15.164Z,1543367355.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-28T01:09:15.164Z,1543367355.164 [Default:Ch