2019-02-22T00:47:43.267Z,1550796463.267 [Supervisor](DEBUG): Initializing supervisor.
2019-02-22T00:47:43.305Z,1550796463.305 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-22T00:47:43.305Z,1550796463.305 [SyncHandler](INFO): Protected caller Thread ID is 6047
2019-02-22T00:47:43.306Z,1550796463.306 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-22T00:47:43.319Z,1550796463.319 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-22T00:47:43.320Z,1550796463.320 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6048
2019-02-22T00:47:43.331Z,1550796463.331 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-22T00:47:43.371Z,1550796463.371 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-22T00:47:43.395Z,1550796463.395 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-22T00:47:43.396Z,1550796463.396 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6049
2019-02-22T00:47:43.397Z,1550796463.397 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-22T00:47:43.424Z,1550796463.424 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-22T00:47:43.425Z,1550796463.425 [logger ThreadHandler](INFO): Protected caller Thread ID is 6050
2019-02-22T00:47:43.427Z,1550796463.427 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-22T00:47:43.427Z,1550796463.427 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-22T00:47:43.443Z,1550796463.443 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-22T00:47:44.042Z,1550796464.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-22T00:47:44.042Z,1550796464.042 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-22T00:47:44.191Z,1550796464.191 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-22T00:47:45.604Z,1550796465.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-22T00:47:45.605Z,1550796465.605 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-22T00:47:46.532Z,1550796466.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-22T00:47:46.533Z,1550796466.533 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-22T00:47:46.735Z,1550796466.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-22T00:47:46.735Z,1550796466.735 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-22T00:47:47.560Z,1550796467.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-22T00:47:47.560Z,1550796467.560 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-22T00:47:48.157Z,1550796468.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-22T00:47:48.158Z,1550796468.158 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-22T00:47:49.588Z,1550796469.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-22T00:47:49.589Z,1550796469.589 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-22T00:47:49.781Z,1550796469.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-22T00:47:49.782Z,1550796469.782 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-22T00:47:49.984Z,1550796469.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-22T00:47:49.985Z,1550796469.985 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-22T00:47:51.273Z,1550796471.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-22T00:47:51.274Z,1550796471.274 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-22T00:47:52.429Z,1550796472.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-22T00:47:52.429Z,1550796472.429 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-22T00:47:53.232Z,1550796473.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-22T00:47:53.233Z,1550796473.233 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-22T00:47:53.569Z,1550796473.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-22T00:47:53.569Z,1550796473.569 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-22T00:47:53.927Z,1550796473.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-22T00:47:53.929Z,1550796473.929 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-02-22T00:47:53.930Z,1550796473.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-02-22T00:47:54.141Z,1550796474.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-02-22T00:47:54.300Z,1550796474.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-02-22T00:47:54.556Z,1550796474.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-02-22T00:47:54.745Z,1550796474.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-02-22T00:47:54.961Z,1550796474.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-02-22T00:47:55.206Z,1550796475.206 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-02-22T00:47:55.714Z,1550796475.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-02-22T00:47:55.847Z,1550796475.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-02-22T00:47:56.393Z,1550796476.393 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-02-22T00:47:56.702Z,1550796476.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-02-22T00:47:56.955Z,1550796476.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-02-22T00:47:57.663Z,1550796477.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-22T00:47:57.664Z,1550796477.664 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-02-22T00:47:57.665Z,1550796477.665 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-22T00:47:57.667Z,1550796477.667 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-22T00:47:57.806Z,1550796477.806 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-22T00:47:58.203Z,1550796478.203 [VerticalControl] Loaded
2019-02-22T00:47:58.204Z,1550796478.204 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-22T00:47:58.204Z,1550796478.204 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-22T00:47:58.415Z,1550796478.415 [HorizontalControl] Loaded
2019-02-22T00:47:58.415Z,1550796478.415 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-22T00:47:58.416Z,1550796478.416 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-22T00:47:58.428Z,1550796478.428 [SpeedControl] Loaded
2019-02-22T00:47:58.428Z,1550796478.428 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-22T00:47:58.433Z,1550796478.433 [LoopControl](DEBUG): Construct LoopControl.
2019-02-22T00:47:58.434Z,1550796478.434 [LoopControl] Loaded
2019-02-22T00:47:58.434Z,1550796478.434 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-22T00:47:58.435Z,1550796478.435 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-22T00:47:58.435Z,1550796478.435 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-22T00:47:58.467Z,1550796478.467 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-22T00:47:58.468Z,1550796478.468 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-22T00:47:58.787Z,1550796478.787 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-22T00:47:58.787Z,1550796478.787 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-22T00:47:59.073Z,1550796479.073 [BuoyancyServo] Loaded
2019-02-22T00:47:59.073Z,1550796479.073 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-22T00:47:59.104Z,1550796479.104 [ElevatorServo] Loaded
2019-02-22T00:47:59.104Z,1550796479.104 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-22T00:47:59.142Z,1550796479.142 [MassServo] Loaded
2019-02-22T00:47:59.142Z,1550796479.142 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-22T00:47:59.205Z,1550796479.205 [RudderServo] Loaded
2019-02-22T00:47:59.206Z,1550796479.206 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-22T00:47:59.258Z,1550796479.258 [ThrusterServo] Loaded
2019-02-22T00:47:59.259Z,1550796479.259 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-22T00:47:59.259Z,1550796479.259 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-22T00:47:59.259Z,1550796479.259 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-22T00:47:59.639Z,1550796479.639 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-22T00:47:59.639Z,1550796479.639 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-22T00:47:59.796Z,1550796479.796 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-22T00:47:59.816Z,1550796479.816 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-22T00:48:00.003Z,1550796480.003 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-22T00:48:00.004Z,1550796480.004 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-22T00:48:00.060Z,1550796480.060 [NavChart] Loaded
2019-02-22T00:48:00.060Z,1550796480.060 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-22T00:48:00.079Z,1550796480.079 [UniversalFixResidualReporter] Loaded
2019-02-22T00:48:00.079Z,1550796480.079 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-22T00:48:00.080Z,1550796480.080 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-22T00:48:00.080Z,1550796480.080 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-22T00:48:00.275Z,1550796480.275 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-22T00:48:00.275Z,1550796480.275 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-22T00:48:01.199Z,1550796481.199 [DataOverHttps] Loaded
2019-02-22T00:48:01.199Z,1550796481.199 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-22T00:48:01.223Z,1550796481.223 [Depth_Keller] Loaded
2019-02-22T00:48:01.223Z,1550796481.223 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-22T00:48:01.263Z,1550796481.263 [DropWeight] Loaded
2019-02-22T00:48:01.263Z,1550796481.263 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-22T00:48:01.565Z,1550796481.565 [NAL9602] Loaded
2019-02-22T00:48:01.565Z,1550796481.565 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-22T00:48:01.623Z,1550796481.623 [Onboard] Loaded
2019-02-22T00:48:01.623Z,1550796481.623 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-22T00:48:01.639Z,1550796481.639 [Radio_Surface] Loaded
2019-02-22T00:48:01.639Z,1550796481.639 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-22T00:48:01.645Z,1550796481.645 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-22T00:48:01.645Z,1550796481.645 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6134
2019-02-22T00:48:01.834Z,1550796481.834 [PNI_TCM] Loaded
2019-02-22T00:48:01.842Z,1550796481.842 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-22T00:48:01.939Z,1550796481.939 [Rowe_600LCM] Loaded
2019-02-22T00:48:01.975Z,1550796481.975 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-22T00:48:01.976Z,1550796481.976 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-22T00:48:01.976Z,1550796481.976 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6135
2019-02-22T00:48:01.986Z,1550796481.986 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-22T00:48:01.987Z,1550796481.987 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-22T00:48:02.083Z,1550796482.083 [DepthRateCalculator] Loaded
2019-02-22T00:48:02.084Z,1550796482.084 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-22T00:48:02.091Z,1550796482.091 [PitchRateCalculator] Loaded
2019-02-22T00:48:02.091Z,1550796482.091 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-22T00:48:02.109Z,1550796482.109 [SpeedCalculator] Loaded
2019-02-22T00:48:02.132Z,1550796482.132 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-22T00:48:02.198Z,1550796482.198 [TempGradientCalculator] Loaded
2019-02-22T00:48:02.198Z,1550796482.198 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-22T00:48:02.225Z,1550796482.225 [YawRateCalculator] Loaded
2019-02-22T00:48:02.225Z,1550796482.225 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-22T00:48:02.279Z,1550796482.279 [ElevatorOffsetCalculator] Loaded
2019-02-22T00:48:02.280Z,1550796482.280 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-22T00:48:02.280Z,1550796482.280 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-22T00:48:02.281Z,1550796482.281 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-22T00:48:02.940Z,1550796482.940 [Aanderaa_O2] Loaded
2019-02-22T00:48:02.940Z,1550796482.940 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-22T00:48:03.216Z,1550796483.216 [CTD_NeilBrown] Loaded
2019-02-22T00:48:03.216Z,1550796483.216 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-22T00:48:03.221Z,1550796483.221 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2019-02-22T00:48:03.221Z,1550796483.221 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6136
2019-02-22T00:48:03.239Z,1550796483.239 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-22T00:48:03.247Z,1550796483.247 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-22T00:48:03.248Z,1550796483.248 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-22T00:48:03.266Z,1550796483.266 [CTD_Seabird](INFO): created writer for : depth
2019-02-22T00:48:03.266Z,1550796483.266 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-22T00:48:03.271Z,1550796483.271 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-22T00:48:03.272Z,1550796483.272 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-22T00:48:03.317Z,1550796483.317 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-22T00:48:03.318Z,1550796483.318 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-22T00:48:03.323Z,1550796483.323 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-22T00:48:03.324Z,1550796483.324 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-22T00:48:03.337Z,1550796483.337 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-22T00:48:03.338Z,1550796483.338 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-22T00:48:03.356Z,1550796483.356 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-22T00:48:03.413Z,1550796483.413 [CTD_Seabird] Loaded
2019-02-22T00:48:03.413Z,1550796483.413 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-22T00:48:03.428Z,1550796483.428 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0
2019-02-22T00:48:03.428Z,1550796483.428 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6137
2019-02-22T00:48:03.465Z,1550796483.465 [PAR_Licor] Loaded
2019-02-22T00:48:03.465Z,1550796483.465 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-22T00:48:03.627Z,1550796483.627 [WetLabsBB2FL] Loaded
2019-02-22T00:48:03.627Z,1550796483.627 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-22T00:48:03.661Z,1550796483.661 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0
2019-02-22T00:48:03.661Z,1550796483.661 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6138
2019-02-22T00:48:03.662Z,1550796483.662 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-22T00:48:03.662Z,1550796483.662 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-22T00:48:03.719Z,1550796483.719 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-22T00:48:03.720Z,1550796483.720 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-22T00:48:04.462Z,1550796484.462 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-22T00:48:04.463Z,1550796484.463 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-22T00:48:04.850Z,1550796484.850 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-22T00:48:04.865Z,1550796484.865 [SBIT] Loaded
2019-02-22T00:48:04.865Z,1550796484.865 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-22T00:48:04.868Z,1550796484.868 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-22T00:48:04.887Z,1550796484.887 [IBIT] Loaded
2019-02-22T00:48:04.887Z,1550796484.887 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-22T00:48:04.892Z,1550796484.892 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-22T00:48:06.198Z,1550796486.198 [CBIT] Loaded
2019-02-22T00:48:06.199Z,1550796486.199 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-22T00:48:06.200Z,1550796486.200 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-22T00:48:06.204Z,1550796486.204 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-22T00:48:06.209Z,1550796486.209 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-22T00:48:06.215Z,1550796486.215 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-22T00:48:06.225Z,1550796486.225 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0
2019-02-22T00:48:06.225Z,1550796486.225 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6139
2019-02-22T00:48:06.230Z,1550796486.230 [Supervisor](INFO): Main Thread ID is 6046
2019-02-22T00:48:06.230Z,1550796486.230 [Supervisor](DEBUG): Running supervisor.
2019-02-22T00:48:06.231Z,1550796486.231 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6140
2019-02-22T00:48:06.248Z,1550796486.248 [controlThread ThreadHandler](INFO): Handler Thread ID is 6141
2019-02-22T00:48:06.248Z,1550796486.248 [controlThread](DEBUG): Initializing ControlThread
2019-02-22T00:48:06.249Z,1550796486.249 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-22T00:48:06.251Z,1550796486.251 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-22T00:48:06.251Z,1550796486.251 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-22T00:48:06.252Z,1550796486.252 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-22T00:48:06.254Z,1550796486.254 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:48:06.255Z,1550796486.255 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-22T00:48:06.255Z,1550796486.255 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-22T00:48:06.259Z,1550796486.259 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-22T00:48:06.259Z,1550796486.259 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-22T00:48:06.260Z,1550796486.260 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-22T00:48:06.260Z,1550796486.260 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-22T00:48:06.260Z,1550796486.260 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-22T00:48:06.261Z,1550796486.261 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-22T00:48:06.265Z,1550796486.265 [SBIT](INFO): Initialize SBIT Component.
2019-02-22T00:48:06.266Z,1550796486.266 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a
2019-02-22T00:48:06.266Z,1550796486.266 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4
2019-02-22T00:48:06.266Z,1550796486.266 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-22T00:48:06.267Z,1550796486.267 [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:48:06.268Z,1550796486.268 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-02-22T00:48:06.269Z,1550796486.269 [IBIT](INFO): Initialize IBIT Component.
2019-02-22T00:48:06.270Z,1550796486.270 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-22T00:48:06.283Z,1550796486.283 [CBIT](DEBUG): Initialized mux pins.
2019-02-22T00:48:06.283Z,1550796486.283 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-22T00:48:06.293Z,1550796486.293 [logger ThreadHandler](INFO): Handler Thread ID is 6142
2019-02-22T00:48:06.313Z,1550796486.313 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-02-22T00:48:06.313Z,1550796486.313 [CBIT](DEBUG): Initializing heartbeat.
2019-02-22T00:48:06.338Z,1550796486.338 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6143
2019-02-22T00:48:06.349Z,1550796486.349 [Radio_Surface](INFO): Powering up
2019-02-22T00:48:06.350Z,1550796486.350 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6144
2019-02-22T00:48:06.351Z,1550796486.351 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:48:06.361Z,1550796486.361 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:48:06.370Z,1550796486.370 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6145
2019-02-22T00:48:06.371Z,1550796486.371 [CTD_NeilBrown](INFO): Powering down
2019-02-22T00:48:06.385Z,1550796486.385 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-22T00:48:06.397Z,1550796486.397 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-22T00:48:06.409Z,1550796486.409 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6146
2019-02-22T00:48:06.410Z,1550796486.410 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:48:06.417Z,1550796486.417 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6148
2019-02-22T00:48:06.418Z,1550796486.418 [WetLabsBB2FL](INFO): Powering down
2019-02-22T00:48:06.434Z,1550796486.434 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-22T00:48:06.444Z,1550796486.444 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-22T00:48:06.447Z,1550796486.447 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:48:06.489Z,1550796486.489 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6149
2019-02-22T00:48:06.492Z,1550796486.492 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-02-22T00:48:06.496Z,1550796486.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-22T00:48:06.497Z,1550796486.497 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-02-22T00:48:06.497Z,1550796486.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-22T00:48:06.499Z,1550796486.499 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-02-22T00:48:06.499Z,1550796486.499 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-22T00:48:06.500Z,1550796486.500 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-02-22T00:48:06.501Z,1550796486.501 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-22T00:48:06.502Z,1550796486.502 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-02-22T00:48:06.502Z,1550796486.502 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-22T00:48:06.504Z,1550796486.504 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-02-22T00:48:06.504Z,1550796486.504 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T00:48:06.506Z,1550796486.506 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-02-22T00:48:06.506Z,1550796486.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:48:06.507Z,1550796486.507 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-02-22T00:48:06.507Z,1550796486.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:48:06.509Z,1550796486.509 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-02-22T00:48:06.555Z,1550796486.555 [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:48:06.556Z,1550796486.556 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-22T00:48:06.597Z,1550796486.597 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:48:06.747Z,1550796486.747 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-22T00:48:06.748Z,1550796486.748 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-22T00:48:06.809Z,1550796486.809 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-22T00:48:06.912Z,1550796486.912 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-22T00:48:06.944Z,1550796486.944 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-22T00:48:07.020Z,1550796487.020 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-22T00:48:07.040Z,1550796487.040 [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:48:07.065Z,1550796487.065 [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:48:07.142Z,1550796487.142 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-22T00:48:07.216Z,1550796487.216 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-02-22T00:48:07.593Z,1550796487.593 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-22T00:48:08.178Z,1550796488.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:48:08.189Z,1550796488.189 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-22T00:48:08.219Z,1550796488.219 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:48:08.237Z,1550796488.237 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-22T00:48:08.277Z,1550796488.277 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:48:08.289Z,1550796488.289 [MassServo](DEBUG): Initializing MassServo.
2019-02-22T00:48:08.319Z,1550796488.319 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:48:08.333Z,1550796488.333 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-22T00:48:08.345Z,1550796488.345 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-22T00:48:08.361Z,1550796488.361 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-22T00:48:08.431Z,1550796488.431 [CommandLine](FAULT): Scheduling is paused
2019-02-22T00:48:08.431Z,1550796488.431 [CBIT](INFO): Critical error at 20190222T004806
2019-02-22T00:48:08.432Z,1550796488.432 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-22T00:48:09.178Z,1550796489.178 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-22T00:48:09.178Z,1550796489.178 [DropWeight] Hardware Fault, FailCount= 1
2019-02-22T00:48:09.178Z,1550796489.178 [DropWeight](ERROR): Hardware Fault
2019-02-22T00:48:09.574Z,1550796489.574 [CBIT](INFO): Critical error at 20190222T004809
2019-02-22T00:48:09.577Z,1550796489.577 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-22T00:48:09.578Z,1550796489.578 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-22T00:48:10.927Z,1550796490.927 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:48:11.199Z,1550796491.199 [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:48:20.509Z,1550796500.509 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:48:20.509Z,1550796500.509 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T00:48:20.509Z,1550796500.509 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:48:20.913Z,1550796500.913 [CTD_Seabird](INFO): Powering down
2019-02-22T00:48:29.646Z,1550796509.646 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:48:29.646Z,1550796509.646 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T00:48:29.647Z,1550796509.647 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:48:29.734Z,1550796509.734 [CBIT](INFO): Critical error at 20190222T004809
2019-02-22T00:48:29.737Z,1550796509.737 [CBIT](CRITICAL): Environmental Failure. Press:14.581689 PSI. Humidity:16%. Temp:23 C. ABORTING MISSION
2019-02-22T00:48:29.737Z,1550796509.737 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:48:29.738Z,1550796509.738 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:48:29.738Z,1550796509.738 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:48:29.738Z,1550796509.738 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T00:48:29.853Z,1550796509.853 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:48:29.854Z,1550796509.854 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:48:29.925Z,1550796509.925 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:48:30.322Z,1550796510.322 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-22T00:48:30.324Z,1550796510.324 [CBIT](INFO): Critical error at 20190222T004829
2019-02-22T00:48:30.327Z,1550796510.327 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-22T00:48:30.832Z,1550796510.832 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.095014
2019-02-22T00:48:31.226Z,1550796511.226 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:48:31.226Z,1550796511.226 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T00:48:31.847Z,1550796511.847 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:48:35.546Z,1550796515.546 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:48:35.546Z,1550796515.546 [PNI_TCM] Data Fault, FailCount= 1
2019-02-22T00:48:35.546Z,1550796515.546 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:48:35.795Z,1550796515.795 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:48:35.947Z,1550796515.947 [PNI_TCM](INFO): Powering down
2019-02-22T00:48:37.228Z,1550796517.228 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:48:37.228Z,1550796517.228 [PNI_TCM] No Fault, FailCount= 1
2019-02-22T00:48:38.202Z,1550796518.202 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:48:41.309Z,1550796521.309 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004681
CHAN A1 (24V): -0.007950
CHAN A2 (12V): -0.002498
CHAN A3 (5V): -0.001365
CHAN B0 (3.3V): 0.000520
CHAN B1 (3.15aV): 0.000024
CHAN B2 (3.15bV): -0.000579
CHAN B3 (GND): -0.000194
OPEN: -0.000212
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-22T00:48:41.547Z,1550796521.547 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:48:41.547Z,1550796521.547 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T00:48:41.547Z,1550796521.547 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:48:41.706Z,1550796521.706 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:48:41.949Z,1550796521.949 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:48:42.877Z,1550796522.877 [PAR_Licor](INFO): A/D timeout, 8 tries over 127 ms

2019-02-22T00:48:42.877Z,1550796522.877 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:48:42.877Z,1550796522.877 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:48:42.993Z,1550796522.993 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:48:42.993Z,1550796522.993 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T00:48:42.994Z,1550796522.994 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:48:42.994Z,1550796522.994 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:48:42.994Z,1550796522.994 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:48:43.917Z,1550796523.917 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:48:43.917Z,1550796523.917 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T00:48:43.917Z,1550796523.917 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:48:44.044Z,1550796524.044 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:48:44.337Z,1550796524.337 [CTD_Seabird](INFO): Powering down
2019-02-22T00:48:44.764Z,1550796524.764 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:48:44.764Z,1550796524.764 [PNI_TCM] Data Fault, FailCount= 2
2019-02-22T00:48:44.764Z,1550796524.764 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:48:44.868Z,1550796524.868 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:48:45.153Z,1550796525.153 [PNI_TCM](INFO): Powering down
2019-02-22T00:48:46.418Z,1550796526.418 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:48:46.418Z,1550796526.418 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T00:48:46.802Z,1550796526.802 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:48:46.803Z,1550796526.803 [PNI_TCM] No Fault, FailCount= 2
2019-02-22T00:48:47.166Z,1550796527.166 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:48:47.168Z,1550796527.168 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:48:52.571Z,1550796532.571 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:48:52.571Z,1550796532.571 [NAL9602] Communications Fault, FailCount= 1
2019-02-22T00:48:52.571Z,1550796532.571 [NAL9602](ERROR): Communications Fault
2019-02-22T00:48:52.771Z,1550796532.771 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:48:52.970Z,1550796532.970 [NAL9602](INFO): Powering down
2019-02-22T00:48:53.398Z,1550796533.398 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:48:53.398Z,1550796533.398 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T00:48:53.399Z,1550796533.399 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:48:53.528Z,1550796533.528 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:48:53.528Z,1550796533.528 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T00:48:53.790Z,1550796533.790 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:48:53.909Z,1550796533.909 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:48:53.909Z,1550796533.909 [NAL9602] No Fault, FailCount= 1
2019-02-22T00:48:54.182Z,1550796534.182 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:48:54.182Z,1550796534.182 [PNI_TCM] Data Fault, FailCount= 3
2019-02-22T00:48:54.182Z,1550796534.182 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:48:54.329Z,1550796534.329 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:48:54.582Z,1550796534.582 [PNI_TCM](INFO): Powering down
2019-02-22T00:48:55.891Z,1550796535.891 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:48:55.891Z,1550796535.891 [PNI_TCM] No Fault, FailCount= 3
2019-02-22T00:49:01.217Z,1550796541.217 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:49:01.217Z,1550796541.217 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T00:49:01.217Z,1550796541.217 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:49:01.487Z,1550796541.487 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:49:01.487Z,1550796541.487 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T00:49:01.621Z,1550796541.621 [CTD_Seabird](INFO): Powering down
2019-02-22T00:49:03.113Z,1550796543.113 [PAR_Licor](INFO): A/D timeout, 6 tries over 124 ms

2019-02-22T00:49:03.124Z,1550796543.124 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:49:03.124Z,1550796543.124 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:03.314Z,1550796543.314 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:03.314Z,1550796543.314 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:03.314Z,1550796543.314 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:49:03.434Z,1550796543.434 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:49:03.434Z,1550796543.434 [PNI_TCM] Data Fault, FailCount= 4
2019-02-22T00:49:03.434Z,1550796543.434 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:49:03.659Z,1550796543.659 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:49:03.777Z,1550796543.777 [PNI_TCM](INFO): Powering down
2019-02-22T00:49:04.733Z,1550796544.733 [PAR_Licor](INFO): A/D timeout, 7 tries over 147 ms

2019-02-22T00:49:04.734Z,1550796544.734 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:49:04.734Z,1550796544.734 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:04.823Z,1550796544.823 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:04.823Z,1550796544.823 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:04.823Z,1550796544.823 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:49:05.111Z,1550796545.111 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:49:05.111Z,1550796545.111 [PNI_TCM] No Fault, FailCount= 4
2019-02-22T00:49:25.748Z,1550796565.748 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:49:25.788Z,1550796565.788 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:49:25.788Z,1550796565.788 [PNI_TCM] Data Fault, FailCount= 5
2019-02-22T00:49:25.788Z,1550796565.788 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:49:25.917Z,1550796565.917 [PAR_Licor](INFO): A/D timeout, 9 tries over 124 ms

2019-02-22T00:49:25.917Z,1550796565.917 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:49:25.917Z,1550796565.917 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:25.999Z,1550796565.999 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:49:25.000Z,1550796566.000 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-02-22T00:49:26.000Z,1550796566.000 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:26.000Z,1550796566.000 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:26.000Z,1550796566.000 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:49:26.123Z,1550796566.123 [PNI_TCM](INFO): Powering down
2019-02-22T00:49:26.182Z,1550796566.182 [CBIT](INFO): Critical error at 20190222T004925
2019-02-22T00:49:27.009Z,1550796567.009 [PAR_Licor](INFO): A/D timeout, 11 tries over 151 ms

2019-02-22T00:49:27.009Z,1550796567.009 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:49:27.009Z,1550796567.009 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:27.094Z,1550796567.094 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:27.094Z,1550796567.094 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:27.094Z,1550796567.094 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:49:27.817Z,1550796567.817 [PAR_Licor](INFO): A/D timeout, 8 tries over 156 ms

2019-02-22T00:49:27.817Z,1550796567.817 [PAR_Licor] Data Fault, FailCount= 2
2019-02-22T00:49:27.817Z,1550796567.817 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:28.162Z,1550796568.162 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:28.163Z,1550796568.163 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:28.163Z,1550796568.163 [PAR_Licor] No Fault, FailCount= 2
2019-02-22T00:49:38.599Z,1550796578.599 [PAR_Licor](INFO): A/D timeout, 5 tries over 128 ms

2019-02-22T00:49:38.599Z,1550796578.599 [PAR_Licor] Data Fault, FailCount= 1
2019-02-22T00:49:38.599Z,1550796578.599 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:38.734Z,1550796578.734 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:38.735Z,1550796578.735 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:38.735Z,1550796578.735 [PAR_Licor] No Fault, FailCount= 1
2019-02-22T00:49:39.433Z,1550796579.433 [PAR_Licor](INFO): A/D timeout, 7 tries over 162 ms

2019-02-22T00:49:39.433Z,1550796579.433 [PAR_Licor] Data Fault, FailCount= 2
2019-02-22T00:49:39.433Z,1550796579.433 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:39.544Z,1550796579.544 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:39.544Z,1550796579.544 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-22T00:49:39.544Z,1550796579.544 [PAR_Licor] No Fault, FailCount= 2
2019-02-22T00:49:39.760Z,1550796579.760 [SBIT](IMPORTANT): SBIT PASSED
2019-02-22T00:49:39.852Z,1550796579.852 [CommandLine](IMPORTANT): got command configSet list
2019-02-22T00:49:39.857Z,1550796579.857 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-22T00:49:39.858Z,1550796579.858 [CommandLine](IMPORTANT): No configSet variables persisted
2019-02-22T00:49:40.196Z,1550796580.196 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-02-22T00:49:40.196Z,1550796580.196 [PAR_Licor] Data Fault, FailCount= 3
2019-02-22T00:49:40.196Z,1550796580.196 [PAR_Licor](ERROR): Data Fault
2019-02-22T00:49:40.263Z,1550796580.263 [MissionManager](IMPORTANT): Started mission Startup
2019-02-22T00:49:40.264Z,1550796580.264 [Startup] Running Loop=1
2019-02-22T00:49:40.264Z,1550796580.264 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-22T00:49:40.264Z,1550796580.264 [Startup:A.GoToSurface] Running Loop=1
2019-02-22T00:49:40.264Z,1550796580.264 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-22T00:49:40.265Z,1550796580.265 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-22T00:49:40.265Z,1550796580.265 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-22T00:49:40.266Z,1550796580.266 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-22T00:49:40.266Z,1550796580.266 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-22T00:49:40.266Z,1550796580.266 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-22T00:49:40.282Z,1550796580.282 [Startup:StartupSatComms] Running Loop=1
2019-02-22T00:49:40.282Z,1550796580.282 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-22T00:49:40.283Z,1550796580.283 [Startup:StartupSatComms:A] Running Loop=1
2019-02-22T00:49:40.341Z,1550796580.341 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-22T00:49:40.341Z,1550796580.341 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-02-22T00:49:40.501Z,1550796580.501 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-22T00:49:40.548Z,1550796580.548 [CBIT](INFO): Critical error at 20190222T004940
2019-02-22T00:49:46.850Z,1550796586.850 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:49:46.850Z,1550796586.850 [NAL9602] Communications Fault, FailCount= 2
2019-02-22T00:49:46.850Z,1550796586.850 [NAL9602](ERROR): Communications Fault
2019-02-22T00:49:46.892Z,1550796586.892 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:49:47.250Z,1550796587.250 [NAL9602](INFO): Powering down
2019-02-22T00:49:48.086Z,1550796588.086 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:49:48.086Z,1550796588.086 [NAL9602] No Fault, FailCount= 2
2019-02-22T00:49:48.712Z,1550796588.712 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T00:50:06.473Z,1550796606.473 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:50:06.473Z,1550796606.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-22T00:50:06.473Z,1550796606.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:50:06.475Z,1550796606.475 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:50:06.475Z,1550796606.475 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-22T00:50:06.475Z,1550796606.475 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:50:06.477Z,1550796606.477 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:50:06.477Z,1550796606.477 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-22T00:50:06.477Z,1550796606.477 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:50:06.542Z,1550796606.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:50:06.542Z,1550796606.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:50:06.542Z,1550796606.542 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:50:06.845Z,1550796606.845 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:50:06.845Z,1550796606.845 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-22T00:50:06.845Z,1550796606.845 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:50:06.898Z,1550796606.898 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:50:06.898Z,1550796606.898 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-22T00:50:06.899Z,1550796606.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:50:06.899Z,1550796606.899 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-22T00:50:06.899Z,1550796606.899 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:50:06.899Z,1550796606.899 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-22T00:50:06.900Z,1550796606.900 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:50:07.249Z,1550796607.249 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:50:07.361Z,1550796607.361 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:50:08.065Z,1550796608.065 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:50:23.716Z,1550796623.716 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:50:23.879Z,1550796623.879 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T00:50:23.879Z,1550796623.879 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-22T00:50:24.233Z,1550796624.233 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:50:24.234Z,1550796624.234 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:50:28.726Z,1550796628.726 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:50:28.980Z,1550796628.980 [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:50:40.320Z,1550796640.320 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-22T00:49:40.3Z
2019-02-22T00:50:40.320Z,1550796640.320 [Startup:StartupSatComms:A] Stopped
2019-02-22T00:50:40.320Z,1550796640.320 [Startup:StartupSatComms:B] Running Loop=1
2019-02-22T00:50:40.720Z,1550796640.720 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-22T00:50:56.498Z,1550796656.498 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:50:56.498Z,1550796656.498 [NAL9602] Communications Fault, FailCount= 3
2019-02-22T00:50:56.498Z,1550796656.498 [NAL9602](ERROR): Communications Fault
2019-02-22T00:50:56.659Z,1550796656.659 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:50:56.697Z,1550796656.697 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20190222T002216/Courier0000.lzma
2019-02-22T00:50:56.743Z,1550796656.743 [NAL9602](INFO): Powering down
2019-02-22T00:50:57.475Z,1550796657.475 [DataOverHttps](INFO): Moved sent file to Logs/20190222T002216/Courier0000.lzma.bak
2019-02-22T00:50:57.475Z,1550796657.475 [DataOverHttps](INFO): SBD MOMSN=10068393
2019-02-22T00:50:57.608Z,1550796657.608 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:50:57.608Z,1550796657.608 [NAL9602] No Fault, FailCount= 3
2019-02-22T00:51:17.656Z,1550796677.656 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T00:51:32.245Z,1550796692.245 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:51:32.348Z,1550796692.348 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:51:32.348Z,1550796692.348 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T00:51:32.362Z,1550796692.362 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:51:32.362Z,1550796692.362 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T00:51:32.381Z,1550796692.381 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:51:32.385Z,1550796692.385 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:51:32.450Z,1550796692.450 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190222T002216/Courier0004.lzma
2019-02-22T00:51:33.175Z,1550796693.175 [DataOverHttps](INFO): Moved sent file to Logs/20190222T002216/Courier0004.lzma.bak
2019-02-22T00:51:33.175Z,1550796693.175 [DataOverHttps](INFO): SBD MOMSN=10068401
2019-02-22T00:51:44.575Z,1550796704.575 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:51:46.453Z,1550796706.453 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:51:46.453Z,1550796706.453 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T00:51:46.453Z,1550796706.453 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:51:46.857Z,1550796706.857 [CTD_Seabird](INFO): Powering down
2019-02-22T00:51:52.526Z,1550796712.526 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:51:52.526Z,1550796712.526 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T00:51:52.526Z,1550796712.526 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:51:52.564Z,1550796712.564 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-22T00:50:40.3Z
2019-02-22T00:51:52.564Z,1550796712.564 [Startup:StartupSatComms:B] Stopped
2019-02-22T00:51:52.564Z,1550796712.564 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-22T00:51:52.564Z,1550796712.564 [Startup:StartupSatComms] Stopped
2019-02-22T00:51:52.564Z,1550796712.564 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-22T00:51:52.601Z,1550796712.601 [Startup](INFO): Completed Startup
2019-02-22T00:51:52.601Z,1550796712.601 [MissionManager](INFO): Startup is completed.
2019-02-22T00:51:52.601Z,1550796712.601 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-22T00:51:52.601Z,1550796712.601 [Startup] Stopped
2019-02-22T00:51:52.602Z,1550796712.602 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-22T00:51:52.602Z,1550796712.602 [Startup:A.GoToSurface] Stopped
2019-02-22T00:51:52.602Z,1550796712.602 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-22T00:51:52.680Z,1550796712.680 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:51:52.680Z,1550796712.680 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:51:52.680Z,1550796712.680 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:51:52.680Z,1550796712.680 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T00:51:52.925Z,1550796712.925 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:51:52.936Z,1550796712.936 [MissionManager](IMPORTANT): Started mission Default
2019-02-22T00:51:52.936Z,1550796712.936 [Default] Running Loop=1
2019-02-22T00:51:52.936Z,1550796712.936 [Default](DEBUG): Aggregate::initialize Default
2019-02-22T00:51:52.936Z,1550796712.936 [Default:B.GoToSurface] Running Loop=1
2019-02-22T00:51:52.936Z,1550796712.936 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-22T00:51:52.937Z,1550796712.937 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-22T00:51:52.937Z,1550796712.937 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-22T00:51:52.937Z,1550796712.937 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-22T00:51:52.938Z,1550796712.938 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-22T00:51:52.938Z,1550796712.938 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-22T00:51:52.938Z,1550796712.938 [Default:A.Wait] Running Loop=1
2019-02-22T00:51:52.938Z,1550796712.938 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-22T00:51:53.037Z,1550796713.037 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:51:53.042Z,1550796713.042 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:51:53.824Z,1550796713.824 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:51:53.824Z,1550796713.824 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T00:52:00.216Z,1550796720.216 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T00:52:06.862Z,1550796726.862 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:52:07.093Z,1550796727.093 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:52:07.093Z,1550796727.093 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T00:52:07.093Z,1550796727.093 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:52:07.497Z,1550796727.497 [CTD_Seabird](INFO): Powering down
2019-02-22T00:52:12.639Z,1550796732.639 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:52:12.639Z,1550796732.639 [NAL9602] Communications Fault, FailCount= 4
2019-02-22T00:52:12.639Z,1550796732.639 [NAL9602](ERROR): Communications Fault
2019-02-22T00:52:12.664Z,1550796732.664 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:52:12.664Z,1550796732.664 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T00:52:12.664Z,1550796732.664 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:52:12.737Z,1550796732.737 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:52:12.737Z,1550796732.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-22T00:52:12.737Z,1550796732.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:52:12.741Z,1550796732.741 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:52:12.742Z,1550796732.742 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-22T00:52:12.742Z,1550796732.742 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:52:12.743Z,1550796732.743 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:52:12.743Z,1550796732.743 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-22T00:52:12.743Z,1550796732.743 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:52:12.746Z,1550796732.746 [Default:A.Wait](INFO): Done Waiting.
2019-02-22T00:52:12.746Z,1550796732.746 [Default:A.Wait] Stopped
2019-02-22T00:52:12.746Z,1550796732.746 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-22T00:52:12.800Z,1550796732.800 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:52:12.829Z,1550796732.829 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:52:12.829Z,1550796732.829 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:52:12.829Z,1550796732.829 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:52:12.830Z,1550796732.830 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:52:12.830Z,1550796732.830 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:52:12.830Z,1550796732.830 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:52:12.830Z,1550796732.830 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T00:52:12.869Z,1550796732.869 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:52:12.872Z,1550796732.872 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:52:12.970Z,1550796732.970 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20190222T003157/Courier0000.lzma
2019-02-22T00:52:12.998Z,1550796732.998 [NAL9602](INFO): Powering down
2019-02-22T00:52:13.137Z,1550796733.137 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:52:13.147Z,1550796733.147 [Default:CheckIn] Running Loop=1
2019-02-22T00:52:13.147Z,1550796733.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-22T00:52:13.147Z,1550796733.147 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-22T00:52:13.177Z,1550796733.177 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:52:13.177Z,1550796733.177 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-22T00:52:13.178Z,1550796733.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:52:13.178Z,1550796733.178 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-22T00:52:13.178Z,1550796733.178 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:52:13.178Z,1550796733.178 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-22T00:52:13.361Z,1550796733.361 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:52:13.363Z,1550796733.363 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-22T00:52:13.638Z,1550796733.638 [DataOverHttps](INFO): Moved sent file to Logs/20190222T003157/Courier0000.lzma.bak
2019-02-22T00:52:13.638Z,1550796733.638 [DataOverHttps](INFO): SBD MOMSN=10068415
2019-02-22T00:52:13.782Z,1550796733.782 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:52:13.782Z,1550796733.782 [NAL9602] No Fault, FailCount= 4
2019-02-22T00:52:13.783Z,1550796733.783 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:52:13.783Z,1550796733.783 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T00:52:24.082Z,1550796744.082 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:52:24.082Z,1550796744.082 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T00:52:24.083Z,1550796744.083 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:52:24.119Z,1550796744.119 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:52:24.119Z,1550796744.119 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T00:52:24.505Z,1550796744.505 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:52:24.505Z,1550796744.505 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-22T00:52:24.505Z,1550796744.505 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:52:24.577Z,1550796744.577 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:52:24.637Z,1550796744.637 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:52:24.909Z,1550796744.909 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:52:25.712Z,1550796745.712 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:52:26.929Z,1550796746.929 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:52:26.929Z,1550796746.929 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T00:52:26.929Z,1550796746.929 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:52:27.328Z,1550796747.328 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T00:52:27.328Z,1550796747.328 [Rowe_600LCM] No Fault, FailCount= 2
2019-02-22T00:52:27.329Z,1550796747.329 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:52:27.329Z,1550796747.329 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:52:27.333Z,1550796747.333 [CTD_Seabird](INFO): Powering down
2019-02-22T00:52:27.338Z,1550796747.338 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:52:27.338Z,1550796747.338 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T00:52:31.845Z,1550796751.845 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:52:32.032Z,1550796752.032 [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:52:43.242Z,1550796763.242 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:53:08.981Z,1550796788.981 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:53:08.981Z,1550796788.981 [NAL9602] Communications Fault, FailCount= 5
2019-02-22T00:53:08.981Z,1550796788.981 [NAL9602](ERROR): Communications Fault
2019-02-22T00:53:09.084Z,1550796789.084 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:53:09.084Z,1550796789.084 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-02-22T00:53:09.262Z,1550796789.262 [NAL9602](INFO): Powering down
2019-02-22T00:53:09.363Z,1550796789.363 [CBIT](INFO): Critical error at 20190222T005309
2019-02-22T00:53:48.319Z,1550796828.319 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-22T00:53:48.336Z,1550796828.336 [NavChartDb](INFO): # of records loaded: 19732
2019-02-22T00:53:48.376Z,1550796828.376 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-22T00:53:48.418Z,1550796828.418 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:54:07.183Z,1550796847.183 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:54:13.437Z,1550796853.437 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:54:13.437Z,1550796853.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-22T00:54:13.437Z,1550796853.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:54:13.439Z,1550796853.439 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:54:13.439Z,1550796853.439 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-22T00:54:13.439Z,1550796853.439 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:54:13.440Z,1550796853.440 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:54:13.440Z,1550796853.440 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-22T00:54:13.440Z,1550796853.440 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:54:13.453Z,1550796853.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:54:13.453Z,1550796853.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:54:13.453Z,1550796853.453 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:54:13.921Z,1550796853.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:54:13.921Z,1550796853.921 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-22T00:54:13.922Z,1550796853.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:54:13.922Z,1550796853.922 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-22T00:54:13.922Z,1550796853.922 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:54:13.922Z,1550796853.922 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-22T00:54:14.241Z,1550796854.241 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:54:25.863Z,1550796865.863 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:54:25.863Z,1550796865.863 [PNI_TCM] No Fault, FailCount= 5
2019-02-22T00:54:27.649Z,1550796867.649 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:54:27.649Z,1550796867.649 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-02-22T00:54:27.649Z,1550796867.649 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:54:27.941Z,1550796867.941 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:54:28.053Z,1550796868.053 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:54:28.600Z,1550796868.600 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:54:29.911Z,1550796869.911 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T00:54:29.911Z,1550796869.911 [Rowe_600LCM] No Fault, FailCount= 3
2019-02-22T00:54:30.218Z,1550796870.218 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:54:30.218Z,1550796870.218 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:54:33.428Z,1550796873.428 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:54:33.428Z,1550796873.428 [PNI_TCM] Data Fault, FailCount= 1
2019-02-22T00:54:33.428Z,1550796873.428 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:54:33.515Z,1550796873.515 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:54:33.830Z,1550796873.830 [PNI_TCM](INFO): Powering down
2019-02-22T00:54:34.897Z,1550796874.897 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:54:35.180Z,1550796875.180 [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:54:35.188Z,1550796875.188 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:54:35.188Z,1550796875.188 [PNI_TCM] No Fault, FailCount= 1
2019-02-22T00:54:42.627Z,1550796882.627 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:54:42.627Z,1550796882.627 [PNI_TCM] Data Fault, FailCount= 2
2019-02-22T00:54:42.627Z,1550796882.627 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:54:42.799Z,1550796882.799 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:54:43.046Z,1550796883.046 [PNI_TCM](INFO): Powering down
2019-02-22T00:54:44.336Z,1550796884.336 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:54:44.336Z,1550796884.336 [PNI_TCM] No Fault, FailCount= 2
2019-02-22T00:54:51.839Z,1550796891.839 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:54:51.839Z,1550796891.839 [PNI_TCM] Data Fault, FailCount= 3
2019-02-22T00:54:51.839Z,1550796891.839 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:54:51.957Z,1550796891.957 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:54:52.226Z,1550796892.226 [PNI_TCM](INFO): Powering down
2019-02-22T00:54:52.841Z,1550796892.841 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T00:54:53.457Z,1550796893.457 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:54:53.457Z,1550796893.457 [PNI_TCM] No Fault, FailCount= 3
2019-02-22T00:54:54.290Z,1550796894.290 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:54:54.290Z,1550796894.290 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T00:55:09.197Z,1550796909.197 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:55:09.197Z,1550796909.197 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T00:55:09.197Z,1550796909.197 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:55:09.288Z,1550796909.288 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:55:09.288Z,1550796909.288 [NAL9602] No Fault, FailCount= 5
2019-02-22T00:55:09.288Z,1550796909.288 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:55:09.288Z,1550796909.288 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:55:09.288Z,1550796909.288 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T00:55:09.348Z,1550796909.348 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:55:09.613Z,1550796909.613 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:55:09.629Z,1550796909.629 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:55:09.637Z,1550796909.637 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:55:10.262Z,1550796910.262 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:55:10.262Z,1550796910.262 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T00:55:14.565Z,1550796914.565 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:55:14.565Z,1550796914.565 [PNI_TCM] Data Fault, FailCount= 4
2019-02-22T00:55:14.565Z,1550796914.565 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:55:14.634Z,1550796914.634 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:55:14.966Z,1550796914.966 [PNI_TCM](INFO): Powering down
2019-02-22T00:55:16.190Z,1550796916.190 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T00:55:16.190Z,1550796916.190 [PNI_TCM] No Fault, FailCount= 4
2019-02-22T00:55:20.966Z,1550796920.966 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:55:20.966Z,1550796920.966 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T00:55:20.966Z,1550796920.966 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:55:21.059Z,1550796921.059 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:55:21.485Z,1550796921.485 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:55:22.254Z,1550796922.254 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:55:22.254Z,1550796922.254 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T00:55:23.677Z,1550796923.677 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:55:23.677Z,1550796923.677 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T00:55:23.677Z,1550796923.677 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:55:23.766Z,1550796923.766 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T00:55:23.766Z,1550796923.766 [PNI_TCM] Data Fault, FailCount= 5
2019-02-22T00:55:23.766Z,1550796923.766 [PNI_TCM](ERROR): Data Fault
2019-02-22T00:55:23.787Z,1550796923.787 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T00:55:23.787Z,1550796923.787 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-02-22T00:55:23.787Z,1550796923.787 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:55:24.081Z,1550796924.081 [CTD_Seabird](INFO): Powering down
2019-02-22T00:55:24.175Z,1550796924.175 [PNI_TCM](INFO): Powering down
2019-02-22T00:55:24.305Z,1550796924.305 [CBIT](INFO): Critical error at 20190222T005523
2019-02-22T00:55:26.206Z,1550796926.206 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:55:26.206Z,1550796926.206 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T00:55:27.033Z,1550796927.033 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:55:27.036Z,1550796927.036 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:55:30.154Z,1550796930.154 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:55:30.154Z,1550796930.154 [NAL9602] Communications Fault, FailCount= 1
2019-02-22T00:55:30.154Z,1550796930.154 [NAL9602](ERROR): Communications Fault
2019-02-22T00:55:30.242Z,1550796930.242 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:55:30.550Z,1550796930.550 [NAL9602](INFO): Powering down
2019-02-22T00:55:31.531Z,1550796931.531 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:55:31.532Z,1550796931.532 [NAL9602] No Fault, FailCount= 1
2019-02-22T00:55:32.965Z,1550796932.965 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:55:32.965Z,1550796932.965 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T00:55:32.965Z,1550796932.965 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:55:33.015Z,1550796933.015 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:55:33.015Z,1550796933.015 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T00:55:33.485Z,1550796933.485 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:55:40.610Z,1550796940.610 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T00:55:41.109Z,1550796941.109 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:55:41.109Z,1550796941.109 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T00:55:41.109Z,1550796941.109 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:55:41.406Z,1550796941.406 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:55:41.513Z,1550796941.513 [CTD_Seabird](INFO): Powering down
2019-02-22T00:55:43.565Z,1550796943.565 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:55:43.565Z,1550796943.565 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T00:55:44.461Z,1550796944.461 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:55:44.466Z,1550796944.466 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:55:58.537Z,1550796958.537 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:55:58.537Z,1550796958.537 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T00:55:58.537Z,1550796958.537 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:55:58.941Z,1550796958.941 [CTD_Seabird](INFO): Powering down
2019-02-22T00:56:13.135Z,1550796973.135 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:56:13.292Z,1550796973.292 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:56:13.313Z,1550796973.313 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T00:56:14.616Z,1550796974.616 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:56:14.616Z,1550796974.616 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-22T00:56:14.633Z,1550796974.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:56:14.634Z,1550796974.634 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:56:14.635Z,1550796974.635 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-22T00:56:14.635Z,1550796974.635 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:56:14.636Z,1550796974.636 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:56:14.636Z,1550796974.636 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-22T00:56:14.636Z,1550796974.636 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:56:14.701Z,1550796974.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:56:14.701Z,1550796974.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:56:14.702Z,1550796974.702 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:56:14.956Z,1550796974.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:56:14.956Z,1550796974.956 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-22T00:56:14.957Z,1550796974.957 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:56:14.957Z,1550796974.957 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-22T00:56:14.958Z,1550796974.958 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:56:14.958Z,1550796974.958 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-22T00:56:15.349Z,1550796975.349 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:56:27.143Z,1550796987.143 [NavChartDb](INFO): # of records loaded: 15000
2019-02-22T00:56:30.773Z,1550796990.773 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:56:30.773Z,1550796990.773 [Rowe_600LCM] Communications Fault, FailCount= 4
2019-02-22T00:56:30.773Z,1550796990.773 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:56:31.011Z,1550796991.011 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:56:31.177Z,1550796991.177 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:56:31.966Z,1550796991.966 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:56:33.436Z,1550796993.436 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-22T00:56:33.457Z,1550796993.457 [Rowe_600LCM] No Fault, FailCount= 4
2019-02-22T00:56:33.581Z,1550796993.581 [Rowe_600LCM](INFO): Initializing
2019-02-22T00:56:33.590Z,1550796993.590 [Rowe_600LCM](INFO): Powering up
2019-02-22T00:56:34.126Z,1550796994.126 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:56:34.126Z,1550796994.126 [NAL9602] Communications Fault, FailCount= 2
2019-02-22T00:56:34.127Z,1550796994.127 [NAL9602](ERROR): Communications Fault
2019-02-22T00:56:34.170Z,1550796994.170 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:56:34.526Z,1550796994.526 [NAL9602](INFO): Powering down
2019-02-22T00:56:35.395Z,1550796995.395 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:56:35.395Z,1550796995.395 [NAL9602] No Fault, FailCount= 2
2019-02-22T00:56:38.075Z,1550796998.075 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-22T00:56:38.368Z,1550796998.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:57:05.195Z,1550797025.195 [NavChartDb](INFO): # of records loaded: 20000
2019-02-22T00:57:09.019Z,1550797029.019 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:57:13.220Z,1550797033.220 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-22T00:52:13.1Z
2019-02-22T00:57:13.220Z,1550797033.220 [Default:CheckIn:Read_GPS] Stopped
2019-02-22T00:57:13.220Z,1550797033.220 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-22T00:57:13.725Z,1550797033.725 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-22T00:57:32.722Z,1550797052.722 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20190222T004743/Courier0000.lzma
2019-02-22T00:57:33.474Z,1550797053.474 [DataOverHttps](INFO): Moved sent file to Logs/20190222T004743/Courier0000.lzma.bak
2019-02-22T00:57:33.474Z,1550797053.474 [DataOverHttps](INFO): SBD MOMSN=10068430
2019-02-22T00:57:50.860Z,1550797070.860 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:57:50.860Z,1550797070.860 [NAL9602] Communications Fault, FailCount= 3
2019-02-22T00:57:50.860Z,1550797070.860 [NAL9602](ERROR): Communications Fault
2019-02-22T00:57:51.042Z,1550797071.042 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:57:51.087Z,1550797071.087 [NAL9602](INFO): Powering down
2019-02-22T00:57:51.983Z,1550797071.983 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:57:51.983Z,1550797071.983 [NAL9602] No Fault, FailCount= 3
2019-02-22T00:57:53.681Z,1550797073.681 [NavChartDb](INFO): # of records loaded: 25000
2019-02-22T00:58:08.686Z,1550797088.686 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:58:08.686Z,1550797088.686 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T00:58:08.906Z,1550797088.906 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20190222T004743/Courier0004.lzma
2019-02-22T00:58:09.494Z,1550797089.494 [DataOverHttps](INFO): Moved sent file to Logs/20190222T004743/Courier0004.lzma.bak
2019-02-22T00:58:09.494Z,1550797089.494 [DataOverHttps](INFO): SBD MOMSN=10068439
2019-02-22T00:58:21.287Z,1550797101.287 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:58:31.467Z,1550797111.467 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:58:31.533Z,1550797111.533 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:58:31.534Z,1550797111.534 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T00:58:31.534Z,1550797111.534 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:58:31.623Z,1550797111.623 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:58:31.623Z,1550797111.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-02-22T00:58:31.623Z,1550797111.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T00:58:31.624Z,1550797111.624 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:58:31.645Z,1550797111.645 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-02-22T00:58:31.645Z,1550797111.645 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T00:58:31.647Z,1550797111.647 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T00:58:31.647Z,1550797111.647 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-02-22T00:58:31.647Z,1550797111.647 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T00:58:31.765Z,1550797111.765 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T00:58:31.766Z,1550797111.766 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T00:58:31.766Z,1550797111.766 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T00:58:31.766Z,1550797111.766 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:58:31.767Z,1550797111.767 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:58:31.767Z,1550797111.767 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T00:58:31.825Z,1550797111.825 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:58:31.830Z,1550797111.830 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:58:31.950Z,1550797111.950 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:58:32.039Z,1550797112.039 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T00:58:32.039Z,1550797112.039 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-02-22T00:58:32.040Z,1550797112.040 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T00:58:32.040Z,1550797112.040 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-02-22T00:58:32.040Z,1550797112.040 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T00:58:32.040Z,1550797112.040 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-02-22T00:58:32.252Z,1550797112.252 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T00:58:32.759Z,1550797112.759 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:58:32.759Z,1550797112.759 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T00:58:33.797Z,1550797113.797 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-22T00:58:33.797Z,1550797113.797 [Rowe_600LCM] Communications Fault, FailCount= 5
2019-02-22T00:58:33.797Z,1550797113.797 [Rowe_600LCM](ERROR): Communications Fault
2019-02-22T00:58:34.201Z,1550797114.201 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-22T00:58:34.981Z,1550797114.981 [Rowe_600LCM](INFO): Powering down
2019-02-22T00:58:43.971Z,1550797123.971 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T00:58:45.300Z,1550797125.300 [NavChartDb](INFO): # of records loaded: 30000
2019-02-22T00:58:45.873Z,1550797125.873 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:58:45.873Z,1550797125.873 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T00:58:45.873Z,1550797125.873 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:58:46.277Z,1550797126.277 [CTD_Seabird](INFO): Powering down
2019-02-22T00:58:50.788Z,1550797130.788 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:58:50.788Z,1550797130.788 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-22T00:58:50.788Z,1550797130.788 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:58:50.913Z,1550797130.913 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-22T00:58:50.914Z,1550797130.914 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2019-02-22T00:58:50.914Z,1550797130.914 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:58:50.914Z,1550797130.914 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:58:50.914Z,1550797130.914 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:58:50.915Z,1550797130.915 [CTD_Seabird] No Fault, FailCount= 1
2019-02-22T00:58:50.961Z,1550797130.961 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190221T232104/Express0017.lzma
2019-02-22T00:58:51.117Z,1550797131.117 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:58:51.254Z,1550797131.254 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:58:51.256Z,1550797131.256 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:58:51.727Z,1550797131.727 [DataOverHttps](INFO): Moved sent file to Logs/20190221T232104/Express0017.lzma.bak
2019-02-22T00:58:51.727Z,1550797131.727 [DataOverHttps](INFO): SBD MOMSN=10068443
2019-02-22T00:58:51.828Z,1550797131.828 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T00:58:51.828Z,1550797131.828 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-22T00:59:05.313Z,1550797145.313 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:59:05.313Z,1550797145.313 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-22T00:59:05.313Z,1550797145.313 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:59:05.717Z,1550797145.717 [CTD_Seabird](INFO): Powering down
2019-02-22T00:59:09.319Z,1550797149.319 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T00:59:09.319Z,1550797149.319 [NAL9602] Communications Fault, FailCount= 4
2019-02-22T00:59:09.319Z,1550797149.319 [NAL9602](ERROR): Communications Fault
2019-02-22T00:59:09.370Z,1550797149.370 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T00:59:09.370Z,1550797149.370 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-22T00:59:09.370Z,1550797149.370 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T00:59:09.475Z,1550797149.475 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T00:59:09.475Z,1550797149.475 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T00:59:09.475Z,1550797149.475 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-22T00:59:09.476Z,1550797149.476 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:59:09.506Z,1550797149.506 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T00:59:09.506Z,1550797149.506 [CTD_Seabird] No Fault, FailCount= 2
2019-02-22T00:59:09.574Z,1550797149.574 [NAL9602](INFO): Powering down
2019-02-22T00:59:09.721Z,1550797149.721 [Aanderaa_O2](INFO): Powering down
2019-02-22T00:59:09.877Z,1550797149.877 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T00:59:09.894Z,1550797149.894 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T00:59:10.412Z,1550797150.412 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-22T00:59:10.412Z,1550797150.412 [NAL9602] No Fault, FailCount= 4
2019-02-22T00:59:23.977Z,1550797163.977 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T00:59:23.977Z,1550797163.977 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-22T00:59:23.977Z,1550797163.977 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T00:59:24.389Z,1550797164.389 [CTD_Seabird](INFO): Powering down
2019-02-22T00:59:26.707Z,1550797166.707 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-22T00:59:26.708Z,1550797166.708 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-22T00:59:26.843Z,1550797166.843 [DataOverHttps](INFO): Sending 499 bytes from file Logs/20190222T001039/Express0001.lzma
2019-02-22T00:59:27.519Z,1550797167.519 [DataOverHttps](INFO): Moved sent file to Logs/20190222T001039/Express0001.lzma.bak
2019-02-22T00:59:27.519Z,1550797167.519 [DataOverHttps](INFO): SBD MOMSN=10068454
2019-02-22T00:59:40.823Z,1550797180.823 [NAL9602](INFO): Powering up NAL9602
2019-02-22T00:59:41.505Z,1550797181.505 [NavChartDb](INFO): # of records loaded: 35000
2019-02-22T00:59:54.744Z,1550797194.744 [DataOverHttps](INFO): Sending 486 bytes from file Logs/20190222T001743/Express0001.lzma
2019-02-22T00:59:55.514Z,1550797195.514 [DataOverHttps](INFO): Moved sent file to Logs/20190222T001743/Express0001.lzma.bak
2019-02-22T00:59:55.514Z,1550797195.514 [DataOverHttps](INFO): SBD MOMSN=10068469
2019-02-22T01:00:09.360Z,1550797209.360 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-22T01:00:09.360Z,1550797209.360 [NAL9602] Communications Fault, FailCount= 5
2019-02-22T01:00:09.360Z,1550797209.360 [NAL9602](ERROR): Communications Fault
2019-02-22T01:00:09.504Z,1550797209.504 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-22T01:00:09.529Z,1550797209.529 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-02-22T01:00:09.565Z,1550797209.565 [NAL9602](INFO): Powering down
2019-02-22T01:00:09.760Z,1550797209.760 [CBIT](INFO): Critical error at 20190222T010009
2019-02-22T01:00:11.831Z,1550797211.831 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-22T01:00:11.941Z,1550797211.941 [NavChartDb](INFO): # of records loaded: 37376
2019-02-22T01:00:11.981Z,1550797211.981 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-22T01:00:11.982Z,1550797211.982 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:00:23.375Z,1550797223.375 [DataOverHttps](INFO): Sending 1247 bytes from file Logs/20190222T002216/Express0001.lzma
2019-02-22T01:00:23.595Z,1550797223.595 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:00:23.845Z,1550797223.845 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:00:23.845Z,1550797223.845 [PNI_TCM] No Fault, FailCount= 5
2019-02-22T01:00:24.142Z,1550797224.142 [DataOverHttps](INFO): Moved sent file to Logs/20190222T002216/Express0001.lzma.bak
2019-02-22T01:00:24.142Z,1550797224.142 [DataOverHttps](INFO): SBD MOMSN=10068485
2019-02-22T01:00:38.173Z,1550797238.173 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:00:38.173Z,1550797238.173 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-02-22T01:00:38.173Z,1550797238.173 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-22T01:00:38.189Z,1550797238.189 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:00:38.189Z,1550797238.189 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-02-22T01:00:38.189Z,1550797238.189 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-22T01:00:38.191Z,1550797238.191 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-22T01:00:38.191Z,1550797238.191 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-02-22T01:00:38.191Z,1550797238.191 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-22T01:00:38.276Z,1550797238.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-22T01:00:38.276Z,1550797238.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-22T01:00:38.276Z,1550797238.276 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-22T01:00:38.469Z,1550797238.469 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-22T01:00:38.469Z,1550797238.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-02-22T01:00:38.470Z,1550797238.470 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-22T01:00:38.470Z,1550797238.470 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-02-22T01:00:38.470Z,1550797238.470 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-22T01:00:38.470Z,1550797238.470 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-02-22T01:00:38.742Z,1550797238.742 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-22T01:00:52.693Z,1550797252.693 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:00:52.693Z,1550797252.693 [PNI_TCM] Data Fault, FailCount= 1
2019-02-22T01:00:52.693Z,1550797252.693 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:00:52.839Z,1550797252.839 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:00:52.878Z,1550797252.878 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190222T002216/Express0005.lzma
2019-02-22T01:00:52.911Z,1550797252.911 [PNI_TCM](INFO): Powering down
2019-02-22T01:00:53.662Z,1550797253.662 [DataOverHttps](INFO): Moved sent file to Logs/20190222T002216/Express0005.lzma.bak
2019-02-22T01:00:53.662Z,1550797253.662 [DataOverHttps](INFO): SBD MOMSN=10068533
2019-02-22T01:00:54.850Z,1550797254.849 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:00:54.850Z,1550797254.850 [PNI_TCM] No Fault, FailCount= 1
2019-02-22T01:01:05.776Z,1550797265.776 [NavChartDb](INFO): # of records loaded: 5000
2019-02-22T01:01:22.712Z,1550797282.712 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:01:22.712Z,1550797282.712 [PNI_TCM] Data Fault, FailCount= 2
2019-02-22T01:01:22.712Z,1550797282.712 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:01:22.804Z,1550797282.804 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:01:22.848Z,1550797282.848 [DataOverHttps](INFO): Sending 707 bytes from file Logs/20190222T003157/Express0001.lzma
2019-02-22T01:01:22.898Z,1550797282.898 [PNI_TCM](INFO): Powering down
2019-02-22T01:01:23.638Z,1550797283.638 [DataOverHttps](INFO): Moved sent file to Logs/20190222T003157/Express0001.lzma.bak
2019-02-22T01:01:23.638Z,1550797283.638 [DataOverHttps](INFO): SBD MOMSN=10068535
2019-02-22T01:01:24.850Z,1550797284.850 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:01:24.850Z,1550797284.850 [PNI_TCM] No Fault, FailCount= 2
2019-02-22T01:01:34.863Z,1550797294.863 [NavChartDb](INFO): # of records loaded: 10000
2019-02-22T01:01:39.393Z,1550797299.393 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:01:39.393Z,1550797299.393 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-22T01:01:52.498Z,1550797312.498 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-22T01:01:52.498Z,1550797312.498 [PNI_TCM] Data Fault, FailCount= 3
2019-02-22T01:01:52.498Z,1550797312.498 [PNI_TCM](ERROR): Data Fault
2019-02-22T01:01:52.529Z,1550797312.529 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-22T01:01:52.529Z,1550797312.529 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-22T01:01:52.529Z,1550797312.529 [Aanderaa_O2](ERROR): Communications Fault
2019-02-22T01:01:52.592Z,1550797312.592 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-22T01:01:52.609Z,1550797312.609 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-22T01:01:52.691Z,1550797312.691 [DataOverHttps](INFO): Sending 1174 bytes from file Logs/20190222T004743/Express0001.lzma
2019-02-22T01:01:52.769Z,1550797312.769 [PNI_TCM](INFO): Powering down
2019-02-22T01:01:52.977Z,1550797312.977 [Aanderaa_O2](INFO): Powering down
2019-02-22T01:01:54.023Z,1550797314.023 [DataOverHttps](INFO): Moved sent file to Logs/20190222T004743/Express0001.lzma.bak
2019-02-22T01:01:54.023Z,1550797314.023 [DataOverHttps](INFO): SBD MOMSN=10068558
2019-02-22T01:01:54.144Z,1550797314.144 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-22T01:01:54.144Z,1550797314.144 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-22T01:01:54.144Z,1550797314.144 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-22T01:01:54.144Z,1550797314.144 [CTD_Seabird] No Fault, FailCount= 3
2019-02-22T01:01:54.417Z,1550797314.417 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-22T01:01:54.423Z,1550797314.423 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-22T01:01:55.744Z,1550797315.744 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-22T01:01:55.744Z,1550797315.744 [PNI_TCM] No Fault, FailCount= 3
2019-02-22T01:02:05.343Z,1550797325.343 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-22T01:02:08.493Z,1550797328.493 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-22T01:02:08.493Z,1550797328.493 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-22T01:02:08.493Z,1550797328.493 [CTD_Seabird](ERROR): Communications Fault
2019-02-22T01:02:08.897Z