2019-03-26T16:08:14.032Z,1553616494.032 [Supervisor](DEBUG): Initializing supervisor.
2019-03-26T16:08:14.035Z,1553616494.035 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-26T16:08:14.035Z,1553616494.035 [SyncHandler](INFO): Protected caller Thread ID is 5693
2019-03-26T16:08:14.036Z,1553616494.036 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-26T16:08:14.037Z,1553616494.037 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-26T16:08:14.038Z,1553616494.038 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5694
2019-03-26T16:08:14.041Z,1553616494.041 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-26T16:08:14.052Z,1553616494.052 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-26T16:08:14.053Z,1553616494.053 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-26T16:08:14.054Z,1553616494.054 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5695
2019-03-26T16:08:14.055Z,1553616494.055 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-26T16:08:14.056Z,1553616494.056 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-26T16:08:14.056Z,1553616494.056 [logger ThreadHandler](INFO): Protected caller Thread ID is 5696
2019-03-26T16:08:14.058Z,1553616494.058 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-26T16:08:14.058Z,1553616494.058 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-26T16:08:14.060Z,1553616494.060 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-26T16:08:14.258Z,1553616494.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-26T16:08:14.259Z,1553616494.259 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-26T16:08:14.339Z,1553616494.339 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-26T16:08:14.766Z,1553616494.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-26T16:08:14.767Z,1553616494.767 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-26T16:08:15.089Z,1553616495.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-26T16:08:15.089Z,1553616495.089 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-26T16:08:15.185Z,1553616495.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-26T16:08:15.186Z,1553616495.186 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-26T16:08:15.480Z,1553616495.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-26T16:08:15.481Z,1553616495.481 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-26T16:08:15.674Z,1553616495.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-26T16:08:15.674Z,1553616495.674 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-26T16:08:16.116Z,1553616496.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-26T16:08:16.116Z,1553616496.116 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-26T16:08:16.222Z,1553616496.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-26T16:08:16.222Z,1553616496.222 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-26T16:08:16.323Z,1553616496.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-26T16:08:16.323Z,1553616496.323 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-26T16:08:16.805Z,1553616496.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-26T16:08:16.806Z,1553616496.806 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-26T16:08:17.191Z,1553616497.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-26T16:08:17.192Z,1553616497.192 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-26T16:08:17.385Z,1553616497.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-26T16:08:17.386Z,1553616497.386 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-26T16:08:17.529Z,1553616497.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-26T16:08:17.530Z,1553616497.530 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-26T16:08:17.680Z,1553616497.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-26T16:08:17.682Z,1553616497.682 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-03-26T16:08:17.683Z,1553616497.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-03-26T16:08:17.774Z,1553616497.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-03-26T16:08:17.860Z,1553616497.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-03-26T16:08:17.962Z,1553616497.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-03-26T16:08:18.045Z,1553616498.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-03-26T16:08:18.140Z,1553616498.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-03-26T16:08:18.240Z,1553616498.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-03-26T16:08:18.451Z,1553616498.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-03-26T16:08:18.567Z,1553616498.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-03-26T16:08:18.926Z,1553616498.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-03-26T16:08:19.100Z,1553616499.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-03-26T16:08:19.379Z,1553616499.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-03-26T16:08:19.727Z,1553616499.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-26T16:08:19.729Z,1553616499.729 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-03-26T16:08:19.729Z,1553616499.729 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-26T16:08:19.732Z,1553616499.732 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-26T16:08:19.796Z,1553616499.796 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-26T16:08:19.906Z,1553616499.906 [VerticalControl] Loaded
2019-03-26T16:08:19.906Z,1553616499.906 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-26T16:08:19.907Z,1553616499.907 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-26T16:08:19.973Z,1553616499.973 [HorizontalControl] Loaded
2019-03-26T16:08:19.973Z,1553616499.973 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-26T16:08:19.974Z,1553616499.974 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-26T16:08:19.979Z,1553616499.979 [SpeedControl] Loaded
2019-03-26T16:08:19.979Z,1553616499.979 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-26T16:08:19.980Z,1553616499.980 [LoopControl](DEBUG): Construct LoopControl.
2019-03-26T16:08:19.981Z,1553616499.981 [LoopControl] Loaded
2019-03-26T16:08:19.981Z,1553616499.981 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-26T16:08:19.981Z,1553616499.981 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-26T16:08:19.982Z,1553616499.982 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-26T16:08:19.995Z,1553616499.995 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-26T16:08:19.995Z,1553616499.995 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-26T16:08:20.089Z,1553616500.089 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-26T16:08:20.089Z,1553616500.089 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-26T16:08:20.206Z,1553616500.206 [BuoyancyServo] Loaded
2019-03-26T16:08:20.207Z,1553616500.207 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-26T16:08:20.218Z,1553616500.218 [ElevatorServo] Loaded
2019-03-26T16:08:20.218Z,1553616500.218 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-26T16:08:20.228Z,1553616500.228 [MassServo] Loaded
2019-03-26T16:08:20.228Z,1553616500.228 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-26T16:08:20.239Z,1553616500.239 [RudderServo] Loaded
2019-03-26T16:08:20.239Z,1553616500.239 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-26T16:08:20.250Z,1553616500.250 [ThrusterServo] Loaded
2019-03-26T16:08:20.250Z,1553616500.250 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-26T16:08:20.250Z,1553616500.250 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-26T16:08:20.251Z,1553616500.251 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-26T16:08:20.354Z,1553616500.354 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-26T16:08:20.354Z,1553616500.354 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-26T16:08:20.411Z,1553616500.411 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-26T16:08:20.412Z,1553616500.412 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-26T16:08:20.468Z,1553616500.468 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-26T16:08:20.468Z,1553616500.468 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-26T16:08:20.488Z,1553616500.488 [NavChart] Loaded
2019-03-26T16:08:20.488Z,1553616500.488 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-26T16:08:20.492Z,1553616500.492 [UniversalFixResidualReporter] Loaded
2019-03-26T16:08:20.492Z,1553616500.492 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-26T16:08:20.493Z,1553616500.493 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-26T16:08:20.493Z,1553616500.493 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-26T16:08:20.555Z,1553616500.555 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-26T16:08:20.555Z,1553616500.555 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-26T16:08:20.860Z,1553616500.860 [DataOverHttps] Loaded
2019-03-26T16:08:20.861Z,1553616500.861 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-26T16:08:20.862Z,1553616500.862 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4086D4E0
2019-03-26T16:08:20.862Z,1553616500.862 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5778
2019-03-26T16:08:20.875Z,1553616500.875 [Depth_Keller] Loaded
2019-03-26T16:08:20.876Z,1553616500.876 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-26T16:08:20.880Z,1553616500.880 [DropWeight] Loaded
2019-03-26T16:08:20.881Z,1553616500.881 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-26T16:08:21.007Z,1553616501.007 [DVL_micro] Loaded
2019-03-26T16:08:21.007Z,1553616501.007 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-03-26T16:08:21.101Z,1553616501.101 [NAL9602] Loaded
2019-03-26T16:08:21.101Z,1553616501.101 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-26T16:08:21.116Z,1553616501.116 [Onboard] Loaded
2019-03-26T16:08:21.116Z,1553616501.116 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-26T16:08:21.119Z,1553616501.119 [Radio_Surface] Loaded
2019-03-26T16:08:21.120Z,1553616501.120 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-26T16:08:21.121Z,1553616501.121 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4089D4E0
2019-03-26T16:08:21.121Z,1553616501.121 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5779
2019-03-26T16:08:22.569Z,1553616502.569 [BPC1] Loaded
2019-03-26T16:08:22.569Z,1553616502.569 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-26T16:08:22.570Z,1553616502.570 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-26T16:08:22.570Z,1553616502.570 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-26T16:08:22.609Z,1553616502.609 [DepthRateCalculator] Loaded
2019-03-26T16:08:22.610Z,1553616502.610 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-26T16:08:22.615Z,1553616502.615 [PitchRateCalculator] Loaded
2019-03-26T16:08:22.615Z,1553616502.615 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-26T16:08:22.627Z,1553616502.627 [SpeedCalculator] Loaded
2019-03-26T16:08:22.627Z,1553616502.627 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-26T16:08:22.648Z,1553616502.648 [TempGradientCalculator] Loaded
2019-03-26T16:08:22.648Z,1553616502.648 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-26T16:08:22.653Z,1553616502.653 [YawRateCalculator] Loaded
2019-03-26T16:08:22.653Z,1553616502.653 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-26T16:08:22.682Z,1553616502.682 [ElevatorOffsetCalculator] Loaded
2019-03-26T16:08:22.682Z,1553616502.682 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-26T16:08:22.682Z,1553616502.682 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-26T16:08:22.683Z,1553616502.683 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-26T16:08:22.921Z,1553616502.921 [CTD_NeilBrown] Loaded
2019-03-26T16:08:22.922Z,1553616502.922 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-26T16:08:22.923Z,1553616502.923 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4099E4E0
2019-03-26T16:08:22.923Z,1553616502.923 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5780
2019-03-26T16:08:22.932Z,1553616502.932 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-03-26T16:08:22.937Z,1553616502.937 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-03-26T16:08:22.939Z,1553616502.939 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-03-26T16:08:22.944Z,1553616502.944 [CTD_Seabird](INFO): created writer for : depth
2019-03-26T16:08:22.944Z,1553616502.944 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-03-26T16:08:22.949Z,1553616502.949 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-03-26T16:08:22.950Z,1553616502.950 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-03-26T16:08:22.955Z,1553616502.955 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-03-26T16:08:22.955Z,1553616502.955 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-03-26T16:08:22.960Z,1553616502.960 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-03-26T16:08:22.961Z,1553616502.961 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-03-26T16:08:22.966Z,1553616502.966 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-03-26T16:08:22.966Z,1553616502.966 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-03-26T16:08:22.971Z,1553616502.971 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-03-26T16:08:22.998Z,1553616502.998 [CTD_Seabird] Loaded
2019-03-26T16:08:22.998Z,1553616502.998 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-03-26T16:08:22.999Z,1553616502.999 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409CE4E0
2019-03-26T16:08:22.999Z,1553616502.999 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5781
2019-03-26T16:08:23.025Z,1553616503.025 [ESPComponent] Loaded
2019-03-26T16:08:23.025Z,1553616503.025 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-03-26T16:08:23.039Z,1553616503.039 [PAR_Licor] Loaded
2019-03-26T16:08:23.040Z,1553616503.040 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-03-26T16:08:23.085Z,1553616503.085 [WetLabsBB2FL] Loaded
2019-03-26T16:08:23.086Z,1553616503.086 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-26T16:08:23.087Z,1553616503.087 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0
2019-03-26T16:08:23.087Z,1553616503.087 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5782
2019-03-26T16:08:23.088Z,1553616503.088 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-26T16:08:23.088Z,1553616503.088 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-26T16:08:23.114Z,1553616503.114 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-26T16:08:23.114Z,1553616503.114 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-26T16:08:23.372Z,1553616503.372 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-26T16:08:23.372Z,1553616503.372 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-26T16:08:23.497Z,1553616503.497 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-26T16:08:23.508Z,1553616503.508 [SBIT] Loaded
2019-03-26T16:08:23.508Z,1553616503.508 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-26T16:08:23.509Z,1553616503.509 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-26T16:08:23.521Z,1553616503.521 [IBIT] Loaded
2019-03-26T16:08:23.521Z,1553616503.521 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-26T16:08:23.524Z,1553616503.524 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-26T16:08:23.661Z,1553616503.661 [CBIT] Loaded
2019-03-26T16:08:23.661Z,1553616503.661 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-26T16:08:23.661Z,1553616503.661 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-26T16:08:23.702Z,1553616503.702 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-26T16:08:23.703Z,1553616503.703 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-26T16:08:23.712Z,1553616503.712 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-26T16:08:23.731Z,1553616503.731 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-03-26T16:08:23.732Z,1553616503.732 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5783
2019-03-26T16:08:23.768Z,1553616503.768 [Supervisor](INFO): Main Thread ID is 5534
2019-03-26T16:08:23.768Z,1553616503.768 [Supervisor](DEBUG): Running supervisor.
2019-03-26T16:08:23.769Z,1553616503.769 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5784
2019-03-26T16:08:23.775Z,1553616503.775 [controlThread ThreadHandler](INFO): Handler Thread ID is 5785
2019-03-26T16:08:23.775Z,1553616503.775 [controlThread](DEBUG): Initializing ControlThread
2019-03-26T16:08:23.776Z,1553616503.776 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-26T16:08:23.778Z,1553616503.778 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-26T16:08:23.778Z,1553616503.778 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-26T16:08:23.779Z,1553616503.779 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-26T16:08:23.780Z,1553616503.780 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:08:23.781Z,1553616503.781 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-26T16:08:23.782Z,1553616503.782 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-26T16:08:23.787Z,1553616503.787 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-26T16:08:23.787Z,1553616503.787 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-26T16:08:23.788Z,1553616503.788 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-26T16:08:23.788Z,1553616503.788 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-26T16:08:23.789Z,1553616503.789 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-26T16:08:23.789Z,1553616503.789 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-26T16:08:23.791Z,1553616503.791 [SBIT](INFO): Initialize SBIT Component.
2019-03-26T16:08:23.791Z,1553616503.791 [SBIT](IMPORTANT): git: 2019-03-18-25-g41eb097
2019-03-26T16:08:23.791Z,1553616503.791 [SBIT](INFO): git hash: 41eb097d057d44a6b31f942ace2973a2253fba2e
2019-03-26T16:08:23.792Z,1553616503.792 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-26T16:08:23.801Z,1553616503.801 [logger ThreadHandler](INFO): Handler Thread ID is 5786
2019-03-26T16:08:23.825Z,1553616503.825 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-03-26T16:08:23.826Z,1553616503.826 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-03-26T16:08:23.827Z,1553616503.827 [IBIT](INFO): Initialize IBIT Component.
2019-03-26T16:08:23.828Z,1553616503.828 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-26T16:08:23.833Z,1553616503.833 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5787
2019-03-26T16:08:23.834Z,1553616503.834 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-26T16:08:23.841Z,1553616503.841 [CBIT](DEBUG): Initialized mux pins.
2019-03-26T16:08:23.841Z,1553616503.841 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-26T16:08:23.842Z,1553616503.842 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5788
2019-03-26T16:08:23.843Z,1553616503.843 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5789
2019-03-26T16:08:23.844Z,1553616503.844 [CTD_NeilBrown](INFO): Powering down
2019-03-26T16:08:23.865Z,1553616503.865 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5790
2019-03-26T16:08:23.866Z,1553616503.866 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5791
2019-03-26T16:08:23.867Z,1553616503.867 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5792
2019-03-26T16:08:23.873Z,1553616503.873 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-26T16:08:23.921Z,1553616503.921 [CBIT](DEBUG): Initializing heartbeat.
2019-03-26T16:08:23.925Z,1553616503.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-26T16:08:23.925Z,1553616503.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-26T16:08:23.925Z,1553616503.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-26T16:08:23.925Z,1553616503.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-26T16:08:23.926Z,1553616503.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-26T16:08:23.926Z,1553616503.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-26T16:08:23.926Z,1553616503.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-26T16:08:23.926Z,1553616503.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-26T16:08:23.926Z,1553616503.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-26T16:08:23.927Z,1553616503.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-26T16:08:23.927Z,1553616503.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-26T16:08:23.927Z,1553616503.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-26T16:08:23.927Z,1553616503.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-26T16:08:23.927Z,1553616503.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-26T16:08:23.928Z,1553616503.928 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-26T16:08:23.928Z,1553616503.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-26T16:08:23.941Z,1553616503.941 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-26T16:08:23.943Z,1553616503.943 [WetLabsBB2FL](INFO): Powering down
2019-03-26T16:08:23.949Z,1553616503.949 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-26T16:08:23.993Z,1553616503.993 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-26T16:08:23.993Z,1553616503.993 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-26T16:08:24.029Z,1553616504.029 [CBIT](DEBUG): Backplane powered.
2019-03-26T16:08:24.030Z,1553616504.030 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-26T16:08:24.039Z,1553616504.039 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-26T16:08:24.050Z,1553616504.050 [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-03-26T16:08:24.051Z,1553616504.051 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-26T16:08:24.118Z,1553616504.118 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-26T16:08:24.120Z,1553616504.120 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-26T16:08:24.137Z,1553616504.137 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-26T16:08:24.164Z,1553616504.164 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-26T16:08:24.166Z,1553616504.166 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-26T16:08:24.181Z,1553616504.181 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-26T16:08:24.184Z,1553616504.184 [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-03-26T16:08:24.205Z,1553616504.205 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-26T16:08:24.218Z,1553616504.218 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-03-26T16:08:24.240Z,1553616504.240 [DVL_micro](INFO): Initializing
2019-03-26T16:08:24.245Z,1553616504.245 [Radio_Surface](INFO): Powering up
2019-03-26T16:08:24.317Z,1553616504.317 [ESPComponent](INFO): powering down ESP
2019-03-26T16:08:24.637Z,1553616504.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-26T16:08:24.677Z,1553616504.677 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-26T16:08:24.685Z,1553616504.685 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-26T16:08:24.686Z,1553616504.686 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-26T16:08:24.693Z,1553616504.693 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-26T16:08:24.694Z,1553616504.694 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-26T16:08:24.701Z,1553616504.701 [MassServo](DEBUG): Initializing MassServo.
2019-03-26T16:08:24.702Z,1553616504.702 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-26T16:08:24.709Z,1553616504.709 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-26T16:08:24.710Z,1553616504.710 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-26T16:08:24.717Z,1553616504.717 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-26T16:08:24.883Z,1553616504.883 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-03-26T16:08:24.883Z,1553616504.883 [DropWeight] Hardware Fault, FailCount= 1
2019-03-26T16:08:24.883Z,1553616504.883 [DropWeight](ERROR): Hardware Fault
2019-03-26T16:08:24.950Z,1553616504.950 [CommandLine](FAULT): Scheduling is paused
2019-03-26T16:08:24.951Z,1553616504.951 [CBIT](INFO): Critical error at 20190326T160824
2019-03-26T16:08:24.951Z,1553616504.951 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-26T16:08:24.954Z,1553616504.954 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-03-26T16:08:24.954Z,1553616504.954 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-03-26T16:08:25.203Z,1553616505.203 [CBIT](INFO): Critical error at 20190326T160824
2019-03-26T16:08:31.958Z,1553616511.958 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-03-26T16:08:34.078Z,1553616514.078 [CBIT](CRITICAL): Environmental Failure. Press:14.766173 PSI. Humidity:33%. Temp:26 C. ABORTING MISSION
2019-03-26T16:08:34.512Z,1553616514.512 [CBIT](INFO): Critical error at 20190326T160834
2019-03-26T16:08:35.234Z,1553616515.234 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:08:35.234Z,1553616515.234 [DVL_micro] Communications Fault, FailCount= 1
2019-03-26T16:08:35.234Z,1553616515.234 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:08:35.310Z,1553616515.310 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:08:35.709Z,1553616515.709 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:08:36.523Z,1553616516.523 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:08:36.523Z,1553616516.523 [DVL_micro] No Fault, FailCount= 1
2019-03-26T16:08:36.856Z,1553616516.856 [DVL_micro](INFO): Initializing
2019-03-26T16:08:47.354Z,1553616527.354 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:08:47.354Z,1553616527.354 [DVL_micro] Communications Fault, FailCount= 2
2019-03-26T16:08:47.354Z,1553616527.354 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:08:47.407Z,1553616527.407 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-26T16:08:47.411Z,1553616527.411 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:08:47.411Z,1553616527.411 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-26T16:08:47.829Z,1553616527.829 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:08:48.650Z,1553616528.650 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:08:48.650Z,1553616528.650 [DVL_micro] No Fault, FailCount= 2
2019-03-26T16:08:48.976Z,1553616528.976 [DVL_micro](INFO): Initializing
2019-03-26T16:08:51.482Z,1553616531.482 [NAL9602](INFO): Powering up NAL9602
2019-03-26T16:08:58.806Z,1553616538.806 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.001617
CHAN A1 (24V): -0.133387
CHAN A2 (12V): -0.002513
CHAN A3 (5V): -0.001807
CHAN B0 (3.3V): -0.000354
CHAN B1 (3.15aV): -0.000390
CHAN B2 (3.15bV): 0.000111
CHAN B3 (GND): 0.000277
OPEN: -0.000386
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-26T16:08:59.562Z,1553616539.562 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:08:59.562Z,1553616539.562 [DVL_micro] Communications Fault, FailCount= 3
2019-03-26T16:08:59.562Z,1553616539.562 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:08:59.632Z,1553616539.632 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:09:00.037Z,1553616540.037 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:09:00.851Z,1553616540.851 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:09:00.851Z,1553616540.851 [DVL_micro] No Fault, FailCount= 3
2019-03-26T16:09:01.184Z,1553616541.184 [DVL_micro](INFO): Initializing
2019-03-26T16:09:02.390Z,1553616542.390 [NAL9602](INFO): NAL9602 initialized
2019-03-26T16:09:03.345Z,1553616543.345 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:09:11.806Z,1553616551.806 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:09:11.806Z,1553616551.806 [DVL_micro] Communications Fault, FailCount= 4
2019-03-26T16:09:11.806Z,1553616551.806 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:09:11.906Z,1553616551.906 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:09:12.285Z,1553616552.285 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:09:13.106Z,1553616553.106 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:09:13.106Z,1553616553.106 [DVL_micro] No Fault, FailCount= 4
2019-03-26T16:09:13.432Z,1553616553.432 [DVL_micro](INFO): Initializing
2019-03-26T16:09:23.926Z,1553616563.926 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:09:23.926Z,1553616563.926 [DVL_micro] Communications Fault, FailCount= 5
2019-03-26T16:09:23.926Z,1553616563.926 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:09:23.978Z,1553616563.978 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:09:23.978Z,1553616563.978 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-03-26T16:09:24.405Z,1553616564.405 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:09:24.443Z,1553616564.443 [CBIT](INFO): Critical error at 20190326T160923
2019-03-26T16:09:41.037Z,1553616581.037 [SBIT](IMPORTANT): SBIT PASSED
2019-03-26T16:09:41.130Z,1553616581.130 [CommandLine](IMPORTANT): got command configSet list
2019-03-26T16:09:41.130Z,1553616581.130 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-26T16:09:41.131Z,1553616581.131 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-26T16:09:41.431Z,1553616581.431 [MissionManager](IMPORTANT): Started mission Startup
2019-03-26T16:09:41.431Z,1553616581.431 [Startup] Running Loop=1
2019-03-26T16:09:41.431Z,1553616581.431 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-26T16:09:41.431Z,1553616581.431 [Startup:A.GoToSurface] Running Loop=1
2019-03-26T16:09:41.432Z,1553616581.432 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-26T16:09:41.432Z,1553616581.432 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-26T16:09:41.433Z,1553616581.433 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-26T16:09:41.434Z,1553616581.434 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-26T16:09:41.434Z,1553616581.434 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-26T16:09:41.435Z,1553616581.435 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-26T16:09:41.436Z,1553616581.436 [Startup:StartupSatComms] Running Loop=1
2019-03-26T16:09:41.436Z,1553616581.436 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-26T16:09:41.436Z,1553616581.436 [Startup:StartupSatComms:A] Running Loop=1
2019-03-26T16:09:41.835Z,1553616581.835 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-26T16:10:23.852Z,1553616623.852 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:10:23.852Z,1553616623.852 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-26T16:10:23.852Z,1553616623.852 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:10:23.855Z,1553616623.855 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:10:23.855Z,1553616623.855 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-26T16:10:23.855Z,1553616623.855 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:10:23.856Z,1553616623.856 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:10:23.856Z,1553616623.856 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-26T16:10:23.856Z,1553616623.856 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:10:23.868Z,1553616623.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:10:23.868Z,1553616623.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:10:23.868Z,1553616623.868 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:10:24.267Z,1553616624.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:10:24.267Z,1553616624.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-26T16:10:24.267Z,1553616624.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:10:24.268Z,1553616624.268 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-26T16:10:24.268Z,1553616624.268 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:10:24.268Z,1553616624.268 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-26T16:10:24.646Z,1553616624.646 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:10:41.616Z,1553616641.616 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-26T16:09:41.4Z
2019-03-26T16:10:41.617Z,1553616641.617 [Startup:StartupSatComms:A] Stopped
2019-03-26T16:10:41.617Z,1553616641.617 [Startup:StartupSatComms:B] Running Loop=1
2019-03-26T16:10:42.071Z,1553616642.071 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-26T16:10:42.406Z,1553616642.406 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-03-26T16:11:02.503Z,1553616662.503 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004848
2019-03-26T16:11:07.340Z,1553616667.340 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190326T155803/Courier0007.lzma
2019-03-26T16:11:08.146Z,1553616668.146 [DataOverHttps](INFO): Moved sent file to Logs/20190326T155803/Courier0007.lzma.bak
2019-03-26T16:11:08.147Z,1553616668.147 [DataOverHttps](INFO): SBD MOMSN=10250354
2019-03-26T16:11:18.513Z,1553616678.513 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20190326T160814/Courier0000.lzma
2019-03-26T16:11:19.318Z,1553616679.318 [DataOverHttps](INFO): Moved sent file to Logs/20190326T160814/Courier0000.lzma.bak
2019-03-26T16:11:19.318Z,1553616679.318 [DataOverHttps](INFO): SBD MOMSN=10250356
2019-03-26T16:11:29.685Z,1553616689.685 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20190326T155803/Express0008.lzma
2019-03-26T16:11:30.490Z,1553616690.490 [DataOverHttps](INFO): Moved sent file to Logs/20190326T155803/Express0008.lzma.bak
2019-03-26T16:11:30.490Z,1553616690.490 [DataOverHttps](INFO): SBD MOMSN=10250363
2019-03-26T16:11:40.905Z,1553616700.905 [DataOverHttps](INFO): Sending 772 bytes from file Logs/20190326T160814/Express0001.lzma
2019-03-26T16:11:41.710Z,1553616701.710 [DataOverHttps](INFO): Moved sent file to Logs/20190326T160814/Express0001.lzma.bak
2019-03-26T16:11:41.710Z,1553616701.710 [DataOverHttps](INFO): SBD MOMSN=10250370
2019-03-26T16:11:41.830Z,1553616701.830 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-26T16:10:41.6Z
2019-03-26T16:11:41.830Z,1553616701.830 [Startup:StartupSatComms:B] Stopped
2019-03-26T16:11:41.830Z,1553616701.830 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-26T16:11:41.830Z,1553616701.830 [Startup:StartupSatComms] Stopped
2019-03-26T16:11:41.831Z,1553616701.831 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-26T16:11:41.831Z,1553616701.831 [Startup](INFO): Completed Startup
2019-03-26T16:11:41.831Z,1553616701.831 [MissionManager](INFO): Startup is completed.
2019-03-26T16:11:41.832Z,1553616701.832 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-26T16:11:41.832Z,1553616701.832 [Startup] Stopped
2019-03-26T16:11:41.832Z,1553616701.832 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-26T16:11:41.832Z,1553616701.832 [Startup:A.GoToSurface] Stopped
2019-03-26T16:11:41.832Z,1553616701.832 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-26T16:11:42.226Z,1553616702.226 [MissionManager](IMPORTANT): Started mission Default
2019-03-26T16:11:42.227Z,1553616702.227 [Default] Running Loop=1
2019-03-26T16:11:42.227Z,1553616702.227 [Default](DEBUG): Aggregate::initialize Default
2019-03-26T16:11:42.227Z,1553616702.227 [Default:B.GoToSurface] Running Loop=1
2019-03-26T16:11:42.227Z,1553616702.227 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-26T16:11:42.227Z,1553616702.227 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-26T16:11:42.227Z,1553616702.227 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-26T16:11:42.228Z,1553616702.228 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-26T16:11:42.228Z,1553616702.228 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-26T16:11:42.228Z,1553616702.228 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-26T16:11:42.230Z,1553616702.230 [Default:A.Wait] Running Loop=1
2019-03-26T16:11:42.230Z,1553616702.230 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-26T16:11:55.540Z,1553616715.540 [Default:A.Wait](INFO): Done Waiting.
2019-03-26T16:11:55.559Z,1553616715.559 [Default:A.Wait] Stopped
2019-03-26T16:11:55.559Z,1553616715.559 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-26T16:11:55.944Z,1553616715.944 [Default:CheckIn] Running Loop=1
2019-03-26T16:11:55.944Z,1553616715.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-26T16:11:55.944Z,1553616715.944 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-26T16:11:56.351Z,1553616716.351 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-26T16:12:25.044Z,1553616745.044 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:12:25.045Z,1553616745.045 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-26T16:12:25.045Z,1553616745.045 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:12:25.046Z,1553616745.046 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:12:25.046Z,1553616745.046 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-26T16:12:25.046Z,1553616745.046 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:12:25.048Z,1553616745.048 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:12:25.048Z,1553616745.048 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-26T16:12:25.048Z,1553616745.048 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:12:25.058Z,1553616745.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:12:25.058Z,1553616745.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:12:25.058Z,1553616745.058 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:12:25.450Z,1553616745.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:12:25.450Z,1553616745.450 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-26T16:12:25.451Z,1553616745.451 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:12:25.451Z,1553616745.451 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-26T16:12:25.451Z,1553616745.451 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:12:25.451Z,1553616745.451 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-26T16:12:25.834Z,1553616745.834 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:14:05.194Z,1553616845.194 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-26T16:14:06.021Z,1553616846.021 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:10.869Z,1553616850.869 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:14.101Z,1553616854.101 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:16.925Z,1553616856.925 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:20.165Z,1553616860.165 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:22.993Z,1553616862.993 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:25.841Z,1553616865.841 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:14:25.841Z,1553616865.841 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-26T16:14:25.841Z,1553616865.841 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:14:25.843Z,1553616865.843 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:14:25.843Z,1553616865.843 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-26T16:14:25.843Z,1553616865.843 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:14:25.844Z,1553616865.844 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:14:25.844Z,1553616865.844 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-26T16:14:25.844Z,1553616865.844 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:14:25.855Z,1553616865.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:14:25.855Z,1553616865.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:14:25.855Z,1553616865.855 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:14:26.217Z,1553616866.217 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:26.266Z,1553616866.266 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:14:26.266Z,1553616866.266 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-26T16:14:26.267Z,1553616866.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:14:26.267Z,1553616866.267 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-26T16:14:26.267Z,1553616866.267 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:14:26.267Z,1553616866.267 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-26T16:14:26.649Z,1553616866.649 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:14:29.049Z,1553616869.049 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:31.873Z,1553616871.873 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:35.109Z,1553616875.109 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:37.933Z,1553616877.933 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:41.165Z,1553616881.165 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:43.993Z,1553616883.993 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:47.225Z,1553616887.225 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:50.057Z,1553616890.057 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:52.885Z,1553616892.885 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:54.905Z,1553616894.905 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:14:56.925Z,1553616896.925 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:00.157Z,1553616900.157 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:02.985Z,1553616902.985 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:06.213Z,1553616906.213 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:09.041Z,1553616909.041 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:12.273Z,1553616912.273 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:15.101Z,1553616915.101 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:17.941Z,1553616917.941 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:19.953Z,1553616919.953 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:23.185Z,1553616923.185 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:26.013Z,1553616926.013 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:29.245Z,1553616929.245 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:32.073Z,1553616932.073 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:35.301Z,1553616935.301 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:38.129Z,1553616938.129 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:40.953Z,1553616940.953 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:42.981Z,1553616942.981 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:44.997Z,1553616944.997 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:48.229Z,1553616948.229 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:51.061Z,1553616951.061 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:54.289Z,1553616954.289 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:15:57.117Z,1553616957.117 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:00.349Z,1553616960.349 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:03.181Z,1553616963.181 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:06.013Z,1553616966.013 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:08.029Z,1553616968.029 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:10.041Z,1553616970.041 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:13.281Z,1553616973.281 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:16.125Z,1553616976.125 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:19.341Z,1553616979.341 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:22.165Z,1553616982.165 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:25.401Z,1553616985.401 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:27.028Z,1553616987.028 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:16:27.028Z,1553616987.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-26T16:16:27.028Z,1553616987.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:16:27.029Z,1553616987.029 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:16:27.030Z,1553616987.030 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-26T16:16:27.030Z,1553616987.030 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:16:27.031Z,1553616987.031 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:16:27.031Z,1553616987.031 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-26T16:16:27.031Z,1553616987.031 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:16:27.042Z,1553616987.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:16:27.042Z,1553616987.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:16:27.042Z,1553616987.042 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:16:27.457Z,1553616987.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:16:27.457Z,1553616987.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-26T16:16:27.458Z,1553616987.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:16:27.458Z,1553616987.458 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-26T16:16:27.458Z,1553616987.458 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:16:27.458Z,1553616987.458 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-26T16:16:27.841Z,1553616987.841 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:16:28.229Z,1553616988.229 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:31.053Z,1553616991.053 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:33.077Z,1553616993.077 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:36.309Z,1553616996.309 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:39.137Z,1553616999.137 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:42.369Z,1553617002.369 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:45.197Z,1553617005.197 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:48.425Z,1553617008.425 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:51.253Z,1553617011.253 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:54.081Z,1553617014.081 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:56.105Z,1553617016.105 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:16:56.184Z,1553617016.184 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T16:11:55.9Z
2019-03-26T16:16:56.184Z,1553617016.184 [Default:CheckIn:Read_GPS] Stopped
2019-03-26T16:16:56.184Z,1553617016.184 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-26T16:16:56.544Z,1553617016.544 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-26T16:16:59.333Z,1553617019.333 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:01.296Z,1553617021.296 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190326T160814/Courier0004.lzma
2019-03-26T16:17:02.102Z,1553617022.102 [DataOverHttps](INFO): Moved sent file to Logs/20190326T160814/Courier0004.lzma.bak
2019-03-26T16:17:02.102Z,1553617022.102 [DataOverHttps](INFO): SBD MOMSN=10250419
2019-03-26T16:17:02.161Z,1553617022.161 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:05.393Z,1553617025.393 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:08.225Z,1553617028.225 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:11.453Z,1553617031.453 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:12.508Z,1553617032.508 [DataOverHttps](INFO): Sending 292 bytes from file Logs/20190326T160814/Express0005.lzma
2019-03-26T16:17:13.310Z,1553617033.310 [DataOverHttps](INFO): Moved sent file to Logs/20190326T160814/Express0005.lzma.bak
2019-03-26T16:17:13.310Z,1553617033.310 [DataOverHttps](INFO): SBD MOMSN=10250424
2019-03-26T16:17:13.940Z,1553617033.940 [Default:CheckIn:Read_Iridium] Stopped
2019-03-26T16:17:13.940Z,1553617033.940 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-26T16:17:13.940Z,1553617033.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-26T16:17:14.293Z,1553617034.293 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:17.129Z,1553617037.129 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:19.145Z,1553617039.145 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:22.381Z,1553617042.381 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:25.209Z,1553617045.209 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:28.441Z,1553617048.441 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:31.261Z,1553617051.261 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:34.505Z,1553617054.505 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:37.329Z,1553617057.329 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:40.153Z,1553617060.153 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:42.173Z,1553617062.173 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:45.405Z,1553617065.405 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:48.233Z,1553617068.233 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:51.473Z,1553617071.473 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:54.293Z,1553617074.293 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:17:57.529Z,1553617077.529 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:00.357Z,1553617080.357 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:03.185Z,1553617083.185 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:05.209Z,1553617085.209 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:07.225Z,1553617087.225 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:10.453Z,1553617090.453 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:13.285Z,1553617093.285 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:16.513Z,1553617096.513 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:19.345Z,1553617099.345 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:22.577Z,1553617102.577 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:25.405Z,1553617105.405 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:27.850Z,1553617107.850 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:18:27.850Z,1553617107.850 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-03-26T16:18:27.850Z,1553617107.850 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:18:27.851Z,1553617107.851 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:18:27.852Z,1553617107.852 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-03-26T16:18:27.852Z,1553617107.852 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:18:27.853Z,1553617107.853 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:18:27.853Z,1553617107.853 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-03-26T16:18:27.853Z,1553617107.853 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:18:27.863Z,1553617107.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:18:27.864Z,1553617107.864 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:18:27.864Z,1553617107.864 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:18:28.229Z,1553617108.229 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:28.267Z,1553617108.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:18:28.267Z,1553617108.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-03-26T16:18:28.267Z,1553617108.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:18:28.267Z,1553617108.267 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-03-26T16:18:28.268Z,1553617108.268 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:18:28.268Z,1553617108.268 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-03-26T16:18:28.661Z,1553617108.661 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:18:30.253Z,1553617110.253 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:33.481Z,1553617113.481 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:36.313Z,1553617116.313 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:39.541Z,1553617119.541 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:42.373Z,1553617122.373 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:45.601Z,1553617125.601 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:48.429Z,1553617128.429 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:51.261Z,1553617131.261 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:53.281Z,1553617133.281 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:56.517Z,1553617136.517 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:18:59.341Z,1553617139.341 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:02.569Z,1553617142.569 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:05.390Z,1553617145.390 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-26T16:19:05.390Z,1553617145.390 [NAL9602] Data Fault, FailCount= 1
2019-03-26T16:19:05.390Z,1553617145.390 [NAL9602](ERROR): Data Fault
2019-03-26T16:19:05.421Z,1553617145.421 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-26T16:19:05.790Z,1553617145.790 [NAL9602](INFO): Powering down
2019-03-26T16:19:06.632Z,1553617146.632 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-26T16:19:06.632Z,1553617146.632 [NAL9602] No Fault, FailCount= 1
2019-03-26T16:19:23.999Z,1553617163.999 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:19:23.999Z,1553617163.999 [DVL_micro] No Fault, FailCount= 5
2019-03-26T16:19:24.376Z,1553617164.376 [DVL_micro](INFO): Initializing
2019-03-26T16:19:34.874Z,1553617174.874 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:19:34.874Z,1553617174.874 [DVL_micro] Communications Fault, FailCount= 1
2019-03-26T16:19:34.874Z,1553617174.874 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:19:34.923Z,1553617174.923 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:19:35.353Z,1553617175.353 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:19:36.087Z,1553617176.087 [NAL9602](INFO): Powering up NAL9602
2019-03-26T16:19:36.117Z,1553617176.117 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:19:36.117Z,1553617176.117 [DVL_micro] No Fault, FailCount= 1
2019-03-26T16:19:36.500Z,1553617176.500 [DVL_micro](INFO): Initializing
2019-03-26T16:19:46.998Z,1553617186.998 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:19:46.998Z,1553617186.998 [DVL_micro] Communications Fault, FailCount= 2
2019-03-26T16:19:46.998Z,1553617186.998 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:19:46.999Z,1553617186.999 [NAL9602](INFO): NAL9602 initialized
2019-03-26T16:19:47.124Z,1553617187.124 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:19:47.473Z,1553617187.473 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:19:47.817Z,1553617187.817 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:48.286Z,1553617188.286 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:19:48.286Z,1553617188.286 [DVL_micro] No Fault, FailCount= 2
2019-03-26T16:19:48.620Z,1553617188.620 [DVL_micro](INFO): Initializing
2019-03-26T16:19:50.245Z,1553617190.245 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:53.073Z,1553617193.073 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:56.305Z,1553617196.305 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:59.122Z,1553617199.122 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:19:59.122Z,1553617199.122 [DVL_micro] Communications Fault, FailCount= 3
2019-03-26T16:19:59.122Z,1553617199.122 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:19:59.152Z,1553617199.152 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:19:59.196Z,1553617199.196 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:19:59.593Z,1553617199.593 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:20:00.398Z,1553617200.398 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:20:00.398Z,1553617200.398 [DVL_micro] No Fault, FailCount= 3
2019-03-26T16:20:00.744Z,1553617200.744 [DVL_micro](INFO): Initializing
2019-03-26T16:20:01.961Z,1553617201.961 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:03.985Z,1553617203.985 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:06.001Z,1553617206.001 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:08.021Z,1553617208.021 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:11.242Z,1553617211.242 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:20:11.242Z,1553617211.242 [DVL_micro] Communications Fault, FailCount= 4
2019-03-26T16:20:11.242Z,1553617211.242 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:20:11.257Z,1553617211.257 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:11.288Z,1553617211.288 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:20:11.713Z,1553617211.713 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:20:12.486Z,1553617212.486 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:20:12.486Z,1553617212.486 [DVL_micro] No Fault, FailCount= 4
2019-03-26T16:20:12.860Z,1553617212.860 [DVL_micro](INFO): Initializing
2019-03-26T16:20:14.077Z,1553617214.077 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:17.309Z,1553617217.309 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:20.137Z,1553617220.137 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:23.362Z,1553617223.362 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-03-26T16:20:23.362Z,1553617223.362 [DVL_micro] Communications Fault, FailCount= 5
2019-03-26T16:20:23.362Z,1553617223.362 [DVL_micro](ERROR): Communications Fault
2019-03-26T16:20:23.377Z,1553617223.377 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:23.429Z,1553617223.429 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-03-26T16:20:23.429Z,1553617223.429 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-03-26T16:20:23.833Z,1553617223.833 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:20:23.882Z,1553617223.882 [CBIT](INFO): Critical error at 20190326T162023
2019-03-26T16:20:26.205Z,1553617226.205 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:29.029Z,1553617229.029 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:29.069Z,1553617229.069 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:20:29.069Z,1553617229.069 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-03-26T16:20:29.069Z,1553617229.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:20:29.071Z,1553617229.071 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:20:29.071Z,1553617229.071 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-03-26T16:20:29.071Z,1553617229.071 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:20:29.072Z,1553617229.072 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:20:29.072Z,1553617229.072 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-03-26T16:20:29.072Z,1553617229.072 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:20:29.084Z,1553617229.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:20:29.084Z,1553617229.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:20:29.084Z,1553617229.084 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:20:29.533Z,1553617229.533 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:20:29.533Z,1553617229.533 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-03-26T16:20:29.534Z,1553617229.534 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:20:29.534Z,1553617229.534 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-03-26T16:20:29.534Z,1553617229.534 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:20:29.534Z,1553617229.534 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-03-26T16:20:29.865Z,1553617229.865 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:20:31.049Z,1553617231.049 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:34.277Z,1553617234.277 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:37.109Z,1553617237.109 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:40.341Z,1553617240.341 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:43.169Z,1553617243.169 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:46.401Z,1553617246.401 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:49.225Z,1553617249.225 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:52.057Z,1553617252.057 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:54.077Z,1553617254.077 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:56.093Z,1553617256.093 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:20:59.325Z,1553617259.325 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:02.153Z,1553617262.153 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:05.389Z,1553617265.389 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:08.217Z,1553617268.217 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:11.449Z,1553617271.449 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:14.277Z,1553617274.277 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:17.101Z,1553617277.101 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:19.121Z,1553617279.121 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:22.357Z,1553617282.357 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:25.185Z,1553617285.185 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:28.417Z,1553617288.417 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:31.245Z,1553617291.245 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:34.481Z,1553617294.481 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:37.305Z,1553617297.305 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:40.133Z,1553617300.133 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:42.149Z,1553617302.149 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:45.385Z,1553617305.385 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:48.209Z,1553617308.209 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:51.445Z,1553617311.445 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:54.269Z,1553617314.269 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:21:57.505Z,1553617317.505 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:00.333Z,1553617320.333 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:03.161Z,1553617323.161 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:05.181Z,1553617325.181 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:07.201Z,1553617327.201 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:10.429Z,1553617330.429 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:13.261Z,1553617333.261 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:14.484Z,1553617334.484 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-26T16:22:14.484Z,1553617334.484 [Default:CheckIn:C.Wait] Stopped
2019-03-26T16:22:14.484Z,1553617334.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-26T16:22:14.484Z,1553617334.484 [Default:CheckIn:D] Running Loop=1
2019-03-26T16:22:14.891Z,1553617334.891 [Default:CheckIn:D] Stopped
2019-03-26T16:22:14.891Z,1553617334.891 [Default:CheckIn:E] Running Loop=1
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.544406 min 
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn:E] Stopped
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn] Stopped
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn](INFO): Running loop #2
2019-03-26T16:22:15.292Z,1553617335.292 [Default:CheckIn] Running Loop=2
2019-03-26T16:22:15.293Z,1553617335.293 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-26T16:22:15.293Z,1553617335.293 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-26T16:22:16.489Z,1553617336.489 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:19.317Z,1553617339.317 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:22.553Z,1553617342.553 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:25.377Z,1553617345.377 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:28.205Z,1553617348.205 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:30.229Z,1553617350.229 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:30.260Z,1553617350.260 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:22:30.260Z,1553617350.260 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-03-26T16:22:30.260Z,1553617350.260 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:22:30.262Z,1553617350.262 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:22:30.262Z,1553617350.262 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-03-26T16:22:30.262Z,1553617350.262 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:22:30.263Z,1553617350.263 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:22:30.264Z,1553617350.264 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-03-26T16:22:30.264Z,1553617350.264 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:22:30.274Z,1553617350.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:22:30.274Z,1553617350.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:22:30.275Z,1553617350.275 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:22:30.679Z,1553617350.679 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:22:30.679Z,1553617350.679 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-03-26T16:22:30.680Z,1553617350.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:22:30.680Z,1553617350.680 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-03-26T16:22:30.680Z,1553617350.680 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:22:30.680Z,1553617350.680 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-03-26T16:22:31.057Z,1553617351.057 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:22:33.457Z,1553617353.457 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:36.285Z,1553617356.285 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:39.517Z,1553617359.517 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:42.349Z,1553617362.349 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:45.581Z,1553617365.581 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:48.405Z,1553617368.405 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:51.237Z,1553617371.237 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:53.253Z,1553617373.253 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:55.277Z,1553617375.277 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:22:58.509Z,1553617378.509 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:01.337Z,1553617381.337 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:04.569Z,1553617384.569 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:07.401Z,1553617387.401 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:10.629Z,1553617390.629 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:13.453Z,1553617393.453 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:16.285Z,1553617396.285 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:18.301Z,1553617398.301 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:21.537Z,1553617401.537 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:24.369Z,1553617404.369 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:25.207Z,1553617405.207 [CBIT](INFO): Clearing failed state for component DropWeight
2019-03-26T16:23:25.207Z,1553617405.207 [DropWeight] No Fault, FailCount= 1
2019-03-26T16:23:27.597Z,1553617407.597 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:30.421Z,1553617410.421 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:33.653Z,1553617413.653 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:36.485Z,1553617416.485 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:39.313Z,1553617419.313 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:41.333Z,1553617421.333 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:43.353Z,1553617423.353 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:46.585Z,1553617426.585 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:49.409Z,1553617429.409 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:52.645Z,1553617432.645 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:55.473Z,1553617435.473 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:23:58.705Z,1553617438.705 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:01.533Z,1553617441.533 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:04.361Z,1553617444.361 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:06.381Z,1553617446.381 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:09.617Z,1553617449.617 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:12.441Z,1553617452.441 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:15.677Z,1553617455.677 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:18.497Z,1553617458.497 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:21.729Z,1553617461.729 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:24.557Z,1553617464.557 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:27.389Z,1553617467.389 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:29.409Z,1553617469.409 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:31.438Z,1553617471.438 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:24:31.439Z,1553617471.439 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-03-26T16:24:31.439Z,1553617471.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:24:31.440Z,1553617471.440 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:24:31.440Z,1553617471.440 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-03-26T16:24:31.440Z,1553617471.440 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:24:31.442Z,1553617471.442 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:24:31.442Z,1553617471.442 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-03-26T16:24:31.442Z,1553617471.442 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:24:31.453Z,1553617471.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:24:31.453Z,1553617471.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:24:31.453Z,1553617471.453 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:24:31.858Z,1553617471.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:24:31.858Z,1553617471.858 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-03-26T16:24:31.858Z,1553617471.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:24:31.858Z,1553617471.858 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-03-26T16:24:31.859Z,1553617471.859 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:24:31.859Z,1553617471.859 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-03-26T16:24:32.242Z,1553617472.242 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:24:32.645Z,1553617472.645 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:35.469Z,1553617475.469 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:38.701Z,1553617478.701 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:41.525Z,1553617481.525 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:44.773Z,1553617484.773 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:47.601Z,1553617487.601 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:50.429Z,1553617490.429 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:52.457Z,1553617492.457 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:55.685Z,1553617495.685 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:24:58.513Z,1553617498.513 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:01.745Z,1553617501.745 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:04.569Z,1553617504.569 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:07.805Z,1553617507.805 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:10.633Z,1553617510.633 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:13.461Z,1553617513.461 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:15.477Z,1553617515.477 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:18.713Z,1553617518.713 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:21.537Z,1553617521.537 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:24.773Z,1553617524.773 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:27.601Z,1553617527.601 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:30.833Z,1553617530.833 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:33.661Z,1553617533.661 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:36.493Z,1553617536.493 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:38.509Z,1553617538.509 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:40.529Z,1553617540.529 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:43.761Z,1553617543.761 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:46.589Z,1553617546.589 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:49.817Z,1553617549.817 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:52.645Z,1553617552.645 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:55.881Z,1553617555.881 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:25:58.709Z,1553617558.709 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:01.537Z,1553617561.537 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:03.557Z,1553617563.557 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:06.789Z,1553617566.789 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:09.621Z,1553617569.621 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:12.849Z,1553617572.849 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:15.677Z,1553617575.677 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:18.905Z,1553617578.905 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:21.733Z,1553617581.733 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:24.565Z,1553617584.565 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:26.585Z,1553617586.585 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:28.605Z,1553617588.605 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:31.837Z,1553617591.837 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:32.281Z,1553617592.281 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:26:32.281Z,1553617592.281 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-03-26T16:26:32.281Z,1553617592.281 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:26:32.282Z,1553617592.282 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:26:32.283Z,1553617592.283 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-03-26T16:26:32.283Z,1553617592.283 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:26:32.284Z,1553617592.284 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:26:32.284Z,1553617592.284 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-03-26T16:26:32.284Z,1553617592.284 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:26:32.294Z,1553617592.294 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:26:32.295Z,1553617592.295 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:26:32.295Z,1553617592.295 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:26:32.691Z,1553617592.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:26:32.691Z,1553617592.691 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-03-26T16:26:32.691Z,1553617592.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:26:32.691Z,1553617592.691 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-03-26T16:26:32.692Z,1553617592.692 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:26:32.692Z,1553617592.692 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-03-26T16:26:33.061Z,1553617593.061 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:26:34.665Z,1553617594.665 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:37.897Z,1553617597.897 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:40.725Z,1553617600.725 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:43.957Z,1553617603.957 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:46.785Z,1553617606.785 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:49.613Z,1553617609.613 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:51.629Z,1553617611.629 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:54.865Z,1553617614.865 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:26:57.693Z,1553617617.693 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:00.925Z,1553617620.925 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:03.749Z,1553617623.749 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:06.989Z,1553617626.989 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:09.817Z,1553617629.817 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:12.641Z,1553617632.641 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:14.661Z,1553617634.661 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:15.491Z,1553617635.491 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T16:22:15.3Z
2019-03-26T16:27:15.491Z,1553617635.491 [Default:CheckIn:Read_GPS] Stopped
2019-03-26T16:27:15.491Z,1553617635.491 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-26T16:27:16.677Z,1553617636.677 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:19.909Z,1553617639.909 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:20.532Z,1553617640.532 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190326T160814/Courier0007.lzma
2019-03-26T16:27:21.338Z,1553617641.338 [DataOverHttps](INFO): Moved sent file to Logs/20190326T160814/Courier0007.lzma.bak
2019-03-26T16:27:21.338Z,1553617641.338 [DataOverHttps](INFO): SBD MOMSN=10250498
2019-03-26T16:27:22.749Z,1553617642.749 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:25.969Z,1553617645.969 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:28.797Z,1553617648.797 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:32.033Z,1553617652.033 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:34.857Z,1553617654.857 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:37.417Z,1553617657.417 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20190326T160814/Express0008.lzma
2019-03-26T16:27:37.689Z,1553617657.689 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:38.222Z,1553617658.222 [DataOverHttps](INFO): Moved sent file to Logs/20190326T160814/Express0008.lzma.bak
2019-03-26T16:27:38.222Z,1553617658.222 [DataOverHttps](INFO): SBD MOMSN=10250526
2019-03-26T16:27:38.915Z,1553617658.915 [Default:CheckIn:Read_Iridium] Stopped
2019-03-26T16:27:38.915Z,1553617658.915 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-26T16:27:38.915Z,1553617658.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-26T16:27:39.705Z,1553617659.705 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:42.941Z,1553617662.941 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:45.765Z,1553617665.765 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:48.997Z,1553617668.997 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:51.829Z,1553617671.829 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:55.061Z,1553617675.061 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:27:57.889Z,1553617677.889 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:00.717Z,1553617680.717 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:02.733Z,1553617682.733 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:05.969Z,1553617685.969 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:08.797Z,1553617688.797 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:12.025Z,1553617692.025 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:14.857Z,1553617694.857 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:18.089Z,1553617698.089 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:20.917Z,1553617700.917 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:23.745Z,1553617703.745 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:25.765Z,1553617705.765 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:27.785Z,1553617707.785 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:31.017Z,1553617711.017 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:33.457Z,1553617713.457 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:28:33.457Z,1553617713.457 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-03-26T16:28:33.457Z,1553617713.457 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-26T16:28:33.459Z,1553617713.459 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:28:33.459Z,1553617713.459 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-03-26T16:28:33.459Z,1553617713.459 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-26T16:28:33.460Z,1553617713.460 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-26T16:28:33.460Z,1553617713.460 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-03-26T16:28:33.460Z,1553617713.460 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-26T16:28:33.470Z,1553617713.470 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-26T16:28:33.471Z,1553617713.471 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-26T16:28:33.471Z,1553617713.471 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-26T16:28:33.849Z,1553617713.849 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:33.884Z,1553617713.884 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-26T16:28:33.884Z,1553617713.884 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-03-26T16:28:33.884Z,1553617713.884 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-26T16:28:33.884Z,1553617713.884 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-03-26T16:28:33.886Z,1553617713.886 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-26T16:28:33.886Z,1553617713.886 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-03-26T16:28:34.289Z,1553617714.289 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-26T16:28:37.077Z,1553617717.077 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:39.905Z,1553617719.905 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:43.133Z,1553617723.133 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:45.965Z,1553617725.965 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:48.797Z,1553617728.797 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:50.813Z,1553617730.813 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:54.045Z,1553617734.045 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:28:56.873Z,1553617736.873 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:00.105Z,1553617740.105 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:02.933Z,1553617742.933 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:06.165Z,1553617746.165 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:08.997Z,1553617748.997 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:11.817Z,1553617751.817 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:13.837Z,1553617753.837 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:15.861Z,1553617755.861 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:19.093Z,1553617759.093 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:21.921Z,1553617761.921 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:25.149Z,1553617765.149 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:27.981Z,1553617767.981 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:31.213Z,1553617771.213 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:34.045Z,1553617774.045 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:36.869Z,1553617776.869 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:38.893Z,1553617778.893 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:42.117Z,1553617782.117 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:44.949Z,1553617784.949 [NAL9602](DEBUG): Fix Requested
2019-03-26T16:29:48.170Z,1553617788.170 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-26T16:29:48.170Z,1553617788.170 [NAL9602] Data Fault, FailCount= 2
2019-03-26T16:29:48.170Z,1553617788.170 [NAL9602](ERROR): Data Fault
2019-03-26T16:29:48.202Z,1553617788.202 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-26T16:29:48.574Z,1553617788.574 [NAL9602](INFO): Powering down
2019-03-26T16:29:49.412Z,1553617789.412 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-26T16:29:49.412Z,1553617789.412 [NAL9602] No Fault, FailCount= 2
2019-03-26T16:30:18.874Z,1553617818.874 [NAL9602](INFO): Powering up NAL9602
2019-03-26T16:30:23.753Z,1553617823.753 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-03-26T16:30:23.753Z,1553617823.753 [DVL_micro] No Fault, FailCount= 5
2019-03-26T16:30:24.134Z,1553617824.134 [DVL_micro](INFO): Initializing
2019-03-26T16:30:27.593Z,1553617827.593 [CommandLine](IMPORTANT): got command quit
2019-03-26T16:30:28.597Z,1553617828.597 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:28.597Z,1553617828.597 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:28.765Z,1553617828.765 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-26T16:30:28.765Z,1553617828.765 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:28.766Z,1553617828.766 [CommandLine](INFO): Join timeout helper Thread ID is 5829
2019-03-26T16:30:28.766Z,1553617828.766 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-26T16:30:28.766Z,1553617828.766 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:28.767Z,1553617828.767 [NavChartDb](INFO): Join timeout helper Thread ID is 5830
2019-03-26T16:30:28.817Z,1553617828.817 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:28.817Z,1553617828.817 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:28.825Z,1553617828.825 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-26T16:30:28.825Z,1553617828.825 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:28.825Z,1553617828.825 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5831
2019-03-26T16:30:29.293Z,1553617829.293 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:29.293Z,1553617829.293 [WetLabsBB2FL](INFO): Powering down
2019-03-26T16:30:29.294Z,1553617829.294 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.313Z,1553617829.313 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-03-26T16:30:29.313Z,1553617829.313 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.313Z,1553617829.313 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5832
2019-03-26T16:30:29.357Z,1553617829.357 [CTD_Seabird](INFO): Powering down
2019-03-26T16:30:29.373Z,1553617829.373 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:29.373Z,1553617829.373 [CTD_Seabird](INFO): Powering down
2019-03-26T16:30:29.385Z,1553617829.385 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.402Z,1553617829.402 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-26T16:30:29.403Z,1553617829.403 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.403Z,1553617829.403 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5833
2019-03-26T16:30:29.473Z,1553617829.473 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:29.473Z,1553617829.473 [CTD_NeilBrown](INFO): Powering down
2019-03-26T16:30:29.485Z,1553617829.485 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.494Z,1553617829.494 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-26T16:30:29.494Z,1553617829.494 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.494Z,1553617829.494 [Radio_Surface](INFO): Join timeout helper Thread ID is 5834
2019-03-26T16:30:29.549Z,1553617829.549 [Radio_Surface](INFO): Powering down
2019-03-26T16:30:29.550Z,1553617829.550 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:29.550Z,1553617829.550 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.554Z,1553617829.554 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-26T16:30:29.554Z,1553617829.554 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.554Z,1553617829.554 [DataOverHttps](INFO): Join timeout helper Thread ID is 5835
2019-03-26T16:30:29.778Z,1553617829.778 [NAL9602](INFO): NAL9602 initialized
2019-03-26T16:30:29.933Z,1553617829.933 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:29.933Z,1553617829.933 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.946Z,1553617829.946 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-26T16:30:29.946Z,1553617829.946 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.946Z,1553617829.946 [logger](INFO): Join timeout helper Thread ID is 5836
2019-03-26T16:30:29.953Z,1553617829.953 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:29.953Z,1553617829.953 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.965Z,1553617829.965 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-26T16:30:29.966Z,1553617829.966 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.966Z,1553617829.966 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-26T16:30:29.966Z,1553617829.966 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:29.966Z,1553617829.966 [controlThread](INFO): Join timeout helper Thread ID is 5837
2019-03-26T16:30:30.173Z,1553617830.173 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-26T16:30:30.173Z,1553617830.173 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-26T16:30:30.245Z,1553617830.245 [DVL_micro](INFO): uninitialize:Powering down
2019-03-26T16:30:30.246Z,1553617830.246 [NAL9602](INFO): Powering down
2019-03-26T16:30:30.249Z,1553617830.249 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-26T16:30:30.250Z,1553617830.250 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-26T16:30:30.251Z,1553617830.251 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-26T16:30:30.252Z,1553617830.252 [MissionManager](INFO): Uninitializing Mission Default
2019-03-26T16:30:30.252Z,1553617830.252 [Default] Stopped
2019-03-26T16:30:30.252Z,1553617830.252 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-26T16:30:30.252Z,1553617830.252 [Default:B.GoToSurface] Stopped
2019-03-26T16:30:30.252Z,1553617830.252 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-26T16:30:30.252Z,1553617830.252 [Default:CheckIn] Stopped
2019-03-26T16:30:30.252Z,1553617830.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-26T16:30:30.252Z,1553617830.252 [Default:CheckIn:C.Wait] Stopped
2019-03-26T16:30:30.252Z,1553617830.252 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-26T16:30:30.255Z,1553617830.255 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-26T16:30:30.255Z,1553617830.255 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-26T16:30:30.255Z,1553617830.255 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-26T16:30:30.256Z,1553617830.256 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-26T16:30:30.256Z,1553617830.256 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-26T16:30:30.256Z,1553617830.256 [BuoyancyServo](INFO): Powering down
2019-03-26T16:30:30.269Z,1553617830.269 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-26T16:30:30.269Z,1553617830.269 [ElevatorServo](INFO): Powering down
2019-03-26T16:30:30.270Z,1553617830.270 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-26T16:30:30.270Z,1553617830.270 [MassServo](INFO): Powering down
2019-03-26T16:30:30.271Z,1553617830.271 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-26T16:30:30.271Z,1553617830.271 [RudderServo](INFO): Powering down
2019-03-26T16:30:30.272Z,1553617830.272 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-26T16:30:30.272Z,1553617830.272 [ThrusterServo](INFO): Powering down
2019-03-26T16:30:30.272Z,1553617830.272 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-26T16:30:30.273Z,1553617830.273 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-26T16:30:30.273Z,1553617830.273 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-26T16:30:30.273Z,1553617830.273 [CBIT](DEBUG): Powering off loads.
2019-03-26T16:30:30.285Z,1553617830.285 [CBIT](DEBUG): Disabling WDT.
2019-03-26T16:30:30.297Z,1553617830.297 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-26T16:30:30.297Z,1553617830.297 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.378Z,1553617830.378 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.387Z,1553617830.387 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.416Z,1553617830.416 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.420Z,1553617830.420 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.423Z,1553617830.423 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.459Z,1553617830.459 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-26T16:30:30.517Z,1553617830.517 [logger ThreadHandler](INFO): Thread cancelled.