2019-02-22T00:22:17.061Z,1550794937.061 [Supervisor](DEBUG): Initializing supervisor.
2019-02-22T00:22:17.072Z,1550794937.072 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-22T00:22:17.073Z,1550794937.073 [SyncHandler](INFO): Protected caller Thread ID is 2159
2019-02-22T00:22:17.073Z,1550794937.073 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-22T00:22:17.088Z,1550794937.088 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-22T00:22:17.089Z,1550794937.089 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2160
2019-02-22T00:22:17.091Z,1550794937.091 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-22T00:22:17.120Z,1550794937.120 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-22T00:22:17.146Z,1550794937.146 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-22T00:22:17.146Z,1550794937.146 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2161
2019-02-22T00:22:17.147Z,1550794937.147 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-22T00:22:17.163Z,1550794937.163 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-22T00:22:17.164Z,1550794937.164 [logger ThreadHandler](INFO): Protected caller Thread ID is 2162
2019-02-22T00:22:17.166Z,1550794937.166 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-22T00:22:17.166Z,1550794937.166 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-22T00:22:17.168Z,1550794937.168 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-22T00:22:17.731Z,1550794937.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-22T00:22:17.731Z,1550794937.731 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-22T00:22:17.899Z,1550794937.899 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-22T00:22:19.253Z,1550794939.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-22T00:22:19.254Z,1550794939.254 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-22T00:22:20.461Z,1550794940.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-22T00:22:20.462Z,1550794940.462 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-22T00:22:20.653Z,1550794940.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-22T00:22:20.654Z,1550794940.654 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-22T00:22:21.336Z,1550794941.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-22T00:22:21.338Z,1550794941.338 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-22T00:22:21.954Z,1550794941.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-22T00:22:21.955Z,1550794941.955 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-22T00:22:23.297Z,1550794943.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-22T00:22:23.297Z,1550794943.297 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-22T00:22:23.566Z,1550794943.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-22T00:22:23.567Z,1550794943.567 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-22T00:22:23.799Z,1550794943.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-22T00:22:23.800Z,1550794943.800 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-22T00:22:25.295Z,1550794945.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-22T00:22:25.296Z,1550794945.296 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-22T00:22:26.293Z,1550794946.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-22T00:22:26.293Z,1550794946.293 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-22T00:22:27.005Z,1550794947.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-22T00:22:27.005Z,1550794947.005 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-22T00:22:27.337Z,1550794947.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-22T00:22:27.338Z,1550794947.338 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-22T00:22:27.732Z,1550794947.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-22T00:22:27.734Z,1550794947.734 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-02-22T00:22:27.735Z,1550794947.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-02-22T00:22:27.927Z,1550794947.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-02-22T00:22:28.125Z,1550794948.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-02-22T00:22:28.411Z,1550794948.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-02-22T00:22:28.574Z,1550794948.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-02-22T00:22:28.823Z,1550794948.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-02-22T00:22:29.034Z,1550794949.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-02-22T00:22:29.534Z,1550794949.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-02-22T00:22:29.766Z,1550794949.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-02-22T00:22:30.199Z,1550794950.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-02-22T00:22:30.450Z,1550794950.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-02-22T00:22:30.776Z,1550794950.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-02-22T00:22:31.299Z,1550794951.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-22T00:22:31.300Z,1550794951.300 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-22T00:22:31.301Z,1550794951.301 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-22T00:22:31.493Z,1550794951.493 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-22T00:22:31.889Z,1550794951.889 [VerticalControl] Loaded
2019-02-22T00:22:31.889Z,1550794951.889 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-22T00:22:31.890Z,1550794951.890 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-22T00:22:32.189Z,1550794952.189 [HorizontalControl] Loaded
2019-02-22T00:22:32.190Z,1550794952.190 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-22T00:22:32.190Z,1550794952.190 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-22T00:22:32.202Z,1550794952.202 [SpeedControl] Loaded
2019-02-22T00:22:32.202Z,1550794952.202 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-22T00:22:32.203Z,1550794952.203 [LoopControl](DEBUG): Construct LoopControl.
2019-02-22T00:22:32.204Z,1550794952.204 [LoopControl] Loaded
2019-02-22T00:22:32.205Z,1550794952.205 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-22T00:22:32.205Z,1550794952.205 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-22T00:22:32.206Z,1550794952.206 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-22T00:22:32.262Z,1550794952.262 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-22T00:22:32.262Z,1550794952.262 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-22T00:22:32.551Z,1550794952.551 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-22T00:22:32.552Z,1550794952.552 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-22T00:22:32.924Z,1550794952.924 [BuoyancyServo] Loaded
2019-02-22T00:22:32.924Z,1550794952.924 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-22T00:22:32.969Z,1550794952.969 [ElevatorServo] Loaded
2019-02-22T00:22:32.982Z,1550794952.982 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-22T00:22:33.014Z,1550794953.014 [MassServo] Loaded
2019-02-22T00:22:33.015Z,1550794953.015 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-22T00:22:33.026Z,1550794953.026 [RudderServo] Loaded
2019-02-22T00:22:33.026Z,1550794953.026 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-22T00:22:33.111Z,1550794953.111 [ThrusterServo] Loaded
2019-02-22T00:22:33.111Z,1550794953.111 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-22T00:22:33.112Z,1550794953.112 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-22T00:22:33.112Z,1550794953.112 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-22T00:22:33.518Z,1550794953.518 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-22T00:22:33.519Z,1550794953.519 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-22T00:22:33.733Z,1550794953.733 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-22T00:22:33.733Z,1550794953.733 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-22T00:22:33.904Z,1550794953.904 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-22T00:22:33.904Z,1550794953.904 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-22T00:22:33.964Z,1550794953.964 [NavChart] Loaded
2019-02-22T00:22:33.964Z,1550794953.964 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-22T00:22:33.968Z,1550794953.968 [UniversalFixResidualReporter] Loaded
2019-02-22T00:22:33.968Z,1550794953.968 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-22T00:22:33.969Z,1550794953.969 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-22T00:22:33.969Z,1550794953.969 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-22T00:22:34.168Z,1550794954.168 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-22T00:22:34.169Z,1550794954.169 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-22T00:22:35.096Z,1550794955.096 [DataOverHttps] Loaded
2019-02-22T00:22:35.096Z,1550794955.096 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-22T00:22:35.117Z,1550794955.117 [Depth_Keller] Loaded
2019-02-22T00:22:35.117Z,1550794955.117 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-22T00:22:35.123Z,1550794955.123 [DropWeight] Loaded
2019-02-22T00:22:35.124Z,1550794955.124 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-22T00:22:35.384Z,1550794955.384 [NAL9602] Loaded
2019-02-22T00:22:35.384Z,1550794955.384 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-22T00:22:35.434Z,1550794955.434 [Onboard] Loaded
2019-02-22T00:22:35.435Z,1550794955.435 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-22T00:22:35.442Z,1550794955.442 [Radio_Surface] Loaded
2019-02-22T00:22:35.442Z,1550794955.442 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-22T00:22:35.449Z,1550794955.449 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-22T00:22:35.449Z,1550794955.449 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2244
2019-02-22T00:22:35.576Z,1550794955.576 [PNI_TCM] Loaded
2019-02-22T00:22:35.577Z,1550794955.577 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-22T00:22:35.781Z,1550794955.781 [Rowe_600LCM] Loaded
2019-02-22T00:22:35.782Z,1550794955.782 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-22T00:22:35.786Z,1550794955.786 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-22T00:22:35.786Z,1550794955.786 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 2245
2019-02-22T00:22:35.787Z,1550794955.787 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-22T00:22:35.788Z,1550794955.788 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-22T00:22:35.862Z,1550794955.862 [DepthRateCalculator] Loaded
2019-02-22T00:22:35.862Z,1550794955.862 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-22T00:22:35.869Z,1550794955.869 [PitchRateCalculator] Loaded
2019-02-22T00:22:35.870Z,1550794955.870 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-22T00:22:35.909Z,1550794955.909 [SpeedCalculator] Loaded
2019-02-22T00:22:35.909Z,1550794955.909 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-22T00:22:36.001Z,1550794956.001 [TempGradientCalculator] Loaded
2019-02-22T00:22:36.001Z,1550794956.001 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-22T00:22:36.009Z,1550794956.009 [YawRateCalculator] Loaded
2019-02-22T00:22:36.009Z,1550794956.009 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-22T00:22:36.058Z,1550794956.058 [ElevatorOffsetCalculator] Loaded
2019-02-22T00:22:36.058Z,1550794956.058 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-22T00:22:36.059Z,1550794956.059 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-22T00:22:36.059Z,1550794956.059 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-22T00:22:36.674Z,1550794956.674 [Aanderaa_O2] Loaded
2019-02-22T00:22:36.675Z,1550794956.675 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-22T00:22:36.932Z,1550794956.932 [CTD_NeilBrown] Loaded
2019-02-22T00:22:36.933Z,1550794956.933 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-22T00:22:36.957Z,1550794956.957 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2019-02-22T00:22:36.958Z,1550794956.958 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2246
2019-02-22T00:22:36.967Z,1550794956.967 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-22T00:22:36.991Z,1550794956.991 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-22T00:22:36.993Z,1550794956.993 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-22T00:22:37.017Z,1550794957.017 [CTD_Seabird](INFO): created writer for : depth
2019-02-22T00:22:37.018Z,1550794957.018 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-22T00:22:37.031Z,1550794957.031 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-22T00:22:37.031Z,1550794957.031 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-22T00:22:37.056Z,1550794957.056 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-22T00:22:37.057Z,1550794957.057 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-22T00:22:37.062Z,1550794957.062 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-22T00:22:37.063Z,1550794957.063 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-22T00:22:37.091Z,1550794957.091 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-22T00:22:37.092Z,1550794957.092 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-22T00:22:37.121Z,1550794957.121 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-22T00:22:37.214Z,1550794957.214 [CTD_Seabird] Loaded
2019-02-22T00:22:37.215Z,1550794957.215 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-22T00:22:37.227Z,1550794957.227 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0
2019-02-22T00:22:37.227Z,1550794957.227 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2247
2019-02-22T00:22:37.291Z,1550794957.291 [PAR_Licor] Loaded
2019-02-22T00:22:37.291Z,1550794957.291 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-22T00:22:37.435Z,1550794957.435 [WetLabsBB2FL] Loaded
2019-02-22T00:22:37.435Z,1550794957.435 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-22T00:22:37.444Z,1550794957.444 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0
2019-02-22T00:22:37.444Z,1550794957.444 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2248
2019-02-22T00:22:37.445Z,1550794957.445 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-22T00:22:37.445Z,1550794957.445 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-22T00:22:37.542Z,1550794957.542 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-22T00:22:37.543Z,1550794957.543 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-22T00:22:38.353Z,1550794958.353 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-22T00:22:38.353Z,1550794958.353 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-22T00:22:38.747Z,1550794958.747 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-22T00:22:38.784Z,1550794958.784 [SBIT] Loaded
2019-02-22T00:22:38.794Z,1550794958.794 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-22T00:22:38.795Z,1550794958.795 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-22T00:22:38.824Z,1550794958.824 [IBIT] Loaded
2019-02-22T00:22:38.824Z,1550794958.824 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-22T00:22:38.827Z,1550794958.827 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-22T00:22:40.158Z,1550794960.158 [CBIT] Loaded
2019-02-22T00:22:40.158Z,1550794960.158 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-22T00:22:40.158Z,1550794960.158 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-22T00:22:40.162Z,1550794960.162 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-22T00:22:40.163Z,1550794960.163 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-22T00:22:40.225Z,1550794960.225 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-22T00:22:40.233Z,1550794960.233 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0
2019-02-22T00:22:40.234Z,1550794960.234 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2249
2019-02-22T00:22:40.254Z,1550794960.254 [Supervisor](INFO): Main Thread ID is 2158
2019-02-22T00:22:40.254Z,1550794960.254 [Supervisor](DEBUG): Running supervisor.
2019-02-22T00:22:40.254Z,1550794960.254 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2250
2019-02-22T00:22:40.270Z,1550794960.270 [controlThread ThreadHandler](INFO): Handler Thread ID is 2251
2019-02-22T00:22:40.270Z,1550794960.270 [controlThread](DEBUG): Initializing ControlThread
2019-02-22T00:22:40.271Z,1550794960.271 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-22T00:22:40.273Z,1550794960.273 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-22T00:22:40.274Z,1550794960.274 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-22T00:22:40.274Z,1550794960.274 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-22T00:22:40.276Z,1550794960.276 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:22:40.277Z,1550794960.277 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-22T00:22:40.277Z,1550794960.277 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-22T00:22:40.283Z,1550794960.283 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-22T00:22:40.283Z,1550794960.283 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-22T00:22:40.284Z,1550794960.284 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-22T00:22:40.284Z,1550794960.284 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-22T00:22:40.284Z,1550794960.284 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-22T00:22:40.285Z,1550794960.285 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-22T00:22:40.289Z,1550794960.289 [SBIT](INFO): Initialize SBIT Component.
2019-02-22T00:22:40.290Z,1550794960.290 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a
2019-02-22T00:22:40.290Z,1550794960.290 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4
2019-02-22T00:22:40.290Z,1550794960.290 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-22T00:22:40.291Z,1550794960.291 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-22T00:22:40.292Z,1550794960.292 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-02-22T00:22:40.293Z,1550794960.293 [IBIT](INFO): Initialize IBIT Component.
2019-02-22T00:22:40.294Z,1550794960.294 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-22T00:22:40.306Z,1550794960.306 [CBIT](DEBUG): Initialized mux pins.
2019-02-22T00:22:40.306Z,1550794960.306 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-22T00:22:40.318Z,1550794960.318 [logger ThreadHandler](INFO): Handler Thread ID is 2252
2019-02-22T00:22:40.329Z,1550794960.329 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-22T00:22:40.329Z,1550794960.329 [CBIT](DEBUG): Initializing heartbeat.
2019-02-22T00:22:40.359Z,1550794960.359 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2253
2019-02-22T00:22:40.366Z,1550794960.366 [Radio_Surface](INFO): Powering up
2019-02-22T00:22:40.389Z,1550794960.389 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 2254
2019-02-22T00:22:40.390Z,1550794960.390 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:22:40.399Z,1550794960.399 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:22:40.401Z,1550794960.401 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-22T00:22:40.401Z,1550794960.401 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-22T00:22:40.431Z,1550794960.431 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2255
2019-02-22T00:22:40.432Z,1550794960.432 [CTD_NeilBrown](INFO): Powering down
2019-02-22T00:22:40.438Z,1550794960.438 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-22T00:22:40.452Z,1550794960.452 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-22T00:22:40.486Z,1550794960.486 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2256
2019-02-22T00:22:40.486Z,1550794960.486 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:22:40.496Z,1550794960.496 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2258
2019-02-22T00:22:40.497Z,1550794960.497 [WetLabsBB2FL](INFO): Powering down
2019-02-22T00:22:40.502Z,1550794960.502 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:22:40.561Z,1550794960.561 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2259
2019-02-22T00:22:40.564Z,1550794960.564 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-22T00:22:40.565Z,1550794960.565 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-02-22T00:22:40.565Z,1550794960.565 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-22T00:22:40.567Z,1550794960.567 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-02-22T00:22:40.567Z,1550794960.567 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-22T00:22:40.568Z,1550794960.568 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-02-22T00:22:40.569Z,1550794960.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-22T00:22:40.570Z,1550794960.570 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-02-22T00:22:40.570Z,1550794960.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-22T00:22:40.572Z,1550794960.572 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-02-22T00:22:40.572Z,1550794960.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T00:22:40.574Z,1550794960.574 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-02-22T00:22:40.574Z,1550794960.574 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:22:40.575Z,1550794960.575 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-02-22T00:22:40.576Z,1550794960.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:22:40.577Z,1550794960.577 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-02-22T00:22:40.580Z,1550794960.580 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:22:40.589Z,1550794960.589 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-02-22T00:22:40.590Z,1550794960.590 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-22T00:22:40.825Z,1550794960.825 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-22T00:22:40.828Z,1550794960.828 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-22T00:22:40.859Z,1550794960.859 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-22T00:22:40.939Z,1550794960.939 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-22T00:22:41.008Z,1550794961.008 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-22T00:22:41.055Z,1550794961.055 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-22T00:22:41.090Z,1550794961.090 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-02-22T00:22:41.097Z,1550794961.097 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,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-02-22T00:22:41.156Z,1550794961.156 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-22T00:22:42.178Z,1550794962.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:22:42.205Z,1550794962.205 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-22T00:22:42.224Z,1550794962.224 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:22:42.237Z,1550794962.237 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-22T00:22:42.255Z,1550794962.255 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:22:42.265Z,1550794962.265 [MassServo](DEBUG): Initializing MassServo.
2019-02-22T00:22:42.304Z,1550794962.304 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:22:42.325Z,1550794962.325 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-22T00:22:42.335Z,1550794962.335 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:22:42.353Z,1550794962.353 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-22T00:22:42.750Z,1550794962.750 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-22T00:22:42.750Z,1550794962.750 [DropWeight] Hardware Fault, FailCount= 1
2019-02-22T00:22:42.750Z,1550794962.750 [DropWeight](ERROR): Hardware Fault
2019-02-22T00:22:43.291Z,1550794963.291 [CommandLine](FAULT): Scheduling is paused
2019-02-22T00:22:43.291Z,1550794963.291 [CBIT](INFO): Critical error at 20190222T002242
2019-02-22T00:22:43.291Z,1550794963.291 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-22T00:22:43.294Z,1550794963.294 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-22T00:22:43.295Z,1550794963.295 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-22T00:22:44.986Z,1550794964.986 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:22:45.259Z,1550794965.259 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T00:22:54.545Z,1550794974.545 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:22:54.545Z,1550794974.545 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T00:22:54.545Z,1550794974.545 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:22:54.949Z,1550794974.949 [CTD_Seabird](INFO): Powering down
2019-02-22T00:22:59.582Z,1550794979.582 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:22:59.582Z,1550794979.582 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T00:22:59.582Z,1550794979.582 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:22:59.667Z,1550794979.667 [CBIT](INFO): Critical error at 20190222T002243
2019-02-22T00:22:59.670Z,1550794979.670 [CBIT](CRITICAL): Environmental Failure. Press:14.595343 PSI. Humidity:17%. Temp:24 C. ABORTING MISSION
2019-02-22T00:22:59.670Z,1550794979.670 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:22:59.671Z,1550794979.671 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:22:59.671Z,1550794979.671 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:22:59.671Z,1550794979.671 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T00:22:59.756Z,1550794979.756 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:22:59.857Z,1550794979.857 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:22:59.858Z,1550794979.858 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:23:00.103Z,1550794980.103 [CBIT](INFO): Critical error at 20190222T002259
2019-02-22T00:23:00.707Z,1550794980.707 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.193955
2019-02-22T00:23:01.153Z,1550794981.153 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:23:01.153Z,1550794981.153 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T00:23:03.711Z,1550794983.711 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-22T00:23:03.716Z,1550794983.716 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-22T00:23:05.162Z,1550794985.162 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:23:05.162Z,1550794985.162 [PNI_TCM] Data Fault, FailCount= 1
2019-02-22T00:23:05.162Z,1550794985.162 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:23:05.426Z,1550794985.426 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:23:05.707Z,1550794985.707 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:23:05.728Z,1550794985.728 [PNI_TCM](INFO): Powering down
2019-02-22T00:23:07.235Z,1550794987.235 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:23:07.235Z,1550794987.235 [PNI_TCM] No Fault, FailCount= 1
2019-02-22T00:23:10.747Z,1550794990.747 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:23:11.284Z,1550794991.284 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:23:11.284Z,1550794991.284 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T00:23:11.284Z,1550794991.284 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:23:11.428Z,1550794991.428 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:23:11.671Z,1550794991.671 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:23:12.601Z,1550794992.601 [PAR_Licor](INFO): A/D timeout, 6 tries over 130 ms

