2019-03-06T23:30:48.288Z,1551915048.288 [Supervisor](DEBUG): Initializing supervisor. 2019-03-06T23:30:48.291Z,1551915048.291 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-06T23:30:48.292Z,1551915048.292 [SyncHandler](INFO): Protected caller Thread ID is 6743 2019-03-06T23:30:48.292Z,1551915048.292 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-06T23:30:48.293Z,1551915048.293 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-06T23:30:48.293Z,1551915048.293 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6744 2019-03-06T23:30:48.296Z,1551915048.296 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-06T23:30:48.307Z,1551915048.307 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-06T23:30:48.308Z,1551915048.308 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-06T23:30:48.309Z,1551915048.309 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6745 2019-03-06T23:30:48.310Z,1551915048.310 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-06T23:30:48.311Z,1551915048.311 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-06T23:30:48.311Z,1551915048.311 [logger ThreadHandler](INFO): Protected caller Thread ID is 6746 2019-03-06T23:30:48.313Z,1551915048.313 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-06T23:30:48.313Z,1551915048.313 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-06T23:30:48.315Z,1551915048.315 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-06T23:30:48.747Z,1551915048.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-06T23:30:48.748Z,1551915048.748 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-06T23:30:48.846Z,1551915048.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-06T23:30:48.847Z,1551915048.847 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-06T23:30:49.046Z,1551915049.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-06T23:30:49.047Z,1551915049.047 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-06T23:30:49.523Z,1551915049.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-06T23:30:49.524Z,1551915049.524 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-06T23:30:49.739Z,1551915049.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-06T23:30:49.739Z,1551915049.739 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-06T23:30:49.881Z,1551915049.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-06T23:30:49.882Z,1551915049.882 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-06T23:30:50.152Z,1551915050.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-06T23:30:50.152Z,1551915050.152 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-06T23:30:50.494Z,1551915050.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-06T23:30:50.495Z,1551915050.495 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-06T23:30:50.590Z,1551915050.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-06T23:30:50.591Z,1551915050.591 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-06T23:30:51.085Z,1551915051.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-06T23:30:51.086Z,1551915051.086 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-06T23:30:51.165Z,1551915051.165 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-06T23:30:51.269Z,1551915051.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-06T23:30:51.269Z,1551915051.269 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-06T23:30:51.666Z,1551915051.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-06T23:30:51.666Z,1551915051.666 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-06T23:30:51.987Z,1551915051.987 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-06T23:30:51.988Z,1551915051.988 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-06T23:30:52.139Z,1551915052.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-06T23:30:52.141Z,1551915052.141 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-03-06T23:30:52.142Z,1551915052.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-03-06T23:30:52.354Z,1551915052.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-03-06T23:30:52.458Z,1551915052.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-03-06T23:30:52.621Z,1551915052.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-03-06T23:30:52.716Z,1551915052.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-03-06T23:30:52.813Z,1551915052.813 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-03-06T23:30:52.906Z,1551915052.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-03-06T23:30:52.988Z,1551915052.988 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-03-06T23:30:53.072Z,1551915053.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-03-06T23:30:53.307Z,1551915053.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-06T23:30:53.307Z,1551915053.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-03-06T23:30:53.503Z,1551915053.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-03-06T23:30:53.601Z,1551915053.601 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-03-06T23:30:53.602Z,1551915053.602 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-06T23:30:53.605Z,1551915053.605 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-06T23:30:53.670Z,1551915053.670 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-06T23:30:53.782Z,1551915053.782 [VerticalControl] Loaded 2019-03-06T23:30:53.783Z,1551915053.783 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-06T23:30:53.783Z,1551915053.783 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-06T23:30:53.852Z,1551915053.852 [HorizontalControl] Loaded 2019-03-06T23:30:53.852Z,1551915053.852 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-06T23:30:53.853Z,1551915053.853 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-06T23:30:53.858Z,1551915053.858 [SpeedControl] Loaded 2019-03-06T23:30:53.859Z,1551915053.859 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-06T23:30:53.859Z,1551915053.859 [LoopControl](DEBUG): Construct LoopControl. 2019-03-06T23:30:53.860Z,1551915053.860 [LoopControl] Loaded 2019-03-06T23:30:53.860Z,1551915053.860 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-06T23:30:53.861Z,1551915053.861 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-06T23:30:53.861Z,1551915053.861 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-06T23:30:53.967Z,1551915053.967 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-06T23:30:53.968Z,1551915053.968 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-06T23:30:54.026Z,1551915054.026 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-06T23:30:54.026Z,1551915054.026 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-06T23:30:54.085Z,1551915054.085 [DeadReckonWithRespectToSeafloor] Loaded 2019-03-06T23:30:54.085Z,1551915054.085 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-03-06T23:30:54.105Z,1551915054.105 [NavChart] Loaded 2019-03-06T23:30:54.105Z,1551915054.105 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-06T23:30:54.109Z,1551915054.109 [UniversalFixResidualReporter] Loaded 2019-03-06T23:30:54.110Z,1551915054.110 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-06T23:30:54.110Z,1551915054.110 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-06T23:30:54.111Z,1551915054.111 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-06T23:30:54.243Z,1551915054.243 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-06T23:30:54.254Z,1551915054.254 [SBIT] Loaded 2019-03-06T23:30:54.255Z,1551915054.255 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-06T23:30:54.255Z,1551915054.255 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-06T23:30:54.282Z,1551915054.282 [IBIT] Loaded 2019-03-06T23:30:54.282Z,1551915054.282 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-06T23:30:54.285Z,1551915054.285 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-06T23:30:54.672Z,1551915054.672 [CBIT] Loaded 2019-03-06T23:30:54.672Z,1551915054.672 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-06T23:30:54.673Z,1551915054.673 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-06T23:30:54.673Z,1551915054.673 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-06T23:30:54.700Z,1551915054.700 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-06T23:30:54.701Z,1551915054.701 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-06T23:30:54.795Z,1551915054.795 [BuoyancyServo] Loaded 2019-03-06T23:30:54.795Z,1551915054.795 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-03-06T23:30:54.806Z,1551915054.806 [ElevatorServo] Loaded 2019-03-06T23:30:54.807Z,1551915054.807 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-06T23:30:54.818Z,1551915054.818 [MassServo] Loaded 2019-03-06T23:30:54.818Z,1551915054.818 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-06T23:30:54.829Z,1551915054.829 [RudderServo] Loaded 2019-03-06T23:30:54.829Z,1551915054.829 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-06T23:30:54.840Z,1551915054.840 [ThrusterServo] Loaded 2019-03-06T23:30:54.840Z,1551915054.840 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-03-06T23:30:54.841Z,1551915054.841 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-06T23:30:54.841Z,1551915054.841 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-06T23:30:54.854Z,1551915054.854 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-06T23:30:54.855Z,1551915054.855 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-06T23:30:55.135Z,1551915055.135 [AcousticModem_Benthos_ATM900] Loaded 2019-03-06T23:30:55.136Z,1551915055.136 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-03-06T23:30:55.206Z,1551915055.206 [DataOverHttps] Loaded 2019-03-06T23:30:55.207Z,1551915055.207 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-03-06T23:30:55.208Z,1551915055.208 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408314E0 2019-03-06T23:30:55.208Z,1551915055.208 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6826 2019-03-06T23:30:55.222Z,1551915055.222 [Depth_Keller] Loaded 2019-03-06T23:30:55.222Z,1551915055.222 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-06T23:30:55.227Z,1551915055.227 [DropWeight] Loaded 2019-03-06T23:30:55.227Z,1551915055.227 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-03-06T23:30:55.316Z,1551915055.316 [NAL9602] Loaded 2019-03-06T23:30:55.317Z,1551915055.317 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-06T23:30:55.322Z,1551915055.322 [Onboard] Loaded 2019-03-06T23:30:55.322Z,1551915055.322 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-06T23:30:55.325Z,1551915055.325 [Radio_Surface] Loaded 2019-03-06T23:30:55.326Z,1551915055.326 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-06T23:30:55.327Z,1551915055.327 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408614E0 2019-03-06T23:30:55.327Z,1551915055.327 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6827 2019-03-06T23:30:55.374Z,1551915055.374 [PNI_TCM] Loaded 2019-03-06T23:30:55.374Z,1551915055.374 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-03-06T23:30:55.433Z,1551915055.433 [Rowe_600LCM] Loaded 2019-03-06T23:30:55.433Z,1551915055.433 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-03-06T23:30:55.434Z,1551915055.434 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408914E0 2019-03-06T23:30:55.435Z,1551915055.435 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6828 2019-03-06T23:30:56.994Z,1551915056.994 [BPC1] Loaded 2019-03-06T23:30:56.994Z,1551915056.994 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-03-06T23:30:56.995Z,1551915056.995 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-06T23:30:56.995Z,1551915056.995 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-06T23:30:57.225Z,1551915057.225 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-06T23:30:57.226Z,1551915057.226 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-06T23:30:57.321Z,1551915057.321 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-06T23:30:57.322Z,1551915057.322 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-06T23:30:57.385Z,1551915057.385 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-06T23:30:57.386Z,1551915057.386 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-06T23:30:57.425Z,1551915057.425 [DepthRateCalculator] Loaded 2019-03-06T23:30:57.426Z,1551915057.426 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-06T23:30:57.431Z,1551915057.431 [PitchRateCalculator] Loaded 2019-03-06T23:30:57.431Z,1551915057.431 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-06T23:30:57.443Z,1551915057.443 [SpeedCalculator] Loaded 2019-03-06T23:30:57.443Z,1551915057.443 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-06T23:30:57.464Z,1551915057.464 [TempGradientCalculator] Loaded 2019-03-06T23:30:57.464Z,1551915057.464 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-06T23:30:57.469Z,1551915057.469 [YawRateCalculator] Loaded 2019-03-06T23:30:57.470Z,1551915057.470 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-06T23:30:57.499Z,1551915057.499 [ElevatorOffsetCalculator] Loaded 2019-03-06T23:30:57.499Z,1551915057.499 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-06T23:30:57.500Z,1551915057.500 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-06T23:30:57.501Z,1551915057.501 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-06T23:30:57.672Z,1551915057.672 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-03-06T23:30:57.678Z,1551915057.678 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-03-06T23:30:57.680Z,1551915057.680 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-03-06T23:30:57.685Z,1551915057.685 [CTD_Seabird](INFO): created writer for : depth 2019-03-06T23:30:57.685Z,1551915057.685 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-03-06T23:30:57.690Z,1551915057.690 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-03-06T23:30:57.691Z,1551915057.691 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-03-06T23:30:57.696Z,1551915057.696 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-03-06T23:30:57.697Z,1551915057.697 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-03-06T23:30:57.702Z,1551915057.702 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-03-06T23:30:57.703Z,1551915057.703 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-03-06T23:30:57.708Z,1551915057.708 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-03-06T23:30:57.709Z,1551915057.709 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-03-06T23:30:57.714Z,1551915057.714 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-03-06T23:30:57.741Z,1551915057.741 [CTD_Seabird] Loaded 2019-03-06T23:30:57.741Z,1551915057.741 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-03-06T23:30:57.742Z,1551915057.742 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A804E0 2019-03-06T23:30:57.743Z,1551915057.743 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6829 2019-03-06T23:30:57.769Z,1551915057.769 [ESPComponent] Loaded 2019-03-06T23:30:57.769Z,1551915057.769 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-03-06T23:30:57.783Z,1551915057.783 [PAR_Licor] Loaded 2019-03-06T23:30:57.783Z,1551915057.783 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-03-06T23:30:57.831Z,1551915057.831 [WetLabsBB2FL] Loaded 2019-03-06T23:30:57.831Z,1551915057.831 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-03-06T23:30:57.832Z,1551915057.832 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0 2019-03-06T23:30:57.833Z,1551915057.833 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6830 2019-03-06T23:30:57.842Z,1551915057.842 [WetLabsUBAT](INFO): in readConfig 2019-03-06T23:30:57.843Z,1551915057.843 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.flowrateCalibCoeff 2019-03-06T23:30:57.845Z,1551915057.845 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.minFlowrate 2019-03-06T23:30:57.847Z,1551915057.847 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-03-06T23:30:57.847Z,1551915057.847 [WetLabsUBAT](CRITICAL): Error loading configuration parameters during WetLabsUBAT::start() 2019-03-06T23:30:57.847Z,1551915057.847 [WetLabsUBAT] Data Fault, FailCount= 1 2019-03-06T23:30:57.847Z,1551915057.847 [WetLabsUBAT](ERROR): Data Fault 2019-03-06T23:30:57.848Z,1551915057.848 [WetLabsUBAT] Loaded 2019-03-06T23:30:57.848Z,1551915057.848 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2019-03-06T23:30:57.849Z,1551915057.849 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AE04E0 2019-03-06T23:30:57.849Z,1551915057.849 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 6831 2019-03-06T23:30:57.850Z,1551915057.850 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-06T23:30:57.853Z,1551915057.853 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-06T23:30:57.854Z,1551915057.854 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-06T23:30:57.861Z,1551915057.861 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-06T23:30:57.862Z,1551915057.862 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0 2019-03-06T23:30:57.862Z,1551915057.862 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6832 2019-03-06T23:30:57.867Z,1551915057.867 [Supervisor](INFO): Main Thread ID is 6742 2019-03-06T23:30:57.867Z,1551915057.867 [Supervisor](DEBUG): Running supervisor. 2019-03-06T23:30:57.868Z,1551915057.868 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6833 2019-03-06T23:30:57.871Z,1551915057.871 [controlThread ThreadHandler](INFO): Handler Thread ID is 6834 2019-03-06T23:30:57.871Z,1551915057.871 [controlThread](DEBUG): Initializing ControlThread 2019-03-06T23:30:57.872Z,1551915057.872 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-06T23:30:57.874Z,1551915057.874 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-06T23:30:57.875Z,1551915057.875 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-06T23:30:57.875Z,1551915057.875 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-06T23:30:57.876Z,1551915057.876 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-06T23:30:57.877Z,1551915057.877 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-06T23:30:57.877Z,1551915057.877 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-06T23:30:57.877Z,1551915057.877 [SBIT](INFO): Initialize SBIT Component. 2019-03-06T23:30:57.878Z,1551915057.878 [SBIT](IMPORTANT): git: 2019-03-04-42-g1724f42 2019-03-06T23:30:57.878Z,1551915057.878 [SBIT](INFO): git hash: 1724f426ea8fd108e140ed6521df130d10542842 2019-03-06T23:30:57.878Z,1551915057.878 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-06T23:30:57.880Z,1551915057.880 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-03-06T23:30:57.881Z,1551915057.881 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-03-06T23:30:57.881Z,1551915057.881 [IBIT](INFO): Initialize IBIT Component. 2019-03-06T23:30:57.882Z,1551915057.882 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-06T23:30:57.883Z,1551915057.883 [logger ThreadHandler](INFO): Handler Thread ID is 6835 2019-03-06T23:30:57.893Z,1551915057.893 [CBIT](DEBUG): Initialized mux pins. 2019-03-06T23:30:57.893Z,1551915057.893 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-06T23:30:57.901Z,1551915057.901 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6836 2019-03-06T23:30:57.902Z,1551915057.902 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-06T23:30:57.917Z,1551915057.917 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2019-03-06T23:30:57.917Z,1551915057.917 [CBIT](DEBUG): Initializing heartbeat. 2019-03-06T23:30:57.917Z,1551915057.917 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6837 2019-03-06T23:30:57.937Z,1551915057.937 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6838 2019-03-06T23:30:57.938Z,1551915057.938 [Rowe_600LCM](INFO): Initializing 2019-03-06T23:30:57.947Z,1551915057.947 [Rowe_600LCM](INFO): Powering up 2019-03-06T23:30:57.954Z,1551915057.954 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6839 2019-03-06T23:30:57.954Z,1551915057.954 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-06T23:30:57.957Z,1551915057.957 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-06T23:30:57.958Z,1551915057.958 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6841 2019-03-06T23:30:57.960Z,1551915057.960 [WetLabsBB2FL](INFO): Powering down 2019-03-06T23:30:57.986Z,1551915057.986 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 6842 2019-03-06T23:30:57.989Z,1551915057.989 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-06T23:30:57.989Z,1551915057.989 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-06T23:30:58.002Z,1551915058.002 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6843 2019-03-06T23:30:58.005Z,1551915058.005 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-06T23:30:58.005Z,1551915058.005 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-06T23:30:58.005Z,1551915058.005 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-06T23:30:58.005Z,1551915058.005 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-06T23:30:58.005Z,1551915058.005 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-06T23:30:58.006Z,1551915058.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-06T23:30:58.006Z,1551915058.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-06T23:30:58.006Z,1551915058.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-06T23:30:58.006Z,1551915058.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-06T23:30:58.006Z,1551915058.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-06T23:30:58.006Z,1551915058.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-06T23:30:58.007Z,1551915058.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-06T23:30:58.007Z,1551915058.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-06T23:30:58.007Z,1551915058.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-06T23:30:58.007Z,1551915058.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-06T23:30:58.007Z,1551915058.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-06T23:30:58.032Z,1551915058.032 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-06T23:30:58.032Z,1551915058.032 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-06T23:30:58.032Z,1551915058.032 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-06T23:30:58.033Z,1551915058.033 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-06T23:30:58.033Z,1551915058.033 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-06T23:30:58.034Z,1551915058.034 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-06T23:30:58.042Z,1551915058.042 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-06T23:30:58.068Z,1551915058.068 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-06T23:30:58.107Z,1551915058.107 [MissionManager](DEBUG): 2019-03-06T23:30:58.108Z,1551915058.108 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-06T23:30:58.190Z,1551915058.190 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-06T23:30:58.191Z,1551915058.191 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-06T23:30:58.209Z,1551915058.209 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-06T23:30:58.240Z,1551915058.240 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-06T23:30:58.243Z,1551915058.243 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-06T23:30:58.277Z,1551915058.277 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-06T23:30:58.280Z,1551915058.280 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-03-06T23:30:58.293Z,1551915058.293 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-06T23:30:58.306Z,1551915058.306 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-03-06T23:30:58.306Z,1551915058.306 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-03-06T23:30:58.321Z,1551915058.321 [Radio_Surface](INFO): Powering up 2019-03-06T23:30:58.390Z,1551915058.390 [ESPComponent](INFO): powering down ESP 2019-03-06T23:30:58.732Z,1551915058.732 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-03-06T23:30:58.754Z,1551915058.754 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-03-06T23:30:58.755Z,1551915058.755 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-06T23:30:58.761Z,1551915058.761 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-06T23:30:58.762Z,1551915058.762 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-06T23:30:58.769Z,1551915058.769 [MassServo](DEBUG): Initializing MassServo. 2019-03-06T23:30:58.770Z,1551915058.770 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-06T23:30:58.777Z,1551915058.777 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-06T23:30:58.778Z,1551915058.778 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-03-06T23:30:58.785Z,1551915058.785 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-03-06T23:30:58.787Z,1551915058.787 [CommandLine](FAULT): Scheduling is paused 2019-03-06T23:30:58.788Z,1551915058.788 [CBIT](INFO): Critical error at 20190306T233057 2019-03-06T23:30:58.788Z,1551915058.788 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-03-06T23:30:58.793Z,1551915058.793 [CBIT](ERROR): Data Fault in component: WetLabsUBAT 2019-03-06T23:30:58.793Z,1551915058.793 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2019-03-06T23:30:58.793Z,1551915058.793 [WetLabsUBAT] No Fault, FailCount= 1 2019-03-06T23:30:58.872Z,1551915058.872 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-03-06T23:30:58.889Z,1551915058.889 [DropWeight] Hardware Fault, FailCount= 1 2019-03-06T23:30:58.889Z,1551915058.889 [DropWeight](ERROR): Hardware Fault 2019-03-06T23:30:58.952Z,1551915058.952 [CBIT](INFO): Critical error at 20190306T233058 2019-03-06T23:30:58.954Z,1551915058.954 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-03-06T23:30:58.955Z,1551915058.955 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-03-06T23:30:59.206Z,1551915059.206 [WetLabsUBAT](INFO): start 2019-03-06T23:30:59.208Z,1551915059.208 [WetLabsUBAT](INFO): Initializing 2019-03-06T23:30:59.208Z,1551915059.208 [WetLabsUBAT](INFO): in readConfig 2019-03-06T23:30:59.217Z,1551915059.217 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-03-06T23:30:59.218Z,1551915059.218 [WetLabsUBAT](INFO): Powering up 2019-03-06T23:30:59.219Z,1551915059.219 [WetLabsUBAT](INFO): Checking LCM 2019-03-06T23:30:59.229Z,1551915059.229 [WetLabsUBAT](INFO): LCM OK 2019-03-06T23:30:59.231Z,1551915059.231 [WetLabsUBAT..SerialDriver](INFO): Created logger 2019-03-06T23:30:59.233Z,1551915059.233 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2019-03-06T23:30:59.233Z,1551915059.233 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2019-03-06T23:30:59.277Z,1551915059.277 [WetLabsUBAT](INFO): setup() - setLineFormat() 2019-03-06T23:30:59.280Z,1551915059.280 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata: Override SerialDriver::getDeviceMetadata() 2019-03-06T23:30:59.593Z,1551915059.593 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-03-06T23:30:59.593Z,1551915059.593 [RudderServo](FAULT): Rudder failed to initialize 2019-03-06T23:30:59.593Z,1551915059.593 [RudderServo] Communications Fault, FailCount= 1 2019-03-06T23:30:59.593Z,1551915059.593 [RudderServo](ERROR): Communications Fault 2019-03-06T23:30:59.750Z,1551915059.750 [CBIT](INFO): Critical error at 20190306T233058 2019-03-06T23:30:59.753Z,1551915059.753 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-03-06T23:30:59.943Z,1551915059.943 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-06T23:30:59.944Z,1551915059.944 [RudderServo](INFO): Powering down 2019-03-06T23:31:00.618Z,1551915060.618 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-06T23:31:00.738Z,1551915060.738 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-06T23:31:00.742Z,1551915060.742 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-06T23:31:00.742Z,1551915060.742 [RudderServo] No Fault, FailCount= 1 2019-03-06T23:31:02.238Z,1551915062.238 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-06T23:31:02.436Z,1551915062.436 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-03-06T23:31:03.438Z,1551915063.438 [WetLabsUBAT](INFO): starting 2019-03-06T23:31:04.986Z,1551915064.986 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-03-06T23:31:07.577Z,1551915067.577 [WetLabsUBAT](INFO): starting 2019-03-06T23:31:07.961Z,1551915067.961 [CBIT](CRITICAL): Environmental Failure. Press:14.687189 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION 2019-03-06T23:31:07.981Z,1551915067.981 [WetLabsUBAT](INFO): stop 2019-03-06T23:31:07.981Z,1551915067.981 [WetLabsUBAT](INFO): uninitialize 2019-03-06T23:31:07.981Z,1551915067.981 [WetLabsUBAT](INFO): Powering down 2019-03-06T23:31:08.370Z,1551915068.370 [CBIT](INFO): Critical error at 20190306T233107 2019-03-06T23:31:18.397Z,1551915078.397 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-03-06T23:31:18.397Z,1551915078.397 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2019-03-06T23:31:18.397Z,1551915078.397 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-03-06T23:31:18.482Z,1551915078.482 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-03-06T23:31:18.801Z,1551915078.801 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-03-06T23:31:19.711Z,1551915079.711 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-03-06T23:31:19.711Z,1551915079.711 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2019-03-06T23:31:22.033Z,1551915082.033 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-03-06T23:31:22.033Z,1551915082.033 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-03-06T23:31:22.493Z,1551915082.493 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-06T23:31:22.502Z,1551915082.502 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-06T23:31:25.394Z,1551915085.394 [NAL9602](INFO): Powering up NAL9602 2019-03-06T23:31:29.429Z,1551915089.429 [AcousticModem_Benthos_ATM900](DEBUG): 2019-03-06T23:31:30.643Z,1551915090.643 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2019-03-06T23:31:31.046Z,1551915091.046 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2019-03-06T23:31:31.047Z,1551915091.047 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2019-03-06T23:31:31.047Z,1551915091.047 [AcousticModem_Benthos_ATM900](DEBUG): Feb 19 2019 22:26:48 2019-03-06T23:31:31.450Z,1551915091.450 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2019-03-06T23:31:32.258Z,1551915092.258 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2019-03-06T23:31:32.662Z,1551915092.662 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2019-03-06T23:31:32.663Z,1551915092.663 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2019-03-06T23:31:32.663Z,1551915092.663 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2019-03-06T23:31:33.065Z,1551915093.065 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2019-03-06T23:31:33.469Z,1551915093.469 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2019-03-06T23:31:33.470Z,1551915093.470 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2019-03-06T23:31:33.584Z,1551915093.584 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008915 CHAN A1 (24V): -0.013406 CHAN A2 (12V): -0.005341 CHAN A3 (5V): -0.002563 CHAN B0 (3.3V): -0.001279 CHAN B1 (3.15aV): -0.001372 CHAN B2 (3.15bV): -0.001359 CHAN B3 (GND): 0.000032 OPEN: 0.004290 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-06T23:31:33.876Z,1551915093.876 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2019-03-06T23:31:36.303Z,1551915096.303 [NAL9602](INFO): NAL9602 initialized 2019-03-06T23:31:37.129Z,1551915097.129 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:32:15.885Z,1551915135.885 [SBIT](IMPORTANT): SBIT PASSED 2019-03-06T23:32:15.938Z,1551915135.938 [CommandLine](IMPORTANT): got command configSet list 2019-03-06T23:32:15.938Z,1551915135.938 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-06T23:32:15.939Z,1551915135.939 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2019-03-06T23:32:16.272Z,1551915136.272 [MissionManager](IMPORTANT): Started mission Startup 2019-03-06T23:32:16.272Z,1551915136.272 [Startup] Running Loop=1 2019-03-06T23:32:16.273Z,1551915136.273 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-06T23:32:16.273Z,1551915136.273 [Startup:A.GoToSurface] Running Loop=1 2019-03-06T23:32:16.273Z,1551915136.273 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-06T23:32:16.274Z,1551915136.274 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-06T23:32:16.274Z,1551915136.274 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-06T23:32:16.274Z,1551915136.274 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-06T23:32:16.275Z,1551915136.275 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-06T23:32:16.275Z,1551915136.275 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-06T23:32:16.276Z,1551915136.276 [Startup:StartupSatComms] Running Loop=1 2019-03-06T23:32:16.277Z,1551915136.277 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-03-06T23:32:16.277Z,1551915136.277 [Startup:StartupSatComms:A] Running Loop=1 2019-03-06T23:32:16.691Z,1551915136.691 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-03-06T23:32:38.426Z,1551915158.426 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004569 2019-03-06T23:32:58.313Z,1551915178.313 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-06T23:32:58.313Z,1551915178.313 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-03-06T23:32:58.313Z,1551915178.313 [Rowe_600LCM](ERROR): Communications Fault 2019-03-06T23:32:58.717Z,1551915178.717 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-06T23:32:58.777Z,1551915178.777 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-06T23:32:58.866Z,1551915178.866 [Rowe_600LCM](INFO): Powering down 2019-03-06T23:33:00.318Z,1551915180.318 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-06T23:33:00.318Z,1551915180.318 [Rowe_600LCM] No Fault, FailCount= 1 2019-03-06T23:33:00.481Z,1551915180.481 [Rowe_600LCM](INFO): Initializing 2019-03-06T23:33:00.481Z,1551915180.481 [Rowe_600LCM](INFO): Powering up 2019-03-06T23:33:04.666Z,1551915184.666 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-06T23:33:04.844Z,1551915184.844 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-03-06T23:33:16.482Z,1551915196.482 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-06T23:32:16.3Z 2019-03-06T23:33:16.482Z,1551915196.482 [Startup:StartupSatComms:A] Stopped 2019-03-06T23:33:16.482Z,1551915196.482 [Startup:StartupSatComms:B] Running Loop=1 2019-03-06T23:33:16.888Z,1551915196.888 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-06T23:33:23.413Z,1551915203.413 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190306T231828/Courier0004.lzma 2019-03-06T23:33:24.218Z,1551915204.218 [DataOverHttps](INFO): Moved sent file to Logs/20190306T231828/Courier0004.lzma.bak 2019-03-06T23:33:24.219Z,1551915204.219 [DataOverHttps](INFO): SBD MOMSN=10090471 2019-03-06T23:33:36.874Z,1551915216.874 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20190306T233048/Courier0000.lzma 2019-03-06T23:33:37.678Z,1551915217.678 [DataOverHttps](INFO): Moved sent file to Logs/20190306T233048/Courier0000.lzma.bak 2019-03-06T23:33:37.678Z,1551915217.678 [DataOverHttps](INFO): SBD MOMSN=10090473 2019-03-06T23:33:50.610Z,1551915230.610 [DataOverHttps](INFO): Sending 899 bytes from file Logs/20190306T231828/Express0005.lzma 2019-03-06T23:33:51.414Z,1551915231.414 [DataOverHttps](INFO): Moved sent file to Logs/20190306T231828/Express0005.lzma.bak 2019-03-06T23:33:51.415Z,1551915231.415 [DataOverHttps](INFO): SBD MOMSN=10090483 2019-03-06T23:33:58.116Z,1551915238.116 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-06T23:33:58.116Z,1551915238.116 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-06T23:33:58.118Z,1551915238.118 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-06T23:33:58.118Z,1551915238.118 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-06T23:33:58.119Z,1551915238.119 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-03-06T23:33:58.119Z,1551915238.119 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-06T23:33:58.129Z,1551915238.129 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-06T23:33:58.129Z,1551915238.129 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-06T23:33:58.130Z,1551915238.130 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-06T23:33:58.525Z,1551915238.525 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-06T23:33:58.525Z,1551915238.525 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-06T23:33:58.526Z,1551915238.526 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-06T23:33:58.526Z,1551915238.526 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-06T23:33:58.526Z,1551915238.526 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-06T23:33:58.526Z,1551915238.526 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-03-06T23:33:58.923Z,1551915238.923 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-06T23:34:04.578Z,1551915244.578 [DataOverHttps](INFO): Sending 811 bytes from file Logs/20190306T233048/Express0001.lzma 2019-03-06T23:34:05.363Z,1551915245.363 [DataOverHttps](INFO): Moved sent file to Logs/20190306T233048/Express0001.lzma.bak 2019-03-06T23:34:05.363Z,1551915245.363 [DataOverHttps](INFO): SBD MOMSN=10090511 2019-03-06T23:34:06.616Z,1551915246.616 [Startup:StartupSatComms:B] Stopped 2019-03-06T23:34:06.616Z,1551915246.616 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-03-06T23:34:06.621Z,1551915246.621 [Startup:StartupSatComms] Stopped 2019-03-06T23:34:06.621Z,1551915246.621 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-03-06T23:34:06.622Z,1551915246.622 [Startup](INFO): Completed Startup 2019-03-06T23:34:06.622Z,1551915246.622 [MissionManager](INFO): Startup is completed. 2019-03-06T23:34:06.622Z,1551915246.622 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-06T23:34:06.622Z,1551915246.622 [Startup] Stopped 2019-03-06T23:34:06.622Z,1551915246.622 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-06T23:34:06.622Z,1551915246.622 [Startup:A.GoToSurface] Stopped 2019-03-06T23:34:06.622Z,1551915246.622 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-06T23:34:07.017Z,1551915247.017 [MissionManager](IMPORTANT): Started mission Default 2019-03-06T23:34:07.018Z,1551915247.018 [Default] Running Loop=1 2019-03-06T23:34:07.018Z,1551915247.018 [Default](DEBUG): Aggregate::initialize Default 2019-03-06T23:34:07.018Z,1551915247.018 [Default:B.GoToSurface] Running Loop=1 2019-03-06T23:34:07.018Z,1551915247.018 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-06T23:34:07.018Z,1551915247.018 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-06T23:34:07.019Z,1551915247.019 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-06T23:34:07.019Z,1551915247.019 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-06T23:34:07.019Z,1551915247.019 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-06T23:34:07.020Z,1551915247.020 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-06T23:34:07.020Z,1551915247.020 [Default:A.Wait] Running Loop=1 2019-03-06T23:34:07.020Z,1551915247.020 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-06T23:34:20.349Z,1551915260.349 [Default:A.Wait](INFO): Done Waiting. 2019-03-06T23:34:20.349Z,1551915260.349 [Default:A.Wait] Stopped 2019-03-06T23:34:20.349Z,1551915260.349 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-06T23:34:20.751Z,1551915260.751 [Default:CheckIn] Running Loop=1 2019-03-06T23:34:20.751Z,1551915260.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-06T23:34:20.751Z,1551915260.751 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-06T23:34:21.211Z,1551915261.211 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-03-06T23:35:00.689Z,1551915300.689 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-06T23:35:00.689Z,1551915300.689 [Rowe_600LCM] Communications Fault, FailCount= 2 2019-03-06T23:35:00.689Z,1551915300.689 [Rowe_600LCM](ERROR): Communications Fault 2019-03-06T23:35:00.754Z,1551915300.754 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-06T23:35:01.101Z,1551915301.101 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-06T23:35:01.266Z,1551915301.266 [Rowe_600LCM](INFO): Powering down 2019-03-06T23:35:02.778Z,1551915302.778 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-06T23:35:02.778Z,1551915302.778 [Rowe_600LCM] No Fault, FailCount= 2 2019-03-06T23:35:02.881Z,1551915302.881 [Rowe_600LCM](INFO): Initializing 2019-03-06T23:35:02.881Z,1551915302.881 [Rowe_600LCM](INFO): Powering up 2019-03-06T23:35:07.033Z,1551915307.033 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-06T23:35:07.089Z,1551915307.089 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-03-06T23:36:40.099Z,1551915400.099 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-03-06T23:36:40.901Z,1551915400.901 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:45.753Z,1551915405.753 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:48.981Z,1551915408.981 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:51.809Z,1551915411.809 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:54.645Z,1551915414.645 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:56.657Z,1551915416.657 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:58.681Z,1551915418.681 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:36:59.124Z,1551915419.124 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-06T23:36:59.124Z,1551915419.124 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-06T23:36:59.125Z,1551915419.125 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-06T23:36:59.126Z,1551915419.126 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-06T23:36:59.127Z,1551915419.127 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-03-06T23:36:59.127Z,1551915419.127 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-06T23:36:59.141Z,1551915419.141 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-06T23:36:59.141Z,1551915419.141 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-06T23:36:59.142Z,1551915419.142 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-06T23:36:59.532Z,1551915419.532 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-06T23:36:59.532Z,1551915419.532 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-06T23:36:59.532Z,1551915419.532 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-06T23:36:59.532Z,1551915419.532 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-06T23:36:59.533Z,1551915419.533 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-06T23:36:59.533Z,1551915419.533 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-03-06T23:36:59.918Z,1551915419.918 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-06T23:37:00.697Z,1551915420.697 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:03.105Z,1551915423.105 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-06T23:37:03.105Z,1551915423.105 [Rowe_600LCM] Communications Fault, FailCount= 3 2019-03-06T23:37:03.105Z,1551915423.105 [Rowe_600LCM](ERROR): Communications Fault 2019-03-06T23:37:03.201Z,1551915423.201 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-06T23:37:03.509Z,1551915423.509 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-06T23:37:03.705Z,1551915423.705 [Rowe_600LCM](INFO): Powering down 2019-03-06T23:37:03.957Z,1551915423.957 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:05.187Z,1551915425.187 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-06T23:37:05.187Z,1551915425.187 [Rowe_600LCM] No Fault, FailCount= 3 2019-03-06T23:37:05.321Z,1551915425.321 [Rowe_600LCM](INFO): Initializing 2019-03-06T23:37:05.321Z,1551915425.321 [Rowe_600LCM](INFO): Powering up 2019-03-06T23:37:06.765Z,1551915426.765 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:09.442Z,1551915429.442 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-06T23:37:09.501Z,1551915429.501 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-03-06T23:37:09.989Z,1551915429.989 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:12.821Z,1551915432.821 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:16.053Z,1551915436.053 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:18.877Z,1551915438.877 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:21.709Z,1551915441.709 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:23.729Z,1551915443.729 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:26.965Z,1551915446.965 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:29.785Z,1551915449.785 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:33.025Z,1551915453.025 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:35.845Z,1551915455.845 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:39.081Z,1551915459.081 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:41.905Z,1551915461.905 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:44.737Z,1551915464.737 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:46.757Z,1551915466.757 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:49.985Z,1551915469.985 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:52.813Z,1551915472.813 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:56.045Z,1551915476.045 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:37:58.877Z,1551915478.877 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:02.109Z,1551915482.109 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:04.933Z,1551915484.933 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:07.761Z,1551915487.761 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:09.781Z,1551915489.781 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:11.805Z,1551915491.805 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:15.033Z,1551915495.033 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:17.861Z,1551915497.861 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:21.093Z,1551915501.093 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:23.925Z,1551915503.925 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:27.157Z,1551915507.157 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:29.981Z,1551915509.981 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:32.813Z,1551915512.813 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:34.833Z,1551915514.833 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:38.065Z,1551915518.065 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:40.889Z,1551915520.889 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:44.125Z,1551915524.125 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:46.953Z,1551915526.953 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:50.181Z,1551915530.181 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:53.017Z,1551915533.017 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:55.841Z,1551915535.841 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:57.865Z,1551915537.865 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:38:59.881Z,1551915539.881 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:03.113Z,1551915543.113 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:05.517Z,1551915545.517 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-03-06T23:39:05.517Z,1551915545.517 [Rowe_600LCM] Communications Fault, FailCount= 4 2019-03-06T23:39:05.517Z,1551915545.517 [Rowe_600LCM](ERROR): Communications Fault 2019-03-06T23:39:05.590Z,1551915545.590 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-03-06T23:39:05.921Z,1551915545.921 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-03-06T23:39:05.969Z,1551915545.969 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:06.088Z,1551915546.088 [Rowe_600LCM](INFO): Powering down 2019-03-06T23:39:07.590Z,1551915547.590 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-03-06T23:39:07.591Z,1551915547.591 [Rowe_600LCM] No Fault, FailCount= 4 2019-03-06T23:39:07.705Z,1551915547.705 [Rowe_600LCM](INFO): Initializing 2019-03-06T23:39:07.706Z,1551915547.706 [Rowe_600LCM](INFO): Powering up 2019-03-06T23:39:09.169Z,1551915549.169 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:11.836Z,1551915551.836 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-03-06T23:39:11.890Z,1551915551.890 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-03-06T23:39:12.001Z,1551915552.001 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:15.229Z,1551915555.229 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:18.057Z,1551915558.057 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:20.885Z,1551915560.885 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:20.928Z,1551915560.928 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-06T23:34:20.8Z 2019-03-06T23:39:20.928Z,1551915560.928 [Default:CheckIn:Read_GPS] Stopped 2019-03-06T23:39:20.933Z,1551915560.933 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-06T23:39:21.376Z,1551915561.376 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-06T23:39:21.972Z,1551915561.972 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190306T233048/Courier0004.lzma 2019-03-06T23:39:22.458Z,1551915562.458 [DataOverHttps](INFO): Moved sent file to Logs/20190306T233048/Courier0004.lzma.bak 2019-03-06T23:39:22.458Z,1551915562.458 [DataOverHttps](INFO): SBD MOMSN=10090550 2019-03-06T23:39:22.905Z,1551915562.905 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:26.145Z,1551915566.145 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:27.087Z,1551915567.087 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-03-06T23:39:28.965Z,1551915568.965 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:32.205Z,1551915572.205 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:35.025Z,1551915575.025 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:35.929Z,1551915575.929 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20190306T233048/Express0005.lzma 2019-03-06T23:39:36.734Z,1551915576.734 [DataOverHttps](INFO): Moved sent file to Logs/20190306T233048/Express0005.lzma.bak 2019-03-06T23:39:36.734Z,1551915576.734 [DataOverHttps](INFO): SBD MOMSN=10090552 2019-03-06T23:39:37.907Z,1551915577.907 [Default:CheckIn:Read_Iridium] Stopped 2019-03-06T23:39:37.907Z,1551915577.907 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-06T23:39:37.908Z,1551915577.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-06T23:39:38.257Z,1551915578.257 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:41.085Z,1551915581.085 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:43.913Z,1551915583.913 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:45.935Z,1551915585.935 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:49.177Z,1551915589.177 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:52.005Z,1551915592.005 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:55.237Z,1551915595.237 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:39:58.069Z,1551915598.069 [NAL9602](DEBUG): Fix Requested 2019-03-06T23:40:00.109Z,1551915600.109 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-06T23:40:00.109Z,1551915600.109 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-06T23:40:00.110Z,1551915600.110 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-06T23:40:00.111Z,1551915600.111 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-06T23:40:00.112Z,1551915600.112 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-03-06T23:40:00.112Z,1551915600.112 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-06T23:40:00.122Z,1551915600.122 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-06T23:40:00.122Z,1551915600.122 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-06T23:40:00.122Z,1551915600.122 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-06T23:40:00.532Z,1551915600.532 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-06T23:40:00.532Z,1551915600.532 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-06T23:40:00.532Z,1551915600.532 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-06T23:40:00.532Z,15519