2019-03-25T23:57:47.163Z,1553558267.163 [Supervisor](DEBUG): Initializing supervisor. 2019-03-25T23:57:47.166Z,1553558267.166 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-25T23:57:47.166Z,1553558267.166 [SyncHandler](INFO): Protected caller Thread ID is 6093 2019-03-25T23:57:47.167Z,1553558267.167 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-25T23:57:47.168Z,1553558267.168 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-25T23:57:47.168Z,1553558267.168 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6094 2019-03-25T23:57:47.171Z,1553558267.171 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-25T23:57:47.182Z,1553558267.182 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-25T23:57:47.183Z,1553558267.183 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-25T23:57:47.184Z,1553558267.184 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6095 2019-03-25T23:57:47.184Z,1553558267.184 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-25T23:57:47.185Z,1553558267.185 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-25T23:57:47.186Z,1553558267.186 [logger ThreadHandler](INFO): Protected caller Thread ID is 6096 2019-03-25T23:57:47.188Z,1553558267.188 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-25T23:57:47.188Z,1553558267.188 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-25T23:57:47.190Z,1553558267.190 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-25T23:57:47.386Z,1553558267.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-25T23:57:47.387Z,1553558267.387 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-25T23:57:47.467Z,1553558267.467 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-25T23:57:47.906Z,1553558267.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-25T23:57:47.906Z,1553558267.906 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-25T23:57:48.232Z,1553558268.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-25T23:57:48.233Z,1553558268.233 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-25T23:57:48.330Z,1553558268.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-25T23:57:48.330Z,1553558268.330 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-25T23:57:48.628Z,1553558268.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-25T23:57:48.628Z,1553558268.628 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-25T23:57:48.825Z,1553558268.825 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-25T23:57:48.826Z,1553558268.826 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-25T23:57:49.272Z,1553558269.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-25T23:57:49.272Z,1553558269.272 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-25T23:57:49.378Z,1553558269.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-25T23:57:49.378Z,1553558269.378 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-25T23:57:49.481Z,1553558269.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-25T23:57:49.482Z,1553558269.482 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-25T23:57:49.962Z,1553558269.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-25T23:57:49.962Z,1553558269.962 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-25T23:57:50.358Z,1553558270.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-25T23:57:50.359Z,1553558270.359 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-25T23:57:50.567Z,1553558270.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-25T23:57:50.568Z,1553558270.568 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-25T23:57:50.713Z,1553558270.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-25T23:57:50.714Z,1553558270.714 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-25T23:57:50.935Z,1553558270.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-25T23:57:50.937Z,1553558270.937 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-03-25T23:57:50.938Z,1553558270.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-03-25T23:57:51.031Z,1553558271.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-03-25T23:57:51.121Z,1553558271.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-03-25T23:57:51.227Z,1553558271.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-03-25T23:57:51.311Z,1553558271.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-03-25T23:57:51.409Z,1553558271.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-03-25T23:57:51.509Z,1553558271.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-03-25T23:57:51.691Z,1553558271.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-03-25T23:57:51.769Z,1553558271.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-03-25T23:57:51.955Z,1553558271.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-03-25T23:57:52.096Z,1553558272.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-03-25T23:57:52.249Z,1553558272.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-03-25T23:57:52.792Z,1553558272.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-25T23:57:53.057Z,1553558273.057 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2019-03-25T23:57:53.058Z,1553558273.058 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-25T23:57:53.059Z,1553558273.059 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-25T23:57:53.126Z,1553558273.126 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-25T23:57:53.240Z,1553558273.240 [VerticalControl] Loaded 2019-03-25T23:57:53.241Z,1553558273.241 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-25T23:57:53.241Z,1553558273.241 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-25T23:57:53.310Z,1553558273.310 [HorizontalControl] Loaded 2019-03-25T23:57:53.311Z,1553558273.311 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-25T23:57:53.311Z,1553558273.311 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-25T23:57:53.317Z,1553558273.317 [SpeedControl] Loaded 2019-03-25T23:57:53.317Z,1553558273.317 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-25T23:57:53.318Z,1553558273.318 [LoopControl](DEBUG): Construct LoopControl. 2019-03-25T23:57:53.319Z,1553558273.319 [LoopControl] Loaded 2019-03-25T23:57:53.319Z,1553558273.319 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-25T23:57:53.320Z,1553558273.320 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-25T23:57:53.320Z,1553558273.320 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-25T23:57:53.333Z,1553558273.333 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-25T23:57:53.334Z,1553558273.334 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-25T23:57:53.428Z,1553558273.428 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-25T23:57:53.429Z,1553558273.429 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-25T23:57:53.524Z,1553558273.524 [BuoyancyServo] Loaded 2019-03-25T23:57:53.524Z,1553558273.524 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-03-25T23:57:53.535Z,1553558273.535 [ElevatorServo] Loaded 2019-03-25T23:57:53.536Z,1553558273.536 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-25T23:57:53.546Z,1553558273.546 [MassServo] Loaded 2019-03-25T23:57:53.547Z,1553558273.547 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-25T23:57:53.558Z,1553558273.558 [RudderServo] Loaded 2019-03-25T23:57:53.558Z,1553558273.558 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-25T23:57:53.569Z,1553558273.569 [ThrusterServo] Loaded 2019-03-25T23:57:53.569Z,1553558273.569 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-03-25T23:57:53.570Z,1553558273.570 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-25T23:57:53.570Z,1553558273.570 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-25T23:57:53.675Z,1553558273.675 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-25T23:57:53.675Z,1553558273.675 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-25T23:57:53.734Z,1553558273.734 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-25T23:57:53.734Z,1553558273.734 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-25T23:57:53.792Z,1553558273.792 [DeadReckonWithRespectToSeafloor] Loaded 2019-03-25T23:57:53.792Z,1553558273.792 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-03-25T23:57:53.808Z,1553558273.808 [NavChart] Loaded 2019-03-25T23:57:53.808Z,1553558273.808 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-25T23:57:53.812Z,1553558273.812 [UniversalFixResidualReporter] Loaded 2019-03-25T23:57:53.813Z,1553558273.813 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-25T23:57:53.813Z,1553558273.813 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-25T23:57:53.814Z,1553558273.814 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-25T23:57:53.876Z,1553558273.876 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-25T23:57:53.876Z,1553558273.876 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-25T23:57:54.166Z,1553558274.166 [DataOverHttps] Loaded 2019-03-25T23:57:54.167Z,1553558274.167 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-03-25T23:57:54.180Z,1553558274.180 [Depth_Keller] Loaded 2019-03-25T23:57:54.180Z,1553558274.180 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-25T23:57:54.185Z,1553558274.185 [DropWeight] Loaded 2019-03-25T23:57:54.185Z,1553558274.185 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-03-25T23:57:54.282Z,1553558274.282 [NAL9602] Loaded 2019-03-25T23:57:54.282Z,1553558274.282 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-25T23:57:54.298Z,1553558274.298 [Onboard] Loaded 2019-03-25T23:57:54.298Z,1553558274.298 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-25T23:57:54.305Z,1553558274.305 [Radio_Surface] Loaded 2019-03-25T23:57:54.305Z,1553558274.305 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-25T23:57:54.307Z,1553558274.307 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0 2019-03-25T23:57:54.307Z,1553558274.307 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6179 2019-03-25T23:57:54.353Z,1553558274.353 [PNI_TCM] Loaded 2019-03-25T23:57:54.353Z,1553558274.353 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-03-25T23:57:54.412Z,1553558274.412 [Rowe_600LCM] Loaded 2019-03-25T23:57:54.413Z,1553558274.413 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-03-25T23:57:54.414Z,1553558274.414 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0 2019-03-25T23:57:54.414Z,1553558274.414 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6180 2019-03-25T23:57:54.415Z,1553558274.415 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-25T23:57:54.415Z,1553558274.415 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-25T23:57:54.454Z,1553558274.454 [DepthRateCalculator] Loaded 2019-03-25T23:57:54.455Z,1553558274.455 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-25T23:57:54.460Z,1553558274.460 [PitchRateCalculator] Loaded 2019-03-25T23:57:54.460Z,1553558274.460 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-25T23:57:54.472Z,1553558274.472 [SpeedCalculator] Loaded 2019-03-25T23:57:54.472Z,1553558274.472 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-25T23:57:54.493Z,1553558274.493 [TempGradientCalculator] Loaded 2019-03-25T23:57:54.493Z,1553558274.493 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-25T23:57:54.498Z,1553558274.498 [YawRateCalculator] Loaded 2019-03-25T23:57:54.499Z,1553558274.499 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-25T23:57:54.529Z,1553558274.529 [ElevatorOffsetCalculator] Loaded 2019-03-25T23:57:54.529Z,1553558274.529 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-25T23:57:54.530Z,1553558274.530 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-25T23:57:54.530Z,1553558274.530 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-25T23:57:54.700Z,1553558274.700 [Aanderaa_O2] Loaded 2019-03-25T23:57:54.700Z,1553558274.700 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-03-25T23:57:54.783Z,1553558274.783 [CTD_NeilBrown] Loaded 2019-03-25T23:57:54.784Z,1553558274.784 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-03-25T23:57:54.785Z,1553558274.785 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0 2019-03-25T23:57:54.785Z,1553558274.785 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6181 2019-03-25T23:57:54.794Z,1553558274.794 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-03-25T23:57:54.799Z,1553558274.799 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-03-25T23:57:54.801Z,1553558274.801 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-03-25T23:57:54.806Z,1553558274.806 [CTD_Seabird](INFO): created writer for : depth 2019-03-25T23:57:54.807Z,1553558274.807 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-03-25T23:57:54.812Z,1553558274.812 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-03-25T23:57:54.812Z,1553558274.812 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-03-25T23:57:54.817Z,1553558274.817 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-03-25T23:57:54.818Z,1553558274.818 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-03-25T23:57:54.823Z,1553558274.823 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-03-25T23:57:54.824Z,1553558274.824 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-03-25T23:57:54.829Z,1553558274.829 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-03-25T23:57:54.829Z,1553558274.829 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-03-25T23:57:54.834Z,1553558274.834 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-03-25T23:57:54.861Z,1553558274.861 [CTD_Seabird] Loaded 2019-03-25T23:57:54.861Z,1553558274.861 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-03-25T23:57:54.863Z,1553558274.863 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0 2019-03-25T23:57:54.863Z,1553558274.863 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6182 2019-03-25T23:57:54.877Z,1553558274.877 [PAR_Licor] Loaded 2019-03-25T23:57:54.878Z,1553558274.878 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-03-25T23:57:54.926Z,1553558274.926 [WetLabsBB2FL] Loaded 2019-03-25T23:57:54.926Z,1553558274.926 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-03-25T23:57:54.927Z,1553558274.927 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0 2019-03-25T23:57:54.928Z,1553558274.928 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6183 2019-03-25T23:57:54.928Z,1553558274.928 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-25T23:57:54.929Z,1553558274.929 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-25T23:57:54.955Z,1553558274.955 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-25T23:57:54.956Z,1553558274.956 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-25T23:57:55.185Z,1553558275.185 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-25T23:57:55.186Z,1553558275.186 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-25T23:57:55.313Z,1553558275.313 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-25T23:57:55.324Z,1553558275.324 [SBIT] Loaded 2019-03-25T23:57:55.325Z,1553558275.325 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-25T23:57:55.325Z,1553558275.325 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-25T23:57:55.338Z,1553558275.338 [IBIT] Loaded 2019-03-25T23:57:55.338Z,1553558275.338 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-25T23:57:55.342Z,1553558275.342 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-25T23:57:55.725Z,1553558275.725 [CBIT] Loaded 2019-03-25T23:57:55.725Z,1553558275.725 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-25T23:57:55.726Z,1553558275.726 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-25T23:57:55.729Z,1553558275.729 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-25T23:57:55.730Z,1553558275.730 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-25T23:57:55.737Z,1553558275.737 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-25T23:57:55.738Z,1553558275.738 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0 2019-03-25T23:57:55.739Z,1553558275.739 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6184 2019-03-25T23:57:55.743Z,1553558275.743 [Supervisor](INFO): Main Thread ID is 6092 2019-03-25T23:57:55.743Z,1553558275.743 [Supervisor](DEBUG): Running supervisor. 2019-03-25T23:57:55.744Z,1553558275.744 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6185 2019-03-25T23:57:55.746Z,1553558275.746 [controlThread ThreadHandler](INFO): Handler Thread ID is 6186 2019-03-25T23:57:55.747Z,1553558275.747 [controlThread](DEBUG): Initializing ControlThread 2019-03-25T23:57:55.748Z,1553558275.748 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-25T23:57:55.749Z,1553558275.749 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-25T23:57:55.750Z,1553558275.750 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-25T23:57:55.751Z,1553558275.751 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-25T23:57:55.752Z,1553558275.752 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-25T23:57:55.753Z,1553558275.753 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-25T23:57:55.754Z,1553558275.754 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-25T23:57:55.759Z,1553558275.759 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-25T23:57:55.759Z,1553558275.759 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-25T23:57:55.759Z,1553558275.759 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-25T23:57:55.760Z,1553558275.760 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-25T23:57:55.760Z,1553558275.760 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-25T23:57:55.761Z,1553558275.761 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-25T23:57:55.765Z,1553558275.765 [SBIT](INFO): Initialize SBIT Component. 2019-03-25T23:57:55.766Z,1553558275.766 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a 2019-03-25T23:57:55.766Z,1553558275.766 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4 2019-03-25T23:57:55.766Z,1553558275.766 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-25T23:57:55.767Z,1553558275.767 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-03-25T23:57:55.768Z,1553558275.768 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-03-25T23:57:55.769Z,1553558275.769 [IBIT](INFO): Initialize IBIT Component. 2019-03-25T23:57:55.770Z,1553558275.770 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-25T23:57:55.771Z,1553558275.771 [logger ThreadHandler](INFO): Handler Thread ID is 6187 2019-03-25T23:57:55.781Z,1553558275.781 [CBIT](DEBUG): Initialized mux pins. 2019-03-25T23:57:55.781Z,1553558275.781 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-25T23:57:55.789Z,1553558275.789 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6188 2019-03-25T23:57:55.795Z,1553558275.795 [Radio_Surface](INFO): Powering up 2019-03-25T23:57:55.801Z,1553558275.801 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6189 2019-03-25T23:57:55.802Z,1553558275.802 [Rowe_600LCM](INFO): Initializing 2019-03-25T23:57:55.809Z,1553558275.809 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-03-25T23:57:55.809Z,1553558275.809 [CBIT](DEBUG): Initializing heartbeat. 2019-03-25T23:57:55.812Z,1553558275.812 [Rowe_600LCM](INFO): Powering up 2019-03-25T23:57:55.817Z,1553558275.817 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6190 2019-03-25T23:57:55.818Z,1553558275.818 [CTD_NeilBrown](INFO): Powering down 2019-03-25T23:57:55.846Z,1553558275.846 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6191 2019-03-25T23:57:55.846Z,1553558275.846 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-25T23:57:55.853Z,1553558275.853 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6193 2019-03-25T23:57:55.854Z,1553558275.854 [WetLabsBB2FL](INFO): Powering down 2019-03-25T23:57:55.872Z,1553558275.872 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-25T23:57:55.881Z,1553558275.881 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-25T23:57:55.881Z,1553558275.881 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-25T23:57:55.882Z,1553558275.882 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6194 2019-03-25T23:57:55.884Z,1553558275.884 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2019-03-25T23:57:55.906Z,1553558275.906 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-25T23:57:55.908Z,1553558275.908 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2019-03-25T23:57:55.908Z,1553558275.908 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-25T23:57:55.910Z,1553558275.910 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2019-03-25T23:57:55.910Z,1553558275.910 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-25T23:57:55.911Z,1553558275.911 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2019-03-25T23:57:55.911Z,1553558275.911 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-25T23:57:55.913Z,1553558275.913 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2019-03-25T23:57:55.913Z,1553558275.913 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-25T23:57:55.915Z,1553558275.915 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2019-03-25T23:57:55.915Z,1553558275.915 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-25T23:57:55.918Z,1553558275.918 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-25T23:57:55.928Z,1553558275.928 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-25T23:57:55.916Z,1553558275.916 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2019-03-25T23:57:55.929Z,1553558275.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-25T23:57:55.931Z,1553558275.931 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2019-03-25T23:57:55.931Z,1553558275.931 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-25T23:57:55.945Z,1553558275.945 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2019-03-25T23:57:55.955Z,1553558275.955 [MissionManager](DEBUG): 2019-03-25T23:57:55.956Z,1553558275.956 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-25T23:57:56.009Z,1553558276.009 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-25T23:57:56.072Z,1553558276.072 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-25T23:57:56.093Z,1553558276.093 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-25T23:57:56.095Z,1553558276.095 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-25T23:57:56.133Z,1553558276.133 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-25T23:57:56.136Z,1553558276.136 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-25T23:57:56.166Z,1553558276.166 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-25T23:57:56.198Z,1553558276.198 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-03-25T23:57:56.202Z,1553558276.202 [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,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-25T23:57:56.232Z,1553558276.232 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-25T23:57:56.268Z,1553558276.268 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2019-03-25T23:57:56.602Z,1553558276.602 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-25T23:57:56.899Z,1553558276.899 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-03-25T23:57:56.905Z,1553558276.905 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-03-25T23:57:56.935Z,1553558276.935 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-25T23:57:56.940Z,1553558276.940 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-25T23:57:57.015Z,1553558277.015 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-25T23:57:57.017Z,1553558277.017 [MassServo](DEBUG): Initializing MassServo. 2019-03-25T23:57:57.059Z,1553558277.059 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-25T23:57:57.081Z,1553558277.081 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-25T23:57:57.100Z,1553558277.100 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-03-25T23:57:57.101Z,1553558277.101 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-03-25T23:57:57.503Z,1553558277.503 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-03-25T23:57:57.503Z,1553558277.503 [DropWeight] Hardware Fault, FailCount= 1 2019-03-25T23:57:57.504Z,1553558277.504 [DropWeight](ERROR): Hardware Fault 2019-03-25T23:57:58.041Z,1553558278.041 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-03-25T23:57:58.041Z,1553558278.041 [RudderServo](FAULT): Rudder failed to initialize 2019-03-25T23:57:58.041Z,1553558278.041 [RudderServo] Communications Fault, FailCount= 1 2019-03-25T23:57:58.041Z,1553558278.041 [RudderServo](ERROR): Communications Fault 2019-03-25T23:57:58.150Z,1553558278.150 [CommandLine](FAULT): Scheduling is paused 2019-03-25T23:57:58.151Z,1553558278.151 [CBIT](INFO): Critical error at 20190325T235757 2019-03-25T23:57:58.151Z,1553558278.151 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-03-25T23:57:58.154Z,1553558278.154 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-03-25T23:57:58.154Z,1553558278.154 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-03-25T23:57:58.155Z,1553558278.155 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-03-25T23:58:00.220Z,1553558280.220 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-25T23:58:00.384Z,1553558280.384 [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 & 2019-03-25T23:58:08.247Z,1553558288.247 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-25T23:58:08.247Z,1553558288.247 [RudderServo](INFO): Powering down 2019-03-25T23:58:08.299Z,1553558288.299 [CBIT](INFO): Critical error at 20190325T235758 2019-03-25T23:58:08.302Z,1553558288.302 [CBIT](CRITICAL): Environmental Failure. Press:14.711360 PSI. Humidity:30%. Temp:26 C. ABORTING MISSION 2019-03-25T23:58:08.518Z,1553558288.518 [CBIT](INFO): Critical error at 20190325T235808 2019-03-25T23:58:08.750Z,1553558288.750 [Aanderaa_O2](INFO): Powering down 2019-03-25T23:58:08.855Z,1553558288.855 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-25T23:58:08.855Z,1553558288.855 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-25T23:58:08.864Z,1553558288.864 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-25T23:58:08.864Z,1553558288.864 [RudderServo] No Fault, FailCount= 1 2019-03-25T23:58:09.114Z,1553558289.114 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004623 2019-03-25T23:58:13.767Z,1553558293.767 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-25T23:58:15.157Z,1553558295.157 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-25T23:58:15.157Z,1553558295.157 [PNI_TCM] Data Fault, FailCount= 1 2019-03-25T23:58:15.157Z,1553558295.157 [PNI_TCM](ERROR): Data Fault 2019-03-25T23:58:15.235Z,1553558295.235 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-25T23:58:15.542Z,1553558295.542 [PNI_TCM](INFO): Powering down 2019-03-25T23:58:16.815Z,1553558296.815 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-25T23:58:16.815Z,1553558296.815 [PNI_TCM] No Fault, FailCount= 1 2019-03-25T23:58:19.230Z,1553558299.230 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-25T23:58:19.243Z,1553558299.243 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-25T23:58:24.482Z,1553558304.482 [NAL9602](INFO): Powering up NAL9602 2019-03-25T23:58:25.717Z,1553558305.717 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-25T23:58:25.717Z,1553558305.717 [PNI_TCM] Data Fault, FailCount= 2 2019-03-25T23:58:25.717Z,1553558305.717 [PNI_TCM](ERROR): Data Fault 2019-03-25T23:58:25.843Z,1553558305.843 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-25T23:58:26.097Z,1553558306.097 [PNI_TCM](INFO): Powering down 2019-03-25T23:58:27.419Z,1553558307.419 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-25T23:58:27.419Z,1553558307.419 [PNI_TCM] No Fault, FailCount= 2 2019-03-25T23:58:30.210Z,1553558310.210 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.006829 CHAN A1 (24V): -0.132850 CHAN A2 (12V): -0.002603 CHAN A3 (5V): 4.753929 CHAN B0 (3.3V): 0.001965 CHAN B1 (3.15aV): -0.000395 CHAN B2 (3.15bV): -0.000582 CHAN B3 (GND): 0.000024 OPEN: -0.000124 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-25T23:58:35.566Z,1553558315.566 [NAL9602](INFO): NAL9602 initialized 2019-03-25T23:58:36.049Z,1553558316.049 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-25T23:58:36.049Z,1553558316.049 [PNI_TCM] Data Fault, FailCount= 3 2019-03-25T23:58:36.049Z,1553558316.049 [PNI_TCM](ERROR): Data Fault 2019-03-25T23:58:36.182Z,1553558316.182 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-25T23:58:36.377Z,1553558316.377 [NAL9602](DEBUG): Fix Requested 2019-03-25T23:58:36.393Z,1553558316.393 [PNI_TCM](INFO): Powering down 2019-03-25T23:58:37.742Z,1553558317.742 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-25T23:58:37.742Z,1553558317.742 [PNI_TCM] No Fault, FailCount= 3 2019-03-25T23:58:46.393Z,1553558326.393 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-25T23:58:46.393Z,1553558326.393 [PNI_TCM] Data Fault, FailCount= 4 2019-03-25T23:58:46.393Z,1553558326.393 [PNI_TCM](ERROR): Data Fault 2019-03-25T23:58:46.496Z,1553558326.496 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-25T23:58:46.776Z,1553558326.776 [PNI_TCM](INFO): Powering down 2019-03-25T23:58:48.111Z,1553558328.111 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-25T23:58:48.111Z,1553558328.111 [PNI_TCM] No Fault, FailCount= 4 2019-03-25T23:58:51.846Z,1553558331.846 [NavChartDb](INFO): # of records loaded: 5000 2019-03-25T23:58:56.805Z,1553558336.805 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-25T23:58:56.805Z,1553558336.805 [PNI_TCM] Data Fault, FailCount= 5 2019-03-25T23:58:56.805Z,1553558336.805 [PNI_TCM](ERROR): Data Fault 2019-03-25T23:58:56.929Z,1553558336.929 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-25T23:58:56.930Z,1553558336.930 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-25T23:59:05.256Z,1553558345.256 [PNI_TCM](INFO): Powering down 2019-03-25T23:59:05.348Z,1553558345.348 [CBIT](INFO): Critical error at 20190325T235856 2019-03-25T23:59:19.195Z,1553558359.195 [SBIT](IMPORTANT): SBIT PASSED 2019-03-25T23:59:19.285Z,1553558359.285 [CommandLine](IMPORTANT): got command configSet list 2019-03-25T23:59:19.285Z,1553558359.285 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-25T23:59:19.286Z,1553558359.286 [CommandLine](IMPORTANT): No configSet variables persisted 2019-03-25T23:59:19.609Z,1553558359.609 [MissionManager](IMPORTANT): Started mission Startup 2019-03-25T23:59:19.609Z,1553558359.609 [Startup] Running Loop=1 2019-03-25T23:59:19.609Z,1553558359.609 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-25T23:59:19.609Z,1553558359.609 [Startup:A.GoToSurface] Running Loop=1 2019-03-25T23:59:19.609Z,1553558359.609 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-25T23:59:19.610Z,1553558359.610 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-25T23:59:19.610Z,1553558359.610 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-25T23:59:19.611Z,1553558359.611 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-25T23:59:19.611Z,1553558359.611 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-25T23:59:19.612Z,1553558359.612 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-25T23:59:19.630Z,1553558359.630 [Startup:StartupSatComms] Running Loop=1 2019-03-25T23:59:19.630Z,1553558359.630 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-03-25T23:59:19.630Z,1553558359.630 [Startup:StartupSatComms:A] Running Loop=1 2019-03-25T23:59:19.984Z,1553558359.984 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-03-25T23:59:49.448Z,1553558389.448 [NavChartDb](INFO): # of records loaded: 10000 2019-03-25T23:59:55.958Z,1553558395.958 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-25T23:59:55.958Z,1553558395.958 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-25T23:59:55.958Z,1553558395.958 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-25T23:59:55.960Z,1553558395.960 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-25T23:59:55.960Z,1553558395.960 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-25T23:59:55.960Z,1553558395.960 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-25T23:59:55.994Z,1553558395.994 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-25T23:59:55.994Z,1553558395.994 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-03-25T23:59:55.994Z,1553558395.994 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-25T23:59:56.048Z,1553558396.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-25T23:59:56.048Z,1553558396.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-25T23:59:56.048Z,1553558396.048 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-25T23:59:56.109Z,1553558396.109 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-25T23:59:56.109Z,1553558396.109 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-03-25T23:59:56.109Z,1553558396.109 [Rowe_600LCM](ERROR): Communications Fault 2019-03-25T23:59:56.459Z,1553558396.459 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-25T23:59:56.459Z,1553558396.459 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-25T23:59:56.460Z,1553558396.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-25T23:59:56.460Z,1553558396.460 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-25T23:59:56.460Z,1553558396.460 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-25T23:59:56.461Z,1553558396.461 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-03-25T23:59:56.461Z,1553558396.461 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-25T23:59:56.513Z,1553558396.513 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-25T23:59:56.775Z,1553558396.775 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-25T23:59:56.859Z,1553558396.859 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:00:00.237Z,1553558400.237 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-26T00:00:02.929Z,1553558402.929 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:00:02.929Z,1553558402.929 [Rowe_600LCM] No Fault, FailCount= 1 2019-03-26T00:00:03.343Z,1553558403.343 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:00:03.343Z,1553558403.343 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:00:04.698Z,1553558404.698 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:00:06.866Z,1553558406.866 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-26T00:00:07.572Z,1553558407.572 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:00:07.668Z,1553558407.668 [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 & 2019-03-26T00:00:19.795Z,1553558419.795 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-25T23:59:19.6Z 2019-03-26T00:00:19.796Z,1553558419.796 [Startup:StartupSatComms:A] Stopped 2019-03-26T00:00:19.796Z,1553558419.796 [Startup:StartupSatComms:B] Running Loop=1 2019-03-26T00:00:20.195Z,1553558420.195 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-26T00:00:26.274Z,1553558426.274 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20190228T184937/Courier0004.lzma 2019-03-26T00:00:27.074Z,1553558427.074 [DataOverHttps](INFO): Moved sent file to Logs/20190228T184937/Courier0004.lzma.bak 2019-03-26T00:00:27.074Z,1553558427.074 [DataOverHttps](INFO): SBD MOMSN=10244400 2019-03-26T00:00:42.974Z,1553558442.974 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190325T234304/Courier0000.lzma 2019-03-26T00:00:43.754Z,1553558443.754 [DataOverHttps](INFO): Moved sent file to Logs/20190325T234304/Courier0000.lzma.bak 2019-03-26T00:00:43.754Z,1553558443.754 [DataOverHttps](INFO): SBD MOMSN=10244403 2019-03-26T00:00:53.920Z,1553558453.920 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-26T00:00:53.991Z,1553558453.991 [NavChartDb](INFO): # of records loaded: 19732 2019-03-26T00:00:54.007Z,1553558454.007 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-26T00:00:54.073Z,1553558454.073 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-26T00:01:03.412Z,1553558463.412 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20190325T235747/Courier0000.lzma 2019-03-26T00:01:04.190Z,1553558464.190 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Courier0000.lzma.bak 2019-03-26T00:01:04.190Z,1553558464.190 [DataOverHttps](INFO): SBD MOMSN=10244409 2019-03-26T00:01:05.391Z,1553558465.391 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-26T00:01:24.371Z,1553558484.371 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-26T00:00:19.8Z 2019-03-26T00:01:24.371Z,1553558484.371 [Startup:StartupSatComms:B] Stopped 2019-03-26T00:01:24.372Z,1553558484.372 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-03-26T00:01:24.372Z,1553558484.372 [Startup:StartupSatComms] Stopped 2019-03-26T00:01:24.372Z,1553558484.372 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-03-26T00:01:24.381Z,1553558484.381 [Startup](INFO): Completed Startup 2019-03-26T00:01:24.381Z,1553558484.381 [MissionManager](INFO): Startup is completed. 2019-03-26T00:01:24.382Z,1553558484.382 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-26T00:01:24.382Z,1553558484.382 [Startup] Stopped 2019-03-26T00:01:24.382Z,1553558484.382 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-26T00:01:24.382Z,1553558484.382 [Startup:A.GoToSurface] Stopped 2019-03-26T00:01:24.382Z,1553558484.382 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-26T00:01:24.474Z,1553558484.474 [DataOverHttps](INFO): Sending 575 bytes from file Logs/20190222T010341/Express0005.lzma 2019-03-26T00:01:24.591Z,1553558484.591 [MissionManager](IMPORTANT): Started mission Default 2019-03-26T00:01:24.591Z,1553558484.591 [Default] Running Loop=1 2019-03-26T00:01:24.591Z,1553558484.591 [Default](DEBUG): Aggregate::initialize Default 2019-03-26T00:01:24.591Z,1553558484.591 [Default:B.GoToSurface] Running Loop=1 2019-03-26T00:01:24.592Z,1553558484.592 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-26T00:01:24.592Z,1553558484.592 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-26T00:01:24.592Z,1553558484.592 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-26T00:01:24.601Z,1553558484.601 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-26T00:01:24.601Z,1553558484.601 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-26T00:01:24.602Z,1553558484.602 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-26T00:01:24.602Z,1553558484.602 [Default:A.Wait] Running Loop=1 2019-03-26T00:01:24.602Z,1553558484.602 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:01:25.258Z,1553558485.258 [DataOverHttps](INFO): Moved sent file to Logs/20190222T010341/Express0005.lzma.bak 2019-03-26T00:01:25.258Z,1553558485.258 [DataOverHttps](INFO): SBD MOMSN=10244417 2019-03-26T00:01:33.741Z,1553558493.741 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:01:37.840Z,1553558497.840 [Default:A.Wait](INFO): Done Waiting. 2019-03-26T00:01:37.840Z,1553558497.840 [Default:A.Wait] Stopped 2019-03-26T00:01:37.840Z,1553558497.840 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:01:38.225Z,1553558498.225 [Default:CheckIn] Running Loop=1 2019-03-26T00:01:38.225Z,1553558498.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-26T00:01:38.225Z,1553558498.225 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-26T00:01:38.632Z,1553558498.632 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-03-26T00:01:57.022Z,1553558517.022 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:01:57.022Z,1553558517.022 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-26T00:01:57.022Z,1553558517.022 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:01:57.024Z,1553558517.024 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:01:57.024Z,1553558517.024 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-26T00:01:57.024Z,1553558517.024 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:01:57.065Z,1553558517.065 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:01:57.066Z,1553558517.066 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-03-26T00:01:57.066Z,1553558517.066 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:01:57.158Z,1553558517.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:01:57.159Z,1553558517.159 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:01:57.159Z,1553558517.159 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:01:57.501Z,1553558517.501 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:01:57.501Z,1553558517.501 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-26T00:01:57.502Z,1553558517.502 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:01:57.502Z,1553558517.502 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-26T00:01:57.502Z,1553558517.502 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:01:57.503Z,1553558517.503 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-03-26T00:01:57.810Z,1553558517.810 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:01:57.896Z,1553558517.896 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:02:03.917Z,1553558523.917 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:02:03.917Z,1553558523.917 [Rowe_600LCM] Communications Fault, FailCount= 2 2019-03-26T00:02:03.917Z,1553558523.917 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:02:04.279Z,1553558524.279 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:02:04.321Z,1553558524.321 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:02:04.513Z,1553558524.513 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:02:05.844Z,1553558525.844 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:02:05.844Z,1553558525.844 [Rowe_600LCM] No Fault, FailCount= 2 2019-03-26T00:02:06.141Z,1553558526.141 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:02:06.142Z,1553558526.142 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:02:10.415Z,1553558530.415 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:02:10.521Z,1553558530.521 [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 & 2019-03-26T00:02:13.617Z,1553558533.617 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:02:27.012Z,1553558547.012 [NavChartDb](INFO): # of records loaded: 20000 2019-03-26T00:02:48.789Z,1553558568.789 [NavChartDb](INFO): # of records loaded: 25000 2019-03-26T00:03:02.728Z,1553558582.728 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-26T00:03:11.899Z,1553558591.899 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-26T00:03:12.471Z,1553558592.471 [NavChartDb](INFO): # of records loaded: 30000 2019-03-26T00:03:38.150Z,1553558618.150 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-03-26T00:03:38.965Z,1553558618.965 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:42.153Z,1553558622.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:44.993Z,1553558624.993 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:48.154Z,1553558628.154 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:48.516Z,1553558628.516 [NavChartDb](INFO): # of records loaded: 35000 2019-03-26T00:03:50.953Z,1553558630.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:54.161Z,1553558634.161 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:56.953Z,1553558636.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:03:57.041Z,1553558637.041 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:03:57.041Z,1553558637.041 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:03:57.830Z,1553558637.830 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:03:57.830Z,1553558637.830 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-26T00:03:57.830Z,1553558637.830 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:03:57.832Z,1553558637.832 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:03:57.832Z,1553558637.832 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-26T00:03:57.832Z,1553558637.832 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:03:57.838Z,1553558637.838 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:03:57.838Z,1553558637.838 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-03-26T00:03:57.838Z,1553558637.838 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:03:57.909Z,1553558637.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:03:57.909Z,1553558637.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:03:57.909Z,1553558637.909 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:03:58.239Z,1553558638.239 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:03:58.239Z,1553558638.239 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-26T00:03:58.240Z,1553558638.240 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:03:58.240Z,1553558638.240 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-03-26T00:03:58.240Z,1553558638.240 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:03:58.240Z,1553558638.240 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-03-26T00:03:58.592Z,1553558638.592 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:04:00.153Z,1553558640.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:02.961Z,1553558642.961 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:05.793Z,1553558645.793 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:04:05.793Z,1553558645.793 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:04:05.793Z,1553558645.793 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:04:05.921Z,1553558645.921 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:04:06.157Z,1553558646.157 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:06.181Z,1553558646.181 [PNI_TCM](INFO): Powering down 2019-03-26T00:04:06.209Z,1553558646.209 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:04:06.209Z,1553558646.209 [Rowe_600LCM] Communications Fault, FailCount= 3 2019-03-26T00:04:06.209Z,1553558646.209 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:04:06.315Z,1553558646.315 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:04:06.613Z,1553558646.613 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:04:06.833Z,1553558646.833 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:04:07.424Z,1553558647.424 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:04:07.424Z,1553558647.424 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:04:08.249Z,1553558648.249 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:04:08.250Z,1553558648.250 [Rowe_600LCM] No Fault, FailCount= 3 2019-03-26T00:04:08.457Z,1553558648.457 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:04:08.457Z,1553558648.457 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:04:08.953Z,1553558648.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:10.803Z,1553558650.803 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-26T00:04:10.838Z,1553558650.838 [NavChartDb](INFO): # of records loaded: 37376 2019-03-26T00:04:10.848Z,1553558650.848 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-26T00:04:10.901Z,1553558650.901 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-26T00:04:12.153Z,1553558652.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:12.747Z,1553558652.747 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:04:12.848Z,1553558652.848 [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 & 2019-03-26T00:04:14.953Z,1553558654.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:16.177Z,1553558656.177 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:04:16.177Z,1553558656.177 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:04:16.177Z,1553558656.177 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:04:16.197Z,1553558656.197 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:04:16.558Z,1553558656.558 [PNI_TCM](INFO): Powering down 2019-03-26T00:04:16.953Z,1553558656.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:17.607Z,1553558657.607 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-26T00:04:17.816Z,1553558657.816 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:04:17.816Z,1553558657.816 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:04:18.957Z,1553558658.957 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:20.953Z,1553558660.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:22.953Z,1553558662.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:24.953Z,1553558664.953 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:26.577Z,1553558666.577 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:04:26.577Z,1553558666.577 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:04:26.577Z,1553558666.577 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:04:26.666Z,1553558666.666 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:04:31.642Z,1553558671.642 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:31.696Z,1553558671.696 [PNI_TCM](INFO): Powering down 2019-03-26T00:04:33.025Z,1553558673.025 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:04:33.025Z,1553558673.025 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:04:34.149Z,1553558674.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:36.484Z,1553558676.484 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:04:36.949Z,1553558676.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:38.949Z,1553558678.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:40.949Z,1553558680.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:41.793Z,1553558681.793 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:04:41.793Z,1553558681.793 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:04:41.793Z,1553558681.793 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:04:41.894Z,1553558681.894 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:04:42.154Z,1553558682.154 [PNI_TCM](INFO): Powering down 2019-03-26T00:04:42.949Z,1553558682.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:43.430Z,1553558683.430 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:04:43.430Z,1553558683.430 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:04:44.949Z,1553558684.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:46.436Z,1553558686.436 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:04:46.949Z,1553558686.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:48.949Z,1553558688.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:50.949Z,1553558690.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:52.177Z,1553558692.177 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:04:52.177Z,1553558692.177 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:04:52.177Z,1553558692.177 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:04:52.279Z,1553558692.279 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:04:52.280Z,1553558692.280 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:04:52.566Z,1553558692.566 [PNI_TCM](INFO): Powering down 2019-03-26T00:04:52.634Z,1553558692.634 [CBIT](INFO): Critical error at 20190326T000452 2019-03-26T00:04:53.349Z,1553558693.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:56.149Z,1553558696.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:56.714Z,1553558696.714 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-26T00:04:58.624Z,1553558698.624 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-26T00:04:59.349Z,1553558699.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:00.284Z,1553558700.284 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:05:02.149Z,1553558702.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:05.349Z,1553558705.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:08.150Z,1553558708.150 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:11.377Z,1553558711.377 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:13.478Z,1553558713.478 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-26T00:05:13.612Z,1553558713.612 [NavChartDb](INFO): # of records loaded: 16998 2019-03-26T00:05:13.618Z,1553558713.618 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-26T00:05:13.619Z,1553558713.619 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-26T00:05:14.165Z,1553558714.165 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:17.369Z,1553558717.369 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:19.050Z,1553558719.050 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-26T00:05:20.149Z,1553558720.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:23.357Z,1553558723.357 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:26.149Z,1553558726.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:33.089Z,1553558733.089 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:34.643Z,1553558734.643 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:05:35.069Z,1553558735.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:37.069Z,1553558737.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:39.069Z,1553558739.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:41.069Z,1553558741.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:42.904Z,1553558742.904 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:05:43.073Z,1553558743.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:45.069Z,1553558745.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:47.069Z,1553558747.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:49.069Z,1553558749.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:50.437Z,1553558750.437 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:05:51.069Z,1553558751.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:53.069Z,1553558753.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:55.069Z,1553558755.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:57.069Z,1553558757.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:58.432Z,1553558758.432 [NavChartDb](INFO): # of records loaded: 20000 2019-03-26T00:05:58.706Z,1553558758.706 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:05:58.706Z,1553558758.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-03-26T00:05:58.706Z,1553558758.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:05:58.707Z,1553558758.707 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:05:58.707Z,1553558758.707 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2019-03-26T00:05:58.708Z,1553558758.708 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:05:58.709Z,1553558758.709 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:05:58.709Z,1553558758.709 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2019-03-26T00:05:58.709Z,1553558758.709 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:05:58.762Z,1553558758.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:05:58.762Z,1553558758.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:05:58.762Z,1553558758.762 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:05:59.069Z,1553558759.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:05:59.136Z,1553558759.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:05:59.136Z,1553558759.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-03-26T00:05:59.157Z,1553558759.157 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:05:59.157Z,1553558759.157 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2019-03-26T00:05:59.157Z,1553558759.157 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:05:59.157Z,1553558759.157 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2019-03-26T00:05:59.523Z,1553558759.523 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:06:01.073Z,1553558761.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:03.075Z,1553558763.075 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:05.069Z,1553558765.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:06.100Z,1553558766.100 [NavChartDb](INFO): # of records loaded: 25000 2019-03-26T00:06:07.069Z,1553558767.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:09.069Z,1553558769.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:09.185Z,1553558769.185 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:06:09.185Z,1553558769.185 [Rowe_600LCM] Communications Fault, FailCount= 4 2019-03-26T00:06:09.185Z,1553558769.185 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:06:09.570Z,1553558769.570 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:06:09.597Z,1553558769.597 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:06:09.760Z,1553558769.760 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:06:11.140Z,1553558771.140 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:06:11.140Z,1553558771.140 [Rowe_600LCM] No Fault, FailCount= 4 2019-03-26T00:06:11.378Z,1553558771.378 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:06:11.379Z,1553558771.379 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:06:11.469Z,1553558771.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:13.842Z,1553558773.842 [NavChartDb](INFO): # of records loaded: 30000 2019-03-26T00:06:14.281Z,1553558774.281 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:15.658Z,1553558775.658 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:06:15.756Z,1553558775.756 [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 & 2019-03-26T00:06:17.469Z,1553558777.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:20.269Z,1553558780.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:23.469Z,1553558783.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:24.231Z,1553558784.231 [NavChartDb](INFO): # of records loaded: 35000 2019-03-26T00:06:26.269Z,1553558786.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:33.305Z,1553558793.305 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:35.962Z,1553558795.962 [NavChartDb](INFO): # of records loaded: 40000 2019-03-26T00:06:36.105Z,1553558796.105 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:38.505Z,1553558798.505 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:38.549Z,1553558798.549 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T00:01:38.2Z 2019-03-26T00:06:38.549Z,1553558798.549 [Default:CheckIn:Read_GPS] Stopped 2019-03-26T00:06:38.549Z,1553558798.549 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-26T00:06:38.965Z,1553558798.965 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-26T00:06:47.241Z,1553558807.241 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190325T235747/Courier0004.lzma 2019-03-26T00:06:47.649Z,1553558807.649 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:06:48.034Z,1553558808.034 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Courier0004.lzma.bak 2019-03-26T00:06:48.034Z,1553558808.034 [DataOverHttps](INFO): SBD MOMSN=10244551 2019-03-26T00:06:49.123Z,1553558809.123 [NavChartDb](INFO): # of records loaded: 45000 2019-03-26T00:06:58.689Z,1553558818.689 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:07:07.335Z,1553558827.335 [NavChartDb](INFO): # of records loaded: 50000 2019-03-26T00:07:08.971Z,1553558828.971 [DataOverHttps](INFO): Sending 1195 bytes from file Logs/20190223T041005/Express0001.lzma 2019-03-26T00:07:09.349Z,1553558829.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:07:09.730Z,1553558829.730 [DataOverHttps](INFO): Moved sent file to Logs/20190223T041005/Express0001.lzma.bak 2019-03-26T00:07:09.730Z,1553558829.730 [DataOverHttps](INFO): SBD MOMSN=10244560 2019-03-26T00:07:20.177Z,1553558840.177 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:07:29.258Z,1553558849.258 [NavChartDb](INFO): # of records loaded: 55000 2019-03-26T00:07:30.163Z,1553558850.163 [DataOverHttps](INFO): Sending 564 bytes from file Logs/20190223T041005/Express0005.lzma 2019-03-26T00:07:30.537Z,1553558850.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:07:30.918Z,1553558850.918 [DataOverHttps](INFO): Moved sent file to Logs/20190223T041005/Express0005.lzma.bak 2019-03-26T00:07:30.918Z,1553558850.918 [DataOverHttps](INFO): SBD MOMSN=10244825 2019-03-26T00:07:32.825Z,1553558852.825 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-26T00:07:41.426Z,1553558861.426 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:07:41.643Z,1553558861.643 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-26T00:07:51.137Z,1553558871.137 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190223T041005/Express0008.lzma 2019-03-26T00:07:51.533Z,1553558871.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:07:51.899Z,1553558871.899 [NavChartDb](INFO): # of records loaded: 60000 2019-03-26T00:07:51.918Z,1553558871.918 [DataOverHttps](INFO): Moved sent file to Logs/20190223T041005/Express0008.lzma.bak 2019-03-26T00:07:51.918Z,1553558871.918 [DataOverHttps](INFO): SBD MOMSN=10244864 2019-03-26T00:08:01.811Z,1553558881.811 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:08:01.811Z,1553558881.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-03-26T00:08:01.812Z,1553558881.812 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:08:01.821Z,1553558881.821 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:08:01.821Z,1553558881.821 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2019-03-26T00:08:01.821Z,1553558881.821 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:08:01.823Z,1553558881.823 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:08:01.823Z,1553558881.823 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2019-03-26T00:08:01.823Z,1553558881.823 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:08:01.914Z,1553558881.914 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:08:01.914Z,1553558881.914 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:08:01.915Z,1553558881.915 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:08:02.091Z,1553558882.091 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:08:02.091Z,1553558882.091 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-03-26T00:08:02.092Z,1553558882.092 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:08:02.092Z,1553558882.092 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2019-03-26T00:08:02.092Z,1553558882.092 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:08:02.092Z,1553558882.092 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2019-03-26T00:08:02.385Z,1553558882.385 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:08:02.422Z,1553558882.422 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:08:12.001Z,1553558892.001 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:08:12.001Z,1553558892.001 [Rowe_600LCM] Communications Fault, FailCount= 5 2019-03-26T00:08:12.001Z,1553558892.001 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:08:12.198Z,1553558892.198 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:08:12.199Z,1553558892.199 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2019-03-26T00:08:12.228Z,1553558892.228 [DataOverHttps](INFO): Sending 2257 bytes from file Logs/20190228T184937/Express0001.lzma 2019-03-26T00:08:12.405Z,1553558892.405 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:08:12.637Z,1553558892.637 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:08:12.677Z,1553558892.677 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:08:12.865Z,1553558892.865 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-26T00:08:12.991Z,1553558892.991 [NavChartDb](INFO): # of records loaded: 62026 2019-03-26T00:08:12.999Z,1553558892.999 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-26T00:08:12.000Z,1553558893.000 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-26T00:08:13.014Z,1553558893.014 [DataOverHttps](INFO): Moved sent file to Logs/20190228T184937/Express0001.lzma.bak 2019-03-26T00:08:13.014Z,1553558893.014 [DataOverHttps](INFO): SBD MOMSN=10244867 2019-03-26T00:08:20.209Z,1553558900.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:08:22.225Z,1553558902.225 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-26T00:08:28.140Z,1553558908.140 [DataOverHttps](INFO): Sending 960 bytes from file Logs/20190228T184937/Express0005.lzma 2019-03-26T00:08:28.553Z,1553558908.553 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:08:28.938Z,1553558908.938 [DataOverHttps](INFO): Moved sent file to Logs/20190228T184937/Express0005.lzma.bak 2019-03-26T00:08:28.938Z,1553558908.938 [DataOverHttps](INFO): SBD MOMSN=10245009 2019-03-26T00:08:36.201Z,1553558916.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:08:36.582Z,1553558916.582 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-03-26T00:08:36.582Z,1553558916.582 [NAL9602] Data Fault, FailCount= 1 2019-03-26T00:08:36.582Z,1553558916.582 [NAL9602](ERROR): Data Fault 2019-03-26T00:08:36.700Z,1553558916.700 [CBIT](ERROR): Data Fault in component: NAL9602 2019-03-26T00:08:36.983Z,1553558916.983 [NAL9602](INFO): Powering down 2019-03-26T00:08:37.706Z,1553558917.706 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:08:43.172Z,1553558923.172 [CBIT](INFO): Clearing failed state for component NAL9602 2019-03-26T00:08:43.172Z,1553558923.172 [NAL9602] No Fault, FailCount= 1 2019-03-26T00:08:43.222Z,1553558923.222 [DataOverHttps](INFO): Sending 581 bytes from file Logs/20190325T234304/Express0001.lzma 2019-03-26T00:08:43.982Z,1553558923.982 [DataOverHttps](INFO): Moved sent file to Logs/20190325T234304/Express0001.lzma.bak 2019-03-26T00:08:43.982Z,1553558923.982 [DataOverHttps](INFO): SBD MOMSN=10245048 2019-03-26T00:08:50.856Z,1553558930.856 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:08:58.281Z,1553558938.281 [DataOverHttps](INFO): Sending 1077 bytes from file Logs/20190325T235747/Express0001.lzma 2019-03-26T00:08:59.050Z,1553558939.050 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Express0001.lzma.bak 2019-03-26T00:08:59.050Z,1553558939.050 [DataOverHttps](INFO): SBD MOMSN=10245066 2019-03-26T00:09:02.584Z,1553558942.584 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:09:07.362Z,1553558947.362 [NAL9602](INFO): Powering up NAL9602 2019-03-26T00:09:13.348Z,1553558953.348 [DataOverHttps](INFO): Sending 470 bytes from file Logs/20190325T235747/Express0005.lzma 2019-03-26T00:09:14.098Z,1553558954.098 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Express0005.lzma.bak 2019-03-26T00:09:14.098Z,1553558954.098 [DataOverHttps](INFO): SBD MOMSN=10245099 2019-03-26T00:09:14.916Z,1553558954.916 [Default:CheckIn:Read_Iridium] Stopped 2019-03-26T00:09:14.916Z,1553558954.916 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-26T00:09:14.916Z,1553558954.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:09:15.235Z,1553558955.235 [NavChartDb](INFO): # of records loaded: 20000 2019-03-26T00:09:20.926Z,1553558960.926 [NAL9602](INFO): NAL9602 initialized 2019-03-26T00:09:24.537Z,1553558964.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:26.493Z,1553558966.493 [NavChartDb](INFO): # of records loaded: 25000 2019-03-26T00:09:27.337Z,1553558967.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:30.537Z,1553558970.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:33.337Z,1553558973.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:36.537Z,1553558976.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:36.770Z,1553558976.770 [NavChartDb](INFO): # of records loaded: 30000 2019-03-26T00:09:39.337Z,1553558979.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:42.537Z,1553558982.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:44.463Z,1553558984.463 [NavChartDb](INFO): # of records loaded: 35000 2019-03-26T00:09:45.341Z,1553558985.341 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:48.538Z,1553558988.538 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:51.337Z,1553558991.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:52.304Z,1553558992.304 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:09:52.304Z,1553558992.304 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:09:54.538Z,1553558994.538 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:57.337Z,1553558997.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:58.436Z,1553558998.436 [NavChartDb](INFO): # of records loaded: 40000 2019-03-26T00:10:00.537Z,1553559000.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:00.973Z,1553559000.973 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:10:00.973Z,1553559000.973 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:10:00.973Z,1553559000.973 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:10:00.993Z,1553559000.993 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:10:01.352Z,1553559001.352 [PNI_TCM](INFO): Powering down 2019-03-26T00:10:02.577Z,1553559002.577 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:10:02.577Z,1553559002.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-03-26T00:10:02.577Z,1553559002.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:10:02.579Z,1553559002.579 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:10:02.579Z,1553559002.579 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2019-03-26T00:10:02.579Z,1553559002.579 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:10:02.585Z,1553559002.585 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:10:02.585Z,1553559002.585 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2019-03-26T00:10:02.585Z,1553559002.585 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:10:02.630Z,1553559002.630 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:10:02.630Z,1553559002.630 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:10:02.630Z,1553559002.630 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:10:02.631Z,1553559002.631 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:10:02.631Z,1553559002.631 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:10:02.993Z,1553559002.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:10:02.993Z,1553559002.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-03-26T00:10:02.994Z,1553559002.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:10:02.994Z,1553559002.994 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2019-03-26T00:10:02.994Z,1553559002.994 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:10:02.994Z,1553559002.994 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2019-03-26T00:10:03.337Z,1553559003.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:03.397Z,1553559003.397 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:10:06.538Z,1553559006.538 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:09.337Z,1553559009.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:11.365Z,1553559011.365 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:10:11.365Z,1553559011.365 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:10:11.365Z,1553559011.365 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:10:11.447Z,1553559011.447 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:10:11.742Z,1553559011.742 [PNI_TCM](INFO): Powering down 2019-03-26T00:10:12.537Z,1553559012.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:13.035Z,1553559013.035 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:10:13.035Z,1553559013.035 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:10:15.337Z,1553559015.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:15.665Z,1553559015.665 [NavChartDb](INFO): # of records loaded: 45000 2019-03-26T00:10:19.402Z,1553559019.402 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-26T00:10:20.630Z,1553559020.630 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-03-26T00:10:20.630Z,1553559020.630 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:10:20.630Z,1553559020.630 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:10:20.688Z,1553559020.688 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:10:20.743Z,1553559020.743 [PNI_TCM](INFO): Powering down 2019-03-26T00:10:21.404Z,1553559021.404 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-26T00:10:22.016Z,1553559022.016 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:10:22.016Z,1553559022.016 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:10:22.317Z,1553559022.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:24.321Z,1553559024.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:26.329Z,1553559026.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:28.317Z,1553559028.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:30.317Z,1553559030.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:30.753Z,1553559030.753 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:10:30.753Z,1553559030.753 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:10:30.753Z,1553559030.753 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:10:30.775Z,1553559030.775 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:10:31.132Z,1553559031.132 [PNI_TCM](INFO): Powering down 2019-03-26T00:10:32.317Z,1553559032.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:32.378Z,1553559032.378 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:10:32.378Z,1553559032.378 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:10:33.347Z,1553559033.347 [NavChartDb](INFO): # of records loaded: 50000 2019-03-26T00:10:34.317Z,1553559034.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:36.321Z,1553559036.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:38.317Z,1553559038.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:40.721Z,1553559040.721 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:41.157Z,1553559041.157 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:10:41.157Z,1553559041.158 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:10:41.158Z,1553559041.158 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:10:41.192Z,1553559041.192 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:10:41.197Z,1553559041.197 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:10:41.532Z,1553559041.532 [PNI_TCM](INFO): Powering down 2019-03-26T00:10:41.652Z,1553559041.652 [CBIT](INFO): Critical error at 20190326T001041 2019-03-26T00:10:43.517Z,1553559043.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:46.717Z,1553559046.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:49.517Z,1553559049.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:52.354Z,1553559052.354 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-26T00:10:52.495Z,1553559052.495 [NavChartDb](INFO): # of records loaded: 53494 2019-03-26T00:10:52.504Z,1553559052.504 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-26T00:10:52.505Z,1553559052.505 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-26T00:10:52.721Z,1553559052.721 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:55.420Z,1553559055.420 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-26T00:10:55.521Z,1553559055.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:58.718Z,1553559058.718 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:01.518Z,1553559061.518 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:04.717Z,1553559064.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:06.483Z,1553559066.483 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:11:07.517Z,1553559067.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:10.717Z,1553559070.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:13.517Z,1553559073.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:17.621Z,1553559077.621 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:11:21.973Z,1553559081.973 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:24.773Z,1553559084.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:26.711Z,1553559086.711 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:11:27.573Z,1553559087.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:30.773Z,1553559090.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:33.573Z,1553559093.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:34.520Z,1553559094.520 [NavChartDb](INFO): # of records loaded: 20000 2019-03-26T00:11:36.773Z,1553559096.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:39.573Z,1553559099.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:42.187Z,1553559102.187 [NavChartDb](INFO): # of records loaded: 25000 2019-03-26T00:11:42.773Z,1553559102.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:45.577Z,1553559105.577 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:48.773Z,1553559108.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:49.819Z,1553559109.819 [NavChartDb](INFO): # of records loaded: 30000 2019-03-26T00:11:51.573Z,1553559111.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:54.773Z,1553559114.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:57.439Z,1553559117.439 [NavChartDb](INFO): # of records loaded: 35000 2019-03-26T00:11:57.573Z,1553559117.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:00.773Z,1553559120.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:03.573Z,1553559123.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:03.622Z,1553559123.622 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:12:03.622Z,1553559123.622 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-03-26T00:12:03.622Z,1553559123.622 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:12:03.624Z,1553559123.624 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:12:03.624Z,1553559123.624 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2019-03-26T00:12:03.624Z,1553559123.624 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:12:03.630Z,1553559123.630 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:12:03.630Z,1553559123.630 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7 2019-03-26T00:12:03.630Z,1553559123.630 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:12:03.695Z,1553559123.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:12:03.695Z,1553559123.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:12:03.696Z,1553559123.696 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:12:04.056Z,1553559124.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:12:04.056Z,1553559124.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-03-26T00:12:04.056Z,1553559124.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:12:04.056Z,1553559124.056 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2019-03-26T00:12:04.057Z,1553559124.057 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:12:04.057Z,1553559124.057 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7 2019-03-26T00:12:04.417Z,1553559124.417 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:12:05.346Z,1553559125.346 [NavChartDb](INFO): # of records loaded: 40000 2019-03-26T00:12:06.773Z,1553559126.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:09.573Z,1553559129.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:12.773Z,1553559132.773 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:12.989Z,1553559132.989 [NavChartDb](INFO): # of records loaded: 45000 2019-03-26T00:12:15.573Z,1553559135.573 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:22.738Z,1553559142.738 [NavChartDb](INFO): # of records loaded: 50000 2019-03-26T00:12:23.617Z,1553559143.617 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:26.813Z,1553559146.813 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:29.613Z,1553559149.613 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:30.618Z,1553559150.618 [NavChartDb](INFO): # of records loaded: 55000 2019-03-26T00:12:32.817Z,1553559152.817 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:35.613Z,1553559155.613 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:38.507Z,1553559158.507 [NavChartDb](INFO): # of records loaded: 60000 2019-03-26T00:12:38.817Z,1553559158.817 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:41.609Z,1553559161.609 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:44.817Z,1553559164.817 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:46.331Z,1553559166.331 [NavChartDb](INFO): # of records loaded: 65000 2019-03-26T00:12:47.611Z,1553559167.611 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:50.809Z,1553559170.809 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:53.609Z,1553559173.609 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:54.036Z,1553559174.036 [NavChartDb](INFO): # of records loaded: 70000 2019-03-26T00:12:56.813Z,1553559176.813 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:57.687Z,1553559177.687 [CBIT](INFO): Clearing failed state for component DropWeight 2019-03-26T00:12:57.687Z,1553559177.687 [DropWeight] No Fault, FailCount= 1 2019-03-26T00:12:59.616Z,1553559179.616 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:01.901Z,1553559181.901 [NavChartDb](INFO): # of records loaded: 75000 2019-03-26T00:13:02.809Z,1553559182.809 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:05.613Z,1553559185.613 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:08.809Z,1553559188.809 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:09.725Z,1553559189.725 [NavChartDb](INFO): # of records loaded: 80000 2019-03-26T00:13:11.613Z,1553559191.613 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:14.809Z,1553559194.809 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:23.368Z,1553559203.368 [NavChartDb](INFO): # of records loaded: 85000 2019-03-26T00:13:23.597Z,1553559203.597 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:25.597Z,1553559205.597 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:27.601Z,1553559207.601 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:29.597Z,1553559209.597 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:31.119Z,1553559211.119 [NavChartDb](INFO): # of records loaded: 90000 2019-03-26T00:13:31.601Z,1553559211.601 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:33.597Z,1553559213.597 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:35.597Z,1553559215.597 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:37.605Z,1553559217.605 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:39.020Z,1553559219.020 [NavChartDb](INFO): # of records loaded: 95000 2019-03-26T00:13:39.601Z,1553559219.601 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:41.598Z,1553559221.598 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:43.997Z,1553559223.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:46.801Z,1553559226.801 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:47.233Z,1553559227.233 [NavChartDb](INFO): # of records loaded: 100000 2019-03-26T00:13:49.997Z,1553559229.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:52.801Z,1553559232.801 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:55.293Z,1553559235.293 [NavChartDb](INFO): # of records loaded: 105000 2019-03-26T00:13:55.997Z,1553559235.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:58.809Z,1553559238.809 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:02.002Z,1553559242.002 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:04.274Z,1553559244.274 [NavChartDb](INFO): # of records loaded: 110000 2019-03-26T00:14:04.432Z,1553559244.432 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:14:04.432Z,1553559244.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2019-03-26T00:14:04.432Z,1553559244.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:14:04.438Z,1553559244.438 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:14:04.439Z,1553559244.439 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2019-03-26T00:14:04.439Z,1553559244.439 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:14:04.440Z,1553559244.440 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:14:04.446Z,1553559244.446 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8 2019-03-26T00:14:04.446Z,1553559244.446 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:14:04.586Z,1553559244.586 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:14:04.587Z,1553559244.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:14:04.587Z,1553559244.587 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:14:04.813Z,1553559244.813 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:04.890Z,1553559244.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:14:04.890Z,1553559244.890 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2019-03-26T00:14:04.890Z,1553559244.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:14:04.890Z,1553559244.890 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2019-03-26T00:14:04.891Z,1553559244.891 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:14:04.891Z,1553559244.891 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8 2019-03-26T00:14:05.238Z,1553559245.238 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:14:07.997Z,1553559247.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:10.805Z,1553559250.805 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:12.017Z,1553559252.017 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-26T00:14:13.000Z,1553559253.000 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-26T00:14:13.997Z,1553559253.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:15.257Z,1553559255.257 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-03-26T00:14:15.257Z,1553559255.257 [Default:CheckIn:C.Wait] Stopped 2019-03-26T00:14:15.257Z,1553559255.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:14:15.257Z,1553559255.257 [Default:CheckIn:D] Running Loop=1 2019-03-26T00:14:15.665Z,1553559255.665 [Default:CheckIn:D] Stopped 2019-03-26T00:14:15.665Z,1553559255.665 [Default:CheckIn:E] Running Loop=1 2019-03-26T00:14:15.874Z,1553559255.874 [NavChartDb](INFO): # of records loaded: 115000 2019-03-26T00:14:16.028Z,1553559256.028 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.851202 min 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn:E] Stopped 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn] Stopped 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn](INFO): Running loop #2 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn] Running Loop=2 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-26T00:14:16.035Z,1553559256.035 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-26T00:14:21.314Z,1553559261.314 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:23.689Z,1553559263.689 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:24.778Z,1553559264.778 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-26T00:14:25.065Z,1553559265.065 [NavChartDb](INFO): # of records loaded: 117290 2019-03-26T00:14:25.070Z,1553559265.070 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-26T00:14:25.071Z,1553559265.071 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-26T00:14:25.681Z,1553559265.681 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:27.107Z,1553559267.107 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-26T00:14:27.677Z,1553559267.677 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:29.677Z,1553559269.677 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:31.681Z,1553559271.681 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:34.089Z,1553559274.089 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:35.441Z,1553559275.441 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:14:36.877Z,1553559276.877 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:40.081Z,1553559280.081 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:42.877Z,1553559282.877 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:43.314Z,1553559283.314 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:14:46.081Z,1553559286.081 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:48.885Z,1553559288.885 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:51.118Z,1553559291.118 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:14:52.081Z,1553559292.081 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:54.877Z,1553559294.877 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:58.088Z,1553559298.088 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:59.104Z,1553559299.104 [NavChartDb](INFO): # of records loaded: 20000 2019-03-26T00:15:00.877Z,1553559300.877 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:04.081Z,1553559304.081 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:06.889Z,1553559306.889 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:07.907Z,1553559307.907 [NavChartDb](INFO): # of records loaded: 25000 2019-03-26T00:15:10.081Z,1553559310.081 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:12.881Z,1553559312.881 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:16.082Z,1553559316.082 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:17.447Z,1553559317.447 [NavChartDb](INFO): # of records loaded: 30000 2019-03-26T00:15:24.145Z,1553559324.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:26.957Z,1553559326.957 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:28.541Z,1553559328.541 [NavChartDb](INFO): # of records loaded: 35000 2019-03-26T00:15:30.145Z,1553559330.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:32.945Z,1553559332.945 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:36.149Z,1553559336.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:37.314Z,1553559337.314 [NavChartDb](INFO): # of records loaded: 40000 2019-03-26T00:15:38.949Z,1553559338.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:39.298Z,1553559339.298 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-26T00:15:40.072Z,1553559340.072 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-26T00:15:41.391Z,1553559341.391 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:15:41.391Z,1553559341.391 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:15:42.155Z,1553559342.155 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:44.945Z,1553559344.945 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:48.145Z,1553559348.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:49.525Z,1553559349.525 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-26T00:15:49.643Z,1553559349.643 [NavChartDb](INFO): # of records loaded: 44880 2019-03-26T00:15:49.647Z,1553559349.647 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-26T00:15:49.648Z,1553559349.648 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-26T00:15:50.169Z,1553559350.169 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:15:50.169Z,1553559350.169 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:15:50.169Z,1553559350.169 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:15:50.238Z,1553559350.238 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:15:50.556Z,1553559350.556 [PNI_TCM](INFO): Powering down 2019-03-26T00:15:50.945Z,1553559350.945 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:51.824Z,1553559351.824 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:15:51.824Z,1553559351.824 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:15:53.896Z,1553559353.896 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-26T00:15:54.145Z,1553559354.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:56.945Z,1553559356.945 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:00.145Z,1553559360.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:00.589Z,1553559360.589 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:16:00.589Z,1553559360.589 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:16:00.589Z,1553559360.589 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:16:00.664Z,1553559360.664 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:16:00.979Z,1553559360.979 [PNI_TCM](INFO): Powering down 2019-03-26T00:16:02.210Z,1553559362.210 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:16:02.210Z,1553559362.210 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:16:02.949Z,1553559362.949 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:04.782Z,1553559364.782 [NavChartDb](INFO): # of records loaded: 5000 2019-03-26T00:16:05.369Z,1553559365.369 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:16:05.369Z,1553559365.369 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2019-03-26T00:16:05.369Z,1553559365.369 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:16:05.371Z,1553559365.371 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:16:05.371Z,1553559365.371 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2019-03-26T00:16:05.371Z,1553559365.371 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:16:05.372Z,1553559365.372 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:16:05.373Z,1553559365.373 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9 2019-03-26T00:16:05.373Z,1553559365.373 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:16:05.450Z,1553559365.450 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:16:05.451Z,1553559365.451 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:16:05.451Z,1553559365.451 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:16:05.804Z,1553559365.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:16:05.804Z,1553559365.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2019-03-26T00:16:05.804Z,1553559365.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:16:05.804Z,1553559365.804 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2019-03-26T00:16:05.805Z,1553559365.805 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:16:05.805Z,1553559365.805 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9 2019-03-26T00:16:06.145Z,1553559366.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:06.222Z,1553559366.222 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:16:08.945Z,1553559368.945 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:10.969Z,1553559370.969 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:16:10.969Z,1553559370.969 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:16:10.969Z,1553559370.969 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:16:11.056Z,1553559371.056 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:16:11.350Z,1553559371.350 [PNI_TCM](INFO): Powering down 2019-03-26T00:16:12.153Z,1553559372.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:12.646Z,1553559372.646 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:16:12.646Z,1553559372.646 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:16:14.506Z,1553559374.506 [NavChartDb](INFO): # of records loaded: 10000 2019-03-26T00:16:14.981Z,1553559374.981 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:21.997Z,1553559381.997 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:16:21.997Z,1553559381.997 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:16:21.997Z,1553559381.997 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:16:22.068Z,1553559382.068 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:16:22.124Z,1553559382.124 [PNI_TCM](INFO): Powering down 2019-03-26T00:16:22.501Z,1553559382.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:23.370Z,1553559383.370 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:16:23.371Z,1553559383.371 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:16:24.901Z,1553559384.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:25.965Z,1553559385.965 [NavChartDb](INFO): # of records loaded: 15000 2019-03-26T00:16:26.921Z,1553559386.921 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:28.906Z,1553559388.906 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:30.901Z,1553559390.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:32.131Z,1553559392.131 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:16:32.131Z,1553559392.131 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:16:32.131Z,1553559392.131 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:16:32.199Z,1553559392.199 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:16:32.200Z,1553559392.200 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:16:32.505Z,1553559392.505 [PNI_TCM](INFO): Powering down 2019-03-26T00:16:32.607Z,1553559392.607 [CBIT](INFO): Critical error at 20190326T001632 2019-03-26T00:16:32.901Z,1553559392.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:34.905Z,1553559394.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:36.825Z,1553559396.825 [NavChartDb](INFO): # of records loaded: 20000 2019-03-26T00:16:36.901Z,1553559396.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:38.914Z,1553559398.914 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-26T00:16:38.921Z,1553559398.921 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:40.596Z,1553559400.596 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-26T00:16:40.905Z,1553559400.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:42.933Z,1553559402.933 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:44.901Z,1553559404.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:46.905Z,1553559406.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:48.929Z,1553559408.929 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:50.901Z,1553559410.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:52.905Z,1553559412.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:54.901Z,1553559414.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:56.901Z,1553559416.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:59.301Z,1553559419.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:02.125Z,1553559422.125 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:03.456Z,1553559423.456 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-26T00:17:04.023Z,1553559424.023 [NavChartDb](INFO): # of records loaded: 24424 2019-03-26T00:17:04.032Z,1553559424.032 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-26T00:17:05.305Z,1553559425.305 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:07.485Z,1553559427.485 [NavChartDb](INFO): Creating index for soundings 2019-03-26T00:17:08.101Z,1553559428.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:11.301Z,1553559431.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:14.101Z,1553559434.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:17.003Z,1553559437.003 [NavChartDb](INFO): Creating index for 0p0 2019-03-26T00:17:17.305Z,1553559437.305 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:22.640Z,1553559442.640 [NavChartDb](INFO): Creating index for 1p8 2019-03-26T00:17:24.649Z,1553559444.649 [NavChartDb](INFO): Creating index for 3p6 2019-03-26T00:17:25.335Z,1553559445.335 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:27.840Z,1553559447.840 [NavChartDb](INFO): Creating index for 5p4 2019-03-26T00:17:28.129Z,1553559448.129 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:30.656Z,1553559450.656 [NavChartDb](INFO): Creating index for 9p1 2019-03-26T00:17:31.329Z,1553559451.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:31.936Z,1553559451.936 [NavChartDb](INFO): Creating index for 10p9 2019-03-26T00:17:34.129Z,1553559454.129 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:34.282Z,1553559454.282 [NavChartDb](INFO): Creating index for 18p2 2019-03-26T00:17:36.492Z,1553559456.492 [NavChartDb](INFO): Creating index for 36p5 2019-03-26T00:17:37.329Z,1553559457.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:38.485Z,1553559458.485 [NavChartDb](INFO): Creating index for 54p8 2019-03-26T00:17:39.910Z,1553559459.910 [NavChartDb](INFO): Creating index for 73p1 2019-03-26T00:17:40.125Z,1553559460.125 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:41.659Z,1553559461.659 [NavChartDb](INFO): Creating index for 91p4 2019-03-26T00:17:43.329Z,1553559463.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:45.022Z,1553559465.022 [NavChartDb](INFO): Creating index for 182p8 2019-03-26T00:17:46.125Z,1553559466.125 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:46.427Z,1553559466.427 [NavChartDb](INFO): Creating index for 274p3 2019-03-26T00:17:48.290Z,1553559468.290 [NavChartDb](INFO): Creating index for 365p7 2019-03-26T00:17:49.329Z,1553559469.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:49.400Z,1553559469.400 [NavChartDb](INFO): Creating index for 457p2 2019-03-26T00:17:51.212Z,1553559471.212 [NavChartDb](INFO): Creating index for 548p6 2019-03-26T00:17:52.130Z,1553559472.130 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:52.588Z,1553559472.588 [NavChartDb](INFO): Creating index for 640p0 2019-03-26T00:17:54.326Z,1553559474.326 [NavChartDb](INFO): Creating index for 731p5 2019-03-26T00:17:55.329Z,1553559475.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:55.444Z,1553559475.444 [NavChartDb](INFO): Creating index for 822p9 2019-03-26T00:17:58.129Z,1553559478.129 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:58.166Z,1553559478.166 [NavChartDb](INFO): Creating index for 914p4 2019-03-26T00:17:59.462Z,1553559479.462 [NavChartDb](INFO): Creating index for 1005p8 2019-03-26T00:18:00.467Z,1553559480.467 [NavChartDb](INFO): Creating index for 1097p2 2019-03-26T00:18:01.072Z,1553559481.072 [NavChartDb](INFO): Creating index for 1188p7 2019-03-26T00:18:01.329Z,1553559481.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:02.010Z,1553559482.010 [NavChartDb](INFO): Creating index for 1280p1 2019-03-26T00:18:03.267Z,1553559483.267 [NavChartDb](INFO): Creating index for 1463p0 2019-03-26T00:18:04.125Z,1553559484.125 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:04.868Z,1553559484.868 [NavChartDb](INFO): Creating index for 1645p9 2019-03-26T00:18:06.561Z,1553559486.561 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:18:06.562Z,1553559486.562 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2019-03-26T00:18:06.562Z,1553559486.562 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:18:06.563Z,1553559486.563 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:18:06.563Z,1553559486.563 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2019-03-26T00:18:06.563Z,1553559486.563 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:18:06.569Z,1553559486.569 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:18:06.569Z,1553559486.569 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10 2019-03-26T00:18:06.569Z,1553559486.569 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:18:06.626Z,1553559486.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:18:06.626Z,1553559486.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:18:06.626Z,1553559486.626 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:18:06.993Z,1553559486.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:18:06.993Z,1553559486.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2019-03-26T00:18:06.993Z,1553559486.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:18:06.993Z,1553559486.993 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2019-03-26T00:18:06.994Z,1553559486.994 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:18:06.994Z,1553559486.994 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10 2019-03-26T00:18:07.329Z,1553559487.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:07.378Z,1553559487.378 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:18:07.694Z,1553559487.694 [NavChartDb](INFO): Creating index for 1828p8 2019-03-26T00:18:09.640Z,1553559489.640 [NavChartDb](INFO): Creating index for 2011p6 2019-03-26T00:18:10.129Z,1553559490.129 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:10.493Z,1553559490.493 [NavChartDb](INFO): Creating index for 2194p5 2019-03-26T00:18:11.247Z,1553559491.247 [NavChartDb](INFO): Creating index for 2377p4 2019-03-26T00:18:12.036Z,1553559492.036 [NavChartDb](INFO): Creating index for 2560p3 2019-03-26T00:18:12.174Z,1553559492.174 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:18:12.174Z,1553559492.174 [Rowe_600LCM] No Fault, FailCount= 5 2019-03-26T00:18:12.401Z,1553559492.401 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:18:12.401Z,1553559492.401 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:18:13.235Z,1553559493.235 [NavChartDb](INFO): Creating index for 2743p2 2019-03-26T00:18:13.329Z,1553559493.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:14.483Z,1553559494.483 [NavChartDb](INFO): Creating index for 2926p0 2019-03-26T00:18:15.293Z,1553559495.293 [NavChartDb](INFO): Creating index for 3108p9 2019-03-26T00:18:16.079Z,1553559496.079 [NavChartDb](INFO): Creating index for 3291p8 2019-03-26T00:18:16.129Z,1553559496.129 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:16.671Z,1553559496.671 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:18:16.775Z,1553559496.775 [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 & 2019-03-26T00:18:16.821Z,1553559496.821 [NavChartDb](INFO): Creating index for 3474p7 2019-03-26T00:18:27.097Z,1553559507.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:27.789Z,1553559507.789 [NavChartDb](INFO): Creating index for 3657p6 2019-03-26T00:18:30.301Z,1553559510.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:33.101Z,1553559513.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:35.101Z,1553559515.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:37.097Z,1553559517.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:39.101Z,1553559519.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:41.101Z,1553559521.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:43.101Z,1553559523.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:45.113Z,1553559525.113 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:47.101Z,1553559527.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:49.097Z,1553559529.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:51.097Z,1553559531.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:53.101Z,1553559533.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:55.097Z,1553559535.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:57.101Z,1553559537.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:59.097Z,1553559539.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:01.117Z,1553559541.117 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:03.101Z,1553559543.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:05.097Z,1553559545.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:07.501Z,1553559547.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:10.297Z,1553559550.297 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:13.501Z,1553559553.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:16.307Z,1553559556.307 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:16.371Z,1553559556.371 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T00:14:16.0Z 2019-03-26T00:19:16.371Z,1553559556.371 [Default:CheckIn:Read_GPS] Stopped 2019-03-26T00:19:16.371Z,1553559556.371 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-26T00:19:23.200Z,1553559563.200 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20190325T235747/Courier0007.lzma 2019-03-26T00:19:23.990Z,1553559563.990 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Courier0007.lzma.bak 2019-03-26T00:19:23.990Z,1553559563.990 [DataOverHttps](INFO): SBD MOMSN=10245178 2019-03-26T00:19:30.919Z,1553559570.919 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-03-26T00:19:30.919Z,1553559570.919 [NAL9602] Data Fault, FailCount= 2 2019-03-26T00:19:30.919Z,1553559570.919 [NAL9602](ERROR): Data Fault 2019-03-26T00:19:31.007Z,1553559571.007 [CBIT](ERROR): Data Fault in component: NAL9602 2019-03-26T00:19:31.048Z,1553559571.048 [NAL9602](INFO): Powering down 2019-03-26T00:19:31.923Z,1553559571.923 [CBIT](INFO): Clearing failed state for component NAL9602 2019-03-26T00:19:31.923Z,1553559571.923 [NAL9602] No Fault, FailCount= 2 2019-03-26T00:19:38.803Z,1553559578.803 [DataOverHttps](INFO): Sending 629 bytes from file Logs/20190325T235747/Express0008.lzma 2019-03-26T00:19:39.582Z,1553559579.582 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Express0008.lzma.bak 2019-03-26T00:19:39.582Z,1553559579.582 [DataOverHttps](INFO): SBD MOMSN=10245182 2019-03-26T00:19:40.484Z,1553559580.484 [Default:CheckIn:Read_Iridium] Stopped 2019-03-26T00:19:40.484Z,1553559580.484 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-26T00:19:40.484Z,1553559580.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:20:01.055Z,1553559601.055 [NAL9602](INFO): Powering up NAL9602 2019-03-26T00:20:07.494Z,1553559607.494 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:20:07.494Z,1553559607.494 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2019-03-26T00:20:07.494Z,1553559607.494 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:20:07.495Z,1553559607.495 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:20:07.496Z,1553559607.496 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2019-03-26T00:20:07.496Z,1553559607.496 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:20:07.529Z,1553559607.529 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:20:07.530Z,1553559607.530 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11 2019-03-26T00:20:07.530Z,1553559607.530 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:20:07.562Z,1553559607.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:20:07.563Z,1553559607.563 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:20:07.563Z,1553559607.563 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:20:07.903Z,1553559607.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:20:07.903Z,1553559607.903 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2019-03-26T00:20:07.904Z,1553559607.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:20:07.904Z,1553559607.904 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2019-03-26T00:20:07.904Z,1553559607.904 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:20:07.904Z,1553559607.904 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11 2019-03-26T00:20:08.302Z,1553559608.302 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:20:12.242Z,1553559612.242 [NAL9602](INFO): NAL9602 initialized 2019-03-26T00:20:12.429Z,1553559612.429 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:20:12.429Z,1553559612.429 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-03-26T00:20:12.429Z,1553559612.429 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:20:12.726Z,1553559612.726 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:20:12.836Z,1553559612.836 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:20:12.994Z,1553559612.994 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:20:13.069Z,1553559613.069 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:14.276Z,1553559614.276 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:20:14.277Z,1553559614.277 [Rowe_600LCM] No Fault, FailCount= 1 2019-03-26T00:20:14.609Z,1553559614.609 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:20:14.609Z,1553559614.609 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:20:16.257Z,1553559616.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:18.814Z,1553559618.814 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:20:18.891Z,1553559618.891 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-03-26T00:20:19.057Z,1553559619.057 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:22.257Z,1553559622.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:25.061Z,1553559625.061 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:28.257Z,1553559628.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:31.053Z,1553559631.053 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:34.257Z,1553559634.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:37.061Z,1553559637.061 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:40.257Z,1553559640.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:44.665Z,1553559644.665 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:47.062Z,1553559647.062 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:50.261Z,1553559650.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:53.057Z,1553559653.057 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:56.257Z,1553559656.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:59.061Z,1553559659.061 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:02.261Z,1553559662.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:05.062Z,1553559665.062 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:08.257Z,1553559668.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:11.058Z,1553559671.058 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:14.257Z,1553559674.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:17.057Z,1553559677.057 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:20.257Z,1553559680.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:23.057Z,1553559683.057 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:26.257Z,1553559686.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:29.057Z,1553559689.057 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:31.061Z,1553559691.061 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:32.332Z,1553559692.332 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:21:32.332Z,1553559692.332 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:21:34.261Z,1553559694.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:37.065Z,1553559697.065 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:39.058Z,1553559699.058 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:41.059Z,1553559701.059 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:41.097Z,1553559701.097 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:21:41.097Z,1553559701.097 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:21:41.097Z,1553559701.097 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:21:41.189Z,1553559701.189 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:21:47.106Z,1553559707.106 [PNI_TCM](INFO): Powering down 2019-03-26T00:21:48.552Z,1553559708.552 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:21:48.552Z,1553559708.552 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:21:49.277Z,1553559709.277 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:52.073Z,1553559712.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:54.077Z,1553559714.077 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:56.073Z,1553559716.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:57.305Z,1553559717.305 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:21:57.305Z,1553559717.305 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:21:57.305Z,1553559717.305 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:21:57.410Z,1553559717.410 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:21:57.686Z,1553559717.686 [PNI_TCM](INFO): Powering down 2019-03-26T00:21:58.073Z,1553559718.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:58.938Z,1553559718.938 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:21:58.938Z,1553559718.938 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:22:00.077Z,1553559720.077 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:02.073Z,1553559722.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:04.085Z,1553559724.085 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:06.081Z,1553559726.081 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:07.705Z,1553559727.705 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:22:07.705Z,1553559727.705 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:22:07.705Z,1553559727.705 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:22:07.782Z,1553559727.782 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:22:08.073Z,1553559728.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:08.089Z,1553559728.089 [PNI_TCM](INFO): Powering down 2019-03-26T00:22:08.552Z,1553559728.552 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:22:08.552Z,1553559728.552 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2019-03-26T00:22:08.552Z,1553559728.552 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:22:08.554Z,1553559728.554 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:22:08.554Z,1553559728.554 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2019-03-26T00:22:08.554Z,1553559728.554 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:22:08.555Z,1553559728.555 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:22:08.555Z,1553559728.555 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12 2019-03-26T00:22:08.555Z,1553559728.555 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:22:08.581Z,1553559728.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:22:08.582Z,1553559728.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:22:08.582Z,1553559728.582 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:22:08.953Z,1553559728.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:22:08.954Z,1553559728.954 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2019-03-26T00:22:08.954Z,1553559728.954 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:22:08.954Z,1553559728.954 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2019-03-26T00:22:08.955Z,1553559728.955 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:22:08.955Z,1553559728.955 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12 2019-03-26T00:22:09.321Z,1553559729.321 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:22:09.360Z,1553559729.360 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:22:09.360Z,1553559729.360 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:22:10.073Z,1553559730.073 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:12.473Z,1553559732.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:15.273Z,1553559735.273 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:15.329Z,1553559735.329 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:22:15.329Z,1553559735.329 [Rowe_600LCM] Communications Fault, FailCount= 2 2019-03-26T00:22:15.329Z,1553559735.329 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:22:15.412Z,1553559735.412 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:22:15.745Z,1553559735.745 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:22:15.941Z,1553559735.941 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:22:17.324Z,1553559737.324 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:22:17.324Z,1553559737.324 [Rowe_600LCM] No Fault, FailCount= 2 2019-03-26T00:22:17.557Z,1553559737.557 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:22:17.557Z,1553559737.557 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:22:18.109Z,1553559738.109 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:22:18.109Z,1553559738.109 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:22:18.109Z,1553559738.109 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:22:18.159Z,1553559738.159 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:22:18.477Z,1553559738.477 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:18.495Z,1553559738.495 [PNI_TCM](INFO): Powering down 2019-03-26T00:22:19.716Z,1553559739.716 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:22:19.716Z,1553559739.716 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:22:21.281Z,1553559741.281 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:21.731Z,1553559741.731 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:22:21.839Z,1553559741.839 [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 & 2019-03-26T00:22:24.477Z,1553559744.477 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:27.273Z,1553559747.273 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:28.501Z,1553559748.501 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:22:28.501Z,1553559748.501 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:22:28.501Z,1553559748.501 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:22:28.556Z,1553559748.556 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:22:28.556Z,1553559748.556 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:22:28.877Z,1553559748.877 [PNI_TCM](INFO): Powering down 2019-03-26T00:22:28.923Z,1553559748.923 [CBIT](INFO): Critical error at 20190326T002228 2019-03-26T00:22:30.477Z,1553559750.477 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:33.277Z,1553559753.277 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:36.477Z,1553559756.477 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:39.281Z,1553559759.281 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:44.869Z,1553559764.869 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:47.273Z,1553559767.273 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:50.473Z,1553559770.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:53.269Z,1553559773.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:56.473Z,1553559776.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:59.269Z,1553559779.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:02.469Z,1553559782.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:05.275Z,1553559785.275 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:08.473Z,1553559788.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:11.281Z,1553559791.281 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:14.473Z,1553559794.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:17.272Z,1553559797.272 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:20.469Z,1553559800.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:23.311Z,1553559803.311 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:26.489Z,1553559806.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:29.273Z,1553559809.273 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:32.469Z,1553559812.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:35.269Z,1553559815.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:38.469Z,1553559818.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:41.273Z,1553559821.273 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:49.373Z,1553559829.373 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:52.561Z,1553559832.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:55.367Z,1553559835.367 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:58.561Z,1553559838.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:01.365Z,1553559841.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:04.561Z,1553559844.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:07.365Z,1553559847.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:09.399Z,1553559849.399 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:24:09.399Z,1553559849.399 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2019-03-26T00:24:09.399Z,1553559849.399 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:24:09.401Z,1553559849.401 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:24:09.401Z,1553559849.401 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2019-03-26T00:24:09.401Z,1553559849.401 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:24:09.413Z,1553559849.413 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:24:09.413Z,1553559849.413 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 13 2019-03-26T00:24:09.413Z,1553559849.413 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:24:09.426Z,1553559849.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:24:09.426Z,1553559849.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:24:09.426Z,1553559849.426 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:24:09.826Z,1553559849.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:24:09.826Z,1553559849.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2019-03-26T00:24:09.827Z,1553559849.827 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:24:09.827Z,1553559849.827 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2019-03-26T00:24:09.827Z,1553559849.827 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:24:09.827Z,1553559849.827 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 13 2019-03-26T00:24:10.194Z,1553559850.194 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:24:10.561Z,1553559850.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:13.365Z,1553559853.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:16.561Z,1553559856.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:18.289Z,1553559858.289 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:24:18.289Z,1553559858.289 [Rowe_600LCM] Communications Fault, FailCount= 3 2019-03-26T00:24:18.289Z,1553559858.289 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:24:18.640Z,1553559858.640 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:24:18.693Z,1553559858.693 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:24:18.866Z,1553559858.866 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:24:19.361Z,1553559859.361 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:20.220Z,1553559860.220 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:24:20.220Z,1553559860.220 [Rowe_600LCM] No Fault, FailCount= 3 2019-03-26T00:24:20.481Z,1553559860.481 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:24:20.481Z,1553559860.481 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:24:22.561Z,1553559862.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:24.702Z,1553559864.702 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:24:24.768Z,1553559864.768 [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 & 2019-03-26T00:24:25.365Z,1553559865.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:28.569Z,1553559868.569 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:31.365Z,1553559871.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:34.561Z,1553559874.561 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:37.370Z,1553559877.370 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:40.565Z,1553559880.565 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:40.998Z,1553559880.998 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-03-26T00:24:40.998Z,1553559880.998 [Default:CheckIn:C.Wait] Stopped 2019-03-26T00:24:40.998Z,1553559880.998 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:24:40.998Z,1553559880.998 [Default:CheckIn:D] Running Loop=1 2019-03-26T00:24:41.393Z,1553559881.393 [Default:CheckIn:D] Stopped 2019-03-26T00:24:41.394Z,1553559881.394 [Default:CheckIn:E] Running Loop=1 2019-03-26T00:24:41.790Z,1553559881.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.280033 min 2019-03-26T00:24:41.790Z,1553559881.790 [Default:CheckIn:E] Stopped 2019-03-26T00:24:41.790Z,1553559881.790 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-03-26T00:24:41.791Z,1553559881.791 [Default:CheckIn] Stopped 2019-03-26T00:24:41.791Z,1553559881.791 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-26T00:24:41.791Z,1553559881.791 [Default:CheckIn](INFO): Running loop #3 2019-03-26T00:24:41.791Z,1553559881.791 [Default:CheckIn] Running Loop=3 2019-03-26T00:24:41.791Z,1553559881.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-26T00:24:41.791Z,1553559881.791 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-26T00:24:45.329Z,1553559885.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:47.318Z,1553559887.318 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:49.321Z,1553559889.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:51.325Z,1553559891.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:53.717Z,1553559893.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:56.521Z,1553559896.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:59.717Z,1553559899.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:02.521Z,1553559902.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:05.717Z,1553559905.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:08.521Z,1553559908.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:11.719Z,1553559911.719 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:14.523Z,1553559914.523 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:17.717Z,1553559917.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:20.521Z,1553559920.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:23.717Z,1553559923.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:26.533Z,1553559926.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:29.717Z,1553559929.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:32.522Z,1553559932.522 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:35.717Z,1553559935.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:38.521Z,1553559938.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:41.717Z,1553559941.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:49.481Z,1553559949.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:52.685Z,1553559952.685 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:55.481Z,1553559955.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:58.685Z,1553559958.685 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:01.481Z,1553559961.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:03.481Z,1553559963.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:06.691Z,1553559966.691 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:09.485Z,1553559969.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:10.321Z,1553559970.321 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:26:10.321Z,1553559970.321 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2019-03-26T00:26:10.321Z,1553559970.321 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:26:10.323Z,1553559970.323 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:26:10.323Z,1553559970.323 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2019-03-26T00:26:10.323Z,1553559970.323 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:26:10.324Z,1553559970.324 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:26:10.330Z,1553559970.330 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 14 2019-03-26T00:26:10.330Z,1553559970.330 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:26:10.415Z,1553559970.415 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:26:10.415Z,1553559970.415 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:26:10.416Z,1553559970.416 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:26:10.744Z,1553559970.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:26:10.744Z,1553559970.744 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2019-03-26T00:26:10.744Z,1553559970.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:26:10.744Z,1553559970.744 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2019-03-26T00:26:10.749Z,1553559970.749 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:26:10.749Z,1553559970.749 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 14 2019-03-26T00:26:11.101Z,1553559971.101 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:26:11.485Z,1553559971.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:13.482Z,1553559973.482 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:15.481Z,1553559975.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:17.485Z,1553559977.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:19.489Z,1553559979.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:21.129Z,1553559981.129 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:26:21.129Z,1553559981.129 [Rowe_600LCM] Communications Fault, FailCount= 4 2019-03-26T00:26:21.129Z,1553559981.129 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:26:21.164Z,1553559981.164 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:26:21.485Z,1553559981.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:21.533Z,1553559981.533 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:26:21.748Z,1553559981.748 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:26:23.146Z,1553559983.146 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:26:23.146Z,1553559983.146 [Rowe_600LCM] No Fault, FailCount= 4 2019-03-26T00:26:23.366Z,1553559983.366 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:26:23.367Z,1553559983.367 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:26:23.489Z,1553559983.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:25.485Z,1553559985.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:27.489Z,1553559987.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:27.553Z,1553559987.553 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:26:27.664Z,1553559987.664 [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 & 2019-03-26T00:26:29.485Z,1553559989.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:31.481Z,1553559991.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:33.485Z,1553559993.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:35.485Z,1553559995.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:37.481Z,1553559997.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:39.485Z,1553559999.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:41.885Z,1553560001.885 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:47.889Z,1553560007.889 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:50.685Z,1553560010.685 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:53.885Z,1553560013.885 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:56.683Z,1553560016.683 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:26:59.881Z,1553560019.881 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:02.681Z,1553560022.681 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:05.885Z,1553560025.885 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:08.681Z,1553560028.681 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:11.881Z,1553560031.881 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:14.693Z,1553560034.693 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:17.890Z,1553560037.890 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:20.681Z,1553560040.681 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:23.885Z,1553560043.885 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:26.681Z,1553560046.681 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:28.761Z,1553560048.761 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:27:28.761Z,1553560048.761 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:27:29.885Z,1553560049.885 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:32.693Z,1553560052.693 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:35.881Z,1553560055.881 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:37.505Z,1553560057.505 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:27:37.505Z,1553560057.505 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:27:37.505Z,1553560057.505 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:27:37.584Z,1553560057.584 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:27:37.887Z,1553560057.887 [PNI_TCM](INFO): Powering down 2019-03-26T00:27:38.693Z,1553560058.693 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:39.162Z,1553560059.162 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:27:39.162Z,1553560059.162 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:27:41.881Z,1553560061.881 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:48.696Z,1553560068.696 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:27:48.696Z,1553560068.696 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:27:48.696Z,1553560068.696 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:27:48.734Z,1553560068.734 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:27:48.825Z,1553560068.825 [PNI_TCM](INFO): Powering down 2019-03-26T00:27:49.161Z,1553560069.161 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:50.037Z,1553560070.037 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:27:50.037Z,1553560070.037 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:27:51.957Z,1553560071.957 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:54.757Z,1553560074.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:57.957Z,1553560077.957 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:27:58.805Z,1553560078.805 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:27:58.805Z,1553560078.805 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:27:58.805Z,1553560078.805 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:27:58.901Z,1553560078.901 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:27:59.162Z,1553560079.162 [PNI_TCM](INFO): Powering down 2019-03-26T00:28:00.436Z,1553560080.436 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:28:00.436Z,1553560080.436 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:28:00.757Z,1553560080.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:03.961Z,1553560083.961 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:06.757Z,1553560086.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:09.181Z,1553560089.181 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:28:09.181Z,1553560089.181 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:28:09.181Z,1553560089.181 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:28:09.256Z,1553560089.256 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:28:09.561Z,1553560089.561 [PNI_TCM](INFO): Powering down 2019-03-26T00:28:09.961Z,1553560089.961 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:10.877Z,1553560090.877 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:28:10.877Z,1553560090.877 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:28:11.210Z,1553560091.210 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:28:11.210Z,1553560091.210 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2019-03-26T00:28:11.210Z,1553560091.210 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:28:11.212Z,1553560091.212 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:28:11.212Z,1553560091.212 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2019-03-26T00:28:11.212Z,1553560091.212 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:28:11.213Z,1553560091.213 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:28:11.213Z,1553560091.213 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 15 2019-03-26T00:28:11.213Z,1553560091.213 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:28:11.258Z,1553560091.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:28:11.259Z,1553560091.259 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:28:11.259Z,1553560091.259 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:28:11.632Z,1553560091.632 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:28:11.632Z,1553560091.632 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2019-03-26T00:28:11.633Z,1553560091.633 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:28:11.633Z,1553560091.633 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2019-03-26T00:28:11.633Z,1553560091.633 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:28:11.633Z,1553560091.634 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 15 2019-03-26T00:28:12.004Z,1553560092.004 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:28:12.757Z,1553560092.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:15.961Z,1553560095.961 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:18.757Z,1553560098.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:19.609Z,1553560099.609 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:28:19.609Z,1553560099.609 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:28:19.609Z,1553560099.609 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:28:19.685Z,1553560099.685 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:28:19.685Z,1553560099.685 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:28:19.967Z,1553560099.967 [PNI_TCM](INFO): Powering down 2019-03-26T00:28:20.126Z,1553560100.126 [CBIT](INFO): Critical error at 20190326T002819 2019-03-26T00:28:21.981Z,1553560101.981 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:24.085Z,1553560104.085 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:28:24.085Z,1553560104.085 [Rowe_600LCM] Communications Fault, FailCount= 5 2019-03-26T00:28:24.085Z,1553560104.085 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:28:24.100Z,1553560104.100 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:28:24.100Z,1553560104.100 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2019-03-26T00:28:24.489Z,1553560104.489 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:28:24.723Z,1553560104.723 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:28:24.761Z,1553560104.761 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:27.957Z,1553560107.957 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:30.757Z,1553560110.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:33.961Z,1553560113.961 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:36.757Z,1553560116.757 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:39.961Z,1553560119.961 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:42.761Z,1553560122.761 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:47.941Z,1553560127.941 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:50.741Z,1553560130.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:52.741Z,1553560132.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:54.739Z,1553560134.739 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:56.741Z,1553560136.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:28:58.741Z,1553560138.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:00.738Z,1553560140.738 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:02.741Z,1553560142.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:04.743Z,1553560144.743 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:06.737Z,1553560146.737 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:08.742Z,1553560148.742 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:10.743Z,1553560150.743 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:12.737Z,1553560152.737 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:14.741Z,1553560154.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:16.741Z,1553560156.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:18.737Z,1553560158.737 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:20.745Z,1553560160.745 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:22.741Z,1553560162.741 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:24.737Z,1553560164.737 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:27.137Z,1553560167.137 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:29.941Z,1553560169.941 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:33.137Z,1553560173.137 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:35.937Z,1553560175.937 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:39.137Z,1553560179.137 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:41.941Z,1553560181.941 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:41.964Z,1553560181.964 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T00:24:41.8Z 2019-03-26T00:29:41.965Z,1553560181.965 [Default:CheckIn:Read_GPS] Stopped 2019-03-26T00:29:41.965Z,1553560181.965 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-26T00:29:46.441Z,1553560186.441 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20190325T235747/Courier0010.lzma 2019-03-26T00:29:46.853Z,1553560186.853 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:47.226Z,1553560187.226 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Courier0010.lzma.bak 2019-03-26T00:29:47.226Z,1553560187.226 [DataOverHttps](INFO): SBD MOMSN=10245298 2019-03-26T00:29:51.601Z,1553560191.601 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:55.972Z,1553560195.972 [DataOverHttps](INFO): Sending 486 bytes from file Logs/20190325T235747/Express0011.lzma 2019-03-26T00:29:56.353Z,1553560196.353 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:29:56.738Z,1553560196.738 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Express0011.lzma.bak 2019-03-26T00:29:56.738Z,1553560196.738 [DataOverHttps](INFO): SBD MOMSN=10245302 2019-03-26T00:29:57.405Z,1553560197.405 [Default:CheckIn:Read_Iridium] Stopped 2019-03-26T00:29:57.405Z,1553560197.405 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-26T00:29:57.405Z,1553560197.405 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:30:01.105Z,1553560201.105 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:30:03.905Z,1553560203.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:30:07.109Z,1553560207.109 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:30:09.909Z,1553560209.909 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:30:12.322Z,1553560212.322 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:30:12.322Z,1553560212.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2019-03-26T00:30:12.322Z,1553560212.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:30:12.324Z,1553560212.324 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:30:12.324Z,1553560212.324 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2019-03-26T00:30:12.324Z,1553560212.324 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:30:12.338Z,1553560212.338 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:30:12.338Z,1553560212.338 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 16 2019-03-26T00:30:12.338Z,1553560212.338 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:30:12.351Z,1553560212.351 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:30:12.351Z,1553560212.351 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:30:12.351Z,1553560212.351 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:30:12.738Z,1553560212.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:30:12.738Z,1553560212.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2019-03-26T00:30:12.738Z,1553560212.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:30:12.738Z,1553560212.738 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2019-03-26T00:30:12.739Z,1553560212.739 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:30:12.739Z,1553560212.739 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 16 2019-03-26T00:30:13.090Z,1553560213.090 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-03-26T00:30:13.090Z,1553560213.090 [NAL9602] Data Fault, FailCount= 3 2019-03-26T00:30:13.091Z,1553560213.091 [NAL9602](ERROR): Data Fault 2019-03-26T00:30:13.146Z,1553560213.146 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:30:13.158Z,1553560213.158 [CBIT](ERROR): Data Fault in component: NAL9602 2019-03-26T00:30:13.490Z,1553560213.490 [NAL9602](INFO): Powering down 2019-03-26T00:30:14.357Z,1553560214.357 [CBIT](INFO): Clearing failed state for component NAL9602 2019-03-26T00:30:14.357Z,1553560214.357 [NAL9602] No Fault, FailCount= 3 2019-03-26T00:30:43.887Z,1553560243.887 [NAL9602](INFO): Powering up NAL9602 2019-03-26T00:30:55.091Z,1553560255.091 [NAL9602](INFO): NAL9602 initialized 2019-03-26T00:30:55.905Z,1553560255.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:01.457Z,1553560261.457 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:03.853Z,1553560263.853 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:07.049Z,1553560267.049 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:09.853Z,1553560269.853 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:13.049Z,1553560273.049 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:15.853Z,1553560275.853 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:19.049Z,1553560279.049 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:21.849Z,1553560281.849 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:25.049Z,1553560285.049 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:27.845Z,1553560287.845 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:31.053Z,1553560291.053 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:33.845Z,1553560293.845 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:37.049Z,1553560297.049 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:39.845Z,1553560299.845 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:43.049Z,1553560303.049 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:45.845Z,1553560305.845 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:47.849Z,1553560307.849 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:49.849Z,1553560309.849 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:51.845Z,1553560311.845 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:53.849Z,1553560313.849 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:55.849Z,1553560315.849 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:31:57.849Z,1553560317.849 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:03.029Z,1553560323.029 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:05.833Z,1553560325.833 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:07.829Z,1553560327.829 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:10.229Z,1553560330.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:13.037Z,1553560333.037 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:13.454Z,1553560333.454 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:32:13.454Z,1553560333.454 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2019-03-26T00:32:13.454Z,1553560333.454 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:32:13.456Z,1553560333.456 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:32:13.456Z,1553560333.456 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2019-03-26T00:32:13.456Z,1553560333.456 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:32:13.474Z,1553560333.474 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:32:13.474Z,1553560333.474 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 17 2019-03-26T00:32:13.474Z,1553560333.474 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:32:13.507Z,1553560333.507 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:32:13.507Z,1553560333.507 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:32:13.508Z,1553560333.508 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:32:13.889Z,1553560333.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:32:13.889Z,1553560333.889 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2019-03-26T00:32:13.889Z,1553560333.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:32:13.889Z,1553560333.889 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2019-03-26T00:32:13.890Z,1553560333.890 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:32:13.890Z,1553560333.890 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 17 2019-03-26T00:32:14.250Z,1553560334.250 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:32:16.231Z,1553560336.231 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:19.037Z,1553560339.037 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:22.229Z,1553560342.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:25.033Z,1553560345.033 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:28.229Z,1553560348.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:31.035Z,1553560351.035 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:34.229Z,1553560354.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:37.035Z,1553560357.035 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:40.235Z,1553560360.235 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:43.033Z,1553560363.033 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:46.234Z,1553560366.234 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:49.035Z,1553560369.035 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:52.229Z,1553560372.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:55.035Z,1553560375.035 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:32:58.238Z,1553560378.238 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:02.997Z,1553560382.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:06.197Z,1553560386.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:09.001Z,1553560389.001 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:12.197Z,1553560392.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:14.997Z,1553560394.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:16.993Z,1553560396.993 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:18.993Z,1553560398.993 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:19.876Z,1553560399.876 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:33:19.876Z,1553560399.876 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:33:20.997Z,1553560400.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:22.997Z,1553560402.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:24.997Z,1553560404.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:26.997Z,1553560406.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:28.620Z,1553560408.620 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:33:28.620Z,1553560408.620 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:33:28.620Z,1553560408.620 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:33:28.648Z,1553560408.648 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:33:28.997Z,1553560408.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:29.011Z,1553560409.011 [PNI_TCM](INFO): Powering down 2019-03-26T00:33:30.223Z,1553560410.223 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:33:30.223Z,1553560410.223 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:33:30.997Z,1553560410.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:32.997Z,1553560412.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:34.997Z,1553560414.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:36.997Z,1553560416.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:39.001Z,1553560419.001 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:39.036Z,1553560419.036 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:33:39.036Z,1553560419.036 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:33:39.036Z,1553560419.036 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:33:39.063Z,1553560419.063 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:33:39.423Z,1553560419.423 [PNI_TCM](INFO): Powering down 2019-03-26T00:33:40.624Z,1553560420.624 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:33:40.624Z,1553560420.624 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:33:40.997Z,1553560420.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:42.997Z,1553560422.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:45.001Z,1553560425.001 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:46.997Z,1553560426.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:48.993Z,1553560428.993 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:49.425Z,1553560429.425 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:33:49.425Z,1553560429.425 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:33:49.425Z,1553560429.425 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:33:49.484Z,1553560429.484 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:33:49.813Z,1553560429.813 [PNI_TCM](INFO): Powering down 2019-03-26T00:33:50.997Z,1553560430.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:51.036Z,1553560431.036 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:33:51.036Z,1553560431.036 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:33:52.997Z,1553560432.997 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:55.397Z,1553560435.397 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:33:58.197Z,1553560438.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:02.125Z,1553560442.125 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:34:02.125Z,1553560442.125 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:34:02.125Z,1553560442.125 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:34:02.181Z,1553560442.181 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:34:02.247Z,1553560442.247 [PNI_TCM](INFO): Powering down 2019-03-26T00:34:02.609Z,1553560442.609 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:03.467Z,1553560443.467 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:34:03.467Z,1553560443.467 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:34:05.413Z,1553560445.413 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:08.205Z,1553560448.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:11.409Z,1553560451.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:12.241Z,1553560452.241 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:34:12.241Z,1553560452.241 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:34:12.241Z,1553560452.241 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:34:12.300Z,1553560452.300 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:34:12.305Z,1553560452.305 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:34:12.612Z,1553560452.612 [PNI_TCM](INFO): Powering down 2019-03-26T00:34:12.722Z,1553560452.722 [CBIT](INFO): Critical error at 20190326T003412 2019-03-26T00:34:14.213Z,1553560454.213 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:14.253Z,1553560454.253 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:34:14.253Z,1553560454.253 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2019-03-26T00:34:14.253Z,1553560454.253 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:34:14.254Z,1553560454.254 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:34:14.255Z,1553560454.255 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2019-03-26T00:34:14.255Z,1553560454.255 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:34:14.256Z,1553560454.256 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:34:14.256Z,1553560454.256 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 18 2019-03-26T00:34:14.256Z,1553560454.256 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:34:14.268Z,1553560454.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:34:14.269Z,1553560454.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:34:14.269Z,1553560454.269 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:34:14.668Z,1553560454.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:34:14.669Z,1553560454.669 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2019-03-26T00:34:14.669Z,1553560454.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:34:14.669Z,1553560454.669 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2019-03-26T00:34:14.670Z,1553560454.670 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:34:14.670Z,1553560454.670 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 18 2019-03-26T00:34:15.034Z,1553560455.034 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:34:17.409Z,1553560457.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:20.213Z,1553560460.213 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:23.410Z,1553560463.410 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:26.217Z,1553560466.217 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:29.411Z,1553560469.411 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:32.205Z,1553560472.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:35.409Z,1553560475.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:38.205Z,1553560478.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:41.409Z,1553560481.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:44.205Z,1553560484.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:47.409Z,1553560487.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:50.205Z,1553560490.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:53.405Z,1553560493.405 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:56.205Z,1553560496.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:34:57.822Z,1553560497.822 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-03-26T00:34:57.822Z,1553560497.822 [Default:CheckIn:C.Wait] Stopped 2019-03-26T00:34:57.822Z,1553560497.822 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:34:57.822Z,1553560497.822 [Default:CheckIn:D] Running Loop=1 2019-03-26T00:34:58.218Z,1553560498.218 [Default:CheckIn:D] Stopped 2019-03-26T00:34:58.218Z,1553560498.218 [Default:CheckIn:E] Running Loop=1 2019-03-26T00:34:58.625Z,1553560498.625 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.560447 min 2019-03-26T00:34:58.625Z,1553560498.625 [Default:CheckIn:E] Stopped 2019-03-26T00:34:58.625Z,1553560498.625 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-03-26T00:34:58.625Z,1553560498.625 [Default:CheckIn] Stopped 2019-03-26T00:34:58.626Z,1553560498.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-26T00:34:58.626Z,1553560498.626 [Default:CheckIn](INFO): Running loop #4 2019-03-26T00:34:58.626Z,1553560498.626 [Default:CheckIn] Running Loop=4 2019-03-26T00:34:58.626Z,1553560498.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-26T00:34:58.626Z,1553560498.626 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-26T00:35:02.101Z,1553560502.101 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:04.189Z,1553560504.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:07.385Z,1553560507.385 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:10.189Z,1553560510.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:13.385Z,1553560513.385 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:16.189Z,1553560516.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:19.385Z,1553560519.385 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:22.189Z,1553560522.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:24.185Z,1553560524.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:26.189Z,1553560526.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:28.189Z,1553560528.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:30.185Z,1553560530.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:32.189Z,1553560532.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:34.189Z,1553560534.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:36.185Z,1553560536.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:38.189Z,1553560538.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:40.197Z,1553560540.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:42.185Z,1553560542.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:44.189Z,1553560544.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:46.189Z,1553560546.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:48.185Z,1553560548.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:50.189Z,1553560550.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:52.189Z,1553560552.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:54.185Z,1553560554.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:56.189Z,1553560556.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:35:58.189Z,1553560558.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:02.937Z,1553560562.937 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:05.337Z,1553560565.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:08.541Z,1553560568.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:11.337Z,1553560571.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:14.541Z,1553560574.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:15.370Z,1553560575.370 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:36:15.370Z,1553560575.370 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2019-03-26T00:36:15.370Z,1553560575.370 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:36:15.372Z,1553560575.372 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:36:15.372Z,1553560575.372 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2019-03-26T00:36:15.372Z,1553560575.372 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:36:15.374Z,1553560575.374 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:36:15.374Z,1553560575.374 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 19 2019-03-26T00:36:15.374Z,1553560575.374 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:36:15.427Z,1553560575.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:36:15.427Z,1553560575.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:36:15.427Z,1553560575.427 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:36:15.765Z,1553560575.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:36:15.765Z,1553560575.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2019-03-26T00:36:15.766Z,1553560575.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:36:15.766Z,1553560575.766 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2019-03-26T00:36:15.766Z,1553560575.766 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-26T00:36:15.766Z,1553560575.766 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 19 2019-03-26T00:36:16.162Z,1553560576.162 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:36:17.337Z,1553560577.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:20.541Z,1553560580.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:23.337Z,1553560583.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:26.541Z,1553560586.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:29.337Z,1553560589.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:32.541Z,1553560592.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:35.337Z,1553560595.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:38.541Z,1553560598.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:41.337Z,1553560601.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:44.541Z,1553560604.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:47.337Z,1553560607.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:50.541Z,1553560610.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:53.337Z,1553560613.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:56.541Z,1553560616.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:36:59.349Z,1553560619.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:04.530Z,1553560624.530 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:07.333Z,1553560627.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:09.329Z,1553560629.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:11.333Z,1553560631.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:13.333Z,1553560633.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:15.333Z,1553560635.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:17.333Z,1553560637.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:19.333Z,1553560639.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:21.329Z,1553560641.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:23.333Z,1553560643.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:25.333Z,1553560645.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:27.329Z,1553560647.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:29.333Z,1553560649.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:31.333Z,1553560651.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:33.733Z,1553560653.733 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:36.534Z,1553560656.534 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:39.735Z,1553560659.735 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:42.533Z,1553560662.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:45.739Z,1553560665.739 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:48.533Z,1553560668.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:51.733Z,1553560671.733 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:54.533Z,1553560674.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:37:57.733Z,1553560677.733 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:03.229Z,1553560683.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:05.714Z,1553560685.714 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:08.509Z,1553560688.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:11.715Z,1553560691.715 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:14.509Z,1553560694.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:16.520Z,1553560696.520 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:38:16.520Z,1553560696.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2019-03-26T00:38:16.520Z,1553560696.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:38:16.522Z,1553560696.522 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:38:16.522Z,1553560696.522 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2019-03-26T00:38:16.522Z,1553560696.522 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:38:16.523Z,1553560696.523 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:38:16.524Z,1553560696.524 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 20 2019-03-26T00:38:16.524Z,1553560696.524 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-26T00:38:16.548Z,1553560696.548 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:38:16.553Z,1553560696.553 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:38:16.553Z,1553560696.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:38:16.554Z,1553560696.554 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:38:16.554Z,1553560696.554 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:38:16.554Z,1553560696.554 [CBIT](FAULT): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-26T00:38:17.713Z,1553560697.713 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:20.515Z,1553560700.515 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:23.709Z,1553560703.709 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:24.158Z,1553560704.158 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-26T00:38:24.158Z,1553560704.158 [Rowe_600LCM] No Fault, FailCount= 5 2019-03-26T00:38:24.237Z,1553560704.237 [Rowe_600LCM](INFO): Initializing 2019-03-26T00:38:24.237Z,1553560704.237 [Rowe_600LCM](INFO): Powering up 2019-03-26T00:38:26.509Z,1553560706.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:28.356Z,1553560708.356 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-26T00:38:28.419Z,1553560708.419 [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 & 2019-03-26T00:38:29.717Z,1553560709.717 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:32.513Z,1553560712.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:35.713Z,1553560715.713 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:38.513Z,1553560718.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:41.710Z,1553560721.710 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:44.513Z,1553560724.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:47.713Z,1553560727.713 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:50.517Z,1553560730.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:52.513Z,1553560732.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:54.513Z,1553560734.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:56.509Z,1553560736.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:38:58.517Z,1553560738.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:00.513Z,1553560740.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:05.701Z,1553560745.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:08.501Z,1553560748.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:10.501Z,1553560750.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:12.513Z,1553560752.513 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:12.564Z,1553560752.564 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:39:12.564Z,1553560752.564 [PNI_TCM] No Fault, FailCount= 5 2019-03-26T00:39:14.501Z,1553560754.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:16.501Z,1553560756.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:18.509Z,1553560758.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:20.501Z,1553560760.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:21.343Z,1553560761.343 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:39:21.343Z,1553560761.343 [PNI_TCM] Data Fault, FailCount= 1 2019-03-26T00:39:21.343Z,1553560761.343 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:39:21.366Z,1553560761.366 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:39:21.712Z,1553560761.712 [PNI_TCM](INFO): Powering down 2019-03-26T00:39:22.501Z,1553560762.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:22.938Z,1553560762.938 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:39:22.938Z,1553560762.938 [PNI_TCM] No Fault, FailCount= 1 2019-03-26T00:39:24.901Z,1553560764.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:27.701Z,1553560767.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:30.908Z,1553560770.908 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:31.733Z,1553560771.733 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:39:31.733Z,1553560771.733 [PNI_TCM] Data Fault, FailCount= 2 2019-03-26T00:39:31.733Z,1553560771.733 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:39:31.797Z,1553560771.797 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:39:32.102Z,1553560772.102 [PNI_TCM](INFO): Powering down 2019-03-26T00:39:33.336Z,1553560773.336 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:39:33.336Z,1553560773.336 [PNI_TCM] No Fault, FailCount= 2 2019-03-26T00:39:33.701Z,1553560773.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:36.905Z,1553560776.905 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:39.701Z,1553560779.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:42.121Z,1553560782.121 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:39:42.121Z,1553560782.121 [PNI_TCM] Data Fault, FailCount= 3 2019-03-26T00:39:42.121Z,1553560782.121 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:39:42.146Z,1553560782.146 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:39:42.501Z,1553560782.501 [PNI_TCM](INFO): Powering down 2019-03-26T00:39:42.901Z,1553560782.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:43.742Z,1553560783.742 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:39:43.742Z,1553560783.742 [PNI_TCM] No Fault, FailCount= 3 2019-03-26T00:39:45.701Z,1553560785.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:48.901Z,1553560788.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:51.701Z,1553560791.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:52.545Z,1553560792.545 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:39:52.545Z,1553560792.545 [PNI_TCM] Data Fault, FailCount= 4 2019-03-26T00:39:52.545Z,1553560792.545 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:39:52.586Z,1553560792.586 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:39:52.901Z,1553560792.901 [PNI_TCM](INFO): Powering down 2019-03-26T00:39:54.117Z,1553560794.117 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-03-26T00:39:54.117Z,1553560794.117 [PNI_TCM] No Fault, FailCount= 4 2019-03-26T00:39:54.901Z,1553560794.901 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:57.701Z,1553560797.701 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:39:58.942Z,1553560798.942 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T00:34:58.6Z 2019-03-26T00:39:58.942Z,1553560798.942 [Default:CheckIn:Read_GPS] Stopped 2019-03-26T00:39:58.942Z,1553560798.942 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-26T00:40:03.237Z,1553560803.237 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:40:03.269Z,1553560803.269 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2019-03-26T00:40:03.269Z,1553560803.269 [PNI_TCM] Data Fault, FailCount= 5 2019-03-26T00:40:03.269Z,1553560803.269 [PNI_TCM](ERROR): Data Fault 2019-03-26T00:40:03.319Z,1553560803.319 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-03-26T00:40:03.320Z,1553560803.320 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-03-26T00:40:03.349Z,1553560803.349 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190325T235747/Courier0013.lzma 2019-03-26T00:40:03.419Z,1553560803.419 [PNI_TCM](INFO): Powering down 2019-03-26T00:40:03.524Z,1553560803.524 [CBIT](INFO): Critical error at 20190326T004003 2019-03-26T00:40:04.142Z,1553560804.142 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Courier0013.lzma.bak 2019-03-26T00:40:04.142Z,1553560804.142 [DataOverHttps](INFO): SBD MOMSN=10245589 2019-03-26T00:40:08.185Z,1553560808.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:40:12.914Z,1553560812.914 [DataOverHttps](INFO): Sending 445 bytes from file Logs/20190325T235747/Express0014.lzma 2019-03-26T00:40:13.301Z,1553560813.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:40:13.684Z,1553560813.684 [DataOverHttps](INFO): Moved sent file to Logs/20190325T235747/Express0014.lzma.bak 2019-03-26T00:40:13.684Z,1553560813.684 [DataOverHttps](INFO): SBD MOMSN=10245592 2019-03-26T00:40:14.323Z,1553560814.323 [Default:CheckIn:Read_Iridium] Stopped 2019-03-26T00:40:14.323Z,1553560814.323 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-26T00:40:14.323Z,1553560814.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:40:18.097Z,1553560818.097 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:40:20.897Z,1553560820.897 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:40:22.726Z,1553560822.726 [CommandLine](IMPORTANT): got command quit 2019-03-26T00:40:23.697Z,1553560823.697 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:40:23.736Z,1553560823.736 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:23.736Z,1553560823.736 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:23.790Z,1553560823.790 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-03-26T00:40:23.790Z,1553560823.790 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:23.791Z,1553560823.791 [CommandLine](INFO): Join timeout helper Thread ID is 6329 2019-03-26T00:40:23.791Z,1553560823.791 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-03-26T00:40:23.792Z,1553560823.792 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:23.792Z,1553560823.792 [NavChartDb](INFO): Join timeout helper Thread ID is 6330 2019-03-26T00:40:24.065Z,1553560824.065 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:24.065Z,1553560824.065 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:24.069Z,1553560824.069 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-03-26T00:40:24.069Z,1553560824.069 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:24.069Z,1553560824.069 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6331 2019-03-26T00:40:24.545Z,1553560824.545 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:24.545Z,1553560824.545 [WetLabsBB2FL](INFO): Powering down 2019-03-26T00:40:24.546Z,1553560824.546 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:24.553Z,1553560824.553 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-03-26T00:40:24.553Z,1553560824.553 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:24.553Z,1553560824.553 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6332 2019-03-26T00:40:24.757Z,1553560824.757 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-26T00:40:24.757Z,1553560824.757 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-03-26T00:40:24.757Z,1553560824.757 [Rowe_600LCM](ERROR): Communications Fault 2019-03-26T00:40:24.922Z,1553560824.922 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-26T00:40:24.985Z,1553560824.985 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:24.985Z,1553560824.985 [CTD_Seabird](INFO): Powering down 2019-03-26T00:40:24.997Z,1553560824.997 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:25.013Z,1553560825.013 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-03-26T00:40:25.013Z,1553560825.013 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:25.013Z,1553560825.013 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6333 2019-03-26T00:40:25.073Z,1553560825.073 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:25.073Z,1553560825.073 [CTD_NeilBrown](INFO): Powering down 2019-03-26T00:40:25.085Z,1553560825.085 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:25.094Z,1553560825.094 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2019-03-26T00:40:25.094Z,1553560825.094 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:25.094Z,1553560825.094 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6334 2019-03-26T00:40:25.161Z,1553560825.161 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:40:25.249Z,1553560825.249 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:40:25.653Z,1553560825.653 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:25.653Z,1553560825.653 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-26T00:40:25.827Z,1553560825.827 [Rowe_600LCM](INFO): Powering down 2019-03-26T00:40:25.828Z,1553560825.828 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:25.846Z,1553560825.846 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-03-26T00:40:25.846Z,1553560825.846 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:25.846Z,1553560825.846 [Radio_Surface](INFO): Join timeout helper Thread ID is 6337 2019-03-26T00:40:26.245Z,1553560826.245 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:26.245Z,1553560826.245 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.254Z,1553560826.254 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-03-26T00:40:26.254Z,1553560826.254 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.254Z,1553560826.254 [logger](INFO): Join timeout helper Thread ID is 6338 2019-03-26T00:40:26.289Z,1553560826.289 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:26.289Z,1553560826.289 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.293Z,1553560826.293 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-03-26T00:40:26.294Z,1553560826.294 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.294Z,1553560826.294 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-03-26T00:40:26.294Z,1553560826.294 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.294Z,1553560826.294 [controlThread](INFO): Join timeout helper Thread ID is 6339 2019-03-26T00:40:26.473Z,1553560826.473 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:40:26.473Z,1553560826.473 [controlThread](DEBUG): Uninitializing ControlThread 2019-03-26T00:40:26.474Z,1553560826.474 [NAL9602](INFO): Powering down 2019-03-26T00:40:26.475Z,1553560826.475 [PNI_TCM](INFO): Powering down 2019-03-26T00:40:26.476Z,1553560826.476 [Aanderaa_O2](INFO): Powering down 2019-03-26T00:40:26.478Z,1553560826.478 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-03-26T00:40:26.478Z,1553560826.478 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-03-26T00:40:26.479Z,1553560826.479 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-03-26T00:40:26.479Z,1553560826.479 [MissionManager](INFO): Uninitializing Mission Default 2019-03-26T00:40:26.479Z,1553560826.479 [Default] Stopped 2019-03-26T00:40:26.479Z,1553560826.479 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-26T00:40:26.480Z,1553560826.480 [Default:B.GoToSurface] Stopped 2019-03-26T00:40:26.480Z,1553560826.480 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-26T00:40:26.480Z,1553560826.480 [Default:CheckIn] Stopped 2019-03-26T00:40:26.480Z,1553560826.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-26T00:40:26.480Z,1553560826.480 [Default:CheckIn:C.Wait] Stopped 2019-03-26T00:40:26.480Z,1553560826.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:40:26.484Z,1553560826.484 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-03-26T00:40:26.484Z,1553560826.484 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-03-26T00:40:26.484Z,1553560826.484 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-03-26T00:40:26.484Z,1553560826.484 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-03-26T00:40:26.484Z,1553560826.484 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-03-26T00:40:26.485Z,1553560826.485 [BuoyancyServo](INFO): Powering down 2019-03-26T00:40:26.497Z,1553560826.497 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:40:26.497Z,1553560826.497 [ElevatorServo](INFO): Powering down 2019-03-26T00:40:26.498Z,1553560826.498 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-26T00:40:26.498Z,1553560826.498 [MassServo](INFO): Powering down 2019-03-26T00:40:26.499Z,1553560826.499 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:40:26.499Z,1553560826.499 [RudderServo](INFO): Powering down 2019-03-26T00:40:26.500Z,1553560826.500 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-03-26T00:40:26.500Z,1553560826.500 [ThrusterServo](INFO): Powering down 2019-03-26T00:40:26.501Z,1553560826.501 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-03-26T00:40:26.501Z,1553560826.501 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-03-26T00:40:26.501Z,1553560826.501 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-03-26T00:40:26.502Z,1553560826.502 [CBIT](DEBUG): Powering off loads. 2019-03-26T00:40:26.513Z,1553560826.513 [CBIT](DEBUG): Disabling WDT. 2019-03-26T00:40:26.525Z,1553560826.525 [CBIT](DEBUG): Opening all GF detection circuits. 2019-03-26T00:40:26.526Z,1553560826.526 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.587Z,1553560826.587 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.594Z,1553560826.594 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.626Z,1553560826.626 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.630Z,1553560826.630 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.634Z,1553560826.634 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.670Z,1553560826.670 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:40:26.734Z,1553560826.734 [logger ThreadHandler](INFO): Thread cancelled.