2019-02-22T00:23:12.601Z,1550794992.601 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:23:12.601Z,1550794992.601 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:23:12.677Z,1550794992.677 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:23:12.677Z,1550794992.677 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T00:23:12.677Z,1550794992.677 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:23:12.678Z,1550794992.678 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:23:12.678Z,1550794992.678 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:23:13.913Z,1550794993.913 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:23:13.913Z,1550794993.913 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T00:23:13.913Z,1550794993.913 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:23:14.204Z,1550794994.204 [PAR_Licor](INFO): A/D timeout, 6 tries over 131 ms

2019-02-22T00:23:14.204Z,1550794994.204 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:23:14.204Z,1550794994.204 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:23:14.317Z,1550794994.317 [CTD_Seabird](INFO): Powering down
2019-02-22T00:23:14.329Z,1550794994.329 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:23:14.330Z,1550794994.330 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:23:14.330Z,1550794994.330 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:23:14.330Z,1550794994.330 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:23:14.520Z,1550794994.520 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:23:14.520Z,1550794994.520 [PNI_TCM] Data Fault, FailCount= 2
2019-02-22T00:23:14.520Z,1550794994.520 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:23:14.651Z,1550794994.651 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:23:14.874Z,1550794994.874 [PNI_TCM](INFO): Powering down
2019-02-22T00:23:15.801Z,1550794995.801 [PAR_Licor](INFO): A/D timeout, 10 tries over 124 ms

