2019-02-07T21:17:01.949Z,1549574221.949 [Supervisor](DEBUG): Initializing supervisor. 2019-02-07T21:17:01.952Z,1549574221.952 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-07T21:17:01.953Z,1549574221.953 [SyncHandler](INFO): Protected caller Thread ID is 4842 2019-02-07T21:17:01.953Z,1549574221.953 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-07T21:17:01.954Z,1549574221.954 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-07T21:17:01.955Z,1549574221.955 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4843 2019-02-07T21:17:01.958Z,1549574221.958 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-07T21:17:01.970Z,1549574221.970 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-07T21:17:01.971Z,1549574221.971 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-07T21:17:01.971Z,1549574221.971 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4844 2019-02-07T21:17:01.972Z,1549574221.972 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-07T21:17:01.973Z,1549574221.973 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-07T21:17:01.973Z,1549574221.973 [logger ThreadHandler](INFO): Protected caller Thread ID is 4845 2019-02-07T21:17:01.975Z,1549574221.975 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-07T21:17:01.976Z,1549574221.976 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-07T21:17:01.977Z,1549574221.977 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-07T21:17:02.128Z,1549574222.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-07T21:17:02.129Z,1549574222.129 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-07T21:17:02.324Z,1549574222.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-07T21:17:02.325Z,1549574222.325 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-07T21:17:02.460Z,1549574222.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-07T21:17:02.461Z,1549574222.461 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-07T21:17:02.917Z,1549574222.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-07T21:17:02.918Z,1549574222.918 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-07T21:17:03.430Z,1549574223.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-07T21:17:03.431Z,1549574223.431 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-07T21:17:03.901Z,1549574223.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-07T21:17:03.902Z,1549574223.902 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-07T21:17:04.206Z,1549574224.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-07T21:17:04.206Z,1549574224.206 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-07T21:17:04.551Z,1549574224.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-07T21:17:04.551Z,1549574224.551 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-07T21:17:04.938Z,1549574224.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-07T21:17:04.938Z,1549574224.938 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-07T21:17:05.086Z,1549574225.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-07T21:17:05.087Z,1549574225.087 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-07T21:17:05.192Z,1549574225.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-07T21:17:05.192Z,1549574225.192 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-07T21:17:05.272Z,1549574225.272 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-07T21:17:05.423Z,1549574225.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-07T21:17:05.424Z,1549574225.424 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-07T21:17:06.078Z,1549574226.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-07T21:17:06.079Z,1549574226.079 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-07T21:17:06.285Z,1549574226.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-07T21:17:06.287Z,1549574226.287 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-02-07T21:17:06.288Z,1549574226.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-02-07T21:17:06.371Z,1549574226.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-02-07T21:17:06.604Z,1549574226.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-07T21:17:06.605Z,1549574226.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-02-07T21:17:06.699Z,1549574226.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-02-07T21:17:06.857Z,1549574226.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-02-07T21:17:07.061Z,1549574227.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-02-07T21:17:07.145Z,1549574227.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-02-07T21:17:07.243Z,1549574227.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-02-07T21:17:07.335Z,1549574227.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-02-07T21:17:07.485Z,1549574227.485 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-02-07T21:17:07.590Z,1549574227.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-02-07T21:17:07.688Z,1549574227.688 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-02-07T21:17:07.688Z,1549574227.688 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-07T21:17:07.690Z,1549574227.690 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-07T21:17:07.792Z,1549574227.792 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-07T21:17:07.792Z,1549574227.792 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-07T21:17:07.898Z,1549574227.898 [BuoyancyServo] Loaded 2019-02-07T21:17:07.898Z,1549574227.898 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-07T21:17:07.913Z,1549574227.913 [ElevatorServo] Loaded 2019-02-07T21:17:07.913Z,1549574227.913 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-07T21:17:07.928Z,1549574227.928 [MassServo] Loaded 2019-02-07T21:17:07.928Z,1549574227.928 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-07T21:17:07.943Z,1549574227.943 [RudderServo] Loaded 2019-02-07T21:17:07.943Z,1549574227.943 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-07T21:17:07.957Z,1549574227.957 [ThrusterServo] Loaded 2019-02-07T21:17:07.957Z,1549574227.957 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-07T21:17:07.958Z,1549574227.958 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-07T21:17:07.959Z,1549574227.959 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-07T21:17:08.092Z,1549574228.092 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-07T21:17:08.120Z,1549574228.120 [SBIT] Loaded 2019-02-07T21:17:08.120Z,1549574228.120 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-07T21:17:08.121Z,1549574228.121 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-07T21:17:08.149Z,1549574228.149 [IBIT] Loaded 2019-02-07T21:17:08.149Z,1549574228.149 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-07T21:17:08.152Z,1549574228.152 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-07T21:17:08.535Z,1549574228.535 [CBIT] Loaded 2019-02-07T21:17:08.535Z,1549574228.535 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-07T21:17:08.536Z,1549574228.536 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-07T21:17:08.536Z,1549574228.536 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-07T21:17:08.715Z,1549574228.715 [Aanderaa_O2] Loaded 2019-02-07T21:17:08.715Z,1549574228.715 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-07T21:17:08.725Z,1549574228.725 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-02-07T21:17:08.731Z,1549574228.731 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-02-07T21:17:08.733Z,1549574228.733 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-02-07T21:17:08.737Z,1549574228.737 [CTD_Seabird](INFO): created writer for : depth 2019-02-07T21:17:08.738Z,1549574228.738 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-02-07T21:17:08.743Z,1549574228.743 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-02-07T21:17:08.744Z,1549574228.744 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-02-07T21:17:08.749Z,1549574228.749 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-02-07T21:17:08.749Z,1549574228.749 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-02-07T21:17:08.755Z,1549574228.755 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-02-07T21:17:08.755Z,1549574228.755 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-02-07T21:17:08.761Z,1549574228.761 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-02-07T21:17:08.761Z,1549574228.761 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-02-07T21:17:08.766Z,1549574228.766 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-02-07T21:17:08.793Z,1549574228.793 [CTD_Seabird] Loaded 2019-02-07T21:17:08.793Z,1549574228.793 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-02-07T21:17:08.798Z,1549574228.798 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062E4E0 2019-02-07T21:17:08.799Z,1549574228.799 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4925 2019-02-07T21:17:08.820Z,1549574228.820 [ESPComponent] Loaded 2019-02-07T21:17:08.820Z,1549574228.820 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-02-07T21:17:08.833Z,1549574228.833 [PAR_Licor] Loaded 2019-02-07T21:17:08.834Z,1549574228.834 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-02-07T21:17:08.881Z,1549574228.881 [WetLabsBB2FL] Loaded 2019-02-07T21:17:08.881Z,1549574228.881 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-02-07T21:17:08.882Z,1549574228.882 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065E4E0 2019-02-07T21:17:08.883Z,1549574228.883 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4926 2019-02-07T21:17:08.883Z,1549574228.883 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-07T21:17:08.884Z,1549574228.884 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-07T21:17:08.926Z,1549574228.926 [DepthRateCalculator] Loaded 2019-02-07T21:17:08.926Z,1549574228.926 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-07T21:17:08.932Z,1549574228.932 [PitchRateCalculator] Loaded 2019-02-07T21:17:08.932Z,1549574228.932 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-07T21:17:08.947Z,1549574228.947 [SpeedCalculator] Loaded 2019-02-07T21:17:08.947Z,1549574228.947 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-07T21:17:08.968Z,1549574228.968 [TempGradientCalculator] Loaded 2019-02-07T21:17:08.968Z,1549574228.968 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-07T21:17:08.974Z,1549574228.974 [YawRateCalculator] Loaded 2019-02-07T21:17:08.974Z,1549574228.974 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-07T21:17:09.007Z,1549574229.007 [ElevatorOffsetCalculator] Loaded 2019-02-07T21:17:09.007Z,1549574229.007 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-07T21:17:09.008Z,1549574229.008 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-07T21:17:09.008Z,1549574229.008 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-07T21:17:09.037Z,1549574229.037 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-07T21:17:09.038Z,1549574229.038 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-07T21:17:09.139Z,1549574229.139 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-07T21:17:09.139Z,1549574229.139 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-07T21:17:09.197Z,1549574229.197 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-07T21:17:09.197Z,1549574229.197 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-07T21:17:09.256Z,1549574229.256 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-07T21:17:09.256Z,1549574229.256 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-07T21:17:09.272Z,1549574229.272 [NavChart] Loaded 2019-02-07T21:17:09.272Z,1549574229.272 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-07T21:17:09.276Z,1549574229.276 [UniversalFixResidualReporter] Loaded 2019-02-07T21:17:09.276Z,1549574229.276 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-07T21:17:09.277Z,1549574229.277 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-07T21:17:09.277Z,1549574229.277 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-07T21:17:09.701Z,1549574229.701 [AcousticModem_Benthos_ATM900] Loaded 2019-02-07T21:17:09.701Z,1549574229.701 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-02-07T21:17:10.002Z,1549574230.002 [DataOverHttps] Loaded 2019-02-07T21:17:10.002Z,1549574230.002 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-07T21:17:10.015Z,1549574230.015 [Depth_Keller] Loaded 2019-02-07T21:17:10.015Z,1549574230.015 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-07T21:17:10.020Z,1549574230.020 [DropWeight] Loaded 2019-02-07T21:17:10.020Z,1549574230.020 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-07T21:17:10.109Z,1549574230.109 [NAL9602] Loaded 2019-02-07T21:17:10.109Z,1549574230.109 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-07T21:17:10.115Z,1549574230.115 [Onboard] Loaded 2019-02-07T21:17:10.115Z,1549574230.115 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-07T21:17:10.121Z,1549574230.121 [Radio_Surface] Loaded 2019-02-07T21:17:10.122Z,1549574230.122 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-07T21:17:10.125Z,1549574230.125 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409254E0 2019-02-07T21:17:10.126Z,1549574230.126 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4927 2019-02-07T21:17:10.172Z,1549574230.172 [PNI_TCM] Loaded 2019-02-07T21:17:10.172Z,1549574230.172 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-02-07T21:17:10.232Z,1549574230.232 [Rowe_600LCM] Loaded 2019-02-07T21:17:10.232Z,1549574230.232 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-02-07T21:17:10.233Z,1549574230.233 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409554E0 2019-02-07T21:17:10.233Z,1549574230.233 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 4928 2019-02-07T21:17:12.171Z,1549574232.171 [BPC1] Loaded 2019-02-07T21:17:12.171Z,1549574232.171 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-07T21:17:12.171Z,1549574232.171 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-07T21:17:12.172Z,1549574232.172 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-07T21:17:12.247Z,1549574232.247 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-07T21:17:12.248Z,1549574232.248 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-07T21:17:12.324Z,1549574232.324 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-07T21:17:12.408Z,1549574232.408 [VerticalControl] Loaded 2019-02-07T21:17:12.408Z,1549574232.408 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-07T21:17:12.409Z,1549574232.409 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-07T21:17:12.466Z,1549574232.466 [HorizontalControl] Loaded 2019-02-07T21:17:12.466Z,1549574232.466 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-07T21:17:12.467Z,1549574232.467 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-07T21:17:12.469Z,1549574232.469 [SpeedControl] Loaded 2019-02-07T21:17:12.469Z,1549574232.469 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-07T21:17:12.469Z,1549574232.469 [LoopControl](DEBUG): Construct LoopControl. 2019-02-07T21:17:12.470Z,1549574232.470 [LoopControl] Loaded 2019-02-07T21:17:12.470Z,1549574232.470 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-07T21:17:12.471Z,1549574232.471 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-07T21:17:12.471Z,1549574232.471 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-07T21:17:12.488Z,1549574232.488 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-07T21:17:12.488Z,1549574232.488 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-07T21:17:12.764Z,1549574232.764 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-07T21:17:12.767Z,1549574232.767 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-07T21:17:12.768Z,1549574232.768 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-07T21:17:12.775Z,1549574232.775 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-07T21:17:12.835Z,1549574232.835 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0 2019-02-07T21:17:12.836Z,1549574232.836 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4929 2019-02-07T21:17:13.157Z,1549574233.157 [Supervisor](INFO): Main Thread ID is 4841 2019-02-07T21:17:13.157Z,1549574233.157 [Supervisor](DEBUG): Running supervisor. 2019-02-07T21:17:13.157Z,1549574233.157 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4930 2019-02-07T21:17:13.160Z,1549574233.160 [controlThread ThreadHandler](INFO): Handler Thread ID is 4931 2019-02-07T21:17:13.161Z,1549574233.161 [controlThread](DEBUG): Initializing ControlThread 2019-02-07T21:17:13.163Z,1549574233.163 [SBIT](INFO): Initialize SBIT Component. 2019-02-07T21:17:13.163Z,1549574233.163 [SBIT](IMPORTANT): git: 2019-01-14A-57-g72ba51e 2019-02-07T21:17:13.163Z,1549574233.163 [SBIT](INFO): git hash: 72ba51edeec12b341e49a5bae224e2abbf93cde2 2019-02-07T21:17:13.164Z,1549574233.164 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-07T21:17:13.165Z,1549574233.165 [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-02-07T21:17:13.166Z,1549574233.166 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-02-07T21:17:13.167Z,1549574233.167 [IBIT](INFO): Initialize IBIT Component. 2019-02-07T21:17:13.168Z,1549574233.168 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-07T21:17:13.169Z,1549574233.169 [logger ThreadHandler](INFO): Handler Thread ID is 4932 2019-02-07T21:17:13.178Z,1549574233.178 [CBIT](DEBUG): Initialized mux pins. 2019-02-07T21:17:13.178Z,1549574233.178 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-07T21:17:13.199Z,1549574233.199 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4933 2019-02-07T21:17:13.199Z,1549574233.199 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-02-07T21:17:13.202Z,1549574233.202 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2019-02-07T21:17:13.202Z,1549574233.202 [CBIT](DEBUG): Initializing heartbeat. 2019-02-07T21:17:13.203Z,1549574233.203 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-02-07T21:17:13.204Z,1549574233.204 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4935 2019-02-07T21:17:13.206Z,1549574233.206 [WetLabsBB2FL](INFO): Powering down 2019-02-07T21:17:13.271Z,1549574233.271 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4936 2019-02-07T21:17:13.274Z,1549574233.274 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-07T21:17:13.274Z,1549574233.274 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-07T21:17:13.277Z,1549574233.277 [Radio_Surface](INFO): Powering up 2019-02-07T21:17:13.315Z,1549574233.315 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-07T21:17:13.316Z,1549574233.316 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-07T21:17:13.316Z,1549574233.316 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-07T21:17:13.317Z,1549574233.317 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-07T21:17:13.317Z,1549574233.317 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-07T21:17:13.318Z,1549574233.318 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-07T21:17:13.319Z,1549574233.319 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:17:13.320Z,1549574233.320 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-07T21:17:13.321Z,1549574233.321 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-07T21:17:13.315Z,1549574233.315 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 4937 2019-02-07T21:17:13.323Z,1549574233.323 [Rowe_600LCM](INFO): Initializing 2019-02-07T21:17:13.342Z,1549574233.342 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-07T21:17:13.344Z,1549574233.344 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-07T21:17:13.345Z,1549574233.345 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-07T21:17:13.345Z,1549574233.345 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-07T21:17:13.351Z,1549574233.351 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-07T21:17:13.371Z,1549574233.371 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4938 2019-02-07T21:17:13.375Z,1549574233.375 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-07T21:17:13.475Z,1549574233.475 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2019-02-07T21:17:13.475Z,1549574233.475 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-07T21:17:13.477Z,1549574233.477 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2019-02-07T21:17:13.477Z,1549574233.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-07T21:17:13.490Z,1549574233.490 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2019-02-07T21:17:13.491Z,1549574233.491 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-07T21:17:13.494Z,1549574233.494 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2019-02-07T21:17:13.494Z,1549574233.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-07T21:17:13.496Z,1549574233.496 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-07T21:17:13.508Z,1549574233.508 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2019-02-07T21:17:13.508Z,1549574233.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-07T21:17:13.509Z,1549574233.509 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2019-02-07T21:17:13.510Z,1549574233.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-07T21:17:13.511Z,1549574233.511 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2019-02-07T21:17:13.511Z,1549574233.511 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-07T21:17:13.513Z,1549574233.513 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2019-02-07T21:17:13.539Z,1549574233.539 [MissionManager](DEBUG): 2019-02-07T21:17:13.540Z,1549574233.540 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-07T21:17:13.651Z,1549574233.651 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-07T21:17:13.798Z,1549574233.798 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-07T21:17:13.799Z,1549574233.799 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-07T21:17:13.801Z,1549574233.801 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-07T21:17:13.853Z,1549574233.853 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-07T21:17:13.880Z,1549574233.880 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-07T21:17:13.932Z,1549574233.932 [Rowe_600LCM](INFO): Powering up 2019-02-07T21:17:13.953Z,1549574233.953 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-07T21:17:13.956Z,1549574233.956 [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-02-07T21:17:13.975Z,1549574233.975 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,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-02-07T21:17:14.049Z,1549574234.049 [ESPComponent](INFO): powering down ESP 2019-02-07T21:17:14.479Z,1549574234.479 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-02-07T21:17:14.480Z,1549574234.480 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-02-07T21:17:14.517Z,1549574234.517 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-07T21:17:15.463Z,1549574235.463 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-07T21:17:15.471Z,1549574235.471 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-07T21:17:15.497Z,1549574235.497 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-07T21:17:15.507Z,1549574235.507 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-07T21:17:15.549Z,1549574235.549 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-07T21:17:15.559Z,1549574235.559 [MassServo](DEBUG): Initializing MassServo. 2019-02-07T21:17:15.577Z,1549574235.577 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-07T21:17:15.580Z,1549574235.580 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-07T21:17:15.595Z,1549574235.595 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-07T21:17:15.603Z,1549574235.603 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-07T21:17:15.660Z,1549574235.660 [CommandLine](FAULT): Scheduling is paused 2019-02-07T21:17:15.660Z,1549574235.660 [CBIT](INFO): Critical error at 20190207T211713 2019-02-07T21:17:15.660Z,1549574235.660 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-02-07T21:17:15.888Z,1549574235.888 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-02-07T21:17:15.888Z,1549574235.888 [DropWeight] Hardware Fault, FailCount= 1 2019-02-07T21:17:15.889Z,1549574235.889 [DropWeight](ERROR): Hardware Fault 2019-02-07T21:17:15.962Z,1549574235.962 [CBIT](INFO): Critical error at 20190207T211715 2019-02-07T21:17:15.965Z,1549574235.965 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-02-07T21:17:15.966Z,1549574235.966 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-02-07T21:17:18.071Z,1549574238.071 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-07T21:17:18.209Z,1549574238.209 [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-02-07T21:17:27.497Z,1549574247.497 [CBIT](INFO): Critical error at 20190207T211715 2019-02-07T21:17:27.501Z,1549574247.501 [CBIT](CRITICAL): Environmental Failure. Press:14.866839 PSI. Humidity:23%. Temp:26 C. ABORTING MISSION 2019-02-07T21:17:27.774Z,1549574247.774 [CBIT](INFO): Critical error at 20190207T211727 2019-02-07T21:17:28.317Z,1549574248.317 [Aanderaa_O2](INFO): Powering down 2019-02-07T21:17:28.415Z,1549574248.415 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004936 2019-02-07T21:17:34.783Z,1549574254.783 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-02-07T21:17:34.783Z,1549574254.783 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2019-02-07T21:17:34.783Z,1549574254.783 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-02-07T21:17:34.893Z,1549574254.893 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-02-07T21:17:34.932Z,1549574254.932 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-07T21:17:35.191Z,1549574255.191 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-02-07T21:17:36.130Z,1549574256.130 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-02-07T21:17:36.130Z,1549574256.130 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2019-02-07T21:17:37.785Z,1549574257.785 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-07T21:17:37.790Z,1549574257.790 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-07T21:17:38.431Z,1549574258.431 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-02-07T21:17:38.431Z,1549574258.431 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-02-07T21:17:40.334Z,1549574260.334 [NAL9602](INFO): Powering up NAL9602 2019-02-07T21:17:45.776Z,1549574265.776 [AcousticModem_Benthos_ATM900](DEBUG): 2019-02-07T21:17:47.408Z,1549574267.408 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2019-02-07T21:17:47.409Z,1549574267.409 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2019-02-07T21:17:47.410Z,1549574267.410 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2019-02-07T21:17:47.418Z,1549574267.418 [AcousticModem_Benthos_ATM900](DEBUG): Feb 7 2019 21:00:15 2019-02-07T21:17:47.800Z,1549574267.800 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2019-02-07T21:17:48.680Z,1549574268.680 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2019-02-07T21:17:48.898Z,1549574268.898 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010990 CHAN A1 (24V): -0.013709 CHAN A2 (12V): -0.004929 CHAN A3 (5V): -0.002209 CHAN B0 (3.3V): -0.000922 CHAN B1 (3.15aV): -0.001376 CHAN B2 (3.15bV): -0.001146 CHAN B3 (GND): -0.000215 OPEN: 0.004643 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-07T21:17:49.027Z,1549574269.027 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2019-02-07T21:17:49.028Z,1549574269.028 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2019-02-07T21:17:49.028Z,1549574269.028 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2019-02-07T21:17:49.435Z,1549574269.435 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2019-02-07T21:17:49.839Z,1549574269.839 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2019-02-07T21:17:49.840Z,1549574269.840 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2019-02-07T21:17:50.236Z,1549574270.236 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2019-02-07T21:17:51.456Z,1549574271.456 [NAL9602](INFO): NAL9602 initialized 2019-02-07T21:17:52.282Z,1549574272.282 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:18:17.994Z,1549574297.994 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:18:26.270Z,1549574306.270 [BPC1](FAULT): Failed to parse data from all battery packs. 2019-02-07T21:18:26.270Z,1549574306.270 [BPC1] Data Fault, FailCount= 1 2019-02-07T21:18:26.271Z,1549574306.271 [BPC1](ERROR): Data Fault 2019-02-07T21:18:26.365Z,1549574306.365 [CBIT](ERROR): Data Fault in component: BPC1 2019-02-07T21:18:35.144Z,1549574315.144 [SBIT](IMPORTANT): SBIT PASSED 2019-02-07T21:18:35.207Z,1549574315.207 [CommandLine](IMPORTANT): got command configSet list 2019-02-07T21:18:35.207Z,1549574315.207 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-07T21:18:35.208Z,1549574315.208 [CommandLine](IMPORTANT): No configSet variables persisted 2019-02-07T21:18:35.687Z,1549574315.687 [MissionManager](IMPORTANT): Started mission Startup 2019-02-07T21:18:35.688Z,1549574315.688 [Startup] Running Loop=1 2019-02-07T21:18:35.689Z,1549574315.689 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-07T21:18:35.689Z,1549574315.689 [Startup:A.GoToSurface] Running Loop=1 2019-02-07T21:18:35.689Z,1549574315.689 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-07T21:18:35.700Z,1549574315.700 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-07T21:18:35.725Z,1549574315.725 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-07T21:18:35.725Z,1549574315.725 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-07T21:18:35.726Z,1549574315.726 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-07T21:18:35.726Z,1549574315.726 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-07T21:18:35.732Z,1549574315.732 [Startup:StartupSatComms] Running Loop=1 2019-02-07T21:18:35.733Z,1549574315.733 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-07T21:18:35.733Z,1549574315.733 [Startup:StartupSatComms:A] Running Loop=1 2019-02-07T21:18:35.979Z,1549574315.979 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-07T21:19:09.927Z,1549574349.927 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:19:18.602Z,1549574358.602 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-07T21:19:24.255Z,1549574364.255 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:19:27.545Z,1549574367.545 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-07T21:19:35.860Z,1549574375.860 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-07T21:18:35.7Z 2019-02-07T21:19:35.860Z,1549574375.860 [Startup:StartupSatComms:A] Stopped 2019-02-07T21:19:35.860Z,1549574375.860 [Startup:StartupSatComms:B] Running Loop=1 2019-02-07T21:19:36.232Z,1549574376.232 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-07T21:19:43.220Z,1549574383.220 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20190207T211701/Courier0000.lzma 2019-02-07T21:19:43.985Z,1549574383.985 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Courier0000.lzma.bak 2019-02-07T21:19:43.985Z,1549574383.985 [DataOverHttps](INFO): SBD MOMSN=9640784 2019-02-07T21:19:58.343Z,1549574398.343 [DataOverHttps](INFO): Sending 846 bytes from file Logs/20190207T211701/Express0001.lzma 2019-02-07T21:19:59.129Z,1549574399.129 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Express0001.lzma.bak 2019-02-07T21:19:59.129Z,1549574399.129 [DataOverHttps](INFO): SBD MOMSN=9640791 2019-02-07T21:19:59.600Z,1549574399.600 [Startup:StartupSatComms:B] Stopped 2019-02-07T21:19:59.600Z,1549574399.600 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-07T21:19:59.600Z,1549574399.600 [Startup:StartupSatComms] Stopped 2019-02-07T21:19:59.600Z,1549574399.600 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-07T21:19:59.601Z,1549574399.601 [Startup](INFO): Completed Startup 2019-02-07T21:19:59.601Z,1549574399.601 [MissionManager](INFO): Startup is completed. 2019-02-07T21:19:59.602Z,1549574399.602 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-07T21:19:59.602Z,1549574399.602 [Startup] Stopped 2019-02-07T21:19:59.602Z,1549574399.602 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-07T21:19:59.602Z,1549574399.602 [Startup:A.GoToSurface] Stopped 2019-02-07T21:19:59.602Z,1549574399.602 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-07T21:20:00.010Z,1549574400.010 [MissionManager](IMPORTANT): Started mission Default 2019-02-07T21:20:00.011Z,1549574400.011 [Default] Running Loop=1 2019-02-07T21:20:00.011Z,1549574400.011 [Default](DEBUG): Aggregate::initialize Default 2019-02-07T21:20:00.011Z,1549574400.011 [Default:B.GoToSurface] Running Loop=1 2019-02-07T21:20:00.011Z,1549574400.011 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-07T21:20:00.011Z,1549574400.011 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-07T21:20:00.012Z,1549574400.012 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-07T21:20:00.012Z,1549574400.012 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-07T21:20:00.012Z,1549574400.012 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-07T21:20:00.013Z,1549574400.013 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-07T21:20:00.013Z,1549574400.013 [Default:A.Wait] Running Loop=1 2019-02-07T21:20:00.013Z,1549574400.013 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-07T21:20:13.392Z,1549574413.392 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-07T21:20:13.392Z,1549574413.392 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:20:13.394Z,1549574413.394 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-07T21:20:13.394Z,1549574413.394 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:20:13.395Z,1549574413.395 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-07T21:20:13.395Z,1549574413.395 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:20:13.397Z,1549574413.397 [Default:A.Wait](INFO): Done Waiting. 2019-02-07T21:20:13.398Z,1549574413.398 [Default:A.Wait] Stopped 2019-02-07T21:20:13.398Z,1549574413.398 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-02-07T21:20:13.408Z,1549574413.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:20:13.408Z,1549574413.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:20:13.408Z,1549574413.408 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:20:13.886Z,1549574413.886 [Default:CheckIn] Running Loop=1 2019-02-07T21:20:13.887Z,1549574413.887 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-07T21:20:13.887Z,1549574413.887 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-07T21:20:13.905Z,1549574413.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:20:13.905Z,1549574413.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-07T21:20:13.905Z,1549574413.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:20:13.905Z,1549574413.905 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-07T21:20:13.906Z,1549574413.906 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:20:13.906Z,1549574413.906 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-07T21:20:14.155Z,1549574414.155 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:20:14.158Z,1549574414.158 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-02-07T21:20:20.079Z,1549574420.079 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-07T21:20:20.092Z,1549574420.092 [NavChartDb](INFO): # of records loaded: 19732 2019-02-07T21:20:20.099Z,1549574420.099 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-07T21:20:20.138Z,1549574420.138 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-07T21:20:30.493Z,1549574430.493 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-07T21:20:51.382Z,1549574451.382 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:21:10.594Z,1549574470.594 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:21:31.674Z,1549574491.674 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:21:49.076Z,1549574509.076 [NavChartDb](INFO): # of records loaded: 20000 2019-02-07T21:22:11.531Z,1549574531.531 [NavChartDb](INFO): # of records loaded: 25000 2019-02-07T21:22:22.269Z,1549574542.269 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-07T21:22:29.756Z,1549574549.756 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-07T21:22:30.229Z,1549574550.229 [NavChartDb](INFO): # of records loaded: 30000 2019-02-07T21:22:57.787Z,1549574577.787 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-02-07T21:22:58.205Z,1549574578.205 [NavChartDb](INFO): # of records loaded: 35000 2019-02-07T21:22:58.582Z,1549574578.582 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:07.174Z,1549574587.174 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:09.550Z,1549574589.550 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:12.378Z,1549574592.378 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:14.461Z,1549574594.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-02-07T21:23:14.461Z,1549574594.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:23:14.463Z,1549574594.463 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-02-07T21:23:14.463Z,1549574594.463 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:23:14.464Z,1549574594.464 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-02-07T21:23:14.464Z,1549574594.464 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:23:14.476Z,1549574594.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:23:14.477Z,1549574594.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:23:14.477Z,1549574594.477 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:23:14.888Z,1549574594.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:23:14.888Z,1549574594.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-02-07T21:23:14.889Z,1549574594.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:23:14.889Z,1549574594.889 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-02-07T21:23:14.889Z,1549574594.889 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:23:14.889Z,1549574594.889 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-02-07T21:23:15.225Z,1549574595.225 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:23:15.618Z,1549574595.618 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:17.907Z,1549574597.907 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-07T21:23:17.933Z,1549574597.933 [NavChartDb](INFO): # of records loaded: 37376 2019-02-07T21:23:17.942Z,1549574597.942 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-07T21:23:17.983Z,1549574597.983 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-07T21:23:18.438Z,1549574598.438 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:21.310Z,1549574601.310 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:24.510Z,1549574604.510 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:25.675Z,1549574605.675 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-07T21:23:27.326Z,1549574607.326 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:30.558Z,1549574610.558 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:33.386Z,1549574613.386 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:36.622Z,1549574616.622 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:39.462Z,1549574619.462 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:42.678Z,1549574622.678 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:45.506Z,1549574625.506 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:48.234Z,1549574628.234 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:23:48.334Z,1549574628.334 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:51.566Z,1549574631.566 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:54.394Z,1549574634.394 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:23:57.638Z,1549574637.638 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:00.454Z,1549574640.454 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:01.594Z,1549574641.594 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:24:08.902Z,1549574648.902 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:11.730Z,1549574651.730 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:14.563Z,1549574654.563 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:15.324Z,1549574655.324 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-07T21:24:17.386Z,1549574657.386 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:17.676Z,1549574657.676 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-07T21:24:19.941Z,1549574659.941 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:24:20.621Z,1549574660.621 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:23.447Z,1549574663.447 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:26.682Z,1549574666.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:29.506Z,1549574669.506 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:32.742Z,1549574672.742 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:35.582Z,1549574675.582 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:37.061Z,1549574677.061 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-07T21:24:37.461Z,1549574677.461 [NavChartDb](INFO): # of records loaded: 16998 2019-02-07T21:24:37.469Z,1549574677.469 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-07T21:24:37.474Z,1549574677.474 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-07T21:24:38.394Z,1549574678.394 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:41.626Z,1549574681.626 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:44.099Z,1549574684.099 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-07T21:24:44.454Z,1549574684.454 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:47.694Z,1549574687.694 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:50.519Z,1549574690.519 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:53.750Z,1549574693.750 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:56.590Z,1549574696.590 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:24:59.418Z,1549574699.418 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:03.840Z,1549574703.840 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:25:09.426Z,1549574709.426 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:11.554Z,1549574711.554 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:14.010Z,1549574714.010 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-07T21:20:13.9Z 2019-02-07T21:25:14.018Z,1549574714.018 [Default:CheckIn:Read_GPS] Stopped 2019-02-07T21:25:14.018Z,1549574714.018 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-02-07T21:25:14.432Z,1549574714.432 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-07T21:25:14.894Z,1549574714.894 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:16.585Z,1549574716.585 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:25:23.533Z,1549574723.533 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190207T211701/Courier0004.lzma 2019-02-07T21:25:23.919Z,1549574723.919 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:24.322Z,1549574724.322 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Courier0004.lzma.bak 2019-02-07T21:25:24.323Z,1549574724.323 [DataOverHttps](INFO): SBD MOMSN=9640823 2019-02-07T21:25:30.440Z,1549574730.440 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:25:32.866Z,1549574732.866 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:41.095Z,1549574741.095 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20190207T211701/Express0005.lzma 2019-02-07T21:25:41.478Z,1549574741.478 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:41.869Z,1549574741.869 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Express0005.lzma.bak 2019-02-07T21:25:41.869Z,1549574741.869 [DataOverHttps](INFO): SBD MOMSN=9640825 2019-02-07T21:25:42.519Z,1549574742.519 [Default:CheckIn:Read_Iridium] Stopped 2019-02-07T21:25:42.519Z,1549574742.519 [Default:CheckIn:C.Wait] Running Loop=1 2019-02-07T21:25:42.519Z,1549574742.519 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-02-07T21:25:43.905Z,1549574743.905 [NavChartDb](INFO): # of records loaded: 20000 2019-02-07T21:25:50.449Z,1549574750.449 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:52.870Z,1549574752.870 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:55.698Z,1549574755.698 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:25:56.449Z,1549574756.449 [NavChartDb](INFO): # of records loaded: 25000 2019-02-07T21:25:58.530Z,1549574758.530 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:01.766Z,1549574761.766 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:04.586Z,1549574764.586 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:06.736Z,1549574766.736 [NavChartDb](INFO): # of records loaded: 30000 2019-02-07T21:26:07.826Z,1549574767.826 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:10.646Z,1549574770.646 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:13.903Z,1549574773.903 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:15.664Z,1549574775.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-02-07T21:26:15.664Z,1549574775.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:26:15.665Z,1549574775.665 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-02-07T21:26:15.665Z,1549574775.665 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:26:15.678Z,1549574775.678 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-02-07T21:26:15.679Z,1549574775.679 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:26:15.779Z,1549574775.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:26:15.779Z,1549574775.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:26:15.779Z,1549574775.779 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:26:16.090Z,1549574776.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:26:16.091Z,1549574776.091 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-02-07T21:26:16.091Z,1549574776.091 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:26:16.091Z,1549574776.091 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-02-07T21:26:16.092Z,1549574776.092 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:26:16.092Z,1549574776.092 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-02-07T21:26:16.466Z,1549574776.466 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:26:16.722Z,1549574776.722 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:19.551Z,1549574779.551 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:22.798Z,1549574782.798 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:24.572Z,1549574784.572 [NavChartDb](INFO): # of records loaded: 35000 2019-02-07T21:26:25.618Z,1549574785.618 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:28.859Z,1549574788.859 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:31.682Z,1549574791.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:34.910Z,1549574794.910 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:37.730Z,1549574797.730 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:37.965Z,1549574797.965 [NavChartDb](INFO): # of records loaded: 40000 2019-02-07T21:26:40.962Z,1549574800.962 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:50.379Z,1549574810.379 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:51.235Z,1549574811.235 [NavChartDb](INFO): # of records loaded: 45000 2019-02-07T21:26:52.730Z,1549574812.730 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:55.963Z,1549574815.963 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:26:58.782Z,1549574818.782 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:01.634Z,1549574821.634 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:01.919Z,1549574821.919 [NavChartDb](INFO): # of records loaded: 50000 2019-02-07T21:27:04.842Z,1549574824.842 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:07.670Z,1549574827.670 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:10.927Z,1549574830.927 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:13.734Z,1549574833.734 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:16.816Z,1549574836.816 [NavChartDb](INFO): # of records loaded: 55000 2019-02-07T21:27:16.963Z,1549574836.963 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:19.094Z,1549574839.094 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-07T21:27:19.790Z,1549574839.790 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:23.022Z,1549574843.022 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:25.292Z,1549574845.292 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-07T21:27:25.851Z,1549574845.851 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:28.678Z,1549574848.678 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:31.465Z,1549574851.465 [NavChartDb](INFO): # of records loaded: 60000 2019-02-07T21:27:31.910Z,1549574851.910 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:34.738Z,1549574854.738 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:37.998Z,1549574857.998 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:40.810Z,1549574860.810 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:44.050Z,1549574864.050 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:27:49.075Z,1549574869.075 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-07T21:27:49.267Z,1549574869.267 [NavChartDb](INFO): # of records loaded: 62026 2019-02-07T21:27:49.293Z,1549574869.293 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-07T21:27:49.294Z,1549574869.294 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-07T21:27:53.132Z,1549574873.132 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-02-07T21:27:53.132Z,1549574873.132 [NAL9602] Data Fault, FailCount= 1 2019-02-07T21:27:53.132Z,1549574873.132 [NAL9602](ERROR): Data Fault 2019-02-07T21:27:53.258Z,1549574873.258 [CBIT](ERROR): Data Fault in component: NAL9602 2019-02-07T21:27:53.455Z,1549574873.455 [NAL9602](INFO): Powering down 2019-02-07T21:27:54.326Z,1549574874.326 [CBIT](INFO): Clearing failed state for component NAL9602 2019-02-07T21:27:54.326Z,1549574874.326 [NAL9602] No Fault, FailCount= 1 2019-02-07T21:27:58.677Z,1549574878.677 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-07T21:28:14.799Z,1549574894.799 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:28:23.764Z,1549574903.764 [NAL9602](INFO): Powering up NAL9602 2019-02-07T21:28:27.362Z,1549574907.362 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:28:27.657Z,1549574907.657 [CBIT](INFO): Clearing failed state for component BPC1 2019-02-07T21:28:27.657Z,1549574907.657 [BPC1] No Fault, FailCount= 1 2019-02-07T21:28:34.676Z,1549574914.676 [NAL9602](INFO): NAL9602 initialized 2019-02-07T21:28:35.499Z,1549574915.499 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:28:38.351Z,1549574918.351 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:28:39.771Z,1549574919.771 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:28:41.571Z,1549574921.571 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:28:50.702Z,1549574930.702 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:28:53.514Z,1549574933.514 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:28:54.591Z,1549574934.591 [NavChartDb](INFO): # of records loaded: 20000 2019-02-07T21:28:56.363Z,1549574936.363 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:28:59.611Z,1549574939.611 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:02.402Z,1549574942.402 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:05.230Z,1549574945.230 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:07.250Z,1549574947.250 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:07.888Z,1549574947.888 [NavChartDb](INFO): # of records loaded: 25000 2019-02-07T21:29:09.270Z,1549574949.270 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:11.297Z,1549574951.297 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:14.531Z,1549574954.531 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:16.678Z,1549574956.678 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-02-07T21:29:16.683Z,1549574956.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:29:16.686Z,1549574956.686 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2019-02-07T21:29:16.686Z,1549574956.686 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:29:16.734Z,1549574956.734 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2019-02-07T21:29:16.735Z,1549574956.735 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:29:16.781Z,1549574956.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:29:16.781Z,1549574956.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:29:16.782Z,1549574956.782 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:29:17.007Z,1549574957.007 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:29:17.007Z,1549574957.007 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-02-07T21:29:17.007Z,1549574957.007 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:29:17.007Z,1549574957.007 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2019-02-07T21:29:17.008Z,1549574957.008 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:29:17.008Z,1549574957.008 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2019-02-07T21:29:17.378Z,1549574957.378 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:17.452Z,1549574957.452 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:29:20.615Z,1549574960.615 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:22.150Z,1549574962.150 [NavChartDb](INFO): # of records loaded: 30000 2019-02-07T21:29:23.410Z,1549574963.410 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:26.647Z,1549574966.647 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:29.470Z,1549574969.470 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:32.330Z,1549574972.330 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:33.370Z,1549574973.370 [NavChartDb](INFO): # of records loaded: 35000 2019-02-07T21:29:33.679Z,1549574973.679 [BPC1](FAULT): Failed to parse data from all battery packs. 2019-02-07T21:29:33.680Z,1549574973.680 [BPC1] Data Fault, FailCount= 2 2019-02-07T21:29:33.680Z,1549574973.680 [BPC1](ERROR): Data Fault 2019-02-07T21:29:33.806Z,1549574973.806 [CBIT](ERROR): Data Fault in component: BPC1 2019-02-07T21:29:34.322Z,1549574974.322 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:37.558Z,1549574977.558 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:40.378Z,1549574980.378 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:43.653Z,1549574983.653 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:51.326Z,1549574991.326 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:52.556Z,1549574992.556 [NavChartDb](INFO): # of records loaded: 40000 2019-02-07T21:29:54.566Z,1549574994.566 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:29:57.382Z,1549574997.382 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:00.687Z,1549575000.687 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:03.458Z,1549575003.458 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:06.710Z,1549575006.710 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:09.518Z,1549575009.518 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:12.338Z,1549575012.338 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:14.358Z,1549575014.358 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:16.387Z,1549575016.387 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:16.516Z,1549575016.516 [NavChartDb](INFO): # of records loaded: 45000 2019-02-07T21:30:18.414Z,1549575018.414 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:20.432Z,1549575020.432 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-07T21:30:21.662Z,1549575021.662 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:22.957Z,1549575022.957 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-07T21:30:24.466Z,1549575024.466 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:27.703Z,1549575027.703 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:30.534Z,1549575030.534 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:33.395Z,1549575033.395 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:35.378Z,1549575035.378 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:37.410Z,1549575037.410 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:38.853Z,1549575038.853 [NavChartDb](INFO): # of records loaded: 50000 2019-02-07T21:30:40.650Z,1549575040.650 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:42.799Z,1549575042.799 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-02-07T21:30:42.799Z,1549575042.799 [Default:CheckIn:C.Wait] Stopped 2019-02-07T21:30:42.800Z,1549575042.800 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-02-07T21:30:42.800Z,1549575042.800 [Default:CheckIn:D] Running Loop=1 2019-02-07T21:30:43.138Z,1549575043.138 [Default:CheckIn:D] Stopped 2019-02-07T21:30:43.138Z,1549575043.138 [Default:CheckIn:E] Running Loop=1 2019-02-07T21:30:43.470Z,1549575043.470 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:43.532Z,1549575043.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.718567 min 2019-02-07T21:30:43.534Z,1549575043.534 [Default:CheckIn:E] Stopped 2019-02-07T21:30:43.534Z,1549575043.534 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-02-07T21:30:43.534Z,1549575043.534 [Default:CheckIn] Stopped 2019-02-07T21:30:43.538Z,1549575043.538 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-07T21:30:43.539Z,1549575043.539 [Default:CheckIn](INFO): Running loop #2 2019-02-07T21:30:43.539Z,1549575043.539 [Default:CheckIn] Running Loop=2 2019-02-07T21:30:43.539Z,1549575043.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-07T21:30:43.539Z,1549575043.539 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-07T21:30:51.162Z,1549575051.162 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:53.566Z,1549575053.566 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:56.811Z,1549575056.811 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:30:59.666Z,1549575059.666 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:02.463Z,1549575062.463 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:05.707Z,1549575065.707 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:06.075Z,1549575066.075 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-07T21:31:06.268Z,1549575066.268 [NavChartDb](INFO): # of records loaded: 53494 2019-02-07T21:31:06.282Z,1549575066.282 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-07T21:31:06.283Z,1549575066.283 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-07T21:31:08.530Z,1549575068.530 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:10.238Z,1549575070.238 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-07T21:31:11.800Z,1549575071.800 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:14.602Z,1549575074.602 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:17.430Z,1549575077.430 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:19.450Z,1549575079.450 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:21.478Z,1549575081.478 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:23.490Z,1549575083.490 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:26.743Z,1549575086.743 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:29.557Z,1549575089.557 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:30.302Z,1549575090.302 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:31:32.827Z,1549575092.827 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:35.623Z,1549575095.623 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:38.863Z,1549575098.863 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:41.682Z,1549575101.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:43.649Z,1549575103.649 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:31:51.266Z,1549575111.266 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:53.835Z,1549575113.835 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:56.253Z,1549575116.253 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:31:56.658Z,1549575116.658 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:31:59.895Z,1549575119.895 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:02.719Z,1549575122.719 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:05.546Z,1549575125.546 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:06.349Z,1549575126.349 [NavChartDb](INFO): # of records loaded: 20000 2019-02-07T21:32:07.573Z,1549575127.573 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:10.806Z,1549575130.806 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:13.629Z,1549575133.629 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:16.138Z,1549575136.138 [CBIT](INFO): Clearing failed state for component DropWeight 2019-02-07T21:32:16.138Z,1549575136.138 [DropWeight] No Fault, FailCount= 1 2019-02-07T21:32:16.667Z,1549575136.667 [NavChartDb](INFO): # of records loaded: 25000 2019-02-07T21:32:16.883Z,1549575136.883 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:17.732Z,1549575137.732 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-02-07T21:32:17.732Z,1549575137.732 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:32:17.733Z,1549575137.733 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2019-02-07T21:32:17.733Z,1549575137.733 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:32:17.776Z,1549575137.776 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2019-02-07T21:32:17.777Z,1549575137.777 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:32:17.845Z,1549575137.845 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:32:17.846Z,1549575137.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:32:17.847Z,1549575137.847 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:32:18.135Z,1549575138.135 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:32:18.136Z,1549575138.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-02-07T21:32:18.136Z,1549575138.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:32:18.136Z,1549575138.136 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2019-02-07T21:32:18.137Z,1549575138.137 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:32:18.137Z,1549575138.137 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2019-02-07T21:32:18.502Z,1549575138.502 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:32:19.690Z,1549575139.690 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:22.927Z,1549575142.927 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:25.746Z,1549575145.746 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:27.120Z,1549575147.120 [NavChartDb](INFO): # of records loaded: 30000 2019-02-07T21:32:28.590Z,1549575148.590 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:30.594Z,1549575150.594 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:33.838Z,1549575153.838 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:36.658Z,1549575156.658 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:37.547Z,1549575157.547 [NavChartDb](INFO): # of records loaded: 35000 2019-02-07T21:32:39.911Z,1549575159.911 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:42.730Z,1549575162.730 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:48.836Z,1549575168.836 [NavChartDb](INFO): # of records loaded: 40000 2019-02-07T21:32:52.671Z,1549575172.671 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:54.802Z,1549575174.802 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:57.639Z,1549575177.639 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:32:59.646Z,1549575179.646 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:00.635Z,1549575180.635 [NavChartDb](INFO): # of records loaded: 45000 2019-02-07T21:33:02.883Z,1549575182.883 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:05.718Z,1549575185.718 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:08.981Z,1549575188.981 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:11.116Z,1549575191.116 [NavChartDb](INFO): # of records loaded: 50000 2019-02-07T21:33:11.766Z,1549575191.766 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:15.003Z,1549575195.003 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:17.830Z,1549575197.830 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:20.662Z,1549575200.662 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:21.331Z,1549575201.331 [NavChartDb](INFO): # of records loaded: 55000 2019-02-07T21:33:22.682Z,1549575202.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:25.912Z,1549575205.912 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:28.747Z,1549575208.747 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:31.724Z,1549575211.724 [NavChartDb](INFO): # of records loaded: 60000 2019-02-07T21:33:31.979Z,1549575211.979 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:34.798Z,1549575214.798 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:38.031Z,1549575218.031 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:40.858Z,1549575220.858 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:41.908Z,1549575221.908 [NavChartDb](INFO): # of records loaded: 65000 2019-02-07T21:33:43.682Z,1549575223.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:52.579Z,1549575232.579 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:54.474Z,1549575234.474 [NavChartDb](INFO): # of records loaded: 70000 2019-02-07T21:33:54.722Z,1549575234.722 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:33:57.962Z,1549575237.962 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:00.782Z,1549575240.782 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:04.019Z,1549575244.019 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:04.742Z,1549575244.742 [NavChartDb](INFO): # of records loaded: 75000 2019-02-07T21:34:06.850Z,1549575246.850 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:10.079Z,1549575250.079 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:12.902Z,1549575252.902 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:15.197Z,1549575255.197 [NavChartDb](INFO): # of records loaded: 80000 2019-02-07T21:34:15.726Z,1549575255.726 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:17.746Z,1549575257.746 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:20.982Z,1549575260.982 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:23.806Z,1549575263.806 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:25.672Z,1549575265.672 [NavChartDb](INFO): # of records loaded: 85000 2019-02-07T21:34:27.047Z,1549575267.047 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:29.878Z,1549575269.878 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:33.118Z,1549575273.118 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:35.926Z,1549575275.926 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:36.283Z,1549575276.283 [NavChartDb](INFO): # of records loaded: 90000 2019-02-07T21:34:38.766Z,1549575278.766 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:40.774Z,1549575280.774 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:42.802Z,1549575282.802 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:48.293Z,1549575288.293 [NavChartDb](INFO): # of records loaded: 95000 2019-02-07T21:34:52.438Z,1549575292.438 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:54.881Z,1549575294.881 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:34:58.121Z,1549575298.121 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:00.860Z,1549575300.860 [NavChartDb](INFO): # of records loaded: 100000 2019-02-07T21:35:00.925Z,1549575300.925 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:04.159Z,1549575304.159 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:06.982Z,1549575306.982 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:09.822Z,1549575309.822 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:11.662Z,1549575311.662 [NavChartDb](INFO): # of records loaded: 105000 2019-02-07T21:35:11.830Z,1549575311.830 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:13.858Z,1549575313.858 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:17.087Z,1549575317.087 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:18.743Z,1549575318.743 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-02-07T21:35:18.743Z,1549575318.743 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:35:18.745Z,1549575318.745 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2019-02-07T21:35:18.745Z,1549575318.745 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:35:18.746Z,1549575318.746 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2019-02-07T21:35:18.746Z,1549575318.746 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:35:18.804Z,1549575318.804 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:35:18.804Z,1549575318.804 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:35:18.804Z,1549575318.804 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:35:19.300Z,1549575319.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:35:19.300Z,1549575319.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-02-07T21:35:19.301Z,1549575319.301 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:35:19.301Z,1549575319.301 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2019-02-07T21:35:19.301Z,1549575319.301 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:35:19.301Z,1549575319.301 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2019-02-07T21:35:19.572Z,1549575319.572 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:35:19.918Z,1549575319.918 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:23.146Z,1549575323.146 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:23.747Z,1549575323.747 [NavChartDb](INFO): # of records loaded: 110000 2019-02-07T21:35:25.970Z,1549575325.970 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:29.207Z,1549575329.207 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:32.034Z,1549575332.034 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:33.424Z,1549575333.424 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-07T21:35:34.609Z,1549575334.609 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-07T21:35:34.858Z,1549575334.858 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:36.883Z,1549575336.883 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:38.427Z,1549575338.427 [NavChartDb](INFO): # of records loaded: 115000 2019-02-07T21:35:40.119Z,1549575340.119 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:42.942Z,1549575342.942 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:43.836Z,1549575343.836 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-07T21:30:43.5Z 2019-02-07T21:35:43.836Z,1549575343.836 [Default:CheckIn:Read_GPS] Stopped 2019-02-07T21:35:43.836Z,1549575343.836 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-02-07T21:35:50.444Z,1549575350.444 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-07T21:35:50.773Z,1549575350.773 [NavChartDb](INFO): # of records loaded: 117290 2019-02-07T21:35:50.790Z,1549575350.790 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-07T21:35:50.803Z,1549575350.803 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-07T21:35:51.883Z,1549575351.883 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190207T211701/Courier0007.lzma 2019-02-07T21:35:51.902Z,1549575351.902 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:35:52.647Z,1549575352.647 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Courier0007.lzma.bak 2019-02-07T21:35:52.647Z,1549575352.647 [DataOverHttps](INFO): SBD MOMSN=9640839 2019-02-07T21:35:53.762Z,1549575353.762 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-07T21:36:00.591Z,1549575360.591 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:08.632Z,1549575368.632 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:36:09.617Z,1549575369.617 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20190207T211701/Express0008.lzma 2019-02-07T21:36:09.638Z,1549575369.638 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:10.413Z,1549575370.413 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Express0008.lzma.bak 2019-02-07T21:36:10.413Z,1549575370.413 [DataOverHttps](INFO): SBD MOMSN=9640841 2019-02-07T21:36:10.896Z,1549575370.896 [Default:CheckIn:Read_Iridium] Stopped 2019-02-07T21:36:10.896Z,1549575370.896 [Default:CheckIn:C.Wait] Running Loop=1 2019-02-07T21:36:10.896Z,1549575370.896 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-02-07T21:36:22.631Z,1549575382.631 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:25.022Z,1549575385.022 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:25.969Z,1549575385.969 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:36:28.263Z,1549575388.263 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:31.082Z,1549575391.082 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:34.379Z,1549575394.379 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:37.248Z,1549575397.248 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:40.102Z,1549575400.102 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:41.447Z,1549575401.447 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:36:42.934Z,1549575402.934 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:44.950Z,1549575404.950 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:46.981Z,1549575406.981 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:48.998Z,1549575408.998 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:52.246Z,1549575412.246 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:54.916Z,1549575414.916 [NavChartDb](INFO): # of records loaded: 20000 2019-02-07T21:36:55.050Z,1549575415.050 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:36:58.283Z,1549575418.283 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:01.106Z,1549575421.106 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:04.343Z,1549575424.343 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:06.938Z,1549575426.938 [NavChartDb](INFO): # of records loaded: 25000 2019-02-07T21:37:07.170Z,1549575427.170 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:09.997Z,1549575429.997 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:18.906Z,1549575438.906 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:20.597Z,1549575440.597 [NavChartDb](INFO): # of records loaded: 30000 2019-02-07T21:37:21.034Z,1549575441.034 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:24.278Z,1549575444.278 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:27.090Z,1549575447.090 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:30.335Z,1549575450.335 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:33.154Z,1549575453.154 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:33.925Z,1549575453.925 [NavChartDb](INFO): # of records loaded: 35000 2019-02-07T21:37:36.387Z,1549575456.387 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:39.210Z,1549575459.210 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:42.038Z,1549575462.038 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:44.066Z,1549575464.066 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:46.082Z,1549575466.082 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:46.636Z,1549575466.636 [NavChartDb](INFO): # of records loaded: 40000 2019-02-07T21:37:49.206Z,1549575469.206 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-07T21:37:49.340Z,1549575469.340 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:50.249Z,1549575470.249 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-07T21:37:52.142Z,1549575472.142 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:55.399Z,1549575475.399 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:37:58.202Z,1549575478.202 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:01.451Z,1549575481.451 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:03.249Z,1549575483.249 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-07T21:38:03.408Z,1549575483.408 [NavChartDb](INFO): # of records loaded: 44880 2019-02-07T21:38:03.481Z,1549575483.481 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-07T21:38:03.517Z,1549575483.517 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-07T21:38:04.286Z,1549575484.286 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:07.090Z,1549575487.090 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:09.057Z,1549575489.057 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-07T21:38:10.327Z,1549575490.327 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:20.238Z,1549575500.238 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:20.278Z,1549575500.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-02-07T21:38:20.278Z,1549575500.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:38:20.299Z,1549575500.299 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2019-02-07T21:38:20.299Z,1549575500.299 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:38:20.301Z,1549575500.301 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7 2019-02-07T21:38:20.301Z,1549575500.301 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:38:20.352Z,1549575500.352 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:38:20.352Z,1549575500.352 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:38:20.352Z,1549575500.352 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:38:20.640Z,1549575500.640 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:38:20.640Z,1549575500.640 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-02-07T21:38:20.641Z,1549575500.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:38:20.641Z,1549575500.641 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2019-02-07T21:38:20.641Z,1549575500.641 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:38:20.641Z,1549575500.641 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7 2019-02-07T21:38:20.821Z,1549575500.821 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:38:22.406Z,1549575502.406 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:25.038Z,1549575505.038 [NavChartDb](INFO): # of records loaded: 5000 2019-02-07T21:38:25.222Z,1549575505.222 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:28.459Z,1549575508.459 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:31.291Z,1549575511.291 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:34.126Z,1549575514.126 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:38:36.120Z,1549575516.120 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-02-07T21:38:36.120Z,1549575516.120 [NAL9602] Data Fault, FailCount= 2 2019-02-07T21:38:36.120Z,1549575516.120 [NAL9602](ERROR): Data Fault 2019-02-07T21:38:36.277Z,1549575516.277 [CBIT](ERROR): Data Fault in component: NAL9602 2019-02-07T21:38:36.525Z,1549575516.525 [NAL9602](INFO): Powering down 2019-02-07T21:38:37.474Z,1549575517.474 [CBIT](INFO): Clearing failed state for component NAL9602 2019-02-07T21:38:37.474Z,1549575517.474 [NAL9602] No Fault, FailCount= 2 2019-02-07T21:38:37.686Z,1549575517.686 [NavChartDb](INFO): # of records loaded: 10000 2019-02-07T21:38:50.375Z,1549575530.375 [NavChartDb](INFO): # of records loaded: 15000 2019-02-07T21:39:04.663Z,1549575544.663 [NavChartDb](INFO): # of records loaded: 20000 2019-02-07T21:39:06.824Z,1549575546.824 [NAL9602](INFO): Powering up NAL9602 2019-02-07T21:39:07.543Z,1549575547.543 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-07T21:39:09.596Z,1549575549.596 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-07T21:39:19.684Z,1549575559.684 [NAL9602](INFO): NAL9602 initialized 2019-02-07T21:39:20.491Z,1549575560.491 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:23.314Z,1549575563.314 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:26.551Z,1549575566.551 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:29.374Z,1549575569.374 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:32.623Z,1549575572.623 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:34.785Z,1549575574.785 [CBIT](INFO): Clearing failed state for component BPC1 2019-02-07T21:39:34.785Z,1549575574.785 [BPC1] No Fault, FailCount= 2 2019-02-07T21:39:35.479Z,1549575575.479 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:38.274Z,1549575578.274 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:38.851Z,1549575578.851 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-07T21:39:39.409Z,1549575579.409 [NavChartDb](INFO): # of records loaded: 24424 2019-02-07T21:39:39.421Z,1549575579.421 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-07T21:39:40.297Z,1549575580.297 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:42.318Z,1549575582.318 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:44.043Z,1549575584.043 [NavChartDb](INFO): Creating index for soundings 2019-02-07T21:39:44.334Z,1549575584.334 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:47.579Z,1549575587.579 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:50.406Z,1549575590.406 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:53.635Z,1549575593.635 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:56.003Z,1549575596.003 [NavChartDb](INFO): Creating index for 0p0 2019-02-07T21:39:56.466Z,1549575596.466 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:39:59.691Z,1549575599.691 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:00.000Z,1549575601.000 [NavChartDb](INFO): Creating index for 1p8 2019-02-07T21:40:02.525Z,1549575602.525 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:03.268Z,1549575603.268 [NavChartDb](INFO): Creating index for 3p6 2019-02-07T21:40:05.346Z,1549575605.346 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:07.382Z,1549575607.382 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:07.451Z,1549575607.451 [NavChartDb](INFO): Creating index for 5p4 2019-02-07T21:40:09.382Z,1549575609.382 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:11.046Z,1549575611.046 [NavChartDb](INFO): Creating index for 9p1 2019-02-07T21:40:12.162Z,1549575612.162 [NavChartDb](INFO): Creating index for 10p9 2019-02-07T21:40:16.282Z,1549575616.282 [NavChartDb](INFO): Creating index for 18p2 2019-02-07T21:40:19.482Z,1549575619.482 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:20.513Z,1549575620.513 [NavChartDb](INFO): Creating index for 36p5 2019-02-07T21:40:21.682Z,1549575621.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:23.031Z,1549575623.031 [NavChartDb](INFO): Creating index for 54p8 2019-02-07T21:40:24.490Z,1549575624.490 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:24.785Z,1549575624.785 [NavChartDb](INFO): Creating index for 73p1 2019-02-07T21:40:27.063Z,1549575627.063 [NavChartDb](INFO): Creating index for 91p4 2019-02-07T21:40:27.731Z,1549575627.731 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:30.554Z,1549575630.554 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:31.041Z,1549575631.041 [NavChartDb](INFO): Creating index for 182p8 2019-02-07T21:40:32.757Z,1549575632.757 [NavChartDb](INFO): Creating index for 274p3 2019-02-07T21:40:33.374Z,1549575633.374 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:35.023Z,1549575635.023 [NavChartDb](INFO): Creating index for 365p7 2019-02-07T21:40:35.398Z,1549575635.398 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:36.347Z,1549575636.347 [NavChartDb](INFO): Creating index for 457p2 2019-02-07T21:40:37.430Z,1549575637.430 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:38.580Z,1549575638.580 [NavChartDb](INFO): Creating index for 548p6 2019-02-07T21:40:40.506Z,1549575640.506 [NavChartDb](INFO): Creating index for 640p0 2019-02-07T21:40:40.664Z,1549575640.664 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:41.106Z,1549575641.106 [BPC1](FAULT): Failed to parse data from all battery packs. 2019-02-07T21:40:41.106Z,1549575641.106 [BPC1] Data Fault, FailCount= 3 2019-02-07T21:40:41.106Z,1549575641.106 [BPC1](ERROR): Data Fault 2019-02-07T21:40:41.174Z,1549575641.174 [CBIT](ERROR): Data Fault in component: BPC1 2019-02-07T21:40:42.826Z,1549575642.826 [NavChartDb](INFO): Creating index for 731p5 2019-02-07T21:40:43.482Z,1549575643.482 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:44.207Z,1549575644.207 [NavChartDb](INFO): Creating index for 822p9 2019-02-07T21:40:46.731Z,1549575646.731 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:47.885Z,1549575647.885 [NavChartDb](INFO): Creating index for 914p4 2019-02-07T21:40:49.480Z,1549575649.480 [NavChartDb](INFO): Creating index for 1005p8 2019-02-07T21:40:49.568Z,1549575649.568 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:51.023Z,1549575651.023 [NavChartDb](INFO): Creating index for 1097p2 2019-02-07T21:40:51.568Z,1549575651.568 [NavChartDb](INFO): Creating index for 1188p7 2019-02-07T21:40:52.771Z,1549575652.771 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:52.881Z,1549575652.881 [NavChartDb](INFO): Creating index for 1280p1 2019-02-07T21:40:54.483Z,1549575654.483 [NavChartDb](INFO): Creating index for 1463p0 2019-02-07T21:40:55.598Z,1549575655.598 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:56.176Z,1549575656.176 [NavChartDb](INFO): Creating index for 1645p9 2019-02-07T21:40:58.422Z,1549575658.422 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:40:59.572Z,1549575659.572 [NavChartDb](INFO): Creating index for 1828p8 2019-02-07T21:41:00.446Z,1549575660.446 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:01.919Z,1549575661.919 [NavChartDb](INFO): Creating index for 2011p6 2019-02-07T21:41:03.136Z,1549575663.136 [NavChartDb](INFO): Creating index for 2194p5 2019-02-07T21:41:03.679Z,1549575663.679 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:04.011Z,1549575664.011 [NavChartDb](INFO): Creating index for 2377p4 2019-02-07T21:41:05.096Z,1549575665.096 [NavChartDb](INFO): Creating index for 2560p3 2019-02-07T21:41:06.510Z,1549575666.510 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:06.664Z,1549575666.664 [NavChartDb](INFO): Creating index for 2743p2 2019-02-07T21:41:07.980Z,1549575667.980 [NavChartDb](INFO): Creating index for 2926p0 2019-02-07T21:41:09.048Z,1549575669.048 [NavChartDb](INFO): Creating index for 3108p9 2019-02-07T21:41:09.743Z,1549575669.743 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:10.313Z,1549575670.313 [NavChartDb](INFO): Creating index for 3291p8 2019-02-07T21:41:10.914Z,1549575670.914 [NavChartDb](INFO): Creating index for 3474p7 2019-02-07T21:41:11.420Z,1549575671.420 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-02-07T21:41:11.420Z,1549575671.420 [Default:CheckIn:C.Wait] Stopped 2019-02-07T21:41:11.420Z,1549575671.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-02-07T21:41:11.420Z,1549575671.420 [Default:CheckIn:D] Running Loop=1 2019-02-07T21:41:11.838Z,1549575671.838 [Default:CheckIn:D] Stopped 2019-02-07T21:41:11.838Z,1549575671.838 [Default:CheckIn:E] Running Loop=1 2019-02-07T21:41:12.242Z,1549575672.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.197056 min 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn:E] Stopped 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn] Stopped 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn](INFO): Running loop #3 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn] Running Loop=3 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-07T21:41:12.243Z,1549575672.243 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-07T21:41:12.700Z,1549575672.700 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:22.886Z,1549575682.886 [NavChartDb](INFO): Creating index for 3657p6 2019-02-07T21:41:23.989Z,1549575683.989 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2019-02-07T21:41:23.989Z,1549575683.989 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:41:23.994Z,1549575683.994 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2019-02-07T21:41:23.994Z,1549575683.994 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:41:23.996Z,1549575683.996 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8 2019-02-07T21:41:23.996Z,1549575683.996 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:41:24.053Z,1549575684.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:41:24.053Z,1549575684.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:41:24.053Z,1549575684.053 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:41:24.207Z,1549575684.207 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:41:24.208Z,1549575684.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2019-02-07T21:41:24.208Z,1549575684.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:41:24.208Z,1549575684.208 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2019-02-07T21:41:24.208Z,1549575684.208 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:41:24.209Z,1549575684.209 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8 2019-02-07T21:41:24.526Z,1549575684.526 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:41:25.711Z,1549575685.711 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:28.530Z,1549575688.530 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:31.772Z,1549575691.772 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:34.586Z,1549575694.586 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:37.847Z,1549575697.847 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:40.646Z,1549575700.646 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:43.486Z,1549575703.486 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:45.509Z,1549575705.509 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:47.522Z,1549575707.522 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:49.534Z,1549575709.534 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:52.795Z,1549575712.795 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:55.602Z,1549575715.602 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:41:58.842Z,1549575718.842 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:01.654Z,1549575721.654 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:04.939Z,1549575724.939 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:07.722Z,1549575727.722 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:10.554Z,1549575730.554 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:13.782Z,1549575733.782 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:19.538Z,1549575739.538 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:21.562Z,1549575741.562 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:23.574Z,1549575743.574 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:26.831Z,1549575746.831 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:29.642Z,1549575749.642 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:32.896Z,1549575752.896 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:35.702Z,1549575755.702 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:38.951Z,1549575758.951 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:41.758Z,1549575761.758 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:44.590Z,1549575764.590 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:46.609Z,1549575766.609 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:49.838Z,1549575769.838 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:52.683Z,1549575772.683 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:55.978Z,1549575775.978 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:42:58.730Z,1549575778.730 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:01.971Z,1549575781.971 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:04.790Z,1549575784.790 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:07.614Z,1549575787.614 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:09.639Z,1549575789.639 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:11.670Z,1549575791.670 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:17.871Z,1549575797.871 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:20.626Z,1549575800.626 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:22.642Z,1549575802.642 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:24.671Z,1549575804.671 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:26.682Z,1549575806.682 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:29.919Z,1549575809.919 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:32.738Z,1549575812.738 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:35.986Z,1549575815.986 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:38.799Z,1549575818.799 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:42.037Z,1549575822.037 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:44.862Z,1549575824.862 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:47.694Z,1549575827.694 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:49.710Z,1549575829.710 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:52.958Z,1549575832.958 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:55.766Z,1549575835.766 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:43:59.006Z,1549575839.006 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:01.826Z,1549575841.826 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:05.062Z,1549575845.062 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:07.886Z,1549575847.886 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:10.714Z,1549575850.714 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:12.734Z,1549575852.734 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:18.650Z,1549575858.650 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:21.082Z,1549575861.082 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:23.902Z,1549575863.902 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:24.752Z,1549575864.752 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2019-02-07T21:44:24.752Z,1549575864.752 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-07T21:44:24.753Z,1549575864.753 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2019-02-07T21:44:24.753Z,1549575864.753 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-07T21:44:24.754Z,1549575864.754 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9 2019-02-07T21:44:24.754Z,1549575864.754 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-07T21:44:24.783Z,1549575864.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-07T21:44:24.783Z,1549575864.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-07T21:44:24.783Z,1549575864.783 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-07T21:44:25.175Z,1549575865.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-07T21:44:25.175Z,1549575865.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2019-02-07T21:44:25.176Z,1549575865.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-07T21:44:25.176Z,1549575865.176 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2019-02-07T21:44:25.176Z,1549575865.176 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-07T21:44:25.177Z,1549575865.177 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9 2019-02-07T21:44:25.572Z,1549575865.572 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-07T21:44:26.730Z,1549575866.730 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:28.756Z,1549575868.756 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:30.774Z,1549575870.774 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:34.019Z,1549575874.019 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:36.835Z,1549575876.835 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:40.071Z,1549575880.071 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:42.894Z,1549575882.894 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:46.144Z,1549575886.144 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:48.954Z,1549575888.954 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:51.778Z,1549575891.778 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:53.802Z,1549575893.802 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:57.055Z,1549575897.055 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:44:59.862Z,1549575899.862 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:03.107Z,1549575903.107 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:05.918Z,1549575905.918 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:09.159Z,1549575909.159 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:11.978Z,1549575911.978 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:18.159Z,1549575918.159 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:21.111Z,1549575921.111 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:23.922Z,1549575923.922 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:27.162Z,1549575927.162 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:29.981Z,1549575929.981 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:33.218Z,1549575933.218 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:36.059Z,1549575936.059 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:38.862Z,1549575938.862 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:40.890Z,1549575940.890 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:44.150Z,1549575944.150 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:46.958Z,1549575946.958 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:50.207Z,1549575950.207 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:53.018Z,1549575953.018 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:56.262Z,1549575956.262 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:45:59.083Z,1549575959.083 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:01.902Z,1549575961.902 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:03.930Z,1549575963.930 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:07.179Z,1549575967.179 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:09.986Z,1549575969.986 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:12.443Z,1549575972.443 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-07T21:41:12.2Z 2019-02-07T21:46:12.443Z,1549575972.443 [Default:CheckIn:Read_GPS] Stopped 2019-02-07T21:46:12.443Z,1549575972.443 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-02-07T21:46:13.241Z,1549575973.241 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:21.907Z,1549575981.907 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190207T211701/Courier0010.lzma 2019-02-07T21:46:22.306Z,1549575982.306 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:22.732Z,1549575982.732 [DataOverHttps](INFO): Moved sent file to Logs/20190207T211701/Courier0010.lzma.bak 2019-02-07T21:46:22.732Z,1549575982.732 [DataOverHttps](INFO): SBD MOMSN=9640856 2019-02-07T21:46:27.746Z,1549575987.746 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:30.084Z,1549575990.084 [CommandLine](IMPORTANT): got command restart application 2019-02-07T21:46:30.170Z,1549575990.170 [NAL9602](DEBUG): Fix Requested 2019-02-07T21:46:31.097Z,1549575991.097 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:31.097Z,1549575991.097 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.248Z,1549575991.248 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-07T21:46:31.248Z,1549575991.248 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.249Z,1549575991.249 [CommandLine](INFO): Join timeout helper Thread ID is 5014 2019-02-07T21:46:31.249Z,1549575991.249 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-07T21:46:31.250Z,1549575991.250 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.251Z,1549575991.251 [NavChartDb](INFO): Join timeout helper Thread ID is 5015 2019-02-07T21:46:31.506Z,1549575991.506 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:31.507Z,1549575991.507 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.522Z,1549575991.522 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2019-02-07T21:46:31.522Z,1549575991.522 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.523Z,1549575991.523 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 5016 2019-02-07T21:46:31.599Z,1549575991.599 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:31.599Z,1549575991.599 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-07T21:46:31.765Z,1549575991.765 [Rowe_600LCM](INFO): Powering down 2019-02-07T21:46:31.767Z,1549575991.767 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.778Z,1549575991.778 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-07T21:46:31.779Z,1549575991.779 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.779Z,1549575991.779 [Radio_Surface](INFO): Join timeout helper Thread ID is 5018 2019-02-07T21:46:31.987Z,1549575991.987 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:31.987Z,1549575991.987 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.990Z,1549575991.990 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-02-07T21:46:31.990Z,1549575991.990 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:31.991Z,1549575991.991 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5019 2019-02-07T21:46:32.250Z,1549575992.250 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:32.251Z,1549575992.251 [WetLabsBB2FL](INFO): Powering down 2019-02-07T21:46:32.252Z,1549575992.252 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:32.267Z,1549575992.267 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-02-07T21:46:32.267Z,1549575992.267 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:32.268Z,1549575992.268 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5020 2019-02-07T21:46:33.151Z,1549575993.151 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:33.151Z,1549575993.151 [CTD_Seabird](INFO): Powering down 2019-02-07T21:46:33.163Z,1549575993.163 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:33.179Z,1549575993.179 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-07T21:46:33.180Z,1549575993.180 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:33.180Z,1549575993.180 [logger](INFO): Join timeout helper Thread ID is 5021 2019-02-07T21:46:33.218Z,1549575993.218 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:33.219Z,1549575993.219 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:33.223Z,1549575993.223 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-07T21:46:33.223Z,1549575993.223 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:33.223Z,1549575993.223 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-07T21:46:33.224Z,1549575993.224 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:33.224Z,1549575993.224 [controlThread](INFO): Join timeout helper Thread ID is 5022 2019-02-07T21:46:33.694Z,1549575993.694 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-07T21:46:33.694Z,1549575993.694 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-07T21:46:33.695Z,1549575993.695 [Aanderaa_O2](INFO): Powering down 2019-02-07T21:46:33.697Z,1549575993.697 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-02-07T21:46:33.818Z,1549575993.818 [NAL9602](INFO): Powering down 2019-02-07T21:46:33.820Z,1549575993.820 [PNI_TCM](INFO): Powering down 2019-02-07T21:46:33.907Z,1549575993.907 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-07T21:46:33.908Z,1549575993.908 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-07T21:46:33.909Z,1549575993.909 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-07T21:46:33.909Z,1549575993.909 [MissionManager](INFO): Uninitializing Mission Default 2019-02-07T21:46:33.910Z,1549575993.910 [Default] Stopped 2019-02-07T21:46:33.910Z,1549575993.910 [Default](DEBUG): Aggregate::uninitialize Default 2019-02-07T21:46:33.910Z,1549575993.910 [Default:B.GoToSurface] Stopped 2019-02-07T21:46:33.910Z,1549575993.910 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-07T21:46:33.910Z,1549575993.910 [Default:CheckIn] Stopped 2019-02-07T21:46:33.910Z,1549575993.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-07T21:46:33.910Z,1549575993.910 [Default:CheckIn:Read_Iridium] Stopped 2019-02-07T21:46:33.913Z,1549575993.913 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-07T21:46:33.914Z,1549575993.914 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-07T21:46:33.914Z,1549575993.914 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-07T21:46:33.914Z,1549575993.914 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-07T21:46:33.915Z,1549575993.915 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-07T21:46:33.915Z,1549575993.915 [BuoyancyServo](INFO): Powering down 2019-02-07T21:46:33.926Z,1549575993.926 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-07T21:46:33.927Z,1549575993.927 [ElevatorServo](INFO): Powering down 2019-02-07T21:46:33.927Z,1549575993.927 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-07T21:46:33.927Z,1549575993.927 [MassServo](INFO): Powering down 2019-02-07T21:46:33.928Z,1549575993.928 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-07T21:46:33.928Z,1549575993.928 [RudderServo](INFO): Powering down 2019-02-07T21:46:33.929Z,1549575993.929 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-07T21:46:33.929Z,1549575993.929 [ThrusterServo](INFO): Powering down 2019-02-07T21:46:33.930Z,1549575993.930 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-07T21:46:33.931Z,1549575993.931 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-07T21:46:33.931Z,1549575993.931 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-07T21:46:33.931Z,1549575993.931 [CBIT](DEBUG): Powering off loads. 2019-02-07T21:46:33.942Z,1549575993.942 [CBIT](DEBUG): Disabling WDT. 2019-02-07T21:46:33.954Z,1549575993.954 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-07T21:46:33.955Z,1549575993.955 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:34.016Z,1549575994.016 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:34.021Z,1549575994.021 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:34.104Z,1549575994.104 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:34.109Z,1549575994.109 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:34.160Z,1549575994.160 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-07T21:46:34.220Z,1549575994.220 [logger ThreadHandler](INFO): Thread cancelled.