2019-02-16T15:38:19.302Z,1550331499.302 [Supervisor](DEBUG): Initializing supervisor.
2019-02-16T15:38:19.305Z,1550331499.305 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-16T15:38:19.305Z,1550331499.305 [SyncHandler](INFO): Protected caller Thread ID is 811
2019-02-16T15:38:19.306Z,1550331499.306 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-16T15:38:19.307Z,1550331499.307 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-16T15:38:19.307Z,1550331499.307 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 812
2019-02-16T15:38:19.310Z,1550331499.310 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-16T15:38:19.321Z,1550331499.321 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-16T15:38:19.322Z,1550331499.322 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-16T15:38:19.323Z,1550331499.323 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 813
2019-02-16T15:38:19.323Z,1550331499.323 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-16T15:38:19.324Z,1550331499.324 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-16T15:38:19.325Z,1550331499.325 [logger ThreadHandler](INFO): Protected caller Thread ID is 814
2019-02-16T15:38:19.327Z,1550331499.327 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-16T15:38:19.327Z,1550331499.327 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-16T15:38:19.331Z,1550331499.331 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-16T15:38:19.426Z,1550331499.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-16T15:38:19.428Z,1550331499.428 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-16T15:38:19.623Z,1550331499.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-16T15:38:19.623Z,1550331499.623 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-16T15:38:19.763Z,1550331499.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-16T15:38:19.763Z,1550331499.763 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-16T15:38:20.223Z,1550331500.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-16T15:38:20.224Z,1550331500.224 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-16T15:38:20.652Z,1550331500.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-16T15:38:20.653Z,1550331500.653 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-16T15:38:21.119Z,1550331501.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-16T15:38:21.120Z,1550331501.120 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-16T15:38:21.420Z,1550331501.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-16T15:38:21.422Z,1550331501.422 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-16T15:38:21.765Z,1550331501.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-16T15:38:21.766Z,1550331501.766 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-16T15:38:22.150Z,1550331502.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-16T15:38:22.151Z,1550331502.151 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-16T15:38:22.299Z,1550331502.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-16T15:38:22.299Z,1550331502.299 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-16T15:38:22.407Z,1550331502.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-16T15:38:22.407Z,1550331502.407 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-16T15:38:22.488Z,1550331502.488 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-16T15:38:22.590Z,1550331502.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-16T15:38:22.591Z,1550331502.591 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-16T15:38:22.787Z,1550331502.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-16T15:38:22.788Z,1550331502.788 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-16T15:38:23.695Z,1550331503.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-16T15:38:23.697Z,1550331503.697 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-02-16T15:38:23.700Z,1550331503.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-02-16T15:38:23.786Z,1550331503.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-02-16T15:38:24.017Z,1550331504.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-16T15:38:24.018Z,1550331504.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-02-16T15:38:24.113Z,1550331504.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-02-16T15:38:24.272Z,1550331504.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-02-16T15:38:24.475Z,1550331504.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-02-16T15:38:24.561Z,1550331504.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-02-16T15:38:24.700Z,1550331504.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-02-16T15:38:24.840Z,1550331504.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-02-16T15:38:24.989Z,1550331504.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-02-16T15:38:25.094Z,1550331505.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-02-16T15:38:25.192Z,1550331505.192 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-02-16T15:38:25.195Z,1550331505.195 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-16T15:38:25.322Z,1550331505.322 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-16T15:38:25.322Z,1550331505.322 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-16T15:38:25.468Z,1550331505.468 [BuoyancyServo] Loaded
2019-02-16T15:38:25.468Z,1550331505.468 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-16T15:38:25.484Z,1550331505.484 [ElevatorServo] Loaded
2019-02-16T15:38:25.484Z,1550331505.484 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-16T15:38:25.499Z,1550331505.499 [MassServo] Loaded
2019-02-16T15:38:25.499Z,1550331505.499 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-16T15:38:25.514Z,1550331505.514 [RudderServo] Loaded
2019-02-16T15:38:25.514Z,1550331505.514 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-16T15:38:25.528Z,1550331505.528 [ThrusterServo] Loaded
2019-02-16T15:38:25.529Z,1550331505.529 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-16T15:38:25.529Z,1550331505.529 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-16T15:38:25.530Z,1550331505.530 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-16T15:38:25.688Z,1550331505.688 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-16T15:38:25.716Z,1550331505.716 [SBIT] Loaded
2019-02-16T15:38:25.716Z,1550331505.716 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-16T15:38:25.717Z,1550331505.717 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-16T15:38:25.745Z,1550331505.745 [IBIT] Loaded
2019-02-16T15:38:25.745Z,1550331505.745 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-16T15:38:25.749Z,1550331505.749 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-16T15:38:26.141Z,1550331506.141 [CBIT] Loaded
2019-02-16T15:38:26.142Z,1550331506.142 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-16T15:38:26.142Z,1550331506.142 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-16T15:38:26.143Z,1550331506.143 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-16T15:38:26.378Z,1550331506.378 [Aanderaa_O2] Loaded
2019-02-16T15:38:26.378Z,1550331506.378 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-16T15:38:26.388Z,1550331506.388 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-16T15:38:26.393Z,1550331506.393 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-16T15:38:26.394Z,1550331506.394 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-16T15:38:26.399Z,1550331506.399 [CTD_Seabird](INFO): created writer for : depth
2019-02-16T15:38:26.400Z,1550331506.400 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-16T15:38:26.405Z,1550331506.405 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-16T15:38:26.405Z,1550331506.405 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-16T15:38:26.411Z,1550331506.411 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-16T15:38:26.411Z,1550331506.411 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-16T15:38:26.416Z,1550331506.416 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-16T15:38:26.417Z,1550331506.417 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-16T15:38:26.423Z,1550331506.423 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-16T15:38:26.423Z,1550331506.423 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-16T15:38:26.428Z,1550331506.428 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-16T15:38:26.455Z,1550331506.455 [CTD_Seabird] Loaded
2019-02-16T15:38:26.455Z,1550331506.455 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-16T15:38:26.456Z,1550331506.456 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062F4E0
2019-02-16T15:38:26.457Z,1550331506.457 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894
2019-02-16T15:38:26.478Z,1550331506.478 [ESPComponent] Loaded
2019-02-16T15:38:26.478Z,1550331506.478 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-02-16T15:38:26.493Z,1550331506.493 [PAR_Licor] Loaded
2019-02-16T15:38:26.493Z,1550331506.493 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-16T15:38:26.541Z,1550331506.541 [WetLabsBB2FL] Loaded
2019-02-16T15:38:26.541Z,1550331506.541 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-16T15:38:26.543Z,1550331506.543 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065F4E0
2019-02-16T15:38:26.543Z,1550331506.543 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895
2019-02-16T15:38:26.544Z,1550331506.544 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-16T15:38:26.545Z,1550331506.545 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-16T15:38:26.601Z,1550331506.601 [DepthRateCalculator] Loaded
2019-02-16T15:38:26.601Z,1550331506.601 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-16T15:38:26.606Z,1550331506.606 [PitchRateCalculator] Loaded
2019-02-16T15:38:26.607Z,1550331506.607 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-16T15:38:26.622Z,1550331506.622 [SpeedCalculator] Loaded
2019-02-16T15:38:26.623Z,1550331506.623 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-16T15:38:26.643Z,1550331506.643 [TempGradientCalculator] Loaded
2019-02-16T15:38:26.644Z,1550331506.644 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-16T15:38:26.649Z,1550331506.649 [YawRateCalculator] Loaded
2019-02-16T15:38:26.650Z,1550331506.650 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-16T15:38:26.683Z,1550331506.683 [ElevatorOffsetCalculator] Loaded
2019-02-16T15:38:26.683Z,1550331506.683 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-16T15:38:26.684Z,1550331506.684 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-16T15:38:26.684Z,1550331506.684 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-16T15:38:26.726Z,1550331506.726 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-16T15:38:26.726Z,1550331506.726 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-16T15:38:26.846Z,1550331506.846 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-16T15:38:26.846Z,1550331506.846 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-16T15:38:26.905Z,1550331506.905 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-16T15:38:26.906Z,1550331506.906 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-16T15:38:26.965Z,1550331506.965 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-16T15:38:26.965Z,1550331506.965 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-16T15:38:26.981Z,1550331506.981 [NavChart] Loaded
2019-02-16T15:38:26.982Z,1550331506.982 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-16T15:38:26.986Z,1550331506.986 [UniversalFixResidualReporter] Loaded
2019-02-16T15:38:26.986Z,1550331506.986 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-16T15:38:26.987Z,1550331506.987 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-16T15:38:26.988Z,1550331506.988 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-16T15:38:27.486Z,1550331507.486 [AcousticModem_Benthos_ATM900] Loaded
2019-02-16T15:38:27.487Z,1550331507.487 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-02-16T15:38:27.627Z,1550331507.627 [DataOverHttps] Loaded
2019-02-16T15:38:27.627Z,1550331507.627 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-02-16T15:38:27.628Z,1550331507.628 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409274E0
2019-02-16T15:38:27.629Z,1550331507.629 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 896
2019-02-16T15:38:27.643Z,1550331507.643 [Depth_Keller] Loaded
2019-02-16T15:38:27.643Z,1550331507.643 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-16T15:38:27.648Z,1550331507.648 [DropWeight] Loaded
2019-02-16T15:38:27.648Z,1550331507.648 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-16T15:38:27.738Z,1550331507.738 [NAL9602] Loaded
2019-02-16T15:38:27.739Z,1550331507.739 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-16T15:38:27.744Z,1550331507.744 [Onboard] Loaded
2019-02-16T15:38:27.744Z,1550331507.744 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-16T15:38:27.747Z,1550331507.747 [Radio_Surface] Loaded
2019-02-16T15:38:27.748Z,1550331507.748 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-16T15:38:27.749Z,1550331507.749 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409574E0
2019-02-16T15:38:27.749Z,1550331507.749 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 897
2019-02-16T15:38:27.967Z,1550331507.967 [PNI_TCM] Loaded
2019-02-16T15:38:27.967Z,1550331507.967 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-16T15:38:28.123Z,1550331508.123 [Rowe_600LCM] Loaded
2019-02-16T15:38:28.123Z,1550331508.123 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-16T15:38:28.124Z,1550331508.124 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409874E0
2019-02-16T15:38:28.124Z,1550331508.124 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 898
2019-02-16T15:38:29.718Z,1550331509.718 [BPC1] Loaded
2019-02-16T15:38:29.719Z,1550331509.719 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-16T15:38:29.719Z,1550331509.719 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-16T15:38:29.720Z,1550331509.720 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-16T15:38:29.824Z,1550331509.824 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-16T15:38:29.824Z,1550331509.824 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-16T15:38:29.911Z,1550331509.911 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-16T15:38:29.996Z,1550331509.996 [VerticalControl] Loaded
2019-02-16T15:38:29.997Z,1550331509.997 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-16T15:38:29.997Z,1550331509.997 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-16T15:38:30.055Z,1550331510.055 [HorizontalControl] Loaded
2019-02-16T15:38:30.055Z,1550331510.055 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-16T15:38:30.056Z,1550331510.056 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-16T15:38:30.057Z,1550331510.057 [SpeedControl] Loaded
2019-02-16T15:38:30.057Z,1550331510.057 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-16T15:38:30.058Z,1550331510.058 [LoopControl](DEBUG): Construct LoopControl.
2019-02-16T15:38:30.059Z,1550331510.059 [LoopControl] Loaded
2019-02-16T15:38:30.059Z,1550331510.059 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-16T15:38:30.059Z,1550331510.059 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-16T15:38:30.060Z,1550331510.060 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-16T15:38:30.084Z,1550331510.084 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-16T15:38:30.085Z,1550331510.085 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-16T15:38:30.363Z,1550331510.363 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-16T15:38:30.366Z,1550331510.366 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-16T15:38:30.367Z,1550331510.367 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-16T15:38:30.374Z,1550331510.374 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-16T15:38:30.375Z,1550331510.375 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0
2019-02-16T15:38:30.376Z,1550331510.376 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 899
2019-02-16T15:38:30.380Z,1550331510.380 [Supervisor](INFO): Main Thread ID is 807
2019-02-16T15:38:30.380Z,1550331510.380 [Supervisor](DEBUG): Running supervisor.
2019-02-16T15:38:30.381Z,1550331510.381 [CommandLine ThreadHandler](INFO): Handler Thread ID is 900
2019-02-16T15:38:30.383Z,1550331510.383 [controlThread ThreadHandler](INFO): Handler Thread ID is 901
2019-02-16T15:38:30.384Z,1550331510.384 [controlThread](DEBUG): Initializing ControlThread
2019-02-16T15:38:30.385Z,1550331510.385 [SBIT](INFO): Initialize SBIT Component.
2019-02-16T15:38:30.386Z,1550331510.386 [SBIT](IMPORTANT): git: 2019-02-11-18-g088f29c
2019-02-16T15:38:30.386Z,1550331510.386 [SBIT](INFO): git hash: 088f29c08f1651a006237423a6ffed1cbba6c6ba
2019-02-16T15:38:30.387Z,1550331510.387 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-16T15:38:30.388Z,1550331510.388 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-16T15:38:30.389Z,1550331510.389 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-02-16T15:38:30.390Z,1550331510.390 [IBIT](INFO): Initialize IBIT Component.
2019-02-16T15:38:30.390Z,1550331510.390 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-16T15:38:30.391Z,1550331510.391 [logger ThreadHandler](INFO): Handler Thread ID is 902
2019-02-16T15:38:30.402Z,1550331510.402 [CBIT](DEBUG): Initialized mux pins.
2019-02-16T15:38:30.402Z,1550331510.402 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-02-16T15:38:30.402Z,1550331510.402 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-16T15:38:30.410Z,1550331510.410 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 903
2019-02-16T15:38:30.411Z,1550331510.411 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-16T15:38:30.415Z,1550331510.415 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-16T15:38:30.416Z,1550331510.416 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 905
2019-02-16T15:38:30.417Z,1550331510.417 [WetLabsBB2FL](INFO): Powering down
2019-02-16T15:38:30.430Z,1550331510.430 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-16T15:38:30.430Z,1550331510.430 [CBIT](DEBUG): Initializing heartbeat.
2019-02-16T15:38:30.466Z,1550331510.466 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 906
2019-02-16T15:38:30.468Z,1550331510.468 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-16T15:38:30.502Z,1550331510.502 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-16T15:38:30.502Z,1550331510.502 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-16T15:38:30.511Z,1550331510.511 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 907
2019-02-16T15:38:30.526Z,1550331510.526 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 908
2019-02-16T15:38:30.527Z,1550331510.527 [Rowe_600LCM](INFO): Initializing
2019-02-16T15:38:30.542Z,1550331510.542 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-16T15:38:30.543Z,1550331510.543 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-16T15:38:30.543Z,1550331510.543 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-16T15:38:30.543Z,1550331510.543 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-16T15:38:30.544Z,1550331510.544 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-16T15:38:30.544Z,1550331510.544 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-16T15:38:30.545Z,1550331510.545 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:38:30.546Z,1550331510.546 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-16T15:38:30.547Z,1550331510.547 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-16T15:38:30.553Z,1550331510.553 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-16T15:38:30.557Z,1550331510.557 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-16T15:38:30.573Z,1550331510.573 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-16T15:38:30.587Z,1550331510.587 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-16T15:38:30.647Z,1550331510.647 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-16T15:38:30.657Z,1550331510.657 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 909
2019-02-16T15:38:30.671Z,1550331510.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-16T15:38:30.672Z,1550331510.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-16T15:38:30.672Z,1550331510.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-16T15:38:30.672Z,1550331510.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-16T15:38:30.672Z,1550331510.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-16T15:38:30.672Z,1550331510.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-16T15:38:30.673Z,1550331510.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-16T15:38:30.673Z,1550331510.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-16T15:38:30.673Z,1550331510.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-16T15:38:30.673Z,1550331510.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-16T15:38:30.673Z,1550331510.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-16T15:38:30.673Z,1550331510.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-16T15:38:30.674Z,1550331510.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-16T15:38:30.674Z,1550331510.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-16T15:38:30.674Z,1550331510.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-16T15:38:30.674Z,1550331510.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-16T15:38:30.816Z,1550331510.816 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-16T15:38:30.914Z,1550331510.914 [Radio_Surface](INFO): Powering up
2019-02-16T15:38:30.971Z,1550331510.971 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-02-16T15:38:30.986Z,1550331510.986 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-16T15:38:31.105Z,1550331511.105 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-16T15:38:31.131Z,1550331511.131 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-16T15:38:31.133Z,1550331511.133 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-16T15:38:31.131Z,1550331511.131 [Rowe_600LCM](INFO): Powering up
2019-02-16T15:38:31.201Z,1550331511.201 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-16T15:38:31.215Z,1550331511.215 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-16T15:38:31.221Z,1550331511.221 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-16T15:38:31.253Z,1550331511.253 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-02-16T15:38:31.275Z,1550331511.275 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-16T15:38:31.349Z,1550331511.349 [ESPComponent](INFO): powering down ESP
2019-02-16T15:38:31.772Z,1550331511.772 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-02-16T15:38:31.772Z,1550331511.772 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-02-16T15:38:32.595Z,1550331512.595 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-16T15:38:32.606Z,1550331512.606 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-16T15:38:32.650Z,1550331512.650 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-16T15:38:32.662Z,1550331512.662 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-16T15:38:32.689Z,1550331512.689 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-16T15:38:32.698Z,1550331512.698 [MassServo](DEBUG): Initializing MassServo.
2019-02-16T15:38:32.708Z,1550331512.708 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-16T15:38:32.718Z,1550331512.718 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-16T15:38:32.736Z,1550331512.736 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-16T15:38:32.742Z,1550331512.742 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-16T15:38:32.977Z,1550331512.977 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-16T15:38:32.977Z,1550331512.977 [DropWeight] Hardware Fault, FailCount= 1
2019-02-16T15:38:32.977Z,1550331512.977 [DropWeight](ERROR): Hardware Fault
2019-02-16T15:38:33.426Z,1550331513.426 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-16T15:38:33.426Z,1550331513.426 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-16T15:38:33.426Z,1550331513.426 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-16T15:38:33.426Z,1550331513.426 [BuoyancyServo](ERROR): Communications Fault
2019-02-16T15:38:33.767Z,1550331513.767 [CommandLine](FAULT): Scheduling is paused
2019-02-16T15:38:33.768Z,1550331513.768 [CBIT](INFO): Critical error at 20190216T153832
2019-02-16T15:38:33.768Z,1550331513.768 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-16T15:38:33.771Z,1550331513.771 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-16T15:38:33.771Z,1550331513.771 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-16T15:38:33.772Z,1550331513.772 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-16T15:38:33.905Z,1550331513.905 [CBIT](INFO): Critical error at 20190216T153833
2019-02-16T15:38:35.372Z,1550331515.372 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T15:38:35.558Z,1550331515.558 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T15:38:36.003Z,1550331516.003 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-16T15:38:36.003Z,1550331516.003 [BuoyancyServo] No Fault, FailCount= 1
2019-02-16T15:38:36.383Z,1550331516.383 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-16T15:38:36.503Z,1550331516.503 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-16T15:38:36.882Z,1550331516.882 [Aanderaa_O2](INFO): Powering down
2019-02-16T15:38:39.467Z,1550331519.467 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-02-16T15:38:40.680Z,1550331520.680 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-02-16T15:38:40.680Z,1550331520.680 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-02-16T15:38:40.681Z,1550331520.681 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-02-16T15:38:40.682Z,1550331520.682 [AcousticModem_Benthos_ATM900](DEBUG): Feb 12 2019 06:11:36 
2019-02-16T15:38:40.795Z,1550331520.795 [CBIT](CRITICAL): Environmental Failure. Press:14.853609 PSI. Humidity:23%. Temp:28 C. ABORTING MISSION
2019-02-16T15:38:41.084Z,1550331521.084 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-02-16T15:38:41.185Z,1550331521.185 [CBIT](INFO): Critical error at 20190216T153840
2019-02-16T15:38:41.891Z,1550331521.891 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-02-16T15:38:42.295Z,1550331522.295 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-02-16T15:38:42.295Z,1550331522.295 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-02-16T15:38:42.296Z,1550331522.296 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-02-16T15:38:42.703Z,1550331522.703 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-02-16T15:38:43.103Z,1550331523.103 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-02-16T15:38:43.104Z,1550331523.104 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-02-16T15:38:43.507Z,1550331523.507 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-02-16T15:38:54.928Z,1550331534.928 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-16T15:38:54.933Z,1550331534.933 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-16T15:38:58.056Z,1550331538.056 [NAL9602](INFO): Powering up NAL9602
2019-02-16T15:39:05.839Z,1550331545.839 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009268
CHAN A1 (24V): -0.012547
CHAN A2 (12V): -0.005644
CHAN A3 (5V): -0.002846
CHAN B0 (3.3V): -0.001278
CHAN B1 (3.15aV): -0.000867
CHAN B2 (3.15bV): -0.001405
CHAN B3 (GND): -0.000269
OPEN: 0.004211
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-16T15:39:08.240Z,1550331548.240 [SBIT](ERROR): Could not read massPosReader_.
2019-02-16T15:39:08.963Z,1550331548.963 [NAL9602](INFO): NAL9602 initialized
2019-02-16T15:39:09.782Z,1550331549.782 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:39:48.717Z,1550331588.717 [SBIT](CRITICAL): SBIT FAILED
2019-02-16T15:39:48.752Z,1550331588.752 [CBIT](INFO): Critical error at 20190216T153948
2019-02-16T15:39:48.755Z,1550331588.755 [CommandLine](IMPORTANT): got command configSet list
2019-02-16T15:39:48.755Z,1550331588.755 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-16T15:39:48.758Z,1550331588.758 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-02-16T15:39:49.123Z,1550331589.123 [MissionManager](IMPORTANT): Started mission Startup
2019-02-16T15:39:49.123Z,1550331589.123 [Startup] Running Loop=1
2019-02-16T15:39:49.123Z,1550331589.123 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-16T15:39:49.124Z,1550331589.124 [Startup:A.GoToSurface] Running Loop=1
2019-02-16T15:39:49.124Z,1550331589.124 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-16T15:39:49.124Z,1550331589.124 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-16T15:39:49.125Z,1550331589.125 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-16T15:39:49.125Z,1550331589.125 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-16T15:39:49.125Z,1550331589.125 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-16T15:39:49.126Z,1550331589.126 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-16T15:39:49.132Z,1550331589.132 [Startup:StartupSatComms] Running Loop=1
2019-02-16T15:39:49.132Z,1550331589.132 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-16T15:39:49.132Z,1550331589.132 [Startup:StartupSatComms:A] Running Loop=1
2019-02-16T15:39:49.527Z,1550331589.527 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-16T15:39:58.449Z,1550331598.449 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-02-16T15:40:31.202Z,1550331631.202 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T15:40:31.202Z,1550331631.202 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-16T15:40:31.202Z,1550331631.202 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T15:40:31.562Z,1550331631.562 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T15:40:31.606Z,1550331631.606 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T15:40:31.699Z,1550331631.699 [Rowe_600LCM](INFO): Powering down
2019-02-16T15:40:33.164Z,1550331633.164 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T15:40:33.164Z,1550331633.164 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-16T15:40:33.314Z,1550331633.314 [Rowe_600LCM](INFO): Initializing
2019-02-16T15:40:33.315Z,1550331633.315 [Rowe_600LCM](INFO): Powering up
2019-02-16T15:40:37.438Z,1550331637.438 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T15:40:37.510Z,1550331637.510 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T15:40:49.411Z,1550331649.411 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-16T15:39:49.1Z
2019-02-16T15:40:49.411Z,1550331649.411 [Startup:StartupSatComms:A] Stopped
2019-02-16T15:40:49.411Z,1550331649.411 [Startup:StartupSatComms:B] Running Loop=1
2019-02-16T15:40:49.727Z,1550331649.727 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-16T15:40:59.084Z,1550331659.084 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004837
2019-02-16T15:40:59.752Z,1550331659.752 [DataOverHttps](INFO): surfaceRadioPower state change: 1
2019-02-16T15:41:04.953Z,1550331664.953 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190216T075601/Courier0034.lzma
2019-02-16T15:41:05.760Z,1550331665.760 [DataOverHttps](INFO): Moved sent file to Logs/20190216T075601/Courier0034.lzma.bak
2019-02-16T15:41:05.760Z,1550331665.760 [DataOverHttps](INFO): SBD MOMSN=9978883
2019-02-16T15:41:30.927Z,1550331690.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-16T15:41:30.927Z,1550331690.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:41:30.929Z,1550331690.929 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-16T15:41:30.929Z,1550331690.929 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:41:30.938Z,1550331690.938 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-16T15:41:30.938Z,1550331690.938 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:41:30.987Z,1550331690.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:41:30.987Z,1550331690.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:41:30.987Z,1550331690.987 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:41:31.387Z,1550331691.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:41:31.387Z,1550331691.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-16T15:41:31.388Z,1550331691.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:41:31.388Z,1550331691.388 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-16T15:41:31.388Z,1550331691.388 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:41:31.389Z,1550331691.389 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-16T15:41:31.731Z,1550331691.731 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:41:49.637Z,1550331709.637 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-16T15:40:49.4Z
2019-02-16T15:41:49.638Z,1550331709.638 [Startup:StartupSatComms:B] Stopped
2019-02-16T15:41:49.659Z,1550331709.659 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-16T15:41:49.660Z,1550331709.660 [Startup:StartupSatComms] Stopped
2019-02-16T15:41:49.660Z,1550331709.660 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-16T15:41:49.661Z,1550331709.661 [Startup](INFO): Completed Startup
2019-02-16T15:41:49.661Z,1550331709.661 [MissionManager](INFO): Startup is completed.
2019-02-16T15:41:49.661Z,1550331709.661 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-16T15:41:49.661Z,1550331709.661 [Startup] Stopped
2019-02-16T15:41:49.661Z,1550331709.661 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-16T15:41:49.661Z,1550331709.661 [Startup:A.GoToSurface] Stopped
2019-02-16T15:41:49.661Z,1550331709.661 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-16T15:41:49.925Z,1550331709.925 [MissionManager](IMPORTANT): Started mission Default
2019-02-16T15:41:49.925Z,1550331709.925 [Default] Running Loop=1
2019-02-16T15:41:49.926Z,1550331709.926 [Default](DEBUG): Aggregate::initialize Default
2019-02-16T15:41:49.930Z,1550331709.930 [Default:B.GoToSurface] Running Loop=1
2019-02-16T15:41:49.930Z,1550331709.930 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-16T15:41:49.930Z,1550331709.930 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-16T15:41:49.931Z,1550331709.931 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-16T15:41:49.931Z,1550331709.931 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-16T15:41:49.931Z,1550331709.931 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-16T15:41:49.932Z,1550331709.932 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-16T15:41:49.932Z,1550331709.932 [Default:A.Wait] Running Loop=1
2019-02-16T15:41:49.932Z,1550331709.932 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-16T15:42:03.264Z,1550331723.264 [Default:A.Wait](INFO): Done Waiting.
2019-02-16T15:42:03.264Z,1550331723.264 [Default:A.Wait] Stopped
2019-02-16T15:42:03.264Z,1550331723.264 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-16T15:42:03.657Z,1550331723.657 [Default:CheckIn] Running Loop=1
2019-02-16T15:42:03.657Z,1550331723.657 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-16T15:42:03.657Z,1550331723.657 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-16T15:42:04.061Z,1550331724.061 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-16T15:42:04.778Z,1550331724.778 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20190216T153819/Courier0000.lzma
2019-02-16T15:42:05.583Z,1550331725.583 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Courier0000.lzma.bak
2019-02-16T15:42:05.583Z,1550331725.583 [DataOverHttps](INFO): SBD MOMSN=9978885
2019-02-16T15:42:33.774Z,1550331753.774 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T15:42:33.774Z,1550331753.774 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-16T15:42:33.774Z,1550331753.774 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T15:42:33.960Z,1550331753.960 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T15:42:34.178Z,1550331754.178 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T15:42:34.411Z,1550331754.411 [Rowe_600LCM](INFO): Powering down
2019-02-16T15:42:35.981Z,1550331755.981 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T15:42:35.981Z,1550331755.981 [Rowe_600LCM] No Fault, FailCount= 2
2019-02-16T15:42:36.026Z,1550331756.026 [Rowe_600LCM](INFO): Initializing
2019-02-16T15:42:36.027Z,1550331756.027 [Rowe_600LCM](INFO): Powering up
2019-02-16T15:42:40.166Z,1550331760.166 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T15:42:40.268Z,1550331760.268 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T15:44:12.480Z,1550331852.480 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-16T15:44:13.294Z,1550331853.294 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:18.158Z,1550331858.158 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:21.382Z,1550331861.382 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:24.210Z,1550331864.210 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:27.434Z,1550331867.434 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:30.262Z,1550331870.262 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:31.913Z,1550331871.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-16T15:44:31.913Z,1550331871.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:44:31.915Z,1550331871.915 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-16T15:44:31.915Z,1550331871.915 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:44:31.916Z,1550331871.916 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-16T15:44:31.916Z,1550331871.916 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:44:31.929Z,1550331871.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:44:31.929Z,1550331871.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:44:31.929Z,1550331871.929 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:44:32.333Z,1550331872.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:44:32.333Z,1550331872.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-16T15:44:32.333Z,1550331872.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:44:32.333Z,1550331872.333 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-16T15:44:32.334Z,1550331872.334 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:44:32.334Z,1550331872.334 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-16T15:44:32.709Z,1550331872.709 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:44:33.094Z,1550331873.094 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:35.110Z,1550331875.110 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:36.550Z,1550331876.550 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T15:44:36.550Z,1550331876.550 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-02-16T15:44:36.550Z,1550331876.550 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T15:44:36.810Z,1550331876.810 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T15:44:36.955Z,1550331876.955 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T15:44:37.123Z,1550331877.123 [Rowe_600LCM](INFO): Powering down
2019-02-16T15:44:38.350Z,1550331878.350 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:38.395Z,1550331878.395 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T15:44:38.395Z,1550331878.395 [Rowe_600LCM] No Fault, FailCount= 3
2019-02-16T15:44:38.738Z,1550331878.738 [Rowe_600LCM](INFO): Initializing
2019-02-16T15:44:38.739Z,1550331878.739 [Rowe_600LCM](INFO): Powering up
2019-02-16T15:44:41.170Z,1550331881.170 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:43.055Z,1550331883.055 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T15:44:43.113Z,1550331883.113 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T15:44:44.410Z,1550331884.410 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:47.230Z,1550331887.230 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:50.462Z,1550331890.462 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:53.290Z,1550331893.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:56.126Z,1550331896.126 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:44:58.138Z,1550331898.138 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:01.370Z,1550331901.370 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:04.198Z,1550331904.198 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:07.438Z,1550331907.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:10.258Z,1550331910.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:13.494Z,1550331913.494 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:16.322Z,1550331916.322 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:19.146Z,1550331919.146 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:21.174Z,1550331921.174 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:24.398Z,1550331924.398 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:27.226Z,1550331927.226 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:30.462Z,1550331930.462 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:33.286Z,1550331933.286 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:36.522Z,1550331936.522 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:39.350Z,1550331939.350 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:42.182Z,1550331942.182 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:44.194Z,1550331944.194 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:46.214Z,1550331946.214 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:49.450Z,1550331949.450 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:52.274Z,1550331952.274 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:55.506Z,1550331955.506 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:45:58.334Z,1550331958.334 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:01.570Z,1550331961.570 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:04.398Z,1550331964.398 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:07.222Z,1550331967.222 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:09.250Z,1550331969.250 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:12.504Z,1550331972.504 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:15.302Z,1550331975.302 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:18.534Z,1550331978.534 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:21.370Z,1550331981.370 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:24.590Z,1550331984.590 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:27.418Z,1550331987.418 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:30.246Z,1550331990.246 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:32.274Z,1550331992.274 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:34.290Z,1550331994.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:37.518Z,1550331997.518 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:39.354Z,1550331999.354 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T15:46:39.354Z,1550331999.354 [Rowe_600LCM] Communications Fault, FailCount= 4
2019-02-16T15:46:39.354Z,1550331999.354 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T15:46:39.606Z,1550331999.606 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T15:46:39.758Z,1550331999.758 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T15:46:39.851Z,1550331999.851 [Rowe_600LCM](INFO): Powering down
2019-02-16T15:46:40.354Z,1550332000.354 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:41.232Z,1550332001.232 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T15:46:41.232Z,1550332001.232 [Rowe_600LCM] No Fault, FailCount= 4
2019-02-16T15:46:41.466Z,1550332001.466 [Rowe_600LCM](INFO): Initializing
2019-02-16T15:46:41.467Z,1550332001.467 [Rowe_600LCM](INFO): Powering up
2019-02-16T15:46:43.578Z,1550332003.578 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:45.643Z,1550332005.643 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T15:46:45.765Z,1550332005.765 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T15:46:46.414Z,1550332006.414 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:49.638Z,1550332009.638 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:52.466Z,1550332012.466 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:55.294Z,1550332015.294 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:57.314Z,1550332017.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:46:59.334Z,1550332019.334 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:02.566Z,1550332022.566 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:03.939Z,1550332023.939 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-16T15:42:03.7Z
2019-02-16T15:47:03.939Z,1550332023.939 [Default:CheckIn:Read_GPS] Stopped
2019-02-16T15:47:03.939Z,1550332023.939 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-16T15:47:04.233Z,1550332024.233 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-16T15:47:05.502Z,1550332025.502 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:06.020Z,1550332026.020 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T153819/Courier0004.lzma
2019-02-16T15:47:06.656Z,1550332026.656 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Courier0004.lzma.bak
2019-02-16T15:47:06.657Z,1550332026.657 [DataOverHttps](INFO): SBD MOMSN=9978892
2019-02-16T15:47:08.326Z,1550332028.326 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:10.346Z,1550332030.346 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:13.578Z,1550332033.578 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:16.406Z,1550332036.406 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:19.638Z,1550332039.638 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:22.466Z,1550332042.466 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:25.698Z,1550332045.698 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:28.526Z,1550332048.526 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:30.002Z,1550332050.002 [DataOverHttps](INFO): Sending 32951 bytes from file Logs/20190216T075601/Express0035.lzma
2019-02-16T15:47:31.354Z,1550332051.354 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:31.964Z,1550332051.964 [DataOverHttps](INFO): Moved sent file to Logs/20190216T075601/Express0035.lzma.bak
2019-02-16T15:47:31.964Z,1550332051.964 [DataOverHttps](INFO): SBD MOMSN=9978894
2019-02-16T15:47:32.993Z,1550332052.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-16T15:47:32.993Z,1550332052.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:47:32.995Z,1550332052.995 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-16T15:47:32.995Z,1550332052.995 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:47:32.996Z,1550332052.996 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-16T15:47:32.996Z,1550332052.996 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:47:33.009Z,1550332053.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:47:33.009Z,1550332053.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:47:33.010Z,1550332053.010 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:47:33.386Z,1550332053.386 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:33.431Z,1550332053.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:47:33.431Z,1550332053.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-16T15:47:33.432Z,1550332053.432 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:47:33.432Z,1550332053.432 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-16T15:47:33.432Z,1550332053.432 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:47:33.433Z,1550332053.433 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-16T15:47:33.839Z,1550332053.839 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:47:36.606Z,1550332056.606 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:39.438Z,1550332059.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:42.666Z,1550332062.666 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:45.502Z,1550332065.502 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:48.726Z,1550332068.726 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:51.554Z,1550332071.554 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:54.390Z,1550332074.390 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:56.402Z,1550332076.402 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:47:58.422Z,1550332078.422 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:01.662Z,1550332081.662 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:04.482Z,1550332084.482 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:07.726Z,1550332087.726 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:10.542Z,1550332090.542 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:13.810Z,1550332093.810 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:16.602Z,1550332096.602 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:19.438Z,1550332099.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:22.662Z,1550332102.662 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:25.490Z,1550332105.490 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:28.722Z,1550332108.722 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:31.550Z,1550332111.550 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:34.786Z,1550332114.786 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:37.618Z,1550332117.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:40.446Z,1550332120.446 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:42.047Z,1550332122.047 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T15:48:42.047Z,1550332122.047 [Rowe_600LCM] Communications Fault, FailCount= 5
2019-02-16T15:48:42.047Z,1550332122.047 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T15:48:42.089Z,1550332122.089 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T15:48:42.089Z,1550332122.089 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2019-02-16T15:48:42.450Z,1550332122.450 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T15:48:42.460Z,1550332122.460 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:42.708Z,1550332122.708 [Rowe_600LCM](INFO): Powering down
2019-02-16T15:48:44.478Z,1550332124.478 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:46.510Z,1550332126.510 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:49.730Z,1550332129.730 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:52.558Z,1550332132.558 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:55.802Z,1550332135.802 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:48:58.618Z,1550332138.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:49:00.784Z,1550332140.784 [DataOverHttps](INFO): Sending 772 bytes from file Logs/20190216T153819/Express0001.lzma
2019-02-16T15:49:01.587Z,1550332141.587 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Express0001.lzma.bak
2019-02-16T15:49:01.588Z,1550332141.588 [DataOverHttps](INFO): SBD MOMSN=9993254
2019-02-16T15:49:01.854Z,1550332141.854 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:49:04.678Z,1550332144.678 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:49:07.506Z,1550332147.506 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:49:09.534Z,1550332149.534 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:49:09.923Z,1550332149.923 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-16T15:49:09.923Z,1550332149.923 [NAL9602] Data Fault, FailCount= 1
2019-02-16T15:49:09.923Z,1550332149.923 [NAL9602](ERROR): Data Fault
2019-02-16T15:49:09.968Z,1550332149.968 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-16T15:49:10.335Z,1550332150.335 [NAL9602](INFO): Powering down
2019-02-16T15:49:11.333Z,1550332151.333 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-16T15:49:11.333Z,1550332151.333 [NAL9602] No Fault, FailCount= 1
2019-02-16T15:49:14.558Z,1550332154.558 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20190216T153819/Express0005.lzma
2019-02-16T15:49:15.363Z,1550332155.363 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Express0005.lzma.bak
2019-02-16T15:49:15.364Z,1550332155.364 [DataOverHttps](INFO): SBD MOMSN=9993270
2019-02-16T15:49:16.011Z,1550332156.011 [Default:CheckIn:Read_Iridium] Stopped
2019-02-16T15:49:16.011Z,1550332156.011 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-16T15:49:16.011Z,1550332156.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-16T15:49:40.623Z,1550332180.623 [NAL9602](INFO): Powering up NAL9602
2019-02-16T15:49:51.531Z,1550332191.531 [NAL9602](INFO): NAL9602 initialized
2019-02-16T15:49:54.370Z,1550332194.370 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:49:57.206Z,1550332197.206 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:00.434Z,1550332200.434 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:03.258Z,1550332203.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:06.090Z,1550332206.090 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:08.109Z,1550332208.109 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:10.126Z,1550332210.126 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:12.146Z,1550332212.146 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:15.378Z,1550332215.378 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:18.218Z,1550332218.218 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:21.446Z,1550332221.446 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:24.266Z,1550332224.266 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:27.498Z,1550332227.498 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:30.326Z,1550332230.326 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:33.154Z,1550332233.154 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:33.996Z,1550332233.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-16T15:50:33.996Z,1550332233.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:50:34.002Z,1550332234.002 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-16T15:50:34.002Z,1550332234.002 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:50:34.003Z,1550332234.003 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-16T15:50:34.003Z,1550332234.003 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:50:34.057Z,1550332234.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:50:34.057Z,1550332234.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:50:34.057Z,1550332234.057 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:50:34.403Z,1550332234.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:50:34.403Z,1550332234.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-16T15:50:34.403Z,1550332234.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:50:34.403Z,1550332234.403 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-16T15:50:34.404Z,1550332234.404 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:50:34.404Z,1550332234.404 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-16T15:50:34.796Z,1550332234.796 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:50:35.182Z,1550332235.182 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:37.198Z,1550332237.198 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:40.426Z,1550332240.426 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:43.258Z,1550332243.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:46.490Z,1550332246.490 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:49.318Z,1550332249.318 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:52.546Z,1550332252.546 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:55.382Z,1550332255.382 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:50:58.214Z,1550332258.214 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:00.222Z,1550332260.222 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:03.454Z,1550332263.454 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:06.293Z,1550332266.293 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:09.522Z,1550332269.522 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:12.342Z,1550332272.342 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:15.578Z,1550332275.578 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:18.406Z,1550332278.406 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:21.236Z,1550332281.236 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:23.254Z,1550332283.254 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:26.490Z,1550332286.490 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:29.314Z,1550332289.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:32.546Z,1550332292.546 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:35.378Z,1550332295.378 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:38.614Z,1550332298.614 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:41.438Z,1550332301.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:44.262Z,1550332304.262 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:46.286Z,1550332306.286 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:48.314Z,1550332308.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:51.534Z,1550332311.534 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:54.366Z,1550332314.366 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:51:57.594Z,1550332317.594 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:00.422Z,1550332320.422 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:03.654Z,1550332323.654 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:06.494Z,1550332326.494 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:09.314Z,1550332329.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:11.330Z,1550332331.330 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:14.566Z,1550332334.566 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:17.390Z,1550332337.390 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:20.622Z,1550332340.622 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:23.450Z,1550332343.450 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:26.682Z,1550332346.682 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:29.514Z,1550332349.514 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:32.338Z,1550332352.338 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:34.366Z,1550332354.366 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:37.590Z,1550332357.590 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:40.418Z,1550332360.418 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:43.654Z,1550332363.654 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:46.478Z,1550332366.478 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:49.718Z,1550332369.718 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:52.546Z,1550332372.546 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:55.366Z,1550332375.366 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:57.390Z,1550332377.390 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:52:59.408Z,1550332379.408 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:02.646Z,1550332382.646 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:05.466Z,1550332385.466 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:08.698Z,1550332388.698 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:11.526Z,1550332391.526 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:14.762Z,1550332394.762 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:17.590Z,1550332397.590 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:20.414Z,1550332400.414 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:22.434Z,1550332402.434 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:25.674Z,1550332405.674 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:28.494Z,1550332408.494 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:31.734Z,1550332411.734 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:33.007Z,1550332413.007 [CBIT](INFO): Clearing failed state for component DropWeight
2019-02-16T15:53:33.007Z,1550332413.007 [DropWeight] No Fault, FailCount= 1
2019-02-16T15:53:34.554Z,1550332414.554 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:35.027Z,1550332415.027 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-02-16T15:53:35.027Z,1550332415.027 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:53:35.028Z,1550332415.028 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-02-16T15:53:35.028Z,1550332415.028 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:53:35.029Z,1550332415.029 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-02-16T15:53:35.029Z,1550332415.029 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:53:35.077Z,1550332415.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:53:35.078Z,1550332415.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:53:35.078Z,1550332415.078 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:53:35.435Z,1550332415.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:53:35.435Z,1550332415.435 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-02-16T15:53:35.436Z,1550332415.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:53:35.436Z,1550332415.436 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-02-16T15:53:35.436Z,1550332415.436 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:53:35.436Z,1550332415.436 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-02-16T15:53:35.804Z,1550332415.804 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:53:37.790Z,1550332417.790 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:40.618Z,1550332420.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:43.442Z,1550332423.442 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:45.466Z,1550332425.466 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:47.486Z,1550332427.486 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:50.726Z,1550332430.726 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:53.550Z,1550332433.550 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:56.774Z,1550332436.774 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:53:59.606Z,1550332439.606 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:02.834Z,1550332442.834 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:05.670Z,1550332445.670 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:08.498Z,1550332448.498 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:10.514Z,1550332450.514 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:13.746Z,1550332453.746 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:16.579Z,1550332456.579 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:16.620Z,1550332456.620 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-16T15:54:16.620Z,1550332456.620 [Default:CheckIn:C.Wait] Stopped
2019-02-16T15:54:16.620Z,1550332456.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-16T15:54:16.620Z,1550332456.620 [Default:CheckIn:D] Running Loop=1
2019-02-16T15:54:17.033Z,1550332457.033 [Default:CheckIn:D] Stopped
2019-02-16T15:54:17.033Z,1550332457.033 [Default:CheckIn:E] Running Loop=1
2019-02-16T15:54:17.438Z,1550332457.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.451758 min 
2019-02-16T15:54:17.440Z,1550332457.440 [Default:CheckIn:E] Stopped
2019-02-16T15:54:17.440Z,1550332457.440 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-16T15:54:17.441Z,1550332457.441 [Default:CheckIn] Stopped
2019-02-16T15:54:17.441Z,1550332457.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-16T15:54:17.441Z,1550332457.441 [Default:CheckIn](INFO): Running loop #2
2019-02-16T15:54:17.441Z,1550332457.441 [Default:CheckIn] Running Loop=2
2019-02-16T15:54:17.441Z,1550332457.441 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-16T15:54:17.441Z,1550332457.441 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-16T15:54:19.802Z,1550332459.802 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:22.642Z,1550332462.642 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:25.870Z,1550332465.870 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:28.694Z,1550332468.694 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:31.526Z,1550332471.526 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:33.542Z,1550332473.542 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:35.566Z,1550332475.566 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:38.794Z,1550332478.794 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:41.618Z,1550332481.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:44.850Z,1550332484.850 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:47.686Z,1550332487.686 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:50.914Z,1550332490.914 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:53.738Z,1550332493.738 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:56.570Z,1550332496.570 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:54:58.586Z,1550332498.586 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:01.826Z,1550332501.826 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:04.646Z,1550332504.646 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:07.882Z,1550332507.882 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:10.726Z,1550332510.726 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:13.946Z,1550332513.946 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:16.766Z,1550332516.766 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:19.598Z,1550332519.598 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:21.618Z,1550332521.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:24.850Z,1550332524.850 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:27.674Z,1550332527.674 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:30.908Z,1550332530.908 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:33.734Z,1550332533.734 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:36.970Z,1550332536.970 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:39.794Z,1550332539.794 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:42.630Z,1550332542.630 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:44.642Z,1550332544.642 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:46.666Z,1550332546.666 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:49.902Z,1550332549.902 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:52.722Z,1550332552.722 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:55.962Z,1550332555.962 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:55:58.786Z,1550332558.786 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:02.018Z,1550332562.018 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:04.846Z,1550332564.846 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:07.690Z,1550332567.690 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:09.702Z,1550332569.702 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:12.926Z,1550332572.926 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:15.762Z,1550332575.762 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:18.998Z,1550332578.998 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:21.822Z,1550332581.822 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:25.058Z,1550332585.058 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:27.886Z,1550332587.886 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:30.718Z,1550332590.718 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:32.738Z,1550332592.738 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:35.966Z,1550332595.966 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:36.038Z,1550332596.038 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-02-16T15:56:36.038Z,1550332596.038 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:56:36.040Z,1550332596.040 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-02-16T15:56:36.040Z,1550332596.040 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:56:36.041Z,1550332596.041 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-02-16T15:56:36.041Z,1550332596.041 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:56:36.070Z,1550332596.070 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:56:36.071Z,1550332596.071 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:56:36.071Z,1550332596.071 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:56:36.475Z,1550332596.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:56:36.475Z,1550332596.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-02-16T15:56:36.475Z,1550332596.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:56:36.475Z,1550332596.475 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-02-16T15:56:36.476Z,1550332596.476 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:56:36.476Z,1550332596.476 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-02-16T15:56:36.834Z,1550332596.834 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:56:38.790Z,1550332598.790 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:42.030Z,1550332602.030 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:44.861Z,1550332604.861 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:48.090Z,1550332608.090 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:50.910Z,1550332610.910 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:53.738Z,1550332613.738 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:55.770Z,1550332615.770 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:56:58.994Z,1550332618.994 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:01.826Z,1550332621.826 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:05.054Z,1550332625.054 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:07.886Z,1550332627.886 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:11.121Z,1550332631.121 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:13.946Z,1550332633.946 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:16.766Z,1550332636.766 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:18.790Z,1550332638.790 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:20.810Z,1550332640.810 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:24.046Z,1550332644.046 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:26.870Z,1550332646.870 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:30.102Z,1550332650.102 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:32.930Z,1550332652.930 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:36.170Z,1550332656.170 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:38.994Z,1550332658.994 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:41.814Z,1550332661.814 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:43.842Z,1550332663.842 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:47.070Z,1550332667.070 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:49.898Z,1550332669.898 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:53.130Z,1550332673.130 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:55.958Z,1550332675.958 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:57:59.190Z,1550332679.190 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:02.018Z,1550332682.018 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:04.850Z,1550332684.850 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:06.870Z,1550332686.870 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:08.882Z,1550332688.882 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:12.118Z,1550332692.118 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:14.945Z,1550332694.945 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:18.186Z,1550332698.186 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:21.022Z,1550332701.022 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:24.238Z,1550332704.238 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:27.066Z,1550332707.066 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:29.894Z,1550332709.894 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:31.914Z,1550332711.914 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:35.148Z,1550332715.148 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:37.970Z,1550332717.970 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:41.210Z,1550332721.210 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:42.090Z,1550332722.090 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T15:58:42.090Z,1550332722.090 [Rowe_600LCM] No Fault, FailCount= 5
2019-02-16T15:58:42.271Z,1550332722.271 [Rowe_600LCM](INFO): Initializing
2019-02-16T15:58:42.271Z,1550332722.271 [Rowe_600LCM](INFO): Powering up
2019-02-16T15:58:44.030Z,1550332724.030 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:46.395Z,1550332726.395 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T15:58:46.517Z,1550332726.517 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T15:58:47.270Z,1550332727.270 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:50.090Z,1550332730.090 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:52.930Z,1550332732.930 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:54.950Z,1550332734.950 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:58:58.174Z,1550332738.174 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:01.002Z,1550332741.002 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:04.230Z,1550332744.230 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:07.062Z,1550332747.062 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:10.290Z,1550332750.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:13.121Z,1550332753.121 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:15.950Z,1550332755.950 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:17.617Z,1550332757.617 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-16T15:54:17.4Z
2019-02-16T15:59:17.617Z,1550332757.617 [Default:CheckIn:Read_GPS] Stopped
2019-02-16T15:59:17.617Z,1550332757.617 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-16T15:59:17.967Z,1550332757.967 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:19.408Z,1550332759.408 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T153819/Courier0007.lzma
2019-02-16T15:59:20.027Z,1550332760.027 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Courier0007.lzma.bak
2019-02-16T15:59:20.028Z,1550332760.028 [DataOverHttps](INFO): SBD MOMSN=9993277
2019-02-16T15:59:21.306Z,1550332761.306 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:24.130Z,1550332764.130 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:26.965Z,1550332766.965 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:28.982Z,1550332768.982 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:30.998Z,1550332770.998 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:33.266Z,1550332773.266 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190216T153819/Express0008.lzma
2019-02-16T15:59:34.071Z,1550332774.071 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Express0008.lzma.bak
2019-02-16T15:59:34.072Z,1550332774.072 [DataOverHttps](INFO): SBD MOMSN=9993279
2019-02-16T15:59:34.234Z,1550332774.234 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:34.692Z,1550332774.692 [Default:CheckIn:Read_Iridium] Stopped
2019-02-16T15:59:34.692Z,1550332774.692 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-16T15:59:34.692Z,1550332774.692 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-16T15:59:37.068Z,1550332777.068 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:37.104Z,1550332777.104 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-02-16T15:59:37.104Z,1550332777.104 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T15:59:37.105Z,1550332777.105 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-02-16T15:59:37.105Z,1550332777.105 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T15:59:37.106Z,1550332777.106 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-02-16T15:59:37.106Z,1550332777.106 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T15:59:37.131Z,1550332777.131 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T15:59:37.132Z,1550332777.132 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T15:59:37.132Z,1550332777.132 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T15:59:37.539Z,1550332777.539 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T15:59:37.539Z,1550332777.539 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-02-16T15:59:37.540Z,1550332777.540 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T15:59:37.540Z,1550332777.540 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-02-16T15:59:37.540Z,1550332777.540 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T15:59:37.540Z,1550332777.540 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-02-16T15:59:37.923Z,1550332777.923 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T15:59:40.298Z,1550332780.298 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:43.118Z,1550332783.118 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:46.354Z,1550332786.354 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:49.190Z,1550332789.190 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:52.006Z,1550332792.006 [NAL9602](DEBUG): Fix Requested
2019-02-16T15:59:52.400Z,1550332792.400 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-16T15:59:52.400Z,1550332792.400 [NAL9602] Data Fault, FailCount= 2
2019-02-16T15:59:52.400Z,1550332792.400 [NAL9602](ERROR): Data Fault
2019-02-16T15:59:52.497Z,1550332792.497 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-16T15:59:52.803Z,1550332792.803 [NAL9602](INFO): Powering down
2019-02-16T15:59:53.667Z,1550332793.667 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-16T15:59:53.667Z,1550332793.667 [NAL9602] No Fault, FailCount= 2
2019-02-16T15:59:59.427Z,1550332799.427 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-02-16T16:00:23.104Z,1550332823.104 [NAL9602](INFO): Powering up NAL9602
2019-02-16T16:00:34.012Z,1550332834.012 [NAL9602](INFO): NAL9602 initialized
2019-02-16T16:00:36.862Z,1550332836.862 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:39.678Z,1550332839.678 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:42.758Z,1550332842.758 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:00:42.758Z,1550332842.758 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-16T16:00:42.758Z,1550332842.758 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:00:42.922Z,1550332842.922 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:42.986Z,1550332842.986 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:00:43.162Z,1550332843.162 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:00:43.382Z,1550332843.382 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:00:44.605Z,1550332844.605 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:00:44.605Z,1550332844.605 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-16T16:00:44.998Z,1550332844.998 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:00:44.999Z,1550332844.999 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:00:45.742Z,1550332845.742 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:48.570Z,1550332848.570 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:49.172Z,1550332849.172 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:00:49.229Z,1550332849.229 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:00:50.600Z,1550332850.600 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:52.618Z,1550332852.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:54.630Z,1550332854.630 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:00:57.866Z,1550332857.866 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:00.686Z,1550332860.686 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:03.922Z,1550332863.922 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:06.746Z,1550332866.746 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:09.986Z,1550332869.986 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:12.806Z,1550332872.806 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:15.686Z,1550332875.686 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:18.870Z,1550332878.870 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:21.702Z,1550332881.702 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:24.930Z,1550332884.930 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:27.762Z,1550332887.762 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:31.000Z,1550332891.000 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:33.834Z,1550332893.834 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:36.646Z,1550332896.646 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:38.670Z,1550332898.670 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:40.698Z,1550332900.698 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:42.718Z,1550332902.718 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:45.942Z,1550332905.942 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:48.770Z,1550332908.770 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:52.002Z,1550332912.002 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:54.838Z,1550332914.838 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:01:58.062Z,1550332918.062 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:00.892Z,1550332920.892 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:03.718Z,1550332923.718 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:05.738Z,1550332925.738 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:08.970Z,1550332928.970 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:11.794Z,1550332931.794 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:15.030Z,1550332935.030 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:17.866Z,1550332937.866 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:21.090Z,1550332941.090 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:23.920Z,1550332943.920 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:26.742Z,1550332946.742 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:29.974Z,1550332949.974 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:32.802Z,1550332952.802 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:36.038Z,1550332956.038 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:38.095Z,1550332958.095 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-02-16T16:02:38.095Z,1550332958.095 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T16:02:38.097Z,1550332958.097 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-02-16T16:02:38.097Z,1550332958.097 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T16:02:38.098Z,1550332958.098 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-02-16T16:02:38.098Z,1550332958.098 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T16:02:38.111Z,1550332958.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T16:02:38.111Z,1550332958.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T16:02:38.111Z,1550332958.111 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T16:02:38.520Z,1550332958.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T16:02:38.520Z,1550332958.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-02-16T16:02:38.520Z,1550332958.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T16:02:38.520Z,1550332958.520 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-02-16T16:02:38.521Z,1550332958.521 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T16:02:38.521Z,1550332958.521 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-02-16T16:02:38.862Z,1550332958.862 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:38.932Z,1550332958.932 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:02:42.106Z,1550332962.106 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:44.922Z,1550332964.922 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:45.494Z,1550332965.494 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:02:45.494Z,1550332965.494 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-16T16:02:45.494Z,1550332965.494 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:02:45.814Z,1550332965.814 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:02:45.898Z,1550332965.898 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:02:46.073Z,1550332966.073 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:02:47.417Z,1550332967.417 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:02:47.418Z,1550332967.418 [Rowe_600LCM] No Fault, FailCount= 2
2019-02-16T16:02:47.690Z,1550332967.690 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:02:47.691Z,1550332967.691 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:02:47.754Z,1550332967.754 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:49.782Z,1550332969.782 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:51.794Z,1550332971.794 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:51.997Z,1550332971.997 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:02:52.089Z,1550332972.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-02-16T16:02:53.814Z,1550332973.814 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:57.060Z,1550332977.060 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:02:59.874Z,1550332979.874 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:03.110Z,1550332983.110 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:05.934Z,1550332985.934 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:09.170Z,1550332989.170 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:11.994Z,1550332991.994 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:14.834Z,1550332994.834 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:16.846Z,1550332996.846 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:20.070Z,1550333000.070 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:22.910Z,1550333002.910 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:26.130Z,1550333006.130 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:28.962Z,1550333008.962 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:32.194Z,1550333012.194 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:35.022Z,1550333015.022 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:37.854Z,1550333017.854 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:39.870Z,1550333019.870 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:43.106Z,1550333023.106 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:45.930Z,1550333025.930 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:49.166Z,1550333029.166 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:51.990Z,1550333031.990 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:55.218Z,1550333035.218 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:03:58.054Z,1550333038.054 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:00.878Z,1550333040.878 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:02.898Z,1550333042.898 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:04.918Z,1550333044.918 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:08.150Z,1550333048.150 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:10.982Z,1550333050.982 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:14.210Z,1550333054.210 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:17.038Z,1550333057.038 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:20.266Z,1550333060.266 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:23.112Z,1550333063.112 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:25.938Z,1550333065.938 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:27.942Z,1550333067.942 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:31.186Z,1550333071.186 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:34.002Z,1550333074.002 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:35.254Z,1550333075.254 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-16T16:04:35.254Z,1550333075.254 [Default:CheckIn:C.Wait] Stopped
2019-02-16T16:04:35.254Z,1550333075.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-16T16:04:35.255Z,1550333075.255 [Default:CheckIn:D] Running Loop=1
2019-02-16T16:04:35.655Z,1550333075.655 [Default:CheckIn:D] Stopped
2019-02-16T16:04:35.655Z,1550333075.655 [Default:CheckIn:E] Running Loop=1
2019-02-16T16:04:36.068Z,1550333076.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.762150 min 
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn:E] Stopped
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn] Stopped
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn](INFO): Running loop #3
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn] Running Loop=3
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-16T16:04:36.069Z,1550333076.069 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-16T16:04:37.238Z,1550333077.238 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:40.070Z,1550333080.070 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:43.294Z,1550333083.294 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:45.736Z,1550333085.736 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-02-16T16:04:46.134Z,1550333086.134 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:48.410Z,1550333088.410 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:04:48.410Z,1550333088.410 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-02-16T16:04:48.410Z,1550333088.410 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:04:48.647Z,1550333088.647 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:04:48.814Z,1550333088.814 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:04:48.954Z,1550333088.954 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:49.085Z,1550333089.085 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:04:50.629Z,1550333090.629 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:04:50.629Z,1550333090.629 [Rowe_600LCM] No Fault, FailCount= 3
2019-02-16T16:04:50.698Z,1550333090.698 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:04:50.699Z,1550333090.699 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:04:50.970Z,1550333090.970 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:53.002Z,1550333093.002 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:54.855Z,1550333094.855 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:04:54.909Z,1550333094.909 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:04:56.226Z,1550333096.226 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:04:59.054Z,1550333099.054 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:02.290Z,1550333102.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:05.114Z,1550333105.114 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:08.350Z,1550333108.350 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:11.170Z,1550333111.170 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:14.038Z,1550333114.038 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:16.026Z,1550333116.026 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:19.254Z,1550333119.254 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:22.082Z,1550333122.082 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:25.314Z,1550333125.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:28.146Z,1550333128.146 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:31.374Z,1550333131.374 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:34.206Z,1550333134.206 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:37.026Z,1550333137.026 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:39.050Z,1550333139.050 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:39.080Z,1550333139.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-02-16T16:05:39.080Z,1550333139.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T16:05:39.082Z,1550333139.082 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-02-16T16:05:39.086Z,1550333139.086 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T16:05:39.087Z,1550333139.087 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-02-16T16:05:39.087Z,1550333139.087 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T16:05:39.112Z,1550333139.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T16:05:39.112Z,1550333139.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T16:05:39.112Z,1550333139.112 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T16:05:39.519Z,1550333139.519 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T16:05:39.519Z,1550333139.519 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-02-16T16:05:39.520Z,1550333139.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T16:05:39.520Z,1550333139.520 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-02-16T16:05:39.520Z,1550333139.520 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T16:05:39.520Z,1550333139.520 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-02-16T16:05:39.888Z,1550333139.888 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:05:41.074Z,1550333141.074 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:44.298Z,1550333144.298 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:47.130Z,1550333147.130 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:50.364Z,1550333150.364 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:53.206Z,1550333153.206 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:56.426Z,1550333156.426 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:05:59.266Z,1550333159.266 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:02.078Z,1550333162.078 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:04.106Z,1550333164.106 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:07.326Z,1550333167.326 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:10.166Z,1550333170.166 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:13.403Z,1550333173.403 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:16.218Z,1550333176.218 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:19.458Z,1550333179.458 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:22.278Z,1550333182.278 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:25.102Z,1550333185.102 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:27.126Z,1550333187.126 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:29.142Z,1550333189.142 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:32.378Z,1550333192.378 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:35.210Z,1550333195.210 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:38.438Z,1550333198.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:41.270Z,1550333201.270 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:44.494Z,1550333204.494 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:47.326Z,1550333207.326 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:50.170Z,1550333210.170 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:51.166Z,1550333211.166 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:06:51.166Z,1550333211.166 [Rowe_600LCM] Communications Fault, FailCount= 4
2019-02-16T16:06:51.166Z,1550333211.166 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:06:51.465Z,1550333211.465 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:06:51.570Z,1550333211.570 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:06:51.741Z,1550333211.741 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:06:52.174Z,1550333212.174 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:53.043Z,1550333213.043 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:06:53.043Z,1550333213.043 [Rowe_600LCM] No Fault, FailCount= 4
2019-02-16T16:06:53.370Z,1550333213.370 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:06:53.371Z,1550333213.371 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:06:55.406Z,1550333215.406 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:06:57.633Z,1550333217.633 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:06:57.768Z,1550333217.768 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:06:58.230Z,1550333218.230 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:01.470Z,1550333221.470 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:04.298Z,1550333224.298 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:07.522Z,1550333227.522 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:10.359Z,1550333230.359 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:13.182Z,1550333233.182 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:15.206Z,1550333235.206 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:18.434Z,1550333238.434 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:21.258Z,1550333241.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:24.498Z,1550333244.498 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:27.342Z,1550333247.342 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:30.558Z,1550333250.558 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:33.382Z,1550333253.382 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:36.214Z,1550333256.214 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:38.234Z,1550333258.234 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:40.246Z,1550333260.246 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:43.482Z,1550333263.482 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:46.310Z,1550333266.310 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:49.554Z,1550333269.554 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:52.370Z,1550333272.370 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:55.602Z,1550333275.602 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:07:58.430Z,1550333278.430 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:01.254Z,1550333281.254 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:03.279Z,1550333283.279 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:06.518Z,1550333286.518 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:09.338Z,1550333289.338 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:12.578Z,1550333292.578 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:15.394Z,1550333295.394 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:18.634Z,1550333298.634 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:21.458Z,1550333301.458 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:24.290Z,1550333304.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:26.314Z,1550333306.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:28.326Z,1550333308.326 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:31.558Z,1550333311.558 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:34.386Z,1550333314.386 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:37.625Z,1550333317.625 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:40.066Z,1550333320.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-02-16T16:08:40.066Z,1550333320.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T16:08:40.068Z,1550333320.068 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-02-16T16:08:40.068Z,1550333320.068 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T16:08:40.069Z,1550333320.069 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-02-16T16:08:40.069Z,1550333320.069 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T16:08:40.084Z,1550333320.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T16:08:40.084Z,1550333320.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T16:08:40.084Z,1550333320.084 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T16:08:40.458Z,1550333320.458 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:40.499Z,1550333320.499 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T16:08:40.499Z,1550333320.499 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-02-16T16:08:40.500Z,1550333320.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T16:08:40.500Z,1550333320.500 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-02-16T16:08:40.500Z,1550333320.500 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T16:08:40.500Z,1550333320.500 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-02-16T16:08:40.887Z,1550333320.887 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:08:43.674Z,1550333323.674 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:46.514Z,1550333326.514 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:49.334Z,1550333329.334 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:51.362Z,1550333331.362 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:53.378Z,1550333333.378 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:54.026Z,1550333334.026 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:08:54.026Z,1550333334.026 [Rowe_600LCM] Communications Fault, FailCount= 5
2019-02-16T16:08:54.026Z,1550333334.026 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:08:54.233Z,1550333334.233 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:08:54.234Z,1550333334.234 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2019-02-16T16:08:54.430Z,1550333334.430 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:08:54.528Z,1550333334.528 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:08:56.606Z,1550333336.606 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:08:59.430Z,1550333339.430 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:02.666Z,1550333342.666 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:05.490Z,1550333345.490 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:08.718Z,1550333348.718 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:11.550Z,1550333351.550 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:14.385Z,1550333354.385 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:16.406Z,1550333356.406 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:19.630Z,1550333359.630 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:22.462Z,1550333362.462 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:25.690Z,1550333365.690 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:28.522Z,1550333368.522 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:31.760Z,1550333371.760 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:32.601Z,1550333372.601 [BPC1](FAULT): Battery stick #1 (s/n: 00A2) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-02-16T16:09:32.602Z,1550333372.602 [BPC1](FAULT): Battery stick #9 (s/n: 007D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-02-16T16:09:32.602Z,1550333372.602 [BPC1](FAULT): Battery stick #12 (s/n: 00B5) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-02-16T16:09:32.603Z,1550333372.603 [BPC1](FAULT): Battery stick #17 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.604Z,1550333372.604 [BPC1](FAULT): Battery stick #18 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-02-16T16:09:32.605Z,1550333372.605 [BPC1](FAULT): Battery stick #19 (s/n: 0095) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.605Z,1550333372.605 [BPC1](FAULT): Battery stick #21 (s/n: 0085) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.606Z,1550333372.606 [BPC1](FAULT): Battery stick #28 (s/n: 008C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-02-16T16:09:32.607Z,1550333372.607 [BPC1](FAULT): Battery stick #29 (s/n: 007C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-02-16T16:09:32.608Z,1550333372.608 [BPC1](FAULT): Battery stick #31 (s/n: 00B6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-02-16T16:09:32.608Z,1550333372.608 [BPC1](FAULT): Battery stick #32 (s/n: 009D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.609Z,1550333372.609 [BPC1](FAULT): Battery stick #33 (s/n: 0093) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-02-16T16:09:32.614Z,1550333372.614 [BPC1](FAULT): Battery stick #34 (s/n: 0068) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-02-16T16:09:32.615Z,1550333372.615 [BPC1](FAULT): Battery stick #36 (s/n: 008A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.615Z,1550333372.615 [BPC1](FAULT): Battery stick #40 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.616Z,1550333372.616 [BPC1](FAULT): Battery stick #43 (s/n: 00A6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.617Z,1550333372.617 [BPC1](FAULT): Battery stick #50 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.618Z,1550333372.618 [BPC1](FAULT): Battery stick #53 (s/n: 0088) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-02-16T16:09:32.618Z,1550333372.618 [BPC1](FAULT): Battery stick #54 (s/n: 0098) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.619Z,1550333372.619 [BPC1](FAULT): Battery stick #56 (s/n: 00AD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4A50.
2019-02-16T16:09:32.620Z,1550333372.620 [BPC1](FAULT): Battery stick #59 (s/n: 00A0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.621Z,1550333372.621 [BPC1](FAULT): Battery stick #61 (s/n: 007F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-02-16T16:09:32.621Z,1550333372.621 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 3.
2019-02-16T16:09:34.594Z,1550333374.594 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:36.220Z,1550333376.220 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-16T16:04:36.1Z
2019-02-16T16:09:36.220Z,1550333376.220 [Default:CheckIn:Read_GPS] Stopped
2019-02-16T16:09:36.220Z,1550333376.220 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-16T16:09:37.410Z,1550333377.410 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:39.426Z,1550333379.426 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:42.014Z,1550333382.014 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20190216T153819/Courier0010.lzma
2019-02-16T16:09:42.654Z,1550333382.654 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:42.819Z,1550333382.819 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Courier0010.lzma.bak
2019-02-16T16:09:42.820Z,1550333382.820 [DataOverHttps](INFO): SBD MOMSN=9993285
2019-02-16T16:09:45.486Z,1550333385.486 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:48.718Z,1550333388.718 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:51.546Z,1550333391.546 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:54.789Z,1550333394.789 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:09:55.687Z,1550333395.687 [DataOverHttps](INFO): Sending 540 bytes from file Logs/20190216T153819/Express0011.lzma
2019-02-16T16:09:56.492Z,1550333396.492 [DataOverHttps](INFO): Moved sent file to Logs/20190216T153819/Express0011.lzma.bak
2019-02-16T16:09:56.492Z,1550333396.492 [DataOverHttps](INFO): SBD MOMSN=9993287
2019-02-16T16:09:57.227Z,1550333397.227 [Default:CheckIn:Read_Iridium] Stopped
2019-02-16T16:09:57.227Z,1550333397.227 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-16T16:09:57.227Z,1550333397.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-16T16:09:57.618Z,1550333397.618 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:00.438Z,1550333400.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:02.458Z,1550333402.458 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:04.478Z,1550333404.478 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:07.710Z,1550333407.710 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:10.534Z,1550333410.534 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:13.770Z,1550333413.770 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:16.590Z,1550333416.590 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:19.830Z,1550333419.830 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:22.658Z,1550333422.658 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:25.486Z,1550333425.486 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:27.506Z,1550333427.506 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:30.738Z,1550333430.738 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:33.566Z,1550333433.566 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:10:36.784Z,1550333436.784 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-16T16:10:36.784Z,1550333436.784 [NAL9602] Data Fault, FailCount= 3
2019-02-16T16:10:36.784Z,1550333436.784 [NAL9602](ERROR): Data Fault
2019-02-16T16:10:36.885Z,1550333436.885 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-16T16:10:37.188Z,1550333437.188 [NAL9602](INFO): Powering down
2019-02-16T16:10:38.051Z,1550333438.051 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-16T16:10:38.051Z,1550333438.051 [NAL9602] No Fault, FailCount= 3
2019-02-16T16:11:07.492Z,1550333467.492 [NAL9602](INFO): Powering up NAL9602
2019-02-16T16:11:18.400Z,1550333478.400 [NAL9602](INFO): NAL9602 initialized
2019-02-16T16:11:19.222Z,1550333479.222 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:22.046Z,1550333482.046 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:25.290Z,1550333485.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:28.106Z,1550333488.106 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:31.346Z,1550333491.346 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:34.166Z,1550333494.166 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:37.002Z,1550333497.002 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:39.018Z,1550333499.018 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:41.063Z,1550333501.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-02-16T16:11:41.063Z,1550333501.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T16:11:41.065Z,1550333501.065 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2019-02-16T16:11:41.065Z,1550333501.065 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T16:11:41.070Z,1550333501.070 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11
2019-02-16T16:11:41.070Z,1550333501.070 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T16:11:41.115Z,1550333501.115 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T16:11:41.115Z,1550333501.115 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T16:11:41.116Z,1550333501.116 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T16:11:41.490Z,1550333501.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T16:11:41.490Z,1550333501.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-02-16T16:11:41.490Z,1550333501.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T16:11:41.491Z,1550333501.491 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2019-02-16T16:11:41.491Z,1550333501.491 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T16:11:41.491Z,1550333501.491 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11
2019-02-16T16:11:41.885Z,1550333501.885 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:11:42.258Z,1550333502.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:45.082Z,1550333505.082 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:48.314Z,1550333508.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:51.138Z,1550333511.138 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:54.370Z,1550333514.370 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:11:57.198Z,1550333517.198 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:00.026Z,1550333520.026 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:02.046Z,1550333522.046 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:05.282Z,1550333525.282 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:08.106Z,1550333528.106 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:11.346Z,1550333531.346 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:14.162Z,1550333534.162 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:17.402Z,1550333537.402 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:20.226Z,1550333540.226 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:23.054Z,1550333543.054 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:25.074Z,1550333545.074 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:28.314Z,1550333548.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:31.134Z,1550333551.134 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:34.366Z,1550333554.366 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:37.194Z,1550333557.194 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:40.430Z,1550333560.430 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:43.250Z,1550333563.250 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:46.082Z,1550333566.082 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:48.102Z,1550333568.102 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:50.118Z,1550333570.118 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:53.354Z,1550333573.354 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:56.178Z,1550333576.178 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:12:59.416Z,1550333579.416 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:02.238Z,1550333582.238 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:05.474Z,1550333585.474 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:08.302Z,1550333588.302 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:11.126Z,1550333591.126 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:13.154Z,1550333593.154 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:15.174Z,1550333595.174 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:18.402Z,1550333598.402 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:21.230Z,1550333601.230 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:24.462Z,1550333604.462 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:27.290Z,1550333607.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:30.522Z,1550333610.522 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:33.354Z,1550333613.354 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:36.186Z,1550333616.186 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:38.202Z,1550333618.202 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:41.426Z,1550333621.426 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:44.258Z,1550333624.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:47.490Z,1550333627.490 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:50.325Z,1550333630.325 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:53.154Z,1550333633.154 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:55.191Z,1550333635.191 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:57.198Z,1550333637.198 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:13:59.218Z,1550333639.218 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:01.238Z,1550333641.238 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:04.466Z,1550333644.466 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:07.314Z,1550333647.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:10.538Z,1550333650.538 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:13.366Z,1550333653.366 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:16.594Z,1550333656.594 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:19.426Z,1550333659.426 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:22.250Z,1550333662.250 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:24.282Z,1550333664.282 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:27.510Z,1550333667.510 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:30.334Z,1550333670.334 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:31.647Z,1550333671.647 [CommandLine](IMPORTANT): got command restart application
2019-02-16T16:14:32.661Z,1550333672.661 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:32.662Z,1550333672.662 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:32.834Z,1550333672.834 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-16T16:14:32.834Z,1550333672.834 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:32.835Z,1550333672.835 [CommandLine](INFO): Join timeout helper Thread ID is 1023
2019-02-16T16:14:32.835Z,1550333672.835 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-16T16:14:32.835Z,1550333672.835 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:32.836Z,1550333672.836 [NavChartDb](INFO): Join timeout helper Thread ID is 1024
2019-02-16T16:14:32.962Z,1550333672.962 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:32.962Z,1550333672.962 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:32.974Z,1550333672.974 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-02-16T16:14:32.974Z,1550333672.974 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:32.974Z,1550333672.974 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1025
2019-02-16T16:14:33.106Z,1550333673.106 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:33.106Z,1550333673.106 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:14:33.306Z,1550333673.306 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:14:33.307Z,1550333673.307 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.310Z,1550333673.310 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-16T16:14:33.311Z,1550333673.311 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.311Z,1550333673.311 [Radio_Surface](INFO): Join timeout helper Thread ID is 1027
2019-02-16T16:14:33.362Z,1550333673.362 [Radio_Surface](INFO): Powering down
2019-02-16T16:14:33.363Z,1550333673.363 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:33.364Z,1550333673.364 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.370Z,1550333673.370 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-02-16T16:14:33.370Z,1550333673.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.370Z,1550333673.370 [DataOverHttps](INFO): Join timeout helper Thread ID is 1028
2019-02-16T16:14:33.506Z,1550333673.506 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:33.507Z,1550333673.507 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.511Z,1550333673.511 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-02-16T16:14:33.511Z,1550333673.511 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.511Z,1550333673.511 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1029
2019-02-16T16:14:33.566Z,1550333673.566 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:14:33.914Z,1550333673.914 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:33.914Z,1550333673.914 [WetLabsBB2FL](INFO): Powering down
2019-02-16T16:14:33.915Z,1550333673.915 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.927Z,1550333673.927 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-02-16T16:14:33.927Z,1550333673.927 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:33.927Z,1550333673.927 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1030
2019-02-16T16:14:34.478Z,1550333674.478 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:34.478Z,1550333674.478 [CTD_Seabird](INFO): Powering down
2019-02-16T16:14:34.498Z,1550333674.498 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:34.519Z,1550333674.519 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-16T16:14:34.519Z,1550333674.519 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:34.519Z,1550333674.519 [logger](INFO): Join timeout helper Thread ID is 1031
2019-02-16T16:14:34.524Z,1550333674.524 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:34.525Z,1550333674.525 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:34.526Z,1550333674.526 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-16T16:14:34.526Z,1550333674.526 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:34.527Z,1550333674.527 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-16T16:14:34.527Z,1550333674.527 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:34.527Z,1550333674.527 [controlThread](INFO): Join timeout helper Thread ID is 1032
2019-02-16T16:14:34.750Z,1550333674.750 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T16:14:34.750Z,1550333674.750 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-16T16:14:34.751Z,1550333674.751 [Aanderaa_O2](INFO): Powering down
2019-02-16T16:14:34.753Z,1550333674.753 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-02-16T16:14:34.871Z,1550333674.871 [NAL9602](INFO): Powering down
2019-02-16T16:14:34.872Z,1550333674.872 [PNI_TCM](INFO): Powering down
2019-02-16T16:14:34.959Z,1550333674.959 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-16T16:14:34.960Z,1550333674.960 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-16T16:14:34.961Z,1550333674.961 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-16T16:14:34.961Z,1550333674.961 [MissionManager](INFO): Uninitializing Mission Default
2019-02-16T16:14:34.961Z,1550333674.961 [Default] Stopped
2019-02-16T16:14:34.961Z,1550333674.961 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-16T16:14:34.961Z,1550333674.961 [Default:B.GoToSurface] Stopped
2019-02-16T16:14:34.961Z,1550333674.961 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-16T16:14:34.962Z,1550333674.962 [Default:CheckIn] Stopped
2019-02-16T16:14:34.962Z,1550333674.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-16T16:14:34.962Z,1550333674.962 [Default:CheckIn:C.Wait] Stopped
2019-02-16T16:14:34.962Z,1550333674.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-16T16:14:34.965Z,1550333674.965 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-16T16:14:34.966Z,1550333674.966 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-16T16:14:34.966Z,1550333674.966 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-16T16:14:34.966Z,1550333674.966 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-16T16:14:34.967Z,1550333674.967 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-16T16:14:34.967Z,1550333674.967 [BuoyancyServo](INFO): Powering down
2019-02-16T16:14:34.982Z,1550333674.982 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-16T16:14:34.982Z,1550333674.982 [ElevatorServo](INFO): Powering down
2019-02-16T16:14:34.983Z,1550333674.983 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-16T16:14:34.983Z,1550333674.983 [MassServo](INFO): Powering down
2019-02-16T16:14:34.984Z,1550333674.984 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-16T16:14:34.984Z,1550333674.984 [RudderServo](INFO): Powering down
2019-02-16T16:14:34.985Z,1550333674.985 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-16T16:14:34.985Z,1550333674.985 [ThrusterServo](INFO): Powering down
2019-02-16T16:14:34.986Z,1550333674.986 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-16T16:14:34.987Z,1550333674.987 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-16T16:14:34.987Z,1550333674.987 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-16T16:14:34.987Z,1550333674.987 [CBIT](DEBUG): Powering off loads.
2019-02-16T16:14:34.998Z,1550333674.998 [CBIT](DEBUG): Disabling WDT.
2019-02-16T16:14:35.010Z,1550333675.010 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-16T16:14:35.011Z,1550333675.011 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.072Z,1550333675.072 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.078Z,1550333675.078 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.163Z,1550333675.163 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.168Z,1550333675.168 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.174Z,1550333675.174 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.229Z,1550333675.229 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-16T16:14:35.287Z,1550333675.287 [logger ThreadHandler](INFO): Thread cancelled.