2018-06-03T19:28:54.730Z,1528054134.730 [Supervisor](DEBUG): Initializing supervisor. 2018-06-03T19:28:54.732Z,1528054134.732 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-06-03T19:28:54.733Z,1528054134.733 [SyncHandler](INFO): Protected caller Thread ID is 1105 2018-06-03T19:28:54.734Z,1528054134.734 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-06-03T19:28:54.734Z,1528054134.734 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-06-03T19:28:54.735Z,1528054134.735 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1106 2018-06-03T19:28:54.738Z,1528054134.738 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-06-03T19:28:54.750Z,1528054134.750 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-06-03T19:28:54.751Z,1528054134.751 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-06-03T19:28:54.751Z,1528054134.751 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1107 2018-06-03T19:28:54.752Z,1528054134.752 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-06-03T19:28:54.753Z,1528054134.753 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-06-03T19:28:54.753Z,1528054134.753 [logger ThreadHandler](INFO): Protected caller Thread ID is 1108 2018-06-03T19:28:54.755Z,1528054134.755 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-06-03T19:28:54.756Z,1528054134.756 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-06-03T19:28:54.757Z,1528054134.757 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-06-03T19:28:55.109Z,1528054135.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-06-03T19:28:55.110Z,1528054135.110 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-06-03T19:28:55.318Z,1528054135.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-06-03T19:28:55.319Z,1528054135.319 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-06-03T19:28:55.621Z,1528054135.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-06-03T19:28:55.621Z,1528054135.621 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-06-03T19:28:55.765Z,1528054135.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-06-03T19:28:55.765Z,1528054135.765 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-06-03T19:28:55.864Z,1528054135.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-06-03T19:28:55.865Z,1528054135.865 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-06-03T19:28:56.356Z,1528054136.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-06-03T19:28:56.356Z,1528054136.356 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-06-03T19:28:56.762Z,1528054136.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-06-03T19:28:56.762Z,1528054136.762 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-06-03T19:28:57.229Z,1528054137.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-06-03T19:28:57.230Z,1528054137.230 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-06-03T19:28:57.337Z,1528054137.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-06-03T19:28:57.337Z,1528054137.337 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-06-03T19:28:57.436Z,1528054137.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-06-03T19:28:57.436Z,1528054137.436 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-06-03T19:28:57.649Z,1528054137.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-06-03T19:28:57.650Z,1528054137.650 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-06-03T19:28:57.867Z,1528054137.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-06-03T19:28:57.867Z,1528054137.867 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-06-03T19:28:58.231Z,1528054138.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-06-03T19:28:58.231Z,1528054138.231 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-06-03T19:28:58.375Z,1528054138.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-06-03T19:28:58.376Z,1528054138.376 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-06-03T19:28:58.459Z,1528054138.459 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-06-03T19:28:58.460Z,1528054138.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-06-03T19:28:58.565Z,1528054138.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-06-03T19:28:58.687Z,1528054138.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-06-03T19:28:58.791Z,1528054138.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-06-03T19:28:58.917Z,1528054138.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-06-03T19:28:59.152Z,1528054139.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-06-03T19:28:59.538Z,1528054139.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-06-03T19:28:59.614Z,1528054139.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-06-03T19:28:59.849Z,1528054139.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-06-03T19:28:59.849Z,1528054139.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-06-03T19:28:59.933Z,1528054139.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-06-03T19:29:00.024Z,1528054140.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-06-03T19:29:00.127Z,1528054140.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-06-03T19:29:00.256Z,1528054140.256 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2018-06-03T19:29:00.256Z,1528054140.256 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-06-03T19:29:00.260Z,1528054140.260 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-06-03T19:29:00.503Z,1528054140.503 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-06-03T19:29:00.504Z,1528054140.504 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-06-03T19:29:00.546Z,1528054140.546 [DepthRateCalculator] Loaded 2018-06-03T19:29:00.547Z,1528054140.547 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-06-03T19:29:00.553Z,1528054140.553 [PitchRateCalculator] Loaded 2018-06-03T19:29:00.553Z,1528054140.553 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-06-03T19:29:00.570Z,1528054140.570 [SpeedCalculator] Loaded 2018-06-03T19:29:00.570Z,1528054140.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-06-03T19:29:00.592Z,1528054140.592 [TempGradientCalculator] Loaded 2018-06-03T19:29:00.592Z,1528054140.592 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-06-03T19:29:00.608Z,1528054140.608 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-06-03T19:29:00.608Z,1528054140.608 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-06-03T19:29:00.614Z,1528054140.614 [YawRateCalculator] Loaded 2018-06-03T19:29:00.615Z,1528054140.615 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-06-03T19:29:00.655Z,1528054140.655 [ElevatorOffsetCalculator] Loaded 2018-06-03T19:29:00.656Z,1528054140.656 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-06-03T19:29:00.656Z,1528054140.656 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-06-03T19:29:00.657Z,1528054140.657 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-06-03T19:29:00.740Z,1528054140.740 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-06-03T19:29:00.740Z,1528054140.740 [StratificationFrontDetector](DEBUG): (re)initializing 2018-06-03T19:29:00.740Z,1528054140.740 [StratificationFrontDetector] Loaded 2018-06-03T19:29:00.741Z,1528054140.741 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-06-03T19:29:00.741Z,1528054140.741 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-06-03T19:29:00.742Z,1528054140.742 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-06-03T19:29:00.859Z,1528054140.859 [BuoyancyServo] Loaded 2018-06-03T19:29:00.859Z,1528054140.859 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-06-03T19:29:00.875Z,1528054140.875 [ElevatorServo] Loaded 2018-06-03T19:29:00.875Z,1528054140.875 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-06-03T19:29:00.890Z,1528054140.890 [MassServo] Loaded 2018-06-03T19:29:00.890Z,1528054140.890 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-06-03T19:29:00.906Z,1528054140.906 [RudderServo] Loaded 2018-06-03T19:29:00.906Z,1528054140.906 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-06-03T19:29:00.921Z,1528054140.921 [ThrusterServo] Loaded 2018-06-03T19:29:00.921Z,1528054140.921 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-06-03T19:29:00.922Z,1528054140.922 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-06-03T19:29:00.922Z,1528054140.922 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-06-03T19:29:00.936Z,1528054140.936 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-06-03T19:29:00.936Z,1528054140.936 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-06-03T19:29:01.037Z,1528054141.037 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-06-03T19:29:01.038Z,1528054141.038 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-06-03T19:29:01.323Z,1528054141.323 [DataOverHttps] Loaded 2018-06-03T19:29:01.324Z,1528054141.324 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-06-03T19:29:01.338Z,1528054141.338 [Depth_Keller] Loaded 2018-06-03T19:29:01.338Z,1528054141.338 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-06-03T19:29:01.343Z,1528054141.343 [DropWeight] Loaded 2018-06-03T19:29:01.343Z,1528054141.343 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-06-03T19:29:01.443Z,1528054141.443 [NAL9602] Loaded 2018-06-03T19:29:01.443Z,1528054141.443 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-06-03T19:29:01.460Z,1528054141.460 [Onboard] Loaded 2018-06-03T19:29:01.460Z,1528054141.460 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-06-03T19:29:01.471Z,1528054141.471 [Radio_Surface] Loaded 2018-06-03T19:29:01.471Z,1528054141.471 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-06-03T19:29:01.472Z,1528054141.472 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0 2018-06-03T19:29:01.472Z,1528054141.472 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1190 2018-06-03T19:29:01.604Z,1528054141.604 [DAT] Loaded 2018-06-03T19:29:01.604Z,1528054141.604 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2018-06-03T19:29:01.653Z,1528054141.653 [PNI_TCM] Loaded 2018-06-03T19:29:01.653Z,1528054141.653 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-06-03T19:29:01.716Z,1528054141.716 [Rowe_600LCM] Loaded 2018-06-03T19:29:01.716Z,1528054141.716 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-06-03T19:29:01.717Z,1528054141.717 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0 2018-06-03T19:29:01.718Z,1528054141.718 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1191 2018-06-03T19:29:03.525Z,1528054143.525 [BPC1] Loaded 2018-06-03T19:29:03.525Z,1528054143.525 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-06-03T19:29:03.526Z,1528054143.526 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-06-03T19:29:03.526Z,1528054143.526 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-06-03T19:29:03.660Z,1528054143.660 [SBIT](DEBUG): Construct Startup Built In Test. 2018-06-03T19:29:03.683Z,1528054143.683 [SBIT] Loaded 2018-06-03T19:29:03.683Z,1528054143.683 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-06-03T19:29:03.684Z,1528054143.684 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-06-03T19:29:03.696Z,1528054143.696 [IBIT] Loaded 2018-06-03T19:29:03.697Z,1528054143.697 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-06-03T19:29:03.700Z,1528054143.700 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-06-03T19:29:03.854Z,1528054143.854 [CBIT] Loaded 2018-06-03T19:29:03.855Z,1528054143.855 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-06-03T19:29:03.855Z,1528054143.855 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-06-03T19:29:03.856Z,1528054143.856 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-06-03T19:29:04.331Z,1528054144.331 [Aanderaa_O2] Loaded 2018-06-03T19:29:04.332Z,1528054144.332 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-06-03T19:29:04.411Z,1528054144.411 [CTD_NeilBrown] Loaded 2018-06-03T19:29:04.412Z,1528054144.412 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-06-03T19:29:04.413Z,1528054144.413 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409754E0 2018-06-03T19:29:04.413Z,1528054144.413 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1192 2018-06-03T19:29:04.461Z,1528054144.461 [CTD_Seabird] Loaded 2018-06-03T19:29:04.461Z,1528054144.461 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-06-03T19:29:04.462Z,1528054144.462 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0 2018-06-03T19:29:04.463Z,1528054144.463 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1193 2018-06-03T19:29:04.478Z,1528054144.478 [PAR_Licor] Loaded 2018-06-03T19:29:04.478Z,1528054144.478 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-06-03T19:29:04.529Z,1528054144.529 [WetLabsBB2FL] Loaded 2018-06-03T19:29:04.529Z,1528054144.529 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-06-03T19:29:04.530Z,1528054144.530 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409D54E0 2018-06-03T19:29:04.530Z,1528054144.530 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1194 2018-06-03T19:29:04.531Z,1528054144.531 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-06-03T19:29:04.531Z,1528054144.531 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-06-03T19:29:04.601Z,1528054144.601 [VerticalControl](DEBUG): Construct VerticalControl. 2018-06-03T19:29:04.689Z,1528054144.689 [VerticalControl] Loaded 2018-06-03T19:29:04.689Z,1528054144.689 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-06-03T19:29:04.690Z,1528054144.690 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-06-03T19:29:04.749Z,1528054144.749 [HorizontalControl] Loaded 2018-06-03T19:29:04.750Z,1528054144.750 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-06-03T19:29:04.750Z,1528054144.750 [SpeedControl](DEBUG): Construct SpeedControl. 2018-06-03T19:29:04.752Z,1528054144.752 [SpeedControl] Loaded 2018-06-03T19:29:04.752Z,1528054144.752 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-06-03T19:29:04.753Z,1528054144.753 [LoopControl](DEBUG): Construct LoopControl. 2018-06-03T19:29:04.754Z,1528054144.754 [LoopControl] Loaded 2018-06-03T19:29:04.754Z,1528054144.754 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-06-03T19:29:04.754Z,1528054144.754 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-06-03T19:29:04.755Z,1528054144.755 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-06-03T19:29:04.780Z,1528054144.780 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-06-03T19:29:04.781Z,1528054144.781 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-06-03T19:29:04.874Z,1528054144.874 [DeadReckonUsingSpeedCalculator] Loaded 2018-06-03T19:29:04.874Z,1528054144.874 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-06-03T19:29:04.891Z,1528054144.891 [NavChart] Loaded 2018-06-03T19:29:04.891Z,1528054144.891 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-06-03T19:29:04.896Z,1528054144.896 [UniversalFixResidualReporter] Loaded 2018-06-03T19:29:04.896Z,1528054144.896 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-06-03T19:29:04.896Z,1528054144.896 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-06-03T19:29:04.900Z,1528054144.900 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-06-03T19:29:04.901Z,1528054144.901 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-06-03T19:29:04.908Z,1528054144.908 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-06-03T19:29:04.909Z,1528054144.909 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A614E0 2018-06-03T19:29:04.909Z,1528054144.909 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1195 2018-06-03T19:29:04.914Z,1528054144.914 [Supervisor](INFO): Main Thread ID is 797 2018-06-03T19:29:04.914Z,1528054144.914 [Supervisor](DEBUG): Running supervisor. 2018-06-03T19:29:04.915Z,1528054144.915 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1196 2018-06-03T19:29:04.917Z,1528054144.917 [controlThread ThreadHandler](INFO): Handler Thread ID is 1197 2018-06-03T19:29:04.918Z,1528054144.918 [controlThread](DEBUG): Initializing ControlThread 2018-06-03T19:29:04.918Z,1528054144.918 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-06-03T19:29:04.919Z,1528054144.919 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-06-03T19:29:04.919Z,1528054144.919 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-06-03T19:29:04.920Z,1528054144.920 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-06-03T19:29:04.920Z,1528054144.920 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-06-03T19:29:04.921Z,1528054144.921 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-06-03T19:29:04.921Z,1528054144.921 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-06-03T19:29:04.928Z,1528054144.928 [SBIT](INFO): Initialize SBIT Component. 2018-06-03T19:29:04.928Z,1528054144.928 [SBIT](IMPORTANT): git: 2018-06-03 2018-06-03T19:29:04.928Z,1528054144.928 [SBIT](INFO): git hash: e89e4219fcae2edd415aee450018ea5d8b44159b 2018-06-03T19:29:04.929Z,1528054144.929 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-06-03T19:29:04.930Z,1528054144.930 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-06-03T19:29:04.931Z,1528054144.931 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2018-06-03T19:29:04.931Z,1528054144.931 [IBIT](INFO): Initialize IBIT Component. 2018-06-03T19:29:04.932Z,1528054144.932 [CBIT](DEBUG): Initialize CBIT Component. 2018-06-03T19:29:04.933Z,1528054144.933 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-06-03T19:29:04.934Z,1528054144.934 [logger ThreadHandler](INFO): Handler Thread ID is 1198 2018-06-03T19:29:04.953Z,1528054144.953 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1199 2018-06-03T19:29:04.959Z,1528054144.959 [Radio_Surface](INFO): Powering up 2018-06-03T19:29:04.965Z,1528054144.965 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1200 2018-06-03T19:29:04.966Z,1528054144.966 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:29:04.966Z,1528054144.966 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:29:04.975Z,1528054144.975 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:29:04.975Z,1528054144.975 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:29:04.981Z,1528054144.981 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1201 2018-06-03T19:29:04.982Z,1528054144.982 [CTD_NeilBrown](INFO): Powering down 2018-06-03T19:29:05.009Z,1528054145.009 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1202 2018-06-03T19:29:05.010Z,1528054145.010 [CTD_Seabird](INFO): Initializing 2018-06-03T19:29:05.011Z,1528054145.011 [CTD_Seabird](INFO): Checking LCM 2018-06-03T19:29:05.011Z,1528054145.011 [CTD_Seabird](INFO): LCM OK 2018-06-03T19:29:05.011Z,1528054145.011 [CTD_Seabird](INFO): Powering up 2018-06-03T19:29:05.021Z,1528054145.021 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1203 2018-06-03T19:29:05.023Z,1528054145.023 [WetLabsBB2FL](INFO): Powering down 2018-06-03T19:29:05.049Z,1528054145.049 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-06-03T19:29:05.051Z,1528054145.051 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-06-03T19:29:05.052Z,1528054145.052 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-06-03T19:29:05.052Z,1528054145.052 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-06-03T19:29:05.053Z,1528054145.053 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:29:05.054Z,1528054145.054 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:29:05.055Z,1528054145.055 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:29:05.055Z,1528054145.055 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-06-03T19:29:05.055Z,1528054145.055 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-06-03T19:29:05.056Z,1528054145.056 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-06-03T19:29:05.065Z,1528054145.065 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1204 2018-06-03T19:29:05.069Z,1528054145.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-06-03T19:29:05.069Z,1528054145.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-06-03T19:29:05.069Z,1528054145.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-06-03T19:29:05.069Z,1528054145.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-06-03T19:29:05.070Z,1528054145.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-06-03T19:29:05.070Z,1528054145.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-06-03T19:29:05.070Z,1528054145.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-06-03T19:29:05.070Z,1528054145.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-06-03T19:29:05.071Z,1528054145.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-06-03T19:29:05.071Z,1528054145.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-06-03T19:29:05.071Z,1528054145.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-06-03T19:29:05.071Z,1528054145.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-06-03T19:29:05.072Z,1528054145.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-06-03T19:29:05.072Z,1528054145.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-06-03T19:29:05.072Z,1528054145.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-06-03T19:29:05.072Z,1528054145.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-06-03T19:29:05.105Z,1528054145.105 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-06-03T19:29:05.140Z,1528054145.140 [MissionManager](DEBUG): 2018-06-03T19:29:05.140Z,1528054145.140 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-06-03T19:29:05.233Z,1528054145.233 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-06-03T19:29:05.234Z,1528054145.234 [Default:A.Wait](DEBUG): Construct Wait. 2018-06-03T19:29:05.236Z,1528054145.236 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-06-03T19:29:05.283Z,1528054145.283 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-06-03T19:29:05.285Z,1528054145.285 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-06-03T19:29:05.311Z,1528054145.311 [Default:E.Execute](DEBUG): Construct Execute. 2018-06-03T19:29:05.323Z,1528054145.323 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-06-03T19:29:05.328Z,1528054145.328 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-06-03T19:29:05.362Z,1528054145.362 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-06-03T19:29:05.428Z,1528054145.428 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-06-03T19:29:05.516Z,1528054145.516 [DAT](INFO): Powering up 2018-06-03T19:29:05.516Z,1528054145.516 [DAT](DEBUG): Initializing DAT. 2018-06-03T19:29:05.830Z,1528054145.830 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-06-03T19:29:05.933Z,1528054145.933 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:06.058Z,1528054146.058 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-06-03T19:29:06.065Z,1528054146.065 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-06-03T19:29:06.080Z,1528054146.080 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-06-03T19:29:06.085Z,1528054146.085 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-06-03T19:29:06.091Z,1528054146.091 [MassServo](DEBUG): Initializing EZServoServo. 2018-06-03T19:29:06.097Z,1528054146.097 [MassServo](DEBUG): Initializing MassServo. 2018-06-03T19:29:06.103Z,1528054146.103 [RudderServo](DEBUG): Initializing EZServoServo. 2018-06-03T19:29:06.109Z,1528054146.109 [RudderServo](DEBUG): Initializing RudderServo. 2018-06-03T19:29:06.115Z,1528054146.115 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-06-03T19:29:06.121Z,1528054146.121 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-06-03T19:29:06.418Z,1528054146.418 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:06.663Z,1528054146.663 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:07.169Z,1528054147.169 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:07.553Z,1528054147.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:07.969Z,1528054147.969 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:08.369Z,1528054148.369 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:08.853Z,1528054148.853 [Aanderaa_O2](INFO): Powering down 2018-06-03T19:29:08.927Z,1528054148.927 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-06-03T19:29:09.315Z,1528054149.315 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:29:09.440Z,1528054149.440 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:29:09.486Z,1528054149.486 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:29:09.487Z,1528054149.487 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:29:09.488Z,1528054149.488 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:29:10.627Z,1528054150.627 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-06-03T19:29:10.820Z,1528054150.820 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-06-03T19:29:10.822Z,1528054150.822 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-06-03T19:29:11.187Z,1528054151.187 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-06-03T19:29:11.187Z,1528054151.187 [CTD_Seabird](INFO): Powering down 2018-06-03T19:29:18.831Z,1528054158.831 [DAT](INFO): setting local address to 2 2018-06-03T19:29:19.233Z,1528054159.233 [DAT](INFO): set local address to 2 2018-06-03T19:29:28.529Z,1528054168.529 [SBIT](IMPORTANT): Beginning Startup BIT 2018-06-03T19:29:28.544Z,1528054168.544 [CBIT](IMPORTANT): Beginning ground fault scan 2018-06-03T19:29:31.820Z,1528054171.820 [NAL9602](INFO): Powering up NAL9602 2018-06-03T19:29:39.555Z,1528054179.555 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.004160 CHAN A1 (24V): -0.007838 CHAN A2 (12V): 4.697929 CHAN A3 (5V): -0.001151 CHAN B0 (3.3V): -0.000231 CHAN B1 (3.15aV): -0.000411 CHAN B2 (3.15bV): 0.000019 CHAN B3 (GND): 0.000065 OPEN: -0.000262 Full Scale Calc: 4.765 mA, -1.589 mA 2018-06-03T19:29:43.019Z,1528054183.019 [NAL9602](INFO): NAL9602 initialized 2018-06-03T19:30:21.975Z,1528054221.975 [SBIT](IMPORTANT): SBIT PASSED 2018-06-03T19:30:22.079Z,1528054222.079 [CommandLine](IMPORTANT): got command configSet list 2018-06-03T19:30:22.079Z,1528054222.079 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-06-03T19:30:22.080Z,1528054222.080 [CommandLine](IMPORTANT): DAT.loadAtStartup=1 bool; 2018-06-03T19:30:22.374Z,1528054222.374 [MissionManager](IMPORTANT): Started mission Startup 2018-06-03T19:30:22.374Z,1528054222.374 [Startup] Running Loop=1 2018-06-03T19:30:22.374Z,1528054222.374 [Startup](DEBUG): Aggregate::initialize Startup 2018-06-03T19:30:22.374Z,1528054222.374 [Startup:A.GoToSurface] Running Loop=1 2018-06-03T19:30:22.375Z,1528054222.375 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-06-03T19:30:22.376Z,1528054222.376 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-06-03T19:30:22.376Z,1528054222.376 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-06-03T19:30:22.385Z,1528054222.385 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-06-03T19:30:22.387Z,1528054222.387 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-06-03T19:30:22.388Z,1528054222.388 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-06-03T19:30:22.426Z,1528054222.426 [Startup:StartupSatComms] Running Loop=1 2018-06-03T19:30:22.426Z,1528054222.426 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-06-03T19:30:22.426Z,1528054222.426 [Startup:StartupSatComms:A] Running Loop=1 2018-06-03T19:30:22.760Z,1528054222.760 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-06-03T19:31:05.045Z,1528054265.045 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:31:05.045Z,1528054265.045 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-03T19:31:05.045Z,1528054265.045 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:31:05.183Z,1528054265.183 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:31:05.449Z,1528054265.449 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:31:05.769Z,1528054265.769 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:31:07.120Z,1528054267.120 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:31:07.120Z,1528054267.120 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-03T19:31:07.385Z,1528054267.385 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:31:07.386Z,1528054267.386 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:31:07.386Z,1528054267.386 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:31:07.386Z,1528054267.386 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:31:11.638Z,1528054271.638 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:31:11.703Z,1528054271.703 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:31:11.705Z,1528054271.705 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:31:11.706Z,1528054271.706 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:31:11.706Z,1528054271.706 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:31:22.733Z,1528054282.733 [Startup:StartupSatComms:A](INFO): Timed out from 2018-06-03T19:30:22.4Z 2018-06-03T19:31:22.733Z,1528054282.733 [Startup:StartupSatComms:A] Stopped 2018-06-03T19:31:22.733Z,1528054282.733 [Startup:StartupSatComms:B] Running Loop=1 2018-06-03T19:31:23.099Z,1528054283.099 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-06-03T19:32:05.102Z,1528054325.102 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:32:05.103Z,1528054325.103 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-06-03T19:32:05.103Z,1528054325.103 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:32:05.119Z,1528054325.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:32:05.520Z,1528054325.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:32:05.520Z,1528054325.520 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-06-03T19:32:05.898Z,1528054325.898 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:32:05.899Z,1528054325.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:32:05.899Z,1528054325.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:32:10.736Z,1528054330.736 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004380 2018-06-03T19:32:15.402Z,1528054335.402 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T191928/Courier0007.lzma 2018-06-03T19:32:16.193Z,1528054336.193 [DataOverHttps](INFO): Moved sent file to Logs/20180603T191928/Courier0007.lzma.bak 2018-06-03T19:32:16.193Z,1528054336.193 [DataOverHttps](INFO): SBD MOMSN=8309935 2018-06-03T19:32:23.025Z,1528054343.025 [Startup:StartupSatComms:B](INFO): Timed out from 2018-06-03T19:31:22.7Z 2018-06-03T19:32:23.025Z,1528054343.025 [Startup:StartupSatComms:B] Stopped 2018-06-03T19:32:23.025Z,1528054343.025 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-06-03T19:32:23.026Z,1528054343.026 [Startup:StartupSatComms] Stopped 2018-06-03T19:32:23.026Z,1528054343.026 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-06-03T19:32:23.027Z,1528054343.027 [Startup](INFO): Completed Startup 2018-06-03T19:32:23.027Z,1528054343.027 [MissionManager](INFO): Startup is completed. 2018-06-03T19:32:23.027Z,1528054343.027 [MissionManager](INFO): Uninitializing Mission Startup 2018-06-03T19:32:23.028Z,1528054343.028 [Startup] Stopped 2018-06-03T19:32:23.028Z,1528054343.028 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-06-03T19:32:23.028Z,1528054343.028 [Startup:A.GoToSurface] Stopped 2018-06-03T19:32:23.028Z,1528054343.028 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-06-03T19:32:26.588Z,1528054346.588 [MissionManager](IMPORTANT): Started mission Default 2018-06-03T19:32:26.588Z,1528054346.588 [Default] Running Loop=1 2018-06-03T19:32:26.589Z,1528054346.589 [Default](DEBUG): Aggregate::initialize Default 2018-06-03T19:32:26.589Z,1528054346.589 [Default:B.GoToSurface] Running Loop=1 2018-06-03T19:32:26.589Z,1528054346.589 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-06-03T19:32:26.589Z,1528054346.589 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-06-03T19:32:26.590Z,1528054346.590 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-06-03T19:32:26.590Z,1528054346.590 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-06-03T19:32:26.590Z,1528054346.590 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-06-03T19:32:26.591Z,1528054346.591 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-06-03T19:32:26.591Z,1528054346.591 [Default:A.Wait] Running Loop=1 2018-06-03T19:32:26.591Z,1528054346.591 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-06-03T19:32:26.686Z,1528054346.686 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20180603T191928/Express0005.lzma 2018-06-03T19:32:27.480Z,1528054347.480 [DataOverHttps](INFO): Moved sent file to Logs/20180603T191928/Express0005.lzma.bak 2018-06-03T19:32:27.480Z,1528054347.480 [DataOverHttps](INFO): SBD MOMSN=8309937 2018-06-03T19:32:40.026Z,1528054360.026 [Default:A.Wait](INFO): Done Waiting. 2018-06-03T19:32:40.027Z,1528054360.027 [Default:A.Wait] Stopped 2018-06-03T19:32:40.027Z,1528054360.027 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T19:32:40.418Z,1528054360.418 [Default:CheckIn] Running Loop=1 2018-06-03T19:32:40.419Z,1528054360.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T19:32:40.419Z,1528054360.419 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T19:32:40.822Z,1528054360.822 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-06-03T19:32:44.454Z,1528054364.454 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:32:45.250Z,1528054365.250 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:33:08.009Z,1528054388.009 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:33:08.009Z,1528054388.009 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-03T19:33:08.009Z,1528054388.009 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:33:08.142Z,1528054388.142 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:33:08.421Z,1528054388.421 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:33:08.677Z,1528054388.677 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:33:10.100Z,1528054390.100 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:33:10.100Z,1528054390.100 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-03T19:33:10.293Z,1528054390.293 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:33:10.293Z,1528054390.293 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:33:10.294Z,1528054390.294 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:33:10.294Z,1528054390.294 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:33:14.435Z,1528054394.435 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:33:14.619Z,1528054394.619 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:33:14.620Z,1528054394.620 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:33:14.621Z,1528054394.621 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:33:14.622Z,1528054394.622 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:34:46.551Z,1528054486.551 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-06-03T19:35:06.208Z,1528054506.208 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:35:06.208Z,1528054506.208 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-06-03T19:35:06.208Z,1528054506.208 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:35:06.241Z,1528054506.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:35:06.643Z,1528054506.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:35:06.643Z,1528054506.643 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-06-03T19:35:07.008Z,1528054507.008 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:35:07.013Z,1528054507.013 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:35:07.013Z,1528054507.013 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:35:11.065Z,1528054511.065 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:35:11.065Z,1528054511.065 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-03T19:35:11.065Z,1528054511.065 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:35:11.457Z,1528054511.457 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:35:11.469Z,1528054511.469 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:35:11.617Z,1528054511.617 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:35:13.026Z,1528054513.026 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:35:13.026Z,1528054513.026 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-03T19:35:13.233Z,1528054513.233 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:35:13.234Z,1528054513.234 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:35:13.234Z,1528054513.234 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:35:13.234Z,1528054513.234 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:35:17.452Z,1528054517.452 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:35:17.551Z,1528054517.551 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:35:17.552Z,1528054517.552 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:35:17.553Z,1528054517.553 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:35:17.554Z,1528054517.554 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:37:14.013Z,1528054634.013 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:37:14.013Z,1528054634.013 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-06-03T19:37:14.013Z,1528054634.013 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:37:14.015Z,1528054634.015 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:37:14.417Z,1528054634.417 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:37:14.555Z,1528054634.555 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:37:15.990Z,1528054635.990 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:37:15.990Z,1528054635.990 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-03T19:37:16.169Z,1528054636.169 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:37:16.170Z,1528054636.170 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:37:16.170Z,1528054636.170 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:37:16.170Z,1528054636.170 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:37:20.307Z,1528054640.307 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:37:20.483Z,1528054640.483 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:37:20.484Z,1528054640.484 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:37:20.485Z,1528054640.485 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:37:20.486Z,1528054640.486 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:37:40.540Z,1528054660.540 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T19:32:40.4Z 2018-06-03T19:37:40.540Z,1528054660.540 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T19:37:40.540Z,1528054660.540 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T19:37:40.928Z,1528054660.928 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-06-03T19:37:49.703Z,1528054669.703 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0004.lzma 2018-06-03T19:37:50.484Z,1528054670.484 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0004.lzma.bak 2018-06-03T19:37:50.484Z,1528054670.484 [DataOverHttps](INFO): SBD MOMSN=8309945 2018-06-03T19:38:00.897Z,1528054680.897 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20180603T191928/Express0008.lzma 2018-06-03T19:38:01.696Z,1528054681.696 [DataOverHttps](INFO): Moved sent file to Logs/20180603T191928/Express0008.lzma.bak 2018-06-03T19:38:01.697Z,1528054681.697 [DataOverHttps](INFO): SBD MOMSN=8309948 2018-06-03T19:38:07.131Z,1528054687.131 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:38:07.131Z,1528054687.131 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-06-03T19:38:07.131Z,1528054687.131 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:38:07.148Z,1528054687.148 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:38:07.499Z,1528054687.499 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:38:07.499Z,1528054687.499 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-06-03T19:38:07.877Z,1528054687.877 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:38:07.878Z,1528054687.878 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:38:07.878Z,1528054687.878 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:38:13.190Z,1528054693.190 [DataOverHttps](INFO): Sending 671 bytes from file Logs/20180603T192854/Express0001.lzma 2018-06-03T19:38:13.973Z,1528054693.973 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0001.lzma.bak 2018-06-03T19:38:13.973Z,1528054693.973 [DataOverHttps](INFO): SBD MOMSN=8309953 2018-06-03T19:38:24.367Z,1528054704.367 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180603T192854/Express0005.lzma 2018-06-03T19:38:25.157Z,1528054705.157 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0005.lzma.bak 2018-06-03T19:38:25.157Z,1528054705.157 [DataOverHttps](INFO): SBD MOMSN=8309967 2018-06-03T19:38:26.572Z,1528054706.572 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T19:38:26.572Z,1528054706.572 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T19:38:26.572Z,1528054706.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T19:39:16.701Z,1528054756.701 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:39:16.701Z,1528054756.701 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-06-03T19:39:16.701Z,1528054756.701 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:39:16.771Z,1528054756.771 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:39:16.772Z,1528054756.772 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-06-03T19:39:17.105Z,1528054757.105 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:39:17.312Z,1528054757.312 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:39:21.095Z,1528054761.095 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T19:39:21.095Z,1528054761.095 [BPC1] Communications Fault, FailCount= 1 2018-06-03T19:39:21.095Z,1528054761.095 [BPC1](ERROR): Communications Fault 2018-06-03T19:39:21.158Z,1528054761.158 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T19:39:22.320Z,1528054762.320 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T19:39:22.320Z,1528054762.320 [BPC1] No Fault, FailCount= 1 2018-06-03T19:39:44.267Z,1528054784.267 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T19:39:44.267Z,1528054784.267 [NAL9602] Data Fault, FailCount= 1 2018-06-03T19:39:44.267Z,1528054784.267 [NAL9602](ERROR): Data Fault 2018-06-03T19:39:44.374Z,1528054784.374 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T19:39:44.666Z,1528054784.666 [NAL9602](INFO): Powering down 2018-06-03T19:39:45.540Z,1528054785.540 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T19:39:45.541Z,1528054785.541 [NAL9602] No Fault, FailCount= 1 2018-06-03T19:40:15.067Z,1528054815.067 [NAL9602](INFO): Powering up NAL9602 2018-06-03T19:40:26.271Z,1528054826.271 [NAL9602](INFO): NAL9602 initialized 2018-06-03T19:41:07.944Z,1528054867.944 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:41:07.944Z,1528054867.944 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-06-03T19:41:07.944Z,1528054867.944 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:41:07.981Z,1528054867.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:41:08.353Z,1528054868.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:41:08.353Z,1528054868.353 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-06-03T19:41:08.747Z,1528054868.747 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:41:08.748Z,1528054868.748 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:41:08.748Z,1528054868.748 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:43:26.804Z,1528055006.804 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T19:43:26.805Z,1528055006.805 [Default:CheckIn:C.Wait] Stopped 2018-06-03T19:43:26.805Z,1528055006.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T19:43:26.805Z,1528055006.805 [Default:CheckIn:D] Running Loop=1 2018-06-03T19:43:27.209Z,1528055007.209 [Default:CheckIn:D] Stopped 2018-06-03T19:43:27.209Z,1528055007.209 [Default:CheckIn:E] Running Loop=1 2018-06-03T19:43:30.301Z,1528055010.301 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.010310 min 2018-06-03T19:43:30.303Z,1528055010.303 [Default:CheckIn:E] Stopped 2018-06-03T19:43:30.303Z,1528055010.303 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T19:43:30.303Z,1528055010.303 [Default:CheckIn] Stopped 2018-06-03T19:43:30.303Z,1528055010.303 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T19:43:30.303Z,1528055010.303 [Default:CheckIn](INFO): Running loop #2 2018-06-03T19:43:30.304Z,1528055010.304 [Default:CheckIn] Running Loop=2 2018-06-03T19:43:30.304Z,1528055010.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T19:43:30.304Z,1528055010.304 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T19:44:08.810Z,1528055048.810 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:44:08.810Z,1528055048.810 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-06-03T19:44:08.810Z,1528055048.810 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:44:08.851Z,1528055048.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:44:09.228Z,1528055049.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:44:09.228Z,1528055049.228 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-06-03T19:44:09.624Z,1528055049.624 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:44:09.625Z,1528055049.625 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:44:09.625Z,1528055049.625 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:45:09.039Z,1528055109.039 [CommandLine](IMPORTANT): got command get DAT.loadAtStartup 2018-06-03T19:45:09.039Z,1528055109.039 [CommandLine](IMPORTANT): DAT.loadAtStartup 1 bool 2018-06-03T19:45:26.447Z,1528055126.447 [CommandLine](IMPORTANT): got command configSet list 2018-06-03T19:45:26.447Z,1528055126.447 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-06-03T19:45:26.448Z,1528055126.448 [CommandLine](IMPORTANT): DAT.loadAtStartup=1 bool; 2018-06-03T19:45:34.893Z,1528055134.893 [CommandLine](IMPORTANT): got command failComponent 2018-06-03T19:45:34.894Z,1528055134.894 [CommandLine](IMPORTANT): Failed components: 2018-06-03T19:45:34.894Z,1528055134.894 [CommandLine](IMPORTANT): Rowe_600LCM: Communications Fault 2018-06-03T19:45:54.983Z,1528055154.983 [CommandLine](IMPORTANT): got command get platform_pitch_angle 2018-06-03T19:45:54.983Z,1528055154.983 [CommandLine](IMPORTANT): platform_pitch_angle -0.024874 rad 2018-06-03T19:47:10.016Z,1528055230.016 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:47:10.016Z,1528055230.016 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-06-03T19:47:10.016Z,1528055230.016 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:47:10.057Z,1528055230.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:47:10.473Z,1528055230.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:47:10.473Z,1528055230.473 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-06-03T19:47:10.824Z,1528055230.824 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:47:10.824Z,1528055230.824 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:47:10.825Z,1528055230.825 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:47:53.227Z,1528055273.227 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T19:47:53.228Z,1528055273.228 [BPC1] Communications Fault, FailCount= 2 2018-06-03T19:47:53.228Z,1528055273.228 [BPC1](ERROR): Communications Fault 2018-06-03T19:47:53.287Z,1528055273.287 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T19:47:54.473Z,1528055274.473 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T19:47:54.473Z,1528055274.473 [BPC1] No Fault, FailCount= 2 2018-06-03T19:48:30.387Z,1528055310.387 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T19:43:30.3Z 2018-06-03T19:48:30.387Z,1528055310.387 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T19:48:30.387Z,1528055310.387 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T19:48:35.330Z,1528055315.330 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0007.lzma 2018-06-03T19:48:36.111Z,1528055316.111 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0007.lzma.bak 2018-06-03T19:48:36.112Z,1528055316.112 [DataOverHttps](INFO): SBD MOMSN=8309984 2018-06-03T19:48:45.440Z,1528055325.440 [DataOverHttps](INFO): Sending 470 bytes from file Logs/20180603T192854/Express0008.lzma 2018-06-03T19:48:46.212Z,1528055326.212 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0008.lzma.bak 2018-06-03T19:48:46.212Z,1528055326.212 [DataOverHttps](INFO): SBD MOMSN=8309986 2018-06-03T19:48:47.489Z,1528055327.489 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T19:48:47.490Z,1528055327.490 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T19:48:47.490Z,1528055327.490 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T19:49:16.871Z,1528055356.871 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:49:16.871Z,1528055356.871 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-03T19:49:17.105Z,1528055357.105 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:49:17.105Z,1528055357.105 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:49:17.106Z,1528055357.106 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:49:17.106Z,1528055357.106 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:49:21.347Z,1528055361.347 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:49:21.420Z,1528055361.420 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:49:21.421Z,1528055361.421 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:49:21.421Z,1528055361.421 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:49:21.422Z,1528055361.422 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:50:11.001Z,1528055411.001 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:50:11.002Z,1528055411.002 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2018-06-03T19:50:11.002Z,1528055411.002 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:50:11.042Z,1528055411.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:50:11.389Z,1528055411.389 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:50:11.389Z,1528055411.389 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2018-06-03T19:50:11.756Z,1528055411.756 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:50:11.756Z,1528055411.756 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:50:11.777Z,1528055411.777 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:50:28.107Z,1528055428.107 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T19:50:28.108Z,1528055428.108 [NAL9602] Data Fault, FailCount= 2 2018-06-03T19:50:28.108Z,1528055428.108 [NAL9602](ERROR): Data Fault 2018-06-03T19:50:28.218Z,1528055428.218 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T19:50:28.507Z,1528055428.507 [NAL9602](INFO): Powering down 2018-06-03T19:50:29.374Z,1528055429.374 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T19:50:29.374Z,1528055429.374 [NAL9602] No Fault, FailCount= 2 2018-06-03T19:50:58.540Z,1528055458.540 [NAL9602](INFO): Powering up NAL9602 2018-06-03T19:51:09.735Z,1528055469.735 [NAL9602](INFO): NAL9602 initialized 2018-06-03T19:51:17.725Z,1528055477.725 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:51:17.725Z,1528055477.725 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-03T19:51:17.725Z,1528055477.725 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:51:17.845Z,1528055477.845 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:51:18.129Z,1528055478.129 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:51:18.392Z,1528055478.392 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:51:19.808Z,1528055479.808 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:51:19.809Z,1528055479.809 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-03T19:51:20.009Z,1528055480.009 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:51:20.010Z,1528055480.010 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:51:20.010Z,1528055480.010 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:51:20.010Z,1528055480.010 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:51:24.182Z,1528055484.182 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:51:24.320Z,1528055484.320 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:51:24.321Z,1528055484.321 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:51:24.321Z,1528055484.321 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:51:24.322Z,1528055484.322 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:53:11.997Z,1528055591.997 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:53:11.997Z,1528055591.997 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2018-06-03T19:53:11.997Z,1528055591.997 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:53:12.026Z,1528055592.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:53:12.429Z,1528055592.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:53:12.429Z,1528055592.429 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2018-06-03T19:53:12.806Z,1528055592.806 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:53:12.807Z,1528055592.807 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:53:12.807Z,1528055592.807 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:53:20.021Z,1528055600.021 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:53:20.021Z,1528055600.021 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-03T19:53:20.021Z,1528055600.021 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:53:20.418Z,1528055600.418 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:53:20.425Z,1528055600.425 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:53:20.566Z,1528055600.566 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:53:21.993Z,1528055601.993 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:53:21.993Z,1528055601.993 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-03T19:53:22.181Z,1528055602.181 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:53:22.182Z,1528055602.182 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:53:22.182Z,1528055602.182 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:53:22.182Z,1528055602.182 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:53:26.319Z,1528055606.319 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:53:26.511Z,1528055606.511 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:53:26.512Z,1528055606.512 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:53:26.513Z,1528055606.513 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:53:26.514Z,1528055606.514 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:53:47.988Z,1528055627.988 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T19:53:47.988Z,1528055627.988 [Default:CheckIn:C.Wait] Stopped 2018-06-03T19:53:47.988Z,1528055627.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T19:53:47.988Z,1528055627.988 [Default:CheckIn:D] Running Loop=1 2018-06-03T19:53:48.406Z,1528055628.406 [Default:CheckIn:D] Stopped 2018-06-03T19:53:48.407Z,1528055628.407 [Default:CheckIn:E] Running Loop=1 2018-06-03T19:53:51.946Z,1528055631.946 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.363635 min 2018-06-03T19:53:51.947Z,1528055631.947 [Default:CheckIn:E] Stopped 2018-06-03T19:53:51.947Z,1528055631.947 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T19:53:51.947Z,1528055631.947 [Default:CheckIn] Stopped 2018-06-03T19:53:51.947Z,1528055631.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T19:53:51.947Z,1528055631.947 [Default:CheckIn](INFO): Running loop #3 2018-06-03T19:53:51.948Z,1528055631.948 [Default:CheckIn] Running Loop=3 2018-06-03T19:53:51.948Z,1528055631.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T19:53:51.948Z,1528055631.948 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T19:53:55.231Z,1528055635.231 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T19:53:55.231Z,1528055635.231 [BPC1] Communications Fault, FailCount= 3 2018-06-03T19:53:55.231Z,1528055635.231 [BPC1](ERROR): Communications Fault 2018-06-03T19:53:55.320Z,1528055635.320 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T19:53:56.513Z,1528055636.513 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T19:53:56.513Z,1528055636.513 [BPC1] No Fault, FailCount= 3 2018-06-03T19:55:22.233Z,1528055722.233 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:55:22.233Z,1528055722.233 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-03T19:55:22.233Z,1528055722.233 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:55:22.561Z,1528055722.561 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:55:22.637Z,1528055722.637 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:55:22.754Z,1528055722.754 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:55:23.561Z,1528055723.561 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2018-06-03T19:55:23.562Z,1528055723.562 [Rowe_600LCM] Hardware Fault, FailCount= 3 2018-06-03T19:55:23.562Z,1528055723.562 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T19:55:24.101Z,1528055724.101 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:55:24.101Z,1528055724.101 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-03T19:55:24.369Z,1528055724.369 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:55:24.369Z,1528055724.369 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:55:24.370Z,1528055724.370 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:55:24.370Z,1528055724.370 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:55:28.609Z,1528055728.609 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:55:28.676Z,1528055728.676 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:55:28.677Z,1528055728.677 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:55:28.677Z,1528055728.677 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:55:28.678Z,1528055728.678 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:55:59.346Z,1528055759.346 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:00.142Z,1528055760.142 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:10.510Z,1528055770.510 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:11.335Z,1528055771.335 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:12.130Z,1528055772.130 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:12.926Z,1528055772.926 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:12.990Z,1528055772.990 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:56:12.990Z,1528055772.990 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2018-06-03T19:56:12.990Z,1528055772.990 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:56:13.028Z,1528055773.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:56:13.436Z,1528055773.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:56:13.436Z,1528055773.436 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2018-06-03T19:56:13.722Z,1528055773.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:13.798Z,1528055773.798 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:56:13.799Z,1528055773.799 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:56:13.799Z,1528055773.799 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:56:14.519Z,1528055774.519 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:15.313Z,1528055775.313 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:16.110Z,1528055776.110 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:16.914Z,1528055776.914 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:17.710Z,1528055777.710 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:18.514Z,1528055778.514 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:19.310Z,1528055779.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:20.114Z,1528055780.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:20.910Z,1528055780.910 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:21.710Z,1528055781.710 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:22.510Z,1528055782.510 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:23.318Z,1528055783.318 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:24.114Z,1528055784.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:24.909Z,1528055784.909 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:25.714Z,1528055785.714 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:26.510Z,1528055786.510 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:27.310Z,1528055787.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:28.119Z,1528055788.119 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:28.914Z,1528055788.914 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:29.710Z,1528055789.710 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:30.515Z,1528055790.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:31.310Z,1528055791.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:32.114Z,1528055792.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:32.910Z,1528055792.910 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:33.718Z,1528055793.718 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:34.513Z,1528055794.513 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:35.310Z,1528055795.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:36.114Z,1528055796.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:36.910Z,1528055796.910 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:37.714Z,1528055797.714 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:38.510Z,1528055798.510 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:39.310Z,1528055799.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:40.114Z,1528055800.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:40.910Z,1528055800.910 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:41.714Z,1528055801.714 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:42.510Z,1528055802.510 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:43.314Z,1528055803.314 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:44.110Z,1528055804.110 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:44.919Z,1528055804.919 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:45.713Z,1528055805.713 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:46.510Z,1528055806.510 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:47.315Z,1528055807.315 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:48.110Z,1528055808.110 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:48.914Z,1528055808.914 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:49.710Z,1528055809.710 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:50.506Z,1528055810.506 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:51.302Z,1528055811.302 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:52.098Z,1528055812.098 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:52.894Z,1528055812.894 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:53.690Z,1528055813.690 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:54.486Z,1528055814.486 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:55.282Z,1528055815.282 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:56.080Z,1528055816.080 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:56.874Z,1528055816.874 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:57.670Z,1528055817.670 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:58.466Z,1528055818.466 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:56:59.264Z,1528055819.264 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:00.058Z,1528055820.058 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:00.854Z,1528055820.854 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:01.650Z,1528055821.650 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:02.446Z,1528055822.446 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:03.244Z,1528055823.244 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:04.038Z,1528055824.038 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:04.834Z,1528055824.834 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:05.630Z,1528055825.630 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:06.426Z,1528055826.426 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:07.222Z,1528055827.222 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:08.018Z,1528055828.018 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:08.814Z,1528055828.814 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:09.610Z,1528055829.610 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:10.408Z,1528055830.408 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:11.203Z,1528055831.203 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:11.998Z,1528055831.998 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:12.794Z,1528055832.794 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:13.590Z,1528055833.590 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:14.387Z,1528055834.387 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:15.182Z,1528055835.182 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:15.978Z,1528055835.978 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:16.774Z,1528055836.774 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:17.571Z,1528055837.571 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:18.367Z,1528055838.367 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:19.163Z,1528055839.163 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:19.958Z,1528055839.958 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:20.754Z,1528055840.754 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:21.550Z,1528055841.550 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:22.346Z,1528055842.346 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:23.142Z,1528055843.142 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:23.938Z,1528055843.938 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:24.734Z,1528055844.734 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:57:25.125Z,1528055845.125 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:57:25.125Z,1528055845.125 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-06-03T19:57:25.125Z,1528055845.125 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:57:25.488Z,1528055845.488 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:57:25.529Z,1528055845.529 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:57:25.647Z,1528055845.647 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:57:26.453Z,1528055846.453 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2018-06-03T19:57:26.453Z,1528055846.453 [Rowe_600LCM] Hardware Fault, FailCount= 4 2018-06-03T19:57:26.453Z,1528055846.453 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T19:57:27.070Z,1528055847.070 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T19:57:27.071Z,1528055847.071 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-03T19:57:27.261Z,1528055847.261 [Rowe_600LCM](INFO): Initializing 2018-06-03T19:57:27.262Z,1528055847.262 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T19:57:27.262Z,1528055847.262 [Rowe_600LCM](INFO): LCM OK 2018-06-03T19:57:27.262Z,1528055847.262 [Rowe_600LCM](INFO): Powering up 2018-06-03T19:57:31.386Z,1528055851.386 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T19:57:31.561Z,1528055851.561 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T19:57:31.563Z,1528055851.563 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T19:57:31.564Z,1528055851.564 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T19:57:31.564Z,1528055851.564 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T19:58:02.290Z,1528055882.290 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:58:03.086Z,1528055883.086 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T19:58:52.303Z,1528055932.303 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T19:53:51.9Z 2018-06-03T19:58:52.303Z,1528055932.303 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T19:58:52.303Z,1528055932.303 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T19:58:57.790Z,1528055937.790 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0010.lzma 2018-06-03T19:58:58.572Z,1528055938.572 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0010.lzma.bak 2018-06-03T19:58:58.573Z,1528055938.573 [DataOverHttps](INFO): SBD MOMSN=8310002 2018-06-03T19:59:08.975Z,1528055948.975 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20180603T192854/Express0011.lzma 2018-06-03T19:59:09.761Z,1528055949.761 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0011.lzma.bak 2018-06-03T19:59:09.761Z,1528055949.761 [DataOverHttps](INFO): SBD MOMSN=8310004 2018-06-03T19:59:11.192Z,1528055951.192 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T19:59:11.192Z,1528055951.192 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T19:59:11.192Z,1528055951.192 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T19:59:14.832Z,1528055954.832 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T19:59:14.832Z,1528055954.832 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2018-06-03T19:59:14.832Z,1528055954.832 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T19:59:14.890Z,1528055954.890 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T19:59:15.017Z,1528055955.017 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T19:59:15.017Z,1528055955.017 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2018-06-03T19:59:15.374Z,1528055955.374 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T19:59:15.375Z,1528055955.375 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T19:59:15.375Z,1528055955.375 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T19:59:27.309Z,1528055967.309 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T19:59:27.309Z,1528055967.309 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-06-03T19:59:27.309Z,1528055967.309 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T19:59:27.430Z,1528055967.430 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T19:59:27.430Z,1528055967.430 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-06-03T19:59:27.713Z,1528055967.713 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T19:59:27.954Z,1528055967.954 [Rowe_600LCM](INFO): Powering down 2018-06-03T19:59:57.338Z,1528055997.338 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T19:59:57.339Z,1528055997.339 [BPC1] Communications Fault, FailCount= 4 2018-06-03T19:59:57.339Z,1528055997.339 [BPC1](ERROR): Communications Fault 2018-06-03T19:59:57.363Z,1528055997.363 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T19:59:58.565Z,1528055998.565 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T19:59:58.565Z,1528055998.565 [BPC1] No Fault, FailCount= 4 2018-06-03T20:01:11.731Z,1528056071.731 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T20:01:11.731Z,1528056071.731 [NAL9602] Data Fault, FailCount= 3 2018-06-03T20:01:11.731Z,1528056071.731 [NAL9602](ERROR): Data Fault 2018-06-03T20:01:11.830Z,1528056071.830 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T20:01:14.843Z,1528056074.843 [NAL9602](INFO): Powering down 2018-06-03T20:01:15.435Z,1528056075.435 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T20:01:15.435Z,1528056075.435 [NAL9602] No Fault, FailCount= 3 2018-06-03T20:01:44.959Z,1528056104.959 [NAL9602](INFO): Powering up NAL9602 2018-06-03T20:01:55.759Z,1528056115.759 [NAL9602](INFO): NAL9602 initialized 2018-06-03T20:02:15.418Z,1528056135.418 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:02:15.418Z,1528056135.418 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2018-06-03T20:02:15.418Z,1528056135.418 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:02:15.452Z,1528056135.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:02:15.859Z,1528056135.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:02:15.859Z,1528056135.859 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2018-06-03T20:02:16.224Z,1528056136.224 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:02:16.224Z,1528056136.224 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:02:16.225Z,1528056136.225 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:04:11.476Z,1528056251.476 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T20:04:11.476Z,1528056251.476 [Default:CheckIn:C.Wait] Stopped 2018-06-03T20:04:11.477Z,1528056251.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T20:04:11.477Z,1528056251.477 [Default:CheckIn:D] Running Loop=1 2018-06-03T20:04:11.891Z,1528056251.891 [Default:CheckIn:D] Stopped 2018-06-03T20:04:11.891Z,1528056251.891 [Default:CheckIn:E] Running Loop=1 2018-06-03T20:04:15.029Z,1528056255.029 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.755035 min 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn:E] Stopped 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn] Stopped 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn](INFO): Running loop #4 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn] Running Loop=4 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T20:04:15.030Z,1528056255.030 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T20:05:16.359Z,1528056316.359 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:05:16.359Z,1528056316.359 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2018-06-03T20:05:16.359Z,1528056316.359 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:05:16.374Z,1528056316.374 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:05:16.779Z,1528056316.779 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:05:16.780Z,1528056316.780 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2018-06-03T20:05:17.135Z,1528056317.135 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:05:17.135Z,1528056317.135 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:05:17.136Z,1528056317.136 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:08:17.274Z,1528056497.274 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:08:17.274Z,1528056497.274 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2018-06-03T20:08:17.274Z,1528056497.274 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:08:17.320Z,1528056497.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:08:17.702Z,1528056497.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:08:17.702Z,1528056497.702 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2018-06-03T20:08:18.080Z,1528056498.080 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:08:18.085Z,1528056498.085 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:08:18.086Z,1528056498.086 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:09:15.177Z,1528056555.177 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:09:15.177Z,1528056555.177 [BPC1] Communications Fault, FailCount= 5 2018-06-03T20:09:15.177Z,1528056555.177 [BPC1](ERROR): Communications Fault 2018-06-03T20:09:15.217Z,1528056555.217 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T20:04:15.0Z 2018-06-03T20:09:15.217Z,1528056555.217 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T20:09:15.217Z,1528056555.217 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T20:09:15.238Z,1528056555.238 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:09:16.282Z,1528056556.282 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:09:16.282Z,1528056556.282 [BPC1] No Fault, FailCount= 5 2018-06-03T20:09:20.148Z,1528056560.148 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0013.lzma 2018-06-03T20:09:20.948Z,1528056560.948 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0013.lzma.bak 2018-06-03T20:09:20.948Z,1528056560.948 [DataOverHttps](INFO): SBD MOMSN=8310014 2018-06-03T20:09:30.262Z,1528056570.262 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:09:30.262Z,1528056570.262 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-03T20:09:30.287Z,1528056570.287 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20180603T192854/Express0014.lzma 2018-06-03T20:09:30.553Z,1528056570.553 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:09:30.553Z,1528056570.553 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:09:30.554Z,1528056570.554 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:09:30.554Z,1528056570.554 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:09:31.072Z,1528056571.072 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0014.lzma.bak 2018-06-03T20:09:31.072Z,1528056571.072 [DataOverHttps](INFO): SBD MOMSN=8310016 2018-06-03T20:09:32.353Z,1528056572.353 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T20:09:32.353Z,1528056572.353 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T20:09:32.353Z,1528056572.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T20:09:34.758Z,1528056574.758 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:09:34.855Z,1528056574.855 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:09:34.856Z,1528056574.856 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:09:34.857Z,1528056574.857 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:09:34.858Z,1528056574.858 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:11:18.163Z,1528056678.163 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:11:18.163Z,1528056678.163 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2018-06-03T20:11:18.163Z,1528056678.163 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:11:18.192Z,1528056678.192 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:11:18.579Z,1528056678.579 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:11:18.579Z,1528056678.579 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2018-06-03T20:11:18.956Z,1528056678.956 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:11:18.956Z,1528056678.956 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:11:18.958Z,1528056678.958 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:11:31.326Z,1528056691.326 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:11:31.326Z,1528056691.326 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-03T20:11:31.326Z,1528056691.326 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:11:31.419Z,1528056691.419 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:11:31.729Z,1528056691.729 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:11:31.955Z,1528056691.955 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:11:36.085Z,1528056696.085 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:11:36.086Z,1528056696.086 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-03T20:11:36.397Z,1528056696.397 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:11:36.398Z,1528056696.398 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:11:36.398Z,1528056696.398 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:11:36.398Z,1528056696.398 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:11:40.644Z,1528056700.644 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:11:40.704Z,1528056700.704 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:11:40.705Z,1528056700.705 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:11:40.706Z,1528056700.706 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:11:40.706Z,1528056700.706 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:11:58.900Z,1528056718.900 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T20:11:58.900Z,1528056718.900 [NAL9602] Data Fault, FailCount= 4 2018-06-03T20:11:58.901Z,1528056718.901 [NAL9602](ERROR): Data Fault 2018-06-03T20:11:58.998Z,1528056718.998 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T20:11:59.303Z,1528056719.303 [NAL9602](INFO): Powering down 2018-06-03T20:12:00.186Z,1528056720.186 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T20:12:00.186Z,1528056720.186 [NAL9602] No Fault, FailCount= 4 2018-06-03T20:12:29.703Z,1528056749.703 [NAL9602](INFO): Powering up NAL9602 2018-06-03T20:12:40.563Z,1528056760.563 [NAL9602](INFO): NAL9602 initialized 2018-06-03T20:13:37.013Z,1528056817.013 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:13:37.013Z,1528056817.013 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-03T20:13:37.013Z,1528056817.013 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:13:37.123Z,1528056817.123 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:13:37.418Z,1528056817.418 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:13:37.665Z,1528056817.665 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:13:39.103Z,1528056819.103 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:13:39.103Z,1528056819.103 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-03T20:13:39.281Z,1528056819.281 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:13:39.281Z,1528056819.281 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:13:39.282Z,1528056819.282 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:13:39.282Z,1528056819.282 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:13:43.426Z,1528056823.426 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:13:43.592Z,1528056823.592 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:13:43.593Z,1528056823.593 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:13:43.594Z,1528056823.594 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:13:43.594Z,1528056823.594 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:14:19.080Z,1528056859.080 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:14:19.081Z,1528056859.081 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2018-06-03T20:14:19.081Z,1528056859.081 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:14:19.097Z,1528056859.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:14:19.502Z,1528056859.502 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:14:19.502Z,1528056859.502 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2018-06-03T20:14:19.866Z,1528056859.866 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:14:19.867Z,1528056859.867 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:14:19.867Z,1528056859.867 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:14:32.679Z,1528056872.679 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T20:14:32.680Z,1528056872.680 [Default:CheckIn:C.Wait] Stopped 2018-06-03T20:14:32.680Z,1528056872.680 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T20:14:32.680Z,1528056872.680 [Default:CheckIn:D] Running Loop=1 2018-06-03T20:14:33.094Z,1528056873.094 [Default:CheckIn:D] Stopped 2018-06-03T20:14:33.094Z,1528056873.094 [Default:CheckIn:E] Running Loop=1 2018-06-03T20:14:36.640Z,1528056876.640 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.108423 min 2018-06-03T20:14:36.640Z,1528056876.640 [Default:CheckIn:E] Stopped 2018-06-03T20:14:36.640Z,1528056876.640 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T20:14:36.640Z,1528056876.640 [Default:CheckIn] Stopped 2018-06-03T20:14:36.645Z,1528056876.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T20:14:36.645Z,1528056876.645 [Default:CheckIn](INFO): Running loop #5 2018-06-03T20:14:36.645Z,1528056876.645 [Default:CheckIn] Running Loop=5 2018-06-03T20:14:36.645Z,1528056876.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T20:14:36.646Z,1528056876.646 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T20:15:39.921Z,1528056939.921 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:15:39.921Z,1528056939.921 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-03T20:15:39.921Z,1528056939.921 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:15:40.041Z,1528056940.041 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:15:40.325Z,1528056940.325 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:15:40.586Z,1528056940.586 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:15:42.012Z,1528056942.012 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:15:42.012Z,1528056942.012 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-03T20:15:42.201Z,1528056942.201 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:15:42.201Z,1528056942.201 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:15:42.202Z,1528056942.202 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:15:42.202Z,1528056942.202 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:15:46.382Z,1528056946.382 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:15:46.515Z,1528056946.515 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:15:46.516Z,1528056946.516 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:15:46.517Z,1528056946.517 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:15:46.518Z,1528056946.518 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:17:20.072Z,1528057040.072 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:17:20.072Z,1528057040.072 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2018-06-03T20:17:20.072Z,1528057040.072 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:17:20.117Z,1528057040.117 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:17:20.519Z,1528057040.519 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:17:20.519Z,1528057040.519 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2018-06-03T20:17:20.882Z,1528057040.882 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:17:20.883Z,1528057040.883 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:17:20.883Z,1528057040.883 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:17:42.793Z,1528057062.793 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:17:42.793Z,1528057062.793 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-06-03T20:17:42.793Z,1528057062.793 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:17:43.021Z,1528057063.021 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:17:43.021Z,1528057063.021 [BPC1] Communications Fault, FailCount= 6 2018-06-03T20:17:43.021Z,1528057063.021 [BPC1](ERROR): Communications Fault 2018-06-03T20:17:43.128Z,1528057063.128 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:17:43.128Z,1528057063.128 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:17:43.197Z,1528057063.197 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:17:43.614Z,1528057063.614 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:17:44.305Z,1528057064.305 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:17:44.305Z,1528057064.305 [BPC1] No Fault, FailCount= 6 2018-06-03T20:17:45.116Z,1528057065.116 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:17:45.116Z,1528057065.116 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-03T20:17:45.229Z,1528057065.229 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:17:45.230Z,1528057065.230 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:17:45.230Z,1528057065.230 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:17:45.230Z,1528057065.230 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:17:49.351Z,1528057069.351 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:17:49.508Z,1528057069.508 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:17:49.509Z,1528057069.509 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:17:49.510Z,1528057069.510 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:17:49.510Z,1528057069.510 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:19:37.061Z,1528057177.061 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T20:14:36.6Z 2018-06-03T20:19:37.061Z,1528057177.061 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T20:19:37.061Z,1528057177.061 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T20:19:42.679Z,1528057182.679 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0016.lzma 2018-06-03T20:19:43.464Z,1528057183.464 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0016.lzma.bak 2018-06-03T20:19:43.466Z,1528057183.466 [DataOverHttps](INFO): SBD MOMSN=8310022 2018-06-03T20:19:45.821Z,1528057185.821 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:19:45.821Z,1528057185.821 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-06-03T20:19:45.821Z,1528057185.821 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:19:46.225Z,1528057186.225 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:19:46.430Z,1528057186.430 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:19:47.237Z,1528057187.237 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2018-06-03T20:19:47.237Z,1528057187.237 [Rowe_600LCM] Hardware Fault, FailCount= 5 2018-06-03T20:19:47.237Z,1528057187.237 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T20:19:48.478Z,1528057188.478 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2018-06-03T20:19:48.478Z,1528057188.478 [CBIT](FAULT): Hardware Fault in component: Rowe_600LCM 2018-06-03T20:19:53.364Z,1528057193.364 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20180603T192854/Express0017.lzma 2018-06-03T20:19:54.148Z,1528057194.148 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0017.lzma.bak 2018-06-03T20:19:54.148Z,1528057194.148 [DataOverHttps](INFO): SBD MOMSN=8310024 2018-06-03T20:19:55.435Z,1528057195.435 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T20:19:55.435Z,1528057195.435 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T20:19:55.435Z,1528057195.435 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T20:20:21.201Z,1528057221.201 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:20:21.201Z,1528057221.201 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2018-06-03T20:20:21.201Z,1528057221.201 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:20:21.254Z,1528057221.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:20:21.716Z,1528057221.716 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:20:21.716Z,1528057221.716 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2018-06-03T20:20:22.004Z,1528057222.004 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:20:22.005Z,1528057222.005 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:20:22.005Z,1528057222.005 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:22:41.539Z,1528057361.539 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T20:22:41.539Z,1528057361.539 [NAL9602] Data Fault, FailCount= 5 2018-06-03T20:22:41.539Z,1528057361.539 [NAL9602](ERROR): Data Fault 2018-06-03T20:22:41.657Z,1528057361.657 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T20:22:41.658Z,1528057361.658 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-06-03T20:22:41.938Z,1528057361.938 [NAL9602](INFO): Powering down 2018-06-03T20:22:42.063Z,1528057362.063 [CommandLine](FAULT): Scheduling is paused 2018-06-03T20:22:42.063Z,1528057362.063 [CBIT](INFO): Critical error at 20180603T202241 2018-06-03T20:22:42.063Z,1528057362.063 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-06-03T20:23:22.093Z,1528057402.093 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:23:22.093Z,1528057402.093 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2018-06-03T20:23:22.093Z,1528057402.093 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:23:22.131Z,1528057402.131 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:23:22.504Z,1528057402.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:23:22.504Z,1528057402.504 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2018-06-03T20:23:22.891Z,1528057402.891 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:23:22.892Z,1528057402.892 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:23:22.892Z,1528057402.892 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:24:41.717Z,1528057481.717 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T20:24:41.717Z,1528057481.717 [NAL9602] No Fault, FailCount= 5 2018-06-03T20:24:42.059Z,1528057482.059 [NAL9602](INFO): Powering up NAL9602 2018-06-03T20:24:53.255Z,1528057493.255 [NAL9602](INFO): NAL9602 initialized 2018-06-03T20:24:55.693Z,1528057495.693 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T20:24:55.694Z,1528057495.694 [Default:CheckIn:C.Wait] Stopped 2018-06-03T20:24:55.694Z,1528057495.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T20:24:55.694Z,1528057495.694 [Default:CheckIn:D] Running Loop=1 2018-06-03T20:24:56.100Z,1528057496.100 [Default:CheckIn:D] Stopped 2018-06-03T20:24:56.100Z,1528057496.100 [Default:CheckIn:E] Running Loop=1 2018-06-03T20:24:56.526Z,1528057496.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.491862 min 2018-06-03T20:24:56.526Z,1528057496.526 [Default:CheckIn:E] Stopped 2018-06-03T20:24:56.526Z,1528057496.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T20:24:56.526Z,1528057496.526 [Default:CheckIn] Stopped 2018-06-03T20:24:56.526Z,1528057496.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T20:24:56.527Z,1528057496.527 [Default:CheckIn](INFO): Running loop #6 2018-06-03T20:24:56.527Z,1528057496.527 [Default:CheckIn] Running Loop=6 2018-06-03T20:24:56.527Z,1528057496.527 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T20:24:56.527Z,1528057496.527 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T20:26:22.937Z,1528057582.937 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:26:22.937Z,1528057582.937 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2018-06-03T20:26:22.937Z,1528057582.937 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:26:22.968Z,1528057582.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:26:23.339Z,1528057583.339 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:26:23.339Z,1528057583.339 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2018-06-03T20:26:23.724Z,1528057583.724 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:26:23.729Z,1528057583.729 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:26:23.729Z,1528057583.729 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:27:06.532Z,1528057626.532 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:27:06.532Z,1528057626.532 [BPC1] Communications Fault, FailCount= 7 2018-06-03T20:27:06.532Z,1528057626.532 [BPC1](ERROR): Communications Fault 2018-06-03T20:27:06.584Z,1528057626.584 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:27:07.752Z,1528057627.752 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:27:07.752Z,1528057627.752 [BPC1] No Fault, FailCount= 7 2018-06-03T20:29:23.766Z,1528057763.766 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:29:23.767Z,1528057763.767 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2018-06-03T20:29:23.767Z,1528057763.767 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:29:23.801Z,1528057763.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:29:23.801Z,1528057763.801 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:29:46.193Z,1528057786.193 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:29:46.194Z,1528057786.194 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-03T20:29:46.237Z,1528057786.237 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:29:46.237Z,1528057786.237 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:29:46.237Z,1528057786.237 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:29:46.238Z,1528057786.238 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:29:50.367Z,1528057790.367 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:29:50.424Z,1528057790.424 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:29:50.425Z,1528057790.425 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:29:50.426Z,1528057790.426 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:29:50.426Z,1528057790.426 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:29:56.553Z,1528057796.553 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T20:24:56.5Z 2018-06-03T20:29:56.553Z,1528057796.553 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T20:29:56.554Z,1528057796.554 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T20:30:00.942Z,1528057800.942 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20180603T192854/Courier0019.lzma 2018-06-03T20:30:01.729Z,1528057801.729 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0019.lzma.bak 2018-06-03T20:30:01.729Z,1528057801.729 [DataOverHttps](INFO): SBD MOMSN=8310032 2018-06-03T20:30:12.937Z,1528057812.937 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20180603T192854/Express0020.lzma 2018-06-03T20:30:13.705Z,1528057813.705 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0020.lzma.bak 2018-06-03T20:30:13.705Z,1528057813.705 [DataOverHttps](INFO): SBD MOMSN=8310035 2018-06-03T20:30:15.101Z,1528057815.101 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T20:30:15.102Z,1528057815.102 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T20:30:15.102Z,1528057815.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T20:31:46.677Z,1528057906.677 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:31:46.677Z,1528057906.677 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-03T20:31:46.677Z,1528057906.677 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:31:46.962Z,1528057906.962 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:31:47.081Z,1528057907.081 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:31:47.194Z,1528057907.194 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:31:48.573Z,1528057908.573 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:31:48.573Z,1528057908.573 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-03T20:31:48.809Z,1528057908.809 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:31:48.810Z,1528057908.810 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:31:48.810Z,1528057908.810 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:31:48.810Z,1528057908.810 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:31:53.058Z,1528057913.058 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:31:53.116Z,1528057913.116 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:31:53.117Z,1528057913.117 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:31:53.117Z,1528057913.117 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:31:53.118Z,1528057913.118 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:33:08.608Z,1528057988.608 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:33:08.608Z,1528057988.608 [BPC1] Communications Fault, FailCount= 8 2018-06-03T20:33:08.608Z,1528057988.608 [BPC1](ERROR): Communications Fault 2018-06-03T20:33:08.636Z,1528057988.636 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:33:09.869Z,1528057989.869 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:33:09.869Z,1528057989.869 [BPC1] No Fault, FailCount= 8 2018-06-03T20:33:49.605Z,1528058029.605 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:33:49.605Z,1528058029.605 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-03T20:33:49.605Z,1528058029.605 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:33:49.983Z,1528058029.983 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:33:50.009Z,1528058030.009 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:33:50.148Z,1528058030.148 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:33:51.588Z,1528058031.588 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:33:51.588Z,1528058031.588 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-03T20:33:51.765Z,1528058031.765 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:33:51.765Z,1528058031.765 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:33:51.766Z,1528058031.766 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:33:51.766Z,1528058031.766 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:33:55.903Z,1528058035.903 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:33:56.073Z,1528058036.073 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:33:56.074Z,1528058036.074 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:33:56.075Z,1528058036.075 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:33:56.075Z,1528058036.075 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:34:14.323Z,1528058054.323 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:14.718Z,1528058054.718 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:15.133Z,1528058055.133 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:15.535Z,1528058055.535 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:15.935Z,1528058055.935 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:19.488Z,1528058059.488 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:19.622Z,1528058059.622 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:19.995Z,1528058059.995 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:20.405Z,1528058060.405 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:20.799Z,1528058060.799 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:21.183Z,1528058061.183 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:21.582Z,1528058061.582 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:21.985Z,1528058061.985 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:22.396Z,1528058062.396 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:22.750Z,1528058062.750 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:22.802Z,1528058062.802 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:23.191Z,1528058063.191 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:23.554Z,1528058063.554 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:23.578Z,1528058063.578 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:23.991Z,1528058063.991 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:24.394Z,1528058064.394 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:24.774Z,1528058064.774 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:25.186Z,1528058065.186 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:25.591Z,1528058065.591 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:26.002Z,1528058066.002 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:26.382Z,1528058066.382 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:26.783Z,1528058066.783 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:27.186Z,1528058067.186 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:27.590Z,1528058067.590 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:27.976Z,1528058067.976 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:28.398Z,1528058068.398 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:28.801Z,1528058068.801 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:29.195Z,1528058069.195 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:29.574Z,1528058069.574 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:29.979Z,1528058069.979 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:30.386Z,1528058070.386 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:30.783Z,1528058070.783 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:31.192Z,1528058071.192 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:31.595Z,1528058071.595 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:31.995Z,1528058071.995 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:32.382Z,1528058072.382 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:32.778Z,1528058072.778 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:33.183Z,1528058073.183 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:33.583Z,1528058073.583 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:33.981Z,1528058073.981 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:34.395Z,1528058074.395 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:34.798Z,1528058074.798 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:35.195Z,1528058075.195 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:35.582Z,1528058075.582 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:35.985Z,1528058075.985 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:36.388Z,1528058076.388 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:36.784Z,1528058076.784 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:37.196Z,1528058077.196 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:37.589Z,1528058077.589 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:37.995Z,1528058077.995 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:38.382Z,1528058078.382 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:38.796Z,1528058078.796 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:39.179Z,1528058079.179 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:39.587Z,1528058079.587 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:39.987Z,1528058079.987 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:40.392Z,1528058080.392 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:40.796Z,1528058080.796 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:41.197Z,1528058081.197 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:41.582Z,1528058081.582 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:41.986Z,1528058081.986 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:42.378Z,1528058082.378 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:42.784Z,1528058082.784 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:43.196Z,1528058083.196 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:43.596Z,1528058083.596 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:43.991Z,1528058083.991 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:44.378Z,1528058084.378 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:44.778Z,1528058084.778 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:45.179Z,1528058085.179 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:45.576Z,1528058085.576 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:45.981Z,1528058085.981 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:46.394Z,1528058086.394 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:46.796Z,1528058086.796 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:47.192Z,1528058087.192 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:47.579Z,1528058087.579 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:47.986Z,1528058087.986 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:48.381Z,1528058088.381 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:48.783Z,1528058088.783 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:49.190Z,1528058089.190 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:49.594Z,1528058089.594 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:49.997Z,1528058089.997 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:50.374Z,1528058090.374 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:50.777Z,1528058090.777 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:51.177Z,1528058091.177 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:51.576Z,1528058091.576 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:51.978Z,1528058091.978 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:52.406Z,1528058092.406 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:52.795Z,1528058092.795 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:53.188Z,1528058093.188 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:53.582Z,1528058093.582 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:34:55.159Z,1528058095.159 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T20:34:55.159Z,1528058095.159 [NAL9602] Data Fault, FailCount= 1 2018-06-03T20:34:55.159Z,1528058095.159 [NAL9602](ERROR): Data Fault 2018-06-03T20:34:55.260Z,1528058095.260 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T20:34:55.558Z,1528058095.558 [NAL9602](INFO): Powering down 2018-06-03T20:34:56.420Z,1528058096.420 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T20:34:56.420Z,1528058096.420 [NAL9602] No Fault, FailCount= 1 2018-06-03T20:35:02.763Z,1528058102.763 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:03.559Z,1528058103.559 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:04.353Z,1528058104.353 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:05.150Z,1528058105.150 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:05.954Z,1528058105.954 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:06.750Z,1528058106.750 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:07.558Z,1528058107.558 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:08.354Z,1528058108.354 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:09.149Z,1528058109.149 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:09.582Z,1528058109.582 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:09.955Z,1528058109.955 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:09.985Z,1528058109.985 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:10.385Z,1528058110.385 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:10.750Z,1528058110.750 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:10.777Z,1528058110.777 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:11.173Z,1528058111.173 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:11.559Z,1528058111.559 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:11.579Z,1528058111.579 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:11.981Z,1528058111.981 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:12.353Z,1528058112.353 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:12.381Z,1528058112.381 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:12.777Z,1528058112.777 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:13.150Z,1528058113.150 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:13.183Z,1528058113.183 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:13.584Z,1528058113.584 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:13.954Z,1528058113.954 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:13.985Z,1528058113.985 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:14.383Z,1528058114.383 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:14.750Z,1528058114.750 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:14.788Z,1528058114.788 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:15.186Z,1528058115.186 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:15.554Z,1528058115.554 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:15.578Z,1528058115.578 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:15.630Z,1528058115.630 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T20:35:15.630Z,1528058115.630 [Default:CheckIn:C.Wait] Stopped 2018-06-03T20:35:15.631Z,1528058115.631 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T20:35:15.631Z,1528058115.631 [Default:CheckIn:D] Running Loop=1 2018-06-03T20:35:15.987Z,1528058115.987 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:16.010Z,1528058116.010 [Default:CheckIn:D] Stopped 2018-06-03T20:35:16.010Z,1528058116.010 [Default:CheckIn:E] Running Loop=1 2018-06-03T20:35:16.350Z,1528058116.350 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:17.146Z,1528058117.146 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:17.942Z,1528058117.942 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:18.738Z,1528058118.738 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:19.524Z,1528058119.524 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:19.545Z,1528058119.545 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:19.565Z,1528058119.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.823694 min 2018-06-03T20:35:19.565Z,1528058119.565 [Default:CheckIn:E] Stopped 2018-06-03T20:35:19.566Z,1528058119.566 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T20:35:19.566Z,1528058119.566 [Default:CheckIn] Stopped 2018-06-03T20:35:19.566Z,1528058119.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T20:35:19.566Z,1528058119.566 [Default:CheckIn](INFO): Running loop #7 2018-06-03T20:35:19.566Z,1528058119.566 [Default:CheckIn] Running Loop=7 2018-06-03T20:35:19.566Z,1528058119.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T20:35:19.567Z,1528058119.567 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T20:35:19.658Z,1528058119.658 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:20.042Z,1528058120.042 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:20.342Z,1528058120.342 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:20.437Z,1528058120.437 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:20.834Z,1528058120.834 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:21.138Z,1528058121.138 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:21.238Z,1528058121.238 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:21.638Z,1528058121.638 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:21.935Z,1528058121.935 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:22.037Z,1528058122.037 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:22.445Z,1528058122.445 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:22.730Z,1528058122.730 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:23.526Z,1528058123.526 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:24.322Z,1528058124.322 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:25.117Z,1528058125.117 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:25.615Z,1528058125.615 [NAL9602](INFO): Powering up NAL9602 2018-06-03T20:35:25.914Z,1528058125.914 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:26.433Z,1528058126.433 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:26.710Z,1528058126.710 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:26.833Z,1528058126.833 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:27.238Z,1528058127.238 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:27.506Z,1528058127.506 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:27.638Z,1528058127.638 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:28.044Z,1528058128.044 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:28.302Z,1528058128.302 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:28.441Z,1528058128.441 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:28.842Z,1528058128.842 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:29.098Z,1528058129.098 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:29.249Z,1528058129.249 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:29.644Z,1528058129.644 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:29.893Z,1528058129.894 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:30.044Z,1528058130.044 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:30.443Z,1528058130.443 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:30.690Z,1528058130.690 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:30.841Z,1528058130.841 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:31.232Z,1528058131.232 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:31.486Z,1528058131.486 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:31.634Z,1528058131.634 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:32.034Z,1528058132.034 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:32.282Z,1528058132.282 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:32.434Z,1528058132.434 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:32.833Z,1528058132.833 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:33.078Z,1528058133.078 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:33.233Z,1528058133.233 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:33.633Z,1528058133.633 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:33.874Z,1528058133.874 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:34.035Z,1528058134.035 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:34.438Z,1528058134.438 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:34.669Z,1528058134.669 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:34.833Z,1528058134.833 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:35.240Z,1528058135.240 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:35.466Z,1528058135.466 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:35.642Z,1528058135.642 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:36.052Z,1528058136.052 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:36.262Z,1528058136.262 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:36.415Z,1528058136.415 [NAL9602](INFO): NAL9602 initialized 2018-06-03T20:35:36.434Z,1528058136.434 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:36.834Z,1528058136.834 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:37.058Z,1528058137.058 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:37.245Z,1528058137.245 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:37.650Z,1528058137.650 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:37.854Z,1528058137.854 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:38.055Z,1528058138.055 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:38.434Z,1528058138.434 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:38.650Z,1528058138.650 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:38.843Z,1528058138.843 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:39.242Z,1528058139.242 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:39.446Z,1528058139.446 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:39.636Z,1528058139.636 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:40.050Z,1528058140.050 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:40.242Z,1528058140.242 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:40.450Z,1528058140.450 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:40.853Z,1528058140.853 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:41.038Z,1528058141.038 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:41.246Z,1528058141.246 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:41.642Z,1528058141.642 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:41.834Z,1528058141.834 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:42.035Z,1528058142.035 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:42.433Z,1528058142.433 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:42.630Z,1528058142.630 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:42.864Z,1528058142.864 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:43.254Z,1528058143.254 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:43.426Z,1528058143.426 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:43.650Z,1528058143.650 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:44.038Z,1528058144.038 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:44.222Z,1528058144.222 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:45.020Z,1528058145.020 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:45.814Z,1528058145.814 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:46.610Z,1528058146.610 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:47.406Z,1528058147.406 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:48.202Z,1528058148.202 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:48.998Z,1528058148.998 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:49.794Z,1528058149.794 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:50.590Z,1528058150.590 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:51.386Z,1528058151.386 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:35:51.777Z,1528058151.777 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:35:51.777Z,1528058151.777 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-03T20:35:51.777Z,1528058151.777 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:35:52.117Z,1528058152.117 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:35:52.181Z,1528058152.181 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:35:52.299Z,1528058152.299 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:35:53.105Z,1528058153.105 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2018-06-03T20:35:53.105Z,1528058153.105 [Rowe_600LCM] Hardware Fault, FailCount= 3 2018-06-03T20:35:53.105Z,1528058153.105 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T20:35:53.673Z,1528058153.673 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:35:53.673Z,1528058153.673 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-03T20:35:53.913Z,1528058153.913 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:35:53.913Z,1528058153.913 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:35:53.914Z,1528058153.914 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:35:53.914Z,1528058153.914 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:35:58.147Z,1528058158.147 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:35:58.228Z,1528058158.228 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:35:58.229Z,1528058158.229 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:35:58.230Z,1528058158.230 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:35:58.230Z,1528058158.230 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:36:26.502Z,1528058186.502 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:26.877Z,1528058186.877 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:27.283Z,1528058187.283 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:28.885Z,1528058188.885 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:29.282Z,1528058189.282 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:29.696Z,1528058189.696 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:33.662Z,1528058193.662 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:34.458Z,1528058194.458 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:50.498Z,1528058210.498 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:50.882Z,1528058210.882 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:51.302Z,1528058211.302 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:57.695Z,1528058217.695 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:58.092Z,1528058218.092 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:58.495Z,1528058218.495 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:58.888Z,1528058218.888 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:59.283Z,1528058219.283 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:36:59.696Z,1528058219.696 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:00.104Z,1528058220.104 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:00.493Z,1528058220.493 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:00.883Z,1528058220.883 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:01.278Z,1528058221.278 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:01.691Z,1528058221.691 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:02.095Z,1528058222.095 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:02.499Z,1528058222.499 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:02.882Z,1528058222.882 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:03.287Z,1528058223.287 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:03.696Z,1528058223.696 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:04.100Z,1528058224.100 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:04.495Z,1528058224.495 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:04.879Z,1528058224.879 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:05.278Z,1528058225.278 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:05.691Z,1528058225.691 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:06.095Z,1528058226.095 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:06.495Z,1528058226.495 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:06.886Z,1528058226.886 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:07.283Z,1528058227.283 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:07.692Z,1528058227.692 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:08.095Z,1528058228.095 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:08.495Z,1528058228.495 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:08.886Z,1528058228.886 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:09.282Z,1528058229.282 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:09.695Z,1528058229.695 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:10.094Z,1528058230.094 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:10.502Z,1528058230.502 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:40.937Z,1528058260.937 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:41.340Z,1528058261.340 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:41.733Z,1528058261.733 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:42.140Z,1528058262.140 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:42.546Z,1528058262.546 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:42.951Z,1528058262.951 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:43.356Z,1528058263.356 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:43.749Z,1528058263.749 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:44.137Z,1528058264.137 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:44.541Z,1528058264.541 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:44.945Z,1528058264.945 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:45.336Z,1528058265.336 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:45.765Z,1528058265.765 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:46.159Z,1528058266.159 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:46.550Z,1528058266.550 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:46.946Z,1528058266.946 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:47.350Z,1528058267.350 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:47.740Z,1528058267.740 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:48.139Z,1528058268.139 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:48.545Z,1528058268.545 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:48.956Z,1528058268.956 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:49.356Z,1528058269.356 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:49.751Z,1528058269.751 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:50.139Z,1528058270.139 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:50.532Z,1528058270.532 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:50.939Z,1528058270.939 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:51.338Z,1528058271.338 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:51.747Z,1528058271.747 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:52.153Z,1528058272.153 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:52.548Z,1528058272.548 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:52.932Z,1528058272.932 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:53.338Z,1528058273.338 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:53.743Z,1528058273.743 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:54.142Z,1528058274.142 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:54.546Z,1528058274.546 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:54.661Z,1528058274.661 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:37:54.661Z,1528058274.661 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-06-03T20:37:54.661Z,1528058274.661 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:37:54.950Z,1528058274.950 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:55.023Z,1528058275.023 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:37:55.065Z,1528058275.065 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:37:55.184Z,1528058275.184 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:37:55.346Z,1528058275.346 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:55.746Z,1528058275.746 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:56.135Z,1528058276.135 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:56.535Z,1528058276.535 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:56.592Z,1528058276.592 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:37:56.592Z,1528058276.592 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-03T20:37:56.797Z,1528058276.797 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:37:56.798Z,1528058276.798 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:37:56.798Z,1528058276.798 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:37:56.798Z,1528058276.798 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:37:56.937Z,1528058276.937 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:57.335Z,1528058277.335 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:57.750Z,1528058277.750 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:58.150Z,1528058278.150 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:58.546Z,1528058278.546 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:58.939Z,1528058278.939 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:59.335Z,1528058279.335 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:37:59.739Z,1528058279.739 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:00.140Z,1528058280.140 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:00.535Z,1528058280.535 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:00.947Z,1528058280.947 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:00.990Z,1528058280.990 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:38:01.099Z,1528058281.099 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:38:01.100Z,1528058281.100 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:38:01.101Z,1528058281.101 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:38:01.102Z,1528058281.102 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:38:01.366Z,1528058281.366 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:01.749Z,1528058281.749 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:02.132Z,1528058282.132 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:02.532Z,1528058282.532 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:02.937Z,1528058282.937 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:03.340Z,1528058283.340 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:03.759Z,1528058283.759 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:04.162Z,1528058284.162 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:04.550Z,1528058284.550 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:04.946Z,1528058284.946 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:05.349Z,1528058285.349 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:38:05.736Z,1528058285.736 [DAT](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:10.705Z,1528058350.705 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:39:10.705Z,1528058350.705 [BPC1] Communications Fault, FailCount= 9 2018-06-03T20:39:10.706Z,1528058350.706 [BPC1](ERROR): Communications Fault 2018-06-03T20:39:10.732Z,1528058350.732 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:39:11.934Z,1528058351.934 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:39:11.934Z,1528058351.934 [BPC1] No Fault, FailCount= 9 2018-06-03T20:39:17.926Z,1528058357.926 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:18.722Z,1528058358.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:19.518Z,1528058359.518 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:20.314Z,1528058360.314 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:21.110Z,1528058361.110 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:21.908Z,1528058361.908 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:22.702Z,1528058362.702 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:23.498Z,1528058363.498 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:24.294Z,1528058364.294 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:25.090Z,1528058365.090 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:25.886Z,1528058365.886 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:26.682Z,1528058366.682 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:27.478Z,1528058367.478 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:28.274Z,1528058368.274 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:29.070Z,1528058369.070 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:29.868Z,1528058369.868 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:30.662Z,1528058370.662 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:31.458Z,1528058371.458 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:32.254Z,1528058372.254 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:33.050Z,1528058373.050 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:33.846Z,1528058373.846 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:34.642Z,1528058374.642 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:35.438Z,1528058375.438 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:36.234Z,1528058376.234 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:37.030Z,1528058377.030 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:37.826Z,1528058377.826 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:38.621Z,1528058378.621 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:39.418Z,1528058379.418 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:40.214Z,1528058380.214 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:41.010Z,1528058381.010 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:41.806Z,1528058381.806 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:42.604Z,1528058382.604 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:43.398Z,1528058383.398 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:44.194Z,1528058384.194 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:44.990Z,1528058384.990 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:45.786Z,1528058385.786 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:46.584Z,1528058386.584 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:47.378Z,1528058387.378 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:48.173Z,1528058388.173 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:48.970Z,1528058388.970 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:49.766Z,1528058389.766 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:50.562Z,1528058390.562 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:51.358Z,1528058391.358 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:52.154Z,1528058392.154 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:52.949Z,1528058392.949 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:53.746Z,1528058393.746 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:54.542Z,1528058394.542 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:55.338Z,1528058395.338 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:56.134Z,1528058396.134 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:56.932Z,1528058396.932 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:39:57.321Z,1528058397.321 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:39:57.321Z,1528058397.321 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-06-03T20:39:57.321Z,1528058397.321 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:39:57.633Z,1528058397.633 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:39:57.634Z,1528058397.634 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-06-03T20:39:57.725Z,1528058397.725 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:39:57.841Z,1528058397.841 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:39:58.649Z,1528058398.649 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2018-06-03T20:39:58.649Z,1528058398.649 [Rowe_600LCM] Hardware Fault, FailCount= 5 2018-06-03T20:39:58.649Z,1528058398.649 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T20:40:19.624Z,1528058419.624 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T20:35:19.6Z 2018-06-03T20:40:19.624Z,1528058419.624 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T20:40:19.624Z,1528058419.624 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T20:40:24.491Z,1528058424.491 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0022.lzma 2018-06-03T20:40:25.279Z,1528058425.279 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0022.lzma.bak 2018-06-03T20:40:25.280Z,1528058425.280 [DataOverHttps](INFO): SBD MOMSN=8310044 2018-06-03T20:40:34.688Z,1528058434.688 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20180603T192854/Express0023.lzma 2018-06-03T20:40:35.479Z,1528058435.479 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0023.lzma.bak 2018-06-03T20:40:35.480Z,1528058435.480 [DataOverHttps](INFO): SBD MOMSN=8310046 2018-06-03T20:40:36.721Z,1528058436.721 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T20:40:36.721Z,1528058436.721 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T20:40:36.721Z,1528058436.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T20:44:24.084Z,1528058664.084 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:44:24.084Z,1528058664.084 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20 2018-06-03T20:44:24.447Z,1528058664.447 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:44:24.448Z,1528058664.448 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:44:24.448Z,1528058664.448 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:45:36.880Z,1528058736.880 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T20:45:36.880Z,1528058736.880 [Default:CheckIn:C.Wait] Stopped 2018-06-03T20:45:36.880Z,1528058736.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T20:45:36.880Z,1528058736.880 [Default:CheckIn:D] Running Loop=1 2018-06-03T20:45:37.227Z,1528058737.227 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T20:45:37.227Z,1528058737.227 [NAL9602] Data Fault, FailCount= 2 2018-06-03T20:45:37.227Z,1528058737.227 [NAL9602](ERROR): Data Fault 2018-06-03T20:45:37.285Z,1528058737.285 [Default:CheckIn:D] Stopped 2018-06-03T20:45:37.285Z,1528058737.285 [Default:CheckIn:E] Running Loop=1 2018-06-03T20:45:37.302Z,1528058737.302 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T20:45:37.631Z,1528058737.631 [NAL9602](INFO): Powering down 2018-06-03T20:45:37.684Z,1528058737.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.178263 min 2018-06-03T20:45:37.685Z,1528058737.685 [Default:CheckIn:E] Stopped 2018-06-03T20:45:37.685Z,1528058737.685 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T20:45:37.685Z,1528058737.685 [Default:CheckIn] Stopped 2018-06-03T20:45:37.685Z,1528058737.685 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T20:45:37.685Z,1528058737.685 [Default:CheckIn](INFO): Running loop #8 2018-06-03T20:45:37.686Z,1528058737.686 [Default:CheckIn] Running Loop=8 2018-06-03T20:45:37.686Z,1528058737.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T20:45:37.686Z,1528058737.686 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T20:45:40.942Z,1528058740.942 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T20:45:40.942Z,1528058740.942 [NAL9602] No Fault, FailCount= 2 2018-06-03T20:45:58.839Z,1528058758.839 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:45:58.839Z,1528058758.839 [BPC1] Communications Fault, FailCount= 10 2018-06-03T20:45:58.839Z,1528058758.839 [BPC1](ERROR): Communications Fault 2018-06-03T20:45:58.886Z,1528058758.886 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:45:58.887Z,1528058758.887 [CBIT](CRITICAL): Communications Fault in component: BPC1 2018-06-03T20:45:59.264Z,1528058759.264 [CBIT](INFO): Critical error at 20180603T204558 2018-06-03T20:46:08.011Z,1528058768.011 [NAL9602](INFO): Powering up NAL9602 2018-06-03T20:46:19.207Z,1528058779.207 [NAL9602](INFO): NAL9602 initialized 2018-06-03T20:47:24.840Z,1528058844.840 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:47:24.840Z,1528058844.840 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-06-03T20:47:24.840Z,1528058844.840 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:47:24.854Z,1528058844.854 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:47:25.271Z,1528058845.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:47:25.271Z,1528058845.271 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-06-03T20:47:25.638Z,1528058845.638 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:47:25.638Z,1528058845.638 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:47:25.639Z,1528058845.639 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:49:57.685Z,1528058997.685 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:49:57.685Z,1528058997.685 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-03T20:49:58.005Z,1528058998.005 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:49:58.005Z,1528058998.005 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:49:58.006Z,1528058998.006 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:49:58.006Z,1528058998.006 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:50:02.243Z,1528059002.243 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:50:02.316Z,1528059002.316 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:50:02.318Z,1528059002.318 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:50:02.319Z,1528059002.319 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:50:02.320Z,1528059002.320 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:50:25.675Z,1528059025.675 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:50:25.675Z,1528059025.675 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-06-03T20:50:25.675Z,1528059025.675 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:50:25.738Z,1528059025.738 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:50:26.129Z,1528059026.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:50:26.129Z,1528059026.129 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-06-03T20:50:26.488Z,1528059026.488 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:50:26.488Z,1528059026.488 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:50:26.489Z,1528059026.489 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:50:37.694Z,1528059037.694 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T20:45:37.7Z 2018-06-03T20:50:37.694Z,1528059037.694 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T20:50:37.694Z,1528059037.694 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T20:50:37.746Z,1528059037.746 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:50:38.542Z,1528059038.542 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:50:42.756Z,1528059042.756 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20180603T192854/Courier0025.lzma 2018-06-03T20:50:43.542Z,1528059043.542 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0025.lzma.bak 2018-06-03T20:50:43.542Z,1528059043.542 [DataOverHttps](INFO): SBD MOMSN=8310058 2018-06-03T20:50:53.910Z,1528059053.910 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20180603T192854/Express0026.lzma 2018-06-03T20:50:54.710Z,1528059054.710 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0026.lzma.bak 2018-06-03T20:50:54.710Z,1528059054.710 [DataOverHttps](INFO): SBD MOMSN=8310061 2018-06-03T20:50:56.095Z,1528059056.095 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T20:50:56.095Z,1528059056.095 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T20:50:56.095Z,1528059056.095 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T20:50:59.807Z,1528059059.807 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:50:59.807Z,1528059059.807 [BPC1] No Fault, FailCount= 10 2018-06-03T20:51:39.851Z,1528059099.851 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:51:40.647Z,1528059100.647 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-03T20:51:58.605Z,1528059118.605 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:51:58.605Z,1528059118.605 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-03T20:51:58.605Z,1528059118.605 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:51:59.009Z,1528059119.009 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:51:59.214Z,1528059119.214 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:52:00.294Z,1528059120.294 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:52:00.433Z,1528059120.433 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:52:00.433Z,1528059120.433 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-03T20:52:00.829Z,1528059120.829 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:52:00.830Z,1528059120.830 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:52:00.830Z,1528059120.830 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:52:00.830Z,1528059120.830 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:52:04.952Z,1528059124.952 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:52:05.023Z,1528059125.023 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:52:05.024Z,1528059125.024 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:52:05.033Z,1528059125.033 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:52:05.034Z,1528059125.034 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:53:26.863Z,1528059206.863 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:53:26.863Z,1528059206.863 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-06-03T20:53:26.863Z,1528059206.863 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:53:26.912Z,1528059206.912 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:53:27.289Z,1528059207.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:53:27.289Z,1528059207.289 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-06-03T20:53:27.656Z,1528059207.656 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:53:27.661Z,1528059207.661 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:53:27.661Z,1528059207.661 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:54:01.253Z,1528059241.253 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:54:01.253Z,1528059241.253 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-03T20:54:01.253Z,1528059241.253 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:54:01.385Z,1528059241.385 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:54:01.657Z,1528059241.657 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:54:01.915Z,1528059241.915 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:54:03.324Z,1528059243.324 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:54:03.325Z,1528059243.325 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-03T20:54:03.529Z,1528059243.529 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:54:03.530Z,1528059243.530 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:54:03.530Z,1528059243.530 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:54:03.531Z,1528059243.531 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:54:07.655Z,1528059247.655 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:54:07.808Z,1528059247.808 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:54:07.809Z,1528059247.809 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:54:07.810Z,1528059247.810 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:54:07.811Z,1528059247.811 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:55:56.615Z,1528059356.615 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-03T20:55:56.616Z,1528059356.616 [Default:CheckIn:C.Wait] Stopped 2018-06-03T20:55:56.616Z,1528059356.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T20:55:56.616Z,1528059356.616 [Default:CheckIn:D] Running Loop=1 2018-06-03T20:55:57.019Z,1528059357.019 [Default:CheckIn:D] Stopped 2018-06-03T20:55:57.019Z,1528059357.019 [Default:CheckIn:E] Running Loop=1 2018-06-03T20:56:00.587Z,1528059360.587 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.507186 min 2018-06-03T20:56:00.588Z,1528059360.588 [Default:CheckIn:E] Stopped 2018-06-03T20:56:00.588Z,1528059360.588 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-03T20:56:00.588Z,1528059360.588 [Default:CheckIn] Stopped 2018-06-03T20:56:00.588Z,1528059360.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T20:56:00.588Z,1528059360.588 [Default:CheckIn](INFO): Running loop #9 2018-06-03T20:56:00.588Z,1528059360.588 [Default:CheckIn] Running Loop=9 2018-06-03T20:56:00.589Z,1528059360.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-03T20:56:00.589Z,1528059360.589 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-03T20:56:04.193Z,1528059364.193 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-03T20:56:04.193Z,1528059364.193 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-03T20:56:04.193Z,1528059364.193 [Rowe_600LCM](ERROR): Communications Fault 2018-06-03T20:56:04.371Z,1528059364.371 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-03T20:56:04.597Z,1528059364.597 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:56:04.875Z,1528059364.875 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:56:06.325Z,1528059366.325 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:56:06.325Z,1528059366.325 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-03T20:56:06.489Z,1528059366.489 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:56:06.490Z,1528059366.490 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:56:06.490Z,1528059366.490 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:56:06.490Z,1528059366.490 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:56:10.622Z,1528059370.622 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-03T20:56:10.795Z,1528059370.795 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-06-03T20:56:10.796Z,1528059370.796 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-03T20:56:10.797Z,1528059370.797 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-03T20:56:10.798Z,1528059370.798 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-03T20:56:21.051Z,1528059381.051 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-03T20:56:21.051Z,1528059381.051 [NAL9602] Data Fault, FailCount= 3 2018-06-03T20:56:21.051Z,1528059381.051 [NAL9602](ERROR): Data Fault 2018-06-03T20:56:21.143Z,1528059381.143 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-03T20:56:21.455Z,1528059381.455 [NAL9602](INFO): Powering down 2018-06-03T20:56:22.369Z,1528059382.369 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-03T20:56:22.369Z,1528059382.369 [NAL9602] No Fault, FailCount= 3 2018-06-03T20:56:27.899Z,1528059387.899 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:56:27.899Z,1528059387.899 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-06-03T20:56:27.900Z,1528059387.900 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:56:27.948Z,1528059387.948 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:56:28.338Z,1528059388.338 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:56:28.338Z,1528059388.338 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-06-03T20:56:28.718Z,1528059388.718 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:56:28.719Z,1528059388.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:56:28.720Z,1528059388.720 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T20:56:51.867Z,1528059411.867 [NAL9602](INFO): Powering up NAL9602 2018-06-03T20:57:00.620Z,1528059420.620 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-03T20:57:00.620Z,1528059420.620 [BPC1] Communications Fault, FailCount= 1 2018-06-03T20:57:00.620Z,1528059420.620 [BPC1](ERROR): Communications Fault 2018-06-03T20:57:00.697Z,1528059420.697 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-03T20:57:01.709Z,1528059421.709 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-03T20:57:01.709Z,1528059421.709 [BPC1] No Fault, FailCount= 1 2018-06-03T20:57:02.725Z,1528059422.725 [NAL9602](INFO): NAL9602 initialized 2018-06-03T20:57:33.506Z,1528059453.506 [CommandLine](IMPORTANT): got command failComponent 2018-06-03T20:57:33.506Z,1528059453.506 [CommandLine](IMPORTANT): Failed components: 2018-06-03T20:57:33.506Z,1528059453.506 [CommandLine](IMPORTANT): No failed Components. 2018-06-03T20:57:39.203Z,1528059459.203 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM 2018-06-03T20:57:39.203Z,1528059459.203 [Rowe_600LCM] Hardware Fault, FailCount= 4 2018-06-03T20:57:39.204Z,1528059459.204 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T20:57:39.204Z,1528059459.204 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault 2018-06-03T20:57:39.232Z,1528059459.232 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2018-06-03T20:57:40.049Z,1528059460.049 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:57:40.194Z,1528059460.194 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:57:40.257Z,1528059460.257 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM 2018-06-03T20:57:40.258Z,1528059460.258 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault 2018-06-03T20:57:41.445Z,1528059461.445 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM 2018-06-03T20:57:41.446Z,1528059461.446 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault 2018-06-03T20:57:41.635Z,1528059461.635 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-03T20:57:41.635Z,1528059461.635 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-03T20:57:41.809Z,1528059461.809 [Rowe_600LCM](INFO): Initializing 2018-06-03T20:57:41.810Z,1528059461.810 [Rowe_600LCM](INFO): Checking LCM 2018-06-03T20:57:41.810Z,1528059461.810 [Rowe_600LCM](INFO): LCM OK 2018-06-03T20:57:41.810Z,1528059461.810 [Rowe_600LCM](INFO): Powering up 2018-06-03T20:57:42.295Z,1528059462.295 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM 2018-06-03T20:57:42.296Z,1528059462.296 [Rowe_600LCM] Hardware Fault, FailCount= 5 2018-06-03T20:57:42.296Z,1528059462.296 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-03T20:57:42.296Z,1528059462.296 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault 2018-06-03T20:57:42.464Z,1528059462.464 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2018-06-03T20:57:42.473Z,1528059462.473 [CBIT](FAULT): Hardware Fault in component: Rowe_600LCM 2018-06-03T20:57:43.021Z,1528059463.021 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T20:57:43.159Z,1528059463.159 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM 2018-06-03T20:57:43.160Z,1528059463.160 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault 2018-06-03T20:57:43.373Z,1528059463.373 [Rowe_600LCM](INFO): Powering down 2018-06-03T20:57:43.928Z,1528059463.928 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM 2018-06-03T20:57:43.928Z,1528059463.928 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault 2018-06-03T20:57:44.877Z,1528059464.877 [CommandLine](IMPORTANT): got command failComponent 2018-06-03T20:57:44.877Z,1528059464.877 [CommandLine](IMPORTANT): Failed components: 2018-06-03T20:57:44.877Z,1528059464.877 [CommandLine](IMPORTANT): Rowe_600LCM: Hardware Fault 2018-06-03T20:59:28.871Z,1528059568.871 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T20:59:28.871Z,1528059568.871 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-06-03T20:59:28.871Z,1528059568.871 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T20:59:28.909Z,1528059568.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T20:59:29.276Z,1528059569.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T20:59:29.276Z,1528059569.276 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-06-03T20:59:29.674Z,1528059569.674 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T20:59:29.675Z,1528059569.675 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T20:59:29.675Z,1528059569.675 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T21:01:00.911Z,1528059660.911 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-03T20:56:00.6Z 2018-06-03T21:01:00.911Z,1528059660.911 [Default:CheckIn:Read_GPS] Stopped 2018-06-03T21:01:00.911Z,1528059660.911 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-03T21:01:05.448Z,1528059665.448 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T192854/Courier0028.lzma 2018-06-03T21:01:06.248Z,1528059666.248 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Courier0028.lzma.bak 2018-06-03T21:01:06.248Z,1528059666.248 [DataOverHttps](INFO): SBD MOMSN=8310065 2018-06-03T21:01:15.713Z,1528059675.713 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20180603T192854/Express0029.lzma 2018-06-03T21:01:17.461Z,1528059677.461 [DataOverHttps](INFO): Moved sent file to Logs/20180603T192854/Express0029.lzma.bak 2018-06-03T21:01:17.461Z,1528059677.461 [DataOverHttps](INFO): SBD MOMSN=8310067 2018-06-03T21:01:18.479Z,1528059678.479 [Default:CheckIn:Read_Iridium] Stopped 2018-06-03T21:01:18.479Z,1528059678.479 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-03T21:01:18.479Z,1528059678.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-03T21:02:29.893Z,1528059749.893 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-03T21:02:29.893Z,1528059749.893 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-06-03T21:02:29.893Z,1528059749.893 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-03T21:02:29.906Z,1528059749.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-03T21:02:30.323Z,1528059750.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-03T21:02:30.323Z,1528059750.323 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-06-03T21:02:30.694Z,1528059750.694 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-03T21:02:30.694Z,1528059750.694 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-03T21:02:30.695Z,1528059750.695 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-03T21:02:47.835Z,1528059767.835 [CommandLine](IMPORTANT): got command restart application 2018-06-03T21:02:48.856Z,1528059768.856 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:48.857Z,1528059768.857 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-06-03T21:02:48.857Z,1528059768.857 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:48.858Z,1528059768.858 [CommandLine](INFO): Join timeout helper Thread ID is 1420 2018-06-03T21:02:48.858Z,1528059768.858 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:48.859Z,1528059768.859 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-06-03T21:02:48.859Z,1528059768.859 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:48.859Z,1528059768.859 [NavChartDb](INFO): Join timeout helper Thread ID is 1421 2018-06-03T21:02:48.957Z,1528059768.957 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:48.957Z,1528059768.957 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:48.977Z,1528059768.977 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-06-03T21:02:48.977Z,1528059768.977 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:48.977Z,1528059768.977 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1422 2018-06-03T21:02:49.197Z,1528059769.197 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:49.197Z,1528059769.197 [WetLabsBB2FL](INFO): Powering down 2018-06-03T21:02:49.198Z,1528059769.198 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.201Z,1528059769.201 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2018-06-03T21:02:49.201Z,1528059769.201 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.201Z,1528059769.201 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1423 2018-06-03T21:02:49.317Z,1528059769.317 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:49.399Z,1528059769.399 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-06-03T21:02:49.400Z,1528059769.400 [CTD_Seabird](INFO): Powering down 2018-06-03T21:02:49.402Z,1528059769.402 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.413Z,1528059769.413 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2018-06-03T21:02:49.413Z,1528059769.413 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.413Z,1528059769.413 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1425 2018-06-03T21:02:49.453Z,1528059769.453 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:49.454Z,1528059769.454 [CTD_NeilBrown](INFO): Powering down 2018-06-03T21:02:49.454Z,1528059769.454 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.475Z,1528059769.475 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2018-06-03T21:02:49.475Z,1528059769.475 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.476Z,1528059769.476 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1426 2018-06-03T21:02:49.737Z,1528059769.737 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:49.737Z,1528059769.737 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-03T21:02:49.825Z,1528059769.825 [Rowe_600LCM](INFO): Powering down 2018-06-03T21:02:49.826Z,1528059769.826 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.834Z,1528059769.834 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-06-03T21:02:49.834Z,1528059769.834 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:49.834Z,1528059769.834 [Radio_Surface](INFO): Join timeout helper Thread ID is 1428 2018-06-03T21:02:50.209Z,1528059770.209 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:50.209Z,1528059770.209 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.218Z,1528059770.218 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-06-03T21:02:50.218Z,1528059770.218 [logger ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.218Z,1528059770.218 [logger](INFO): Join timeout helper Thread ID is 1429 2018-06-03T21:02:50.225Z,1528059770.225 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:50.225Z,1528059770.225 [logger ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.238Z,1528059770.238 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-06-03T21:02:50.238Z,1528059770.238 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.238Z,1528059770.238 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-06-03T21:02:50.238Z,1528059770.238 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.238Z,1528059770.238 [controlThread](INFO): Join timeout helper Thread ID is 1430 2018-06-03T21:02:50.239Z,1528059770.239 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-03T21:02:50.239Z,1528059770.239 [controlThread](DEBUG): Uninitializing ControlThread 2018-06-03T21:02:50.240Z,1528059770.240 [NAL9602](INFO): Powering down 2018-06-03T21:02:50.241Z,1528059770.241 [DAT](INFO): Powering down 2018-06-03T21:02:50.361Z,1528059770.361 [PNI_TCM](INFO): Powering down 2018-06-03T21:02:50.450Z,1528059770.450 [Aanderaa_O2](INFO): Powering down 2018-06-03T21:02:50.451Z,1528059770.451 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-06-03T21:02:50.452Z,1528059770.452 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-06-03T21:02:50.453Z,1528059770.453 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-06-03T21:02:50.454Z,1528059770.454 [MissionManager](INFO): Uninitializing Mission Default 2018-06-03T21:02:50.454Z,1528059770.454 [Default] Stopped 2018-06-03T21:02:50.454Z,1528059770.454 [Default](DEBUG): Aggregate::uninitialize Default 2018-06-03T21:02:50.454Z,1528059770.454 [Default:B.GoToSurface] Stopped 2018-06-03T21:02:50.454Z,1528059770.454 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-06-03T21:02:50.454Z,1528059770.454 [Default:CheckIn] Stopped 2018-06-03T21:02:50.454Z,1528059770.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-03T21:02:50.455Z,1528059770.455 [Default:CheckIn:C.Wait] Stopped 2018-06-03T21:02:50.455Z,1528059770.455 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-03T21:02:50.459Z,1528059770.459 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-06-03T21:02:50.459Z,1528059770.459 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-06-03T21:02:50.460Z,1528059770.460 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-06-03T21:02:50.460Z,1528059770.460 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-06-03T21:02:50.460Z,1528059770.460 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-06-03T21:02:50.460Z,1528059770.460 [BuoyancyServo](INFO): Powering down 2018-06-03T21:02:50.473Z,1528059770.473 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-06-03T21:02:50.473Z,1528059770.473 [ElevatorServo](INFO): Powering down 2018-06-03T21:02:50.474Z,1528059770.474 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-06-03T21:02:50.474Z,1528059770.474 [MassServo](INFO): Powering down 2018-06-03T21:02:50.475Z,1528059770.475 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-06-03T21:02:50.475Z,1528059770.475 [RudderServo](INFO): Powering down 2018-06-03T21:02:50.476Z,1528059770.476 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-06-03T21:02:50.477Z,1528059770.477 [ThrusterServo](INFO): Powering down 2018-06-03T21:02:50.478Z,1528059770.478 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-06-03T21:02:50.478Z,1528059770.478 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-06-03T21:02:50.478Z,1528059770.478 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-06-03T21:02:50.479Z,1528059770.479 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.588Z,1528059770.588 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.598Z,1528059770.598 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.635Z,1528059770.635 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.639Z,1528059770.639 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.641Z,1528059770.641 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.668Z,1528059770.668 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-06-03T21:02:50.720Z,1528059770.720 [logger ThreadHandler](INFO): Thread cancelled.