2019-02-22T00:23:15.801Z,1550794995.801 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:23:15.801Z,1550794995.801 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:23:15.905Z,1550794995.905 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:23:15.905Z,1550794995.905 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:23:15.905Z,1550794995.905 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:23:16.199Z,1550794996.199 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:23:16.199Z,1550794996.199 [PNI_TCM] No Fault, FailCount= 2
2019-02-22T00:23:16.200Z,1550794996.200 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:23:16.200Z,1550794996.200 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T00:23:16.228Z,1550794996.228 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002770
CHAN A1 (24V): -0.006991
CHAN A2 (12V): -0.002053
CHAN A3 (5V): -0.001748
CHAN B0 (3.3V): -0.000620
CHAN B1 (3.15aV): 0.000033
CHAN B2 (3.15bV): -0.000348
CHAN B3 (GND): 0.000017
OPEN: -0.000392
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-22T00:23:17.145Z,1550794997.145 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:23:17.146Z,1550794997.146 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:23:23.145Z,1550795003.145 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:23:23.145Z,1550795003.145 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T00:23:23.146Z,1550795003.146 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:23:23.355Z,1550795003.355 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:23:23.355Z,1550795003.355 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T00:23:23.518Z,1550795003.518 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:23:23.518Z,1550795003.518 [PNI_TCM] Data Fault, FailCount= 3
2019-02-22T00:23:23.519Z,1550795003.519 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:23:23.520Z,1550795003.520 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:23:23.784Z,1550795003.784 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:23:23.934Z,1550795003.934 [PNI_TCM](INFO): Powering down
2019-02-22T00:23:25.219Z,1550795005.219 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:23:25.219Z,1550795005.219 [PNI_TCM] No Fault, FailCount= 3
2019-02-22T00:23:26.698Z,1550795006.698 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:23:26.698Z,1550795006.698 [NAL9602] Communications Fault, FailCount= 1
2019-02-22T00:23:26.699Z,1550795006.699 [NAL9602](ERROR): Communications Fault
2019-02-22T00:23:26.934Z,1550795006.934 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:23:27.098Z,1550795007.098 [NAL9602](INFO): Powering down
2019-02-22T00:23:28.027Z,1550795008.027 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:23:28.027Z,1550795008.027 [NAL9602] No Fault, FailCount= 1
2019-02-22T00:23:31.201Z,1550795011.201 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:23:31.201Z,1550795011.201 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T00:23:31.201Z,1550795011.201 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:23:31.304Z,1550795011.304 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:23:31.304Z,1550795011.304 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T00:23:31.609Z,1550795011.609 [CTD_Seabird](INFO): Powering down
2019-02-22T00:23:32.713Z,1550795012.713 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:23:32.714Z,1550795012.714 [PNI_TCM] Data Fault, FailCount= 4
2019-02-22T00:23:32.714Z,1550795012.714 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:23:32.811Z,1550795012.811 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:23:33.113Z,1550795013.113 [PNI_TCM](INFO): Powering down
2019-02-22T00:23:34.466Z,1550795014.466 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:23:34.466Z,1550795014.466 [PNI_TCM] No Fault, FailCount= 4
2019-02-22T00:23:37.665Z,1550795017.665 [PAR_Licor](INFO): A/D timeout, 11 tries over 125 ms

2019-02-22T00:23:37.666Z,1550795017.666 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:23:37.666Z,1550795017.666 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:23:37.766Z,1550795017.766 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:23:37.766Z,1550795017.766 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:23:37.766Z,1550795017.766 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:23:38.449Z,1550795018.449 [PAR_Licor](INFO): A/D timeout, 7 tries over 131 ms

2019-02-22T00:23:38.449Z,1550795018.449 [PAR_Licor] Data Fault, FailCount= 2
2019-02-22T00:23:38.449Z,1550795018.449 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:23:38.580Z,1550795018.580 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:23:38.580Z,1550795018.580 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:23:38.580Z,1550795018.580 [PAR_Licor] No Fault, FailCount= 2
2019-02-22T00:23:39.249Z,1550795019.249 [PAR_Licor](INFO): A/D timeout, 11 tries over 129 ms

2019-02-22T00:23:39.249Z,1550795019.249 [PAR_Licor] Data Fault, FailCount= 3
2019-02-22T00:23:39.249Z,1550795019.249 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:23:39.332Z,1550795019.332 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:23:39.332Z,1550795019.332 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-02-22T00:23:39.532Z,1550795019.532 [CBIT](INFO): Critical error at 20190222T002339
2019-02-22T00:23:41.916Z,1550795021.916 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:23:41.916Z,1550795021.916 [PNI_TCM] Data Fault, FailCount= 5
2019-02-22T00:23:41.916Z,1550795021.916 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:23:42.052Z,1550795022.052 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:23:42.052Z,1550795022.052 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-02-22T00:24:00.148Z,1550795040.148 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:24:00.202Z,1550795040.202 [PNI_TCM](INFO): Powering down
2019-02-22T00:24:00.285Z,1550795040.285 [CBIT](INFO): Critical error at 20190222T002342
2019-02-22T00:24:14.146Z,1550795054.146 [SBIT](IMPORTANT): SBIT PASSED
2019-02-22T00:24:14.245Z,1550795054.245 [CommandLine](IMPORTANT): got command configSet list
2019-02-22T00:24:14.249Z,1550795054.249 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-22T00:24:14.249Z,1550795054.249 [CommandLine](IMPORTANT): No configSet variables persisted
2019-02-22T00:24:14.508Z,1550795054.508 [MissionManager](IMPORTANT): Started mission Startup
2019-02-22T00:24:14.508Z,1550795054.508 [Startup] Running Loop=1
2019-02-22T00:24:14.508Z,1550795054.508 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-22T00:24:14.508Z,1550795054.508 [Startup:A.GoToSurface] Running Loop=1
2019-02-22T00:24:14.508Z,1550795054.508 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-22T00:24:14.509Z,1550795054.509 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-22T00:24:14.510Z,1550795054.510 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-22T00:24:14.510Z,1550795054.510 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-22T00:24:14.510Z,1550795054.510 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-22T00:24:14.511Z,1550795054.511 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-22T00:24:14.524Z,1550795054.524 [Startup:StartupSatComms] Running Loop=1
2019-02-22T00:24:14.524Z,1550795054.524 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-22T00:24:14.524Z,1550795054.524 [Startup:StartupSatComms:A] Running Loop=1
2019-02-22T00:24:14.912Z,1550795054.912 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-22T00:24:14.930Z,1550795054.930 [CommandLine](IMPORTANT): got command 
2019-02-22T00:24:14.930Z,1550795054.930 [CommandLine](FAULT): Incomplete syntax. Try: help
2019-02-22T00:24:19.642Z,1550795059.642 [CommandLine](IMPORTANT): got command failComponent
2019-02-22T00:24:19.642Z,1550795059.642 [CommandLine](IMPORTANT): Failed components:
2019-02-22T00:24:19.642Z,1550795059.642 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2019-02-22T00:24:19.642Z,1550795059.642 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2019-02-22T00:24:19.643Z,1550795059.643 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-02-22T00:24:19.643Z,1550795059.643 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2019-02-22T00:24:19.643Z,1550795059.643 [CommandLine](IMPORTANT): PAR_Licor: Data Fault
2019-02-22T00:24:21.282Z,1550795061.282 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:24:21.282Z,1550795061.282 [NAL9602] Communications Fault, FailCount= 2
2019-02-22T00:24:21.282Z,1550795061.282 [NAL9602](ERROR): Communications Fault
2019-02-22T00:24:21.334Z,1550795061.334 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:24:21.682Z,1550795061.682 [NAL9602](INFO): Powering down
2019-02-22T00:24:22.173Z,1550795062.173 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T00:24:22.632Z,1550795062.632 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:24:22.632Z,1550795062.632 [NAL9602] No Fault, FailCount= 2
2019-02-22T00:24:40.505Z,1550795080.505 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:24:40.505Z,1550795080.505 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-22T00:24:40.505Z,1550795080.505 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:24:40.507Z,1550795080.507 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:24:40.507Z,1550795080.507 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-22T00:24:40.507Z,1550795080.507 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:24:40.509Z,1550795080.509 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:24:40.509Z,1550795080.509 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-22T00:24:40.509Z,1550795080.509 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:24:40.562Z,1550795080.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:24:40.562Z,1550795080.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:24:40.562Z,1550795080.562 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:24:40.951Z,1550795080.951 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:24:40.951Z,1550795080.951 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-22T00:24:40.951Z,1550795080.951 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:24:40.951Z,1550795080.951 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-22T00:24:40.952Z,1550795080.952 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:24:40.952Z,1550795080.952 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-22T00:24:41.065Z,1550795081.065 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:24:41.065Z,1550795081.065 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-22T00:24:41.065Z,1550795081.065 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:24:41.305Z,1550795081.305 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:24:41.349Z,1550795081.349 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:24:41.469Z,1550795081.469 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:24:42.004Z,1550795082.004 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:24:57.487Z,1550795097.487 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:24:57.634Z,1550795097.634 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T00:24:57.634Z,1550795097.634 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-22T00:24:57.769Z,1550795097.769 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:24:57.770Z,1550795097.770 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:25:02.238Z,1550795102.238 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:25:02.368Z,1550795102.368 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T00:25:14.892Z,1550795114.892 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-22T00:24:14.5Z
2019-02-22T00:25:14.892Z,1550795114.892 [Startup:StartupSatComms:A] Stopped
2019-02-22T00:25:14.892Z,1550795114.892 [Startup:StartupSatComms:B] Running Loop=1
2019-02-22T00:25:15.309Z,1550795115.309 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-22T00:25:30.822Z,1550795130.822 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:25:30.822Z,1550795130.822 [NAL9602] Communications Fault, FailCount= 3
2019-02-22T00:25:30.822Z,1550795130.822 [NAL9602](ERROR): Communications Fault
2019-02-22T00:25:30.979Z,1550795130.979 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:25:31.053Z,1550795131.053 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190221T232104/Courier0016.lzma
2019-02-22T00:25:31.076Z,1550795131.076 [NAL9602](INFO): Powering down
2019-02-22T00:25:31.823Z,1550795131.823 [DataOverHttps](INFO): Moved sent file to Logs/20190221T232104/Courier0016.lzma.bak
2019-02-22T00:25:31.823Z,1550795131.823 [DataOverHttps](INFO): SBD MOMSN=10068243
2019-02-22T00:25:32.012Z,1550795132.012 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:25:32.012Z,1550795132.012 [NAL9602] No Fault, FailCount= 3
2019-02-22T00:25:50.681Z,1550795150.681 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T00:26:07.180Z,1550795167.180 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:26:07.322Z,1550795167.322 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:26:07.322Z,1550795167.322 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T00:26:07.338Z,1550795167.338 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:26:07.338Z,1550795167.338 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T00:26:07.378Z,1550795167.378 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20190222T001039/Courier0000.lzma
2019-02-22T00:26:07.625Z,1550795167.625 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:26:07.628Z,1550795167.628 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:26:08.150Z,1550795168.150 [DataOverHttps](INFO): Moved sent file to Logs/20190222T001039/Courier0000.lzma.bak
2019-02-22T00:26:08.150Z,1550795168.150 [DataOverHttps](INFO): SBD MOMSN=10068246
2019-02-22T00:26:16.927Z,1550795176.927 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:26:21.677Z,1550795181.677 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:26:21.677Z,1550795181.677 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T00:26:21.677Z,1550795181.677 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:26:22.081Z,1550795182.081 [CTD_Seabird](INFO): Powering down
2019-02-22T00:26:28.005Z,1550795188.005 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:26:28.005Z,1550795188.005 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T00:26:28.005Z,1550795188.005 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:26:28.052Z,1550795188.052 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-22T00:25:14.9Z
2019-02-22T00:26:28.052Z,1550795188.052 [Startup:StartupSatComms:B] Stopped
2019-02-22T00:26:28.052Z,1550795188.052 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-22T00:26:28.052Z,1550795188.052 [Startup:StartupSatComms] Stopped
2019-02-22T00:26:28.069Z,1550795188.069 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-22T00:26:28.070Z,1550795188.070 [Startup](INFO): Completed Startup
2019-02-22T00:26:28.070Z,1550795188.070 [MissionManager](INFO): Startup is completed.
2019-02-22T00:26:28.070Z,1550795188.070 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-22T00:26:28.070Z,1550795188.070 [Startup] Stopped
2019-02-22T00:26:28.070Z,1550795188.070 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-22T00:26:28.070Z,1550795188.070 [Startup:A.GoToSurface] Stopped
2019-02-22T00:26:28.071Z,1550795188.071 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-22T00:26:28.212Z,1550795188.212 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:26:28.212Z,1550795188.212 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:26:28.212Z,1550795188.212 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:26:28.212Z,1550795188.212 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T00:26:28.261Z,1550795188.261 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:26:28.264Z,1550795188.264 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:26:28.465Z,1550795188.465 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:26:28.476Z,1550795188.476 [MissionManager](IMPORTANT): Started mission Default
2019-02-22T00:26:28.476Z,1550795188.476 [Default] Running Loop=1
2019-02-22T00:26:28.476Z,1550795188.476 [Default](DEBUG): Aggregate::initialize Default
2019-02-22T00:26:28.476Z,1550795188.476 [Default:B.GoToSurface] Running Loop=1
2019-02-22T00:26:28.476Z,1550795188.476 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-22T00:26:28.477Z,1550795188.477 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-22T00:26:28.477Z,1550795188.477 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-22T00:26:28.477Z,1550795188.477 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-22T00:26:28.478Z,1550795188.478 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-22T00:26:28.478Z,1550795188.478 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-22T00:26:28.478Z,1550795188.478 [Default:A.Wait] Running Loop=1
2019-02-22T00:26:28.478Z,1550795188.478 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-22T00:26:29.403Z,1550795189.403 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:26:29.403Z,1550795189.403 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T00:26:32.064Z,1550795192.064 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T00:26:38.585Z,1550795198.585 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:26:42.329Z,1550795202.329 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:26:42.329Z,1550795202.329 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T00:26:42.329Z,1550795202.329 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:26:42.733Z,1550795202.733 [CTD_Seabird](INFO): Powering down
2019-02-22T00:26:47.857Z,1550795207.857 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:26:47.857Z,1550795207.857 [NAL9602] Communications Fault, FailCount= 4
2019-02-22T00:26:47.857Z,1550795207.857 [NAL9602](ERROR): Communications Fault
2019-02-22T00:26:47.913Z,1550795207.913 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:26:47.914Z,1550795207.914 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T00:26:47.914Z,1550795207.914 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:26:47.952Z,1550795207.952 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:26:47.952Z,1550795207.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-22T00:26:47.952Z,1550795207.952 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:26:47.970Z,1550795207.970 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:26:47.971Z,1550795207.971 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-22T00:26:47.971Z,1550795207.971 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:26:47.972Z,1550795207.972 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:26:47.972Z,1550795207.972 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-22T00:26:47.972Z,1550795207.972 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:26:48.003Z,1550795208.003 [Default:A.Wait](INFO): Done Waiting.
2019-02-22T00:26:48.003Z,1550795208.003 [Default:A.Wait] Stopped
2019-02-22T00:26:48.003Z,1550795208.003 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-22T00:26:48.046Z,1550795208.046 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:26:48.046Z,1550795208.046 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:26:48.046Z,1550795208.046 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:26:48.047Z,1550795208.047 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:26:48.047Z,1550795208.047 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:26:48.047Z,1550795208.047 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:26:48.047Z,1550795208.047 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:26:48.048Z,1550795208.048 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T00:26:48.100Z,1550795208.100 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190222T001743/Courier0000.lzma
2019-02-22T00:26:48.105Z,1550795208.105 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:26:48.107Z,1550795208.107 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:26:48.156Z,1550795208.156 [NAL9602](INFO): Powering down
2019-02-22T00:26:48.337Z,1550795208.337 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:26:48.349Z,1550795208.349 [Default:CheckIn] Running Loop=1
2019-02-22T00:26:48.349Z,1550795208.349 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-22T00:26:48.349Z,1550795208.349 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-22T00:26:48.387Z,1550795208.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:26:48.387Z,1550795208.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-22T00:26:48.387Z,1550795208.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:26:48.388Z,1550795208.388 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-22T00:26:48.388Z,1550795208.388 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:26:48.388Z,1550795208.388 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-22T00:26:48.541Z,1550795208.541 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:26:48.543Z,1550795208.543 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-22T00:26:48.874Z,1550795208.874 [DataOverHttps](INFO): Moved sent file to Logs/20190222T001743/Courier0000.lzma.bak
2019-02-22T00:26:48.874Z,1550795208.874 [DataOverHttps](INFO): SBD MOMSN=10068253
2019-02-22T00:26:49.087Z,1550795209.087 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:26:49.087Z,1550795209.087 [NAL9602] No Fault, FailCount= 4
2019-02-22T00:26:49.088Z,1550795209.088 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:26:49.088Z,1550795209.088 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T00:26:57.933Z,1550795217.933 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:26:57.933Z,1550795217.933 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-22T00:26:57.933Z,1550795217.933 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:26:58.167Z,1550795218.167 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:26:58.337Z,1550795218.337 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:26:58.824Z,1550795218.824 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:26:59.695Z,1550795219.695 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:26:59.695Z,1550795219.695 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T00:26:59.695Z,1550795219.695 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:26:59.751Z,1550795219.751 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:26:59.752Z,1550795219.752 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T00:27:00.213Z,1550795220.213 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:27:00.272Z,1550795220.272 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T00:27:00.272Z,1550795220.272 [Rowe_600LCM] No Fault, FailCount= 2
2019-02-22T00:27:00.493Z,1550795220.493 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:27:00.494Z,1550795220.494 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:27:02.161Z,1550795222.161 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:27:02.161Z,1550795222.161 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T00:27:02.161Z,1550795222.161 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:27:02.532Z,1550795222.532 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:27:02.532Z,1550795222.532 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T00:27:02.581Z,1550795222.581 [CTD_Seabird](INFO): Powering down
2019-02-22T00:27:05.090Z,1550795225.090 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:27:05.452Z,1550795225.452 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-22T00:27:18.482Z,1550795238.482 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:27:44.647Z,1550795264.647 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:27:44.647Z,1550795264.647 [NAL9602] Communications Fault, FailCount= 5
2019-02-22T00:27:44.647Z,1550795264.647 [NAL9602](ERROR): Communications Fault
2019-02-22T00:27:44.834Z,1550795264.834 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:27:44.834Z,1550795264.834 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-02-22T00:27:44.951Z,1550795264.951 [NAL9602](INFO): Powering down
2019-02-22T00:27:45.199Z,1550795265.199 [CBIT](INFO): Critical error at 20190222T002744
2019-02-22T00:28:13.513Z,1550795293.513 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:28:13.556Z,1550795293.556 [NavChartDb](INFO): # of records loaded: 19732
2019-02-22T00:28:13.587Z,1550795293.587 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-22T00:28:13.613Z,1550795293.613 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:28:38.352Z,1550795318.352 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:28:49.501Z,1550795329.501 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:28:49.501Z,1550795329.501 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-22T00:28:49.502Z,1550795329.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:28:49.503Z,1550795329.503 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:28:49.503Z,1550795329.503 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-22T00:28:49.503Z,1550795329.503 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:28:49.561Z,1550795329.561 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:28:49.561Z,1550795329.561 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-22T00:28:49.561Z,1550795329.561 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02