2018-11-08T22:13:37.124Z,1541715217.124 [Supervisor](DEBUG): Initializing supervisor.
2018-11-08T22:13:37.137Z,1541715217.137 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-08T22:13:37.138Z,1541715217.138 [SyncHandler](INFO): Protected caller Thread ID is 800
2018-11-08T22:13:37.138Z,1541715217.138 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-08T22:13:37.149Z,1541715217.149 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-08T22:13:37.149Z,1541715217.149 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801
2018-11-08T22:13:37.152Z,1541715217.152 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-08T22:13:37.175Z,1541715217.175 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-08T22:13:37.185Z,1541715217.185 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-08T22:13:37.185Z,1541715217.185 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802
2018-11-08T22:13:37.186Z,1541715217.186 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-08T22:13:37.197Z,1541715217.197 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-08T22:13:37.197Z,1541715217.197 [logger ThreadHandler](INFO): Protected caller Thread ID is 803
2018-11-08T22:13:37.199Z,1541715217.199 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-08T22:13:37.200Z,1541715217.200 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-08T22:13:37.205Z,1541715217.205 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-08T22:13:37.892Z,1541715217.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-08T22:13:37.893Z,1541715217.893 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-08T22:13:38.742Z,1541715218.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-08T22:13:38.744Z,1541715218.744 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-08T22:13:39.127Z,1541715219.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-08T22:13:39.128Z,1541715219.128 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-08T22:13:39.337Z,1541715219.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-08T22:13:39.337Z,1541715219.337 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-08T22:13:39.825Z,1541715219.825 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-08T22:13:39.826Z,1541715219.826 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-08T22:13:40.572Z,1541715220.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-08T22:13:40.572Z,1541715220.572 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-08T22:13:40.681Z,1541715220.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-08T22:13:40.682Z,1541715220.682 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-08T22:13:40.901Z,1541715220.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-08T22:13:40.902Z,1541715220.902 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-08T22:13:41.054Z,1541715221.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-08T22:13:41.054Z,1541715221.054 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-08T22:13:41.138Z,1541715221.138 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-08T22:13:41.373Z,1541715221.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-08T22:13:41.374Z,1541715221.374 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-08T22:13:41.478Z,1541715221.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-08T22:13:41.478Z,1541715221.478 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-08T22:13:41.578Z,1541715221.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-08T22:13:41.579Z,1541715221.579 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-08T22:13:41.736Z,1541715221.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-08T22:13:41.737Z,1541715221.737 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-08T22:13:42.079Z,1541715222.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-08T22:13:42.081Z,1541715222.081 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-11-08T22:13:42.085Z,1541715222.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-11-08T22:13:42.226Z,1541715222.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-11-08T22:13:42.459Z,1541715222.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-08T22:13:42.459Z,1541715222.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-11-08T22:13:42.647Z,1541715222.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-11-08T22:13:42.752Z,1541715222.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-11-08T22:13:42.857Z,1541715222.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-11-08T22:13:43.068Z,1541715223.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-11-08T22:13:43.155Z,1541715223.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-11-08T22:13:43.258Z,1541715223.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-11-08T22:13:43.355Z,1541715223.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-11-08T22:13:43.440Z,1541715223.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-11-08T22:13:43.554Z,1541715223.554 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-08T22:13:43.567Z,1541715223.567 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-08T22:13:43.728Z,1541715223.728 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-08T22:13:43.760Z,1541715223.760 [SBIT] Loaded
2018-11-08T22:13:43.760Z,1541715223.760 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-08T22:13:43.761Z,1541715223.761 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-08T22:13:43.789Z,1541715223.789 [IBIT] Loaded
2018-11-08T22:13:43.789Z,1541715223.789 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-08T22:13:43.792Z,1541715223.792 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-08T22:13:43.950Z,1541715223.950 [CBIT] Loaded
2018-11-08T22:13:43.951Z,1541715223.951 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-08T22:13:43.951Z,1541715223.951 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-08T22:13:43.953Z,1541715223.953 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-08T22:13:44.092Z,1541715224.092 [BuoyancyServo] Loaded
2018-11-08T22:13:44.092Z,1541715224.092 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-08T22:13:44.107Z,1541715224.107 [ElevatorServo] Loaded
2018-11-08T22:13:44.107Z,1541715224.107 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-08T22:13:44.122Z,1541715224.122 [MassServo] Loaded
2018-11-08T22:13:44.123Z,1541715224.123 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-08T22:13:44.138Z,1541715224.138 [RudderServo] Loaded
2018-11-08T22:13:44.139Z,1541715224.139 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-08T22:13:44.154Z,1541715224.154 [ThrusterServo] Loaded
2018-11-08T22:13:44.154Z,1541715224.154 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-08T22:13:44.154Z,1541715224.154 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-08T22:13:44.155Z,1541715224.155 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-08T22:13:44.274Z,1541715224.274 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-08T22:13:44.274Z,1541715224.274 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-08T22:13:44.329Z,1541715224.329 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-08T22:13:44.329Z,1541715224.329 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-08T22:13:44.346Z,1541715224.346 [NavChart] Loaded
2018-11-08T22:13:44.347Z,1541715224.347 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-08T22:13:44.351Z,1541715224.351 [UniversalFixResidualReporter] Loaded
2018-11-08T22:13:44.351Z,1541715224.351 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-08T22:13:44.351Z,1541715224.351 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-08T22:13:44.352Z,1541715224.352 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-08T22:13:44.376Z,1541715224.376 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-08T22:13:44.377Z,1541715224.377 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-08T22:13:44.672Z,1541715224.672 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-08T22:13:44.673Z,1541715224.673 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-08T22:13:45.284Z,1541715225.284 [DataOverHttps] Loaded
2018-11-08T22:13:45.284Z,1541715225.284 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-08T22:13:45.353Z,1541715225.353 [Depth_Keller] Loaded
2018-11-08T22:13:45.354Z,1541715225.354 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-08T22:13:45.457Z,1541715225.457 [DropWeight] Loaded
2018-11-08T22:13:45.457Z,1541715225.457 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-08T22:13:45.745Z,1541715225.745 [NAL9602] Loaded
2018-11-08T22:13:45.746Z,1541715225.746 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-08T22:13:45.790Z,1541715225.790 [Onboard] Loaded
2018-11-08T22:13:45.790Z,1541715225.790 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-08T22:13:45.805Z,1541715225.805 [Radio_Surface] Loaded
2018-11-08T22:13:45.806Z,1541715225.806 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-08T22:13:45.807Z,1541715225.807 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0
2018-11-08T22:13:45.807Z,1541715225.807 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-11-08T22:13:45.916Z,1541715225.916 [RDI_Pathfinder] Loaded
2018-11-08T22:13:45.916Z,1541715225.916 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-11-08T22:13:46.106Z,1541715226.106 [RDI_PathfinderUp] Loaded
2018-11-08T22:13:46.106Z,1541715226.106 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2018-11-08T22:13:48.018Z,1541715228.018 [BPC1] Loaded
2018-11-08T22:13:48.018Z,1541715228.018 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-08T22:13:48.019Z,1541715228.019 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-08T22:13:48.019Z,1541715228.019 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-08T22:13:48.114Z,1541715228.114 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-08T22:13:48.205Z,1541715228.205 [VerticalControl] Loaded
2018-11-08T22:13:48.205Z,1541715228.205 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-08T22:13:48.206Z,1541715228.206 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-08T22:13:48.266Z,1541715228.266 [HorizontalControl] Loaded
2018-11-08T22:13:48.266Z,1541715228.266 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-08T22:13:48.267Z,1541715228.267 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-08T22:13:48.269Z,1541715228.269 [SpeedControl] Loaded
2018-11-08T22:13:48.269Z,1541715228.269 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-08T22:13:48.270Z,1541715228.270 [LoopControl](DEBUG): Construct LoopControl.
2018-11-08T22:13:48.271Z,1541715228.271 [LoopControl] Loaded
2018-11-08T22:13:48.271Z,1541715228.271 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-08T22:13:48.271Z,1541715228.271 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-08T22:13:48.272Z,1541715228.272 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-08T22:13:48.403Z,1541715228.403 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-08T22:13:48.404Z,1541715228.404 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-08T22:13:48.618Z,1541715228.618 [Aanderaa_O2] Loaded
2018-11-08T22:13:48.619Z,1541715228.619 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-08T22:13:48.704Z,1541715228.704 [CTD_NeilBrown] Loaded
2018-11-08T22:13:48.704Z,1541715228.704 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-08T22:13:48.706Z,1541715228.706 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409364E0
2018-11-08T22:13:48.706Z,1541715228.706 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 883
2018-11-08T22:13:48.752Z,1541715228.752 [WetLabsSeaOWL_UV_A] Loaded
2018-11-08T22:13:48.752Z,1541715228.752 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-11-08T22:13:48.754Z,1541715228.754 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409664E0
2018-11-08T22:13:48.754Z,1541715228.754 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 884
2018-11-08T22:13:48.754Z,1541715228.754 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-08T22:13:48.755Z,1541715228.755 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-08T22:13:48.861Z,1541715228.861 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-08T22:13:48.861Z,1541715228.861 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-08T22:13:48.918Z,1541715228.918 [DepthRateCalculator] Loaded
2018-11-08T22:13:48.919Z,1541715228.919 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-08T22:13:48.924Z,1541715228.924 [PitchRateCalculator] Loaded
2018-11-08T22:13:48.924Z,1541715228.924 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-08T22:13:48.937Z,1541715228.937 [SpeedCalculator] Loaded
2018-11-08T22:13:48.937Z,1541715228.937 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-08T22:13:48.959Z,1541715228.959 [TempGradientCalculator] Loaded
2018-11-08T22:13:48.959Z,1541715228.959 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-08T22:13:48.964Z,1541715228.964 [YawRateCalculator] Loaded
2018-11-08T22:13:48.965Z,1541715228.965 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-08T22:13:48.996Z,1541715228.996 [ElevatorOffsetCalculator] Loaded
2018-11-08T22:13:48.996Z,1541715228.996 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-08T22:13:48.997Z,1541715228.997 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-08T22:13:48.997Z,1541715228.997 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-08T22:13:49.035Z,1541715229.035 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-08T22:13:49.039Z,1541715229.039 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-08T22:13:49.040Z,1541715229.040 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-08T22:13:49.047Z,1541715229.047 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-08T22:13:49.048Z,1541715229.048 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-11-08T22:13:49.048Z,1541715229.048 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2018-11-08T22:13:49.053Z,1541715229.053 [Supervisor](INFO): Main Thread ID is 796
2018-11-08T22:13:49.053Z,1541715229.053 [Supervisor](DEBUG): Running supervisor.
2018-11-08T22:13:49.054Z,1541715229.054 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2018-11-08T22:13:49.056Z,1541715229.056 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2018-11-08T22:13:49.056Z,1541715229.056 [controlThread](DEBUG): Initializing ControlThread
2018-11-08T22:13:49.057Z,1541715229.057 [SBIT](INFO): Initialize SBIT Component.
2018-11-08T22:13:49.058Z,1541715229.058 [SBIT](IMPORTANT): git: 2018-11-05
2018-11-08T22:13:49.058Z,1541715229.058 [SBIT](INFO): git hash: 17f8e4440ddc7f0910aa911cb821ed1a114d9cee
2018-11-08T22:13:49.058Z,1541715229.058 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-08T22:13:49.058Z,1541715229.058 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-08T22:13:49.060Z,1541715229.060 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-08T22:13:49.060Z,1541715229.060 [IBIT](INFO): Initialize IBIT Component.
2018-11-08T22:13:49.061Z,1541715229.061 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-08T22:13:49.061Z,1541715229.061 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-08T22:13:49.062Z,1541715229.062 [logger ThreadHandler](INFO): Handler Thread ID is 888
2018-11-08T22:13:49.089Z,1541715229.089 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889
2018-11-08T22:13:49.095Z,1541715229.095 [Radio_Surface](INFO): Powering up
2018-11-08T22:13:49.113Z,1541715229.113 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 890
2018-11-08T22:13:49.114Z,1541715229.114 [CTD_NeilBrown](INFO): Powering down
2018-11-08T22:13:49.141Z,1541715229.141 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 891
2018-11-08T22:13:49.142Z,1541715229.142 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-11-08T22:13:49.170Z,1541715229.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:13:49.171Z,1541715229.171 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:13:49.171Z,1541715229.171 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:13:49.172Z,1541715229.172 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:13:49.172Z,1541715229.172 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:13:49.172Z,1541715229.172 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:13:49.173Z,1541715229.173 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-08T22:13:49.173Z,1541715229.173 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-08T22:13:49.180Z,1541715229.180 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-08T22:13:49.182Z,1541715229.182 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-08T22:13:49.182Z,1541715229.182 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-08T22:13:49.183Z,1541715229.183 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-08T22:13:49.183Z,1541715229.183 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-08T22:13:49.184Z,1541715229.184 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-08T22:13:49.184Z,1541715229.184 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-08T22:13:49.184Z,1541715229.184 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-08T22:13:49.185Z,1541715229.185 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-08T22:13:49.186Z,1541715229.186 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-08T22:13:49.187Z,1541715229.187 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-08T22:13:49.198Z,1541715229.198 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892
2018-11-08T22:13:49.205Z,1541715229.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-08T22:13:49.205Z,1541715229.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-08T22:13:49.205Z,1541715229.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-08T22:13:49.205Z,1541715229.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-08T22:13:49.206Z,1541715229.206 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-08T22:13:49.206Z,1541715229.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-08T22:13:49.206Z,1541715229.206 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-08T22:13:49.206Z,1541715229.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-08T22:13:49.206Z,1541715229.206 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-08T22:13:49.206Z,1541715229.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-08T22:13:49.207Z,1541715229.207 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-08T22:13:49.207Z,1541715229.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-08T22:13:49.207Z,1541715229.207 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-08T22:13:49.207Z,1541715229.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-08T22:13:49.207Z,1541715229.207 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-08T22:13:49.208Z,1541715229.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-08T22:13:49.236Z,1541715229.236 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-08T22:13:49.280Z,1541715229.280 [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>


2018-11-08T22:13:49.281Z,1541715229.281 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-08T22:13:49.354Z,1541715229.354 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-08T22:13:49.355Z,1541715229.355 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-08T22:13:49.357Z,1541715229.357 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-08T22:13:49.402Z,1541715229.402 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-08T22:13:49.404Z,1541715229.404 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-08T22:13:49.430Z,1541715229.430 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-08T22:13:49.450Z,1541715229.450 [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>


2018-11-08T22:13:49.455Z,1541715229.455 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-08T22:13:49.493Z,1541715229.493 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-08T22:13:49.502Z,1541715229.502 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-11-08T22:13:49.676Z,1541715229.676 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-08T22:13:50.194Z,1541715230.194 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-08T22:13:50.201Z,1541715230.201 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-08T22:13:50.220Z,1541715230.220 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-08T22:13:50.225Z,1541715230.225 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-08T22:13:50.247Z,1541715230.247 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-08T22:13:50.253Z,1541715230.253 [MassServo](DEBUG): Initializing MassServo.
2018-11-08T22:13:50.267Z,1541715230.267 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-08T22:13:50.273Z,1541715230.273 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-08T22:13:50.279Z,1541715230.279 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-08T22:13:50.285Z,1541715230.285 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-08T22:13:50.442Z,1541715230.442 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-08T22:13:50.442Z,1541715230.442 [DropWeight] Hardware Fault, FailCount= 1
2018-11-08T22:13:50.442Z,1541715230.442 [DropWeight](ERROR): Hardware Fault
2018-11-08T22:13:50.545Z,1541715230.545 [RDI_PathfinderUp](INFO): Powering down
2018-11-08T22:13:50.597Z,1541715230.597 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-08T22:13:50.626Z,1541715230.626 [CommandLine](FAULT): Scheduling is paused
2018-11-08T22:13:50.626Z,1541715230.626 [CBIT](INFO): Critical error at 20181108T221350
2018-11-08T22:13:50.627Z,1541715230.627 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-08T22:13:50.629Z,1541715230.629 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-08T22:13:50.630Z,1541715230.630 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-08T22:13:50.785Z,1541715230.785 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-08T22:13:50.815Z,1541715230.815 [CBIT](INFO): Critical error at 20181108T221350
2018-11-08T22:13:59.220Z,1541715239.220 [CBIT](CRITICAL): Environmental Failure. Press:14.721647 PSI. Humidity:26%. Temp:25 C. ABORTING MISSION
2018-11-08T22:13:59.615Z,1541715239.615 [CBIT](INFO): Critical error at 20181108T221359
2018-11-08T22:13:59.982Z,1541715239.982 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:13:59.982Z,1541715239.982 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-08T22:13:59.982Z,1541715239.982 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:14:00.020Z,1541715240.020 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:14:00.501Z,1541715240.501 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:14:01.201Z,1541715241.201 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:14:01.201Z,1541715241.201 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-08T22:14:11.981Z,1541715251.981 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:14:11.981Z,1541715251.981 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-11-08T22:14:11.981Z,1541715251.981 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:14:11.982Z,1541715251.982 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:14:11.982Z,1541715251.982 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-08T22:14:11.983Z,1541715251.983 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:14:12.016Z,1541715252.016 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:14:12.016Z,1541715252.016 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:14:12.453Z,1541715252.453 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:14:12.573Z,1541715252.573 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:14:13.216Z,1541715253.216 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:14:13.216Z,1541715253.216 [RDI_Pathfinder] No Fault, FailCount= 1
2018-11-08T22:14:13.216Z,1541715253.216 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:14:13.216Z,1541715253.216 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-08T22:14:15.966Z,1541715255.966 [NAL9602](INFO): Powering up NAL9602
2018-11-08T22:14:17.617Z,1541715257.617 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-08T22:14:17.622Z,1541715257.622 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-08T22:14:23.699Z,1541715263.699 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:14:23.699Z,1541715263.699 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-08T22:14:23.699Z,1541715263.699 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:14:23.766Z,1541715263.766 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:14:23.767Z,1541715263.767 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-08T22:14:24.209Z,1541715264.209 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:14:26.878Z,1541715266.878 [NAL9602](INFO): NAL9602 initialized
2018-11-08T22:14:28.621Z,1541715268.621 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008921
CHAN A1 (24V): 0.003181
CHAN A2 (12V): -0.006968
CHAN A3 (5V): -0.002104
CHAN B0 (3.3V): 0.000269
CHAN B1 (3.15aV): -0.000149
CHAN B2 (3.15bV): 0.000071
CHAN B3 (GND): 0.002251
OPEN: 0.006316
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-08T22:14:35.873Z,1541715275.873 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:14:35.873Z,1541715275.873 [RDI_Pathfinder] Communications Fault, FailCount= 2
2018-11-08T22:14:35.873Z,1541715275.873 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:14:36.026Z,1541715276.026 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:14:36.345Z,1541715276.345 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:14:37.210Z,1541715277.210 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:14:37.210Z,1541715277.210 [RDI_Pathfinder] No Fault, FailCount= 2
2018-11-08T22:14:59.473Z,1541715299.473 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:14:59.474Z,1541715299.474 [RDI_Pathfinder] Communications Fault, FailCount= 3
2018-11-08T22:14:59.474Z,1541715299.474 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:14:59.682Z,1541715299.682 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:14:59.682Z,1541715299.682 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:14:59.941Z,1541715299.941 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:15:00.420Z,1541715300.420 [CBIT](INFO): Critical error at 20181108T221459
2018-11-08T22:15:11.684Z,1541715311.684 [SBIT](IMPORTANT): SBIT PASSED
2018-11-08T22:15:11.766Z,1541715311.766 [CommandLine](IMPORTANT): got command configSet list
2018-11-08T22:15:11.766Z,1541715311.766 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-08T22:15:11.767Z,1541715311.767 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-11-08T22:15:11.767Z,1541715311.767 [CommandLine](IMPORTANT): BuoyancyServo.powerOnTimeout=1 second;

2018-11-08T22:15:11.767Z,1541715311.767 [CommandLine](IMPORTANT): ElevatorServo.powerOnTimeout=0.8 second;

2018-11-08T22:15:11.767Z,1541715311.767 [CommandLine](IMPORTANT): MassServo.powerOnTimeout=0.8 second;

2018-11-08T22:15:11.767Z,1541715311.767 [CommandLine](IMPORTANT): RudderServo.powerOnTimeout=0.8 second;

2018-11-08T22:15:11.768Z,1541715311.768 [CommandLine](IMPORTANT): SCPI.loadAtStartup=0 bool;

2018-11-08T22:15:11.768Z,1541715311.768 [CommandLine](IMPORTANT): ThrusterServo.powerOnTimeout=0.8 second;

2018-11-08T22:15:12.075Z,1541715312.075 [MissionManager](IMPORTANT): Started mission Startup
2018-11-08T22:15:12.075Z,1541715312.075 [Startup] Running Loop=1
2018-11-08T22:15:12.075Z,1541715312.075 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-08T22:15:12.075Z,1541715312.075 [Startup:A.GoToSurface] Running Loop=1
2018-11-08T22:15:12.075Z,1541715312.075 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-08T22:15:12.076Z,1541715312.076 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-08T22:15:12.076Z,1541715312.076 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-08T22:15:12.077Z,1541715312.077 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-08T22:15:12.077Z,1541715312.077 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-08T22:15:12.078Z,1541715312.078 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-08T22:15:12.083Z,1541715312.083 [Startup:StartupSatComms] Running Loop=1
2018-11-08T22:15:12.084Z,1541715312.084 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-08T22:15:12.084Z,1541715312.084 [Startup:StartupSatComms:A] Running Loop=1
2018-11-08T22:15:12.472Z,1541715312.472 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-08T22:15:49.269Z,1541715349.269 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:15:49.269Z,1541715349.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-11-08T22:15:49.269Z,1541715349.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:15:49.271Z,1541715349.271 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:15:49.271Z,1541715349.271 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-08T22:15:49.271Z,1541715349.271 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:15:49.284Z,1541715349.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:15:49.284Z,1541715349.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:15:49.673Z,1541715349.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:15:49.673Z,1541715349.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-11-08T22:15:49.674Z,1541715349.674 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:15:49.674Z,1541715349.674 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-08T22:15:50.064Z,1541715350.064 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:15:50.064Z,1541715350.064 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:15:50.065Z,1541715350.065 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:15:50.065Z,1541715350.065 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:15:50.066Z,1541715350.066 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:15:50.066Z,1541715350.066 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:16:12.457Z,1541715372.457 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-08T22:15:12.1Z
2018-11-08T22:16:12.458Z,1541715372.458 [Startup:StartupSatComms:A] Stopped
2018-11-08T22:16:12.458Z,1541715372.458 [Startup:StartupSatComms:B] Running Loop=1
2018-11-08T22:16:12.863Z,1541715372.863 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-08T22:16:18.763Z,1541715378.763 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004361
2018-11-08T22:16:26.673Z,1541715386.673 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20181108T214955/Courier0007.lzma
2018-11-08T22:16:27.447Z,1541715387.447 [DataOverHttps](INFO): Moved sent file to Logs/20181108T214955/Courier0007.lzma.bak
2018-11-08T22:16:27.448Z,1541715387.448 [DataOverHttps](INFO): SBD MOMSN=8819991
2018-11-08T22:16:44.309Z,1541715404.309 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20181108T220054/Courier0000.lzma
2018-11-08T22:16:45.107Z,1541715405.107 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220054/Courier0000.lzma.bak
2018-11-08T22:16:45.107Z,1541715405.107 [DataOverHttps](INFO): SBD MOMSN=8820030
2018-11-08T22:16:53.913Z,1541715413.913 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:16:53.914Z,1541715413.914 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-08T22:16:53.958Z,1541715413.958 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20181108T220152/Courier0000.lzma
2018-11-08T22:16:54.723Z,1541715414.723 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220152/Courier0000.lzma.bak
2018-11-08T22:16:54.723Z,1541715414.723 [DataOverHttps](INFO): SBD MOMSN=8820038
2018-11-08T22:17:03.454Z,1541715423.454 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20181108T220327/Courier0000.lzma
2018-11-08T22:17:04.230Z,1541715424.230 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220327/Courier0000.lzma.bak
2018-11-08T22:17:04.230Z,1541715424.230 [DataOverHttps](INFO): SBD MOMSN=8820045
2018-11-08T22:17:04.255Z,1541715424.255 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:17:04.255Z,1541715424.255 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-08T22:17:04.255Z,1541715424.255 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:17:04.303Z,1541715424.303 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:17:04.869Z,1541715424.869 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:17:08.299Z,1541715428.299 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:17:08.299Z,1541715428.299 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-08T22:17:13.041Z,1541715433.041 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-08T22:16:12.5Z
2018-11-08T22:17:13.041Z,1541715433.041 [Startup:StartupSatComms:B] Stopped
2018-11-08T22:17:13.041Z,1541715433.041 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-08T22:17:13.041Z,1541715433.041 [Startup:StartupSatComms] Stopped
2018-11-08T22:17:13.041Z,1541715433.041 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-08T22:17:13.042Z,1541715433.042 [Startup](INFO): Completed Startup
2018-11-08T22:17:13.043Z,1541715433.043 [MissionManager](INFO): Startup is completed.
2018-11-08T22:17:13.043Z,1541715433.043 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-08T22:17:13.043Z,1541715433.043 [Startup] Stopped
2018-11-08T22:17:13.043Z,1541715433.043 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-08T22:17:13.043Z,1541715433.043 [Startup:A.GoToSurface] Stopped
2018-11-08T22:17:13.043Z,1541715433.043 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-08T22:17:13.064Z,1541715433.064 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20181108T220551/Courier0000.lzma
2018-11-08T22:17:13.167Z,1541715433.167 [MissionManager](IMPORTANT): Started mission Default
2018-11-08T22:17:13.167Z,1541715433.167 [Default] Running Loop=1
2018-11-08T22:17:13.167Z,1541715433.167 [Default](DEBUG): Aggregate::initialize Default
2018-11-08T22:17:13.168Z,1541715433.168 [Default:B.GoToSurface] Running Loop=1
2018-11-08T22:17:13.168Z,1541715433.168 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-08T22:17:13.168Z,1541715433.168 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-08T22:17:13.168Z,1541715433.168 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-08T22:17:13.169Z,1541715433.169 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-08T22:17:13.169Z,1541715433.169 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-08T22:17:13.169Z,1541715433.169 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-08T22:17:13.170Z,1541715433.170 [Default:A.Wait] Running Loop=1
2018-11-08T22:17:13.170Z,1541715433.170 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-08T22:17:13.863Z,1541715433.863 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220551/Courier0000.lzma.bak
2018-11-08T22:17:13.863Z,1541715433.863 [DataOverHttps](INFO): SBD MOMSN=8820052
2018-11-08T22:17:18.668Z,1541715438.668 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:17:18.668Z,1541715438.668 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-08T22:17:18.669Z,1541715438.669 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:17:18.731Z,1541715438.731 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:17:19.181Z,1541715439.181 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:17:19.878Z,1541715439.878 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:17:19.878Z,1541715439.878 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-08T22:17:26.264Z,1541715446.264 [Default:A.Wait](INFO): Done Waiting.
2018-11-08T22:17:26.264Z,1541715446.264 [Default:A.Wait] Stopped
2018-11-08T22:17:26.264Z,1541715446.264 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-08T22:17:26.663Z,1541715446.663 [Default:CheckIn] Running Loop=1
2018-11-08T22:17:26.663Z,1541715446.663 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-08T22:17:26.664Z,1541715446.664 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-08T22:17:27.068Z,1541715447.068 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-08T22:17:30.254Z,1541715450.254 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:17:30.254Z,1541715450.254 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-08T22:17:30.254Z,1541715450.254 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:17:30.299Z,1541715450.299 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:17:30.299Z,1541715450.299 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-08T22:17:30.761Z,1541715450.761 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:17:39.706Z,1541715459.706 [CommandLine](IMPORTANT): got command failComponent
2018-11-08T22:17:39.707Z,1541715459.707 [CommandLine](IMPORTANT): Failed components:
2018-11-08T22:17:39.707Z,1541715459.707 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-08T22:17:39.707Z,1541715459.707 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2018-11-08T22:17:39.707Z,1541715459.707 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-11-08T22:17:52.953Z,1541715472.953 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:17:52.953Z,1541715472.953 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-11-08T22:17:52.953Z,1541715472.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:17:52.955Z,1541715472.955 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:17:52.955Z,1541715472.955 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-08T22:17:52.955Z,1541715472.955 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:17:52.968Z,1541715472.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:17:52.968Z,1541715472.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:17:53.109Z,1541715473.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:17:53.109Z,1541715473.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-11-08T22:17:53.110Z,1541715473.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:17:53.110Z,1541715473.110 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-08T22:17:53.438Z,1541715473.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:17:53.438Z,1541715473.438 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:17:53.439Z,1541715473.439 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:17:53.439Z,1541715473.439 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:17:53.439Z,1541715473.439 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:17:53.440Z,1541715473.440 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:17:59.870Z,1541715479.870 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:17:59.870Z,1541715479.870 [RDI_Pathfinder] No Fault, FailCount= 3
2018-11-08T22:18:13.025Z,1541715493.025 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2018-11-08T22:18:13.025Z,1541715493.025 [RDI_Pathfinder] Data Fault, FailCount= 1
2018-11-08T22:18:13.025Z,1541715493.025 [RDI_Pathfinder](ERROR): Data Fault
2018-11-08T22:18:13.083Z,1541715493.083 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2018-11-08T22:18:13.493Z,1541715493.493 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:18:14.334Z,1541715494.334 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:18:14.334Z,1541715494.334 [RDI_Pathfinder] No Fault, FailCount= 1
2018-11-08T22:18:38.332Z,1541715518.332 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:18:38.332Z,1541715518.332 [RDI_Pathfinder] Communications Fault, FailCount= 2
2018-11-08T22:18:38.332Z,1541715518.332 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:18:38.371Z,1541715518.371 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:18:38.789Z,1541715518.789 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:18:39.556Z,1541715519.556 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:18:39.556Z,1541715519.556 [RDI_Pathfinder] No Fault, FailCount= 2
2018-11-08T22:19:03.418Z,1541715543.418 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:19:03.418Z,1541715543.418 [RDI_Pathfinder] Communications Fault, FailCount= 3
2018-11-08T22:19:03.418Z,1541715543.418 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:19:03.437Z,1541715543.437 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:19:03.437Z,1541715543.437 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:19:03.615Z,1541715543.615 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:19:03.642Z,1541715543.642 [CBIT](INFO): Critical error at 20181108T221903
2018-11-08T22:19:29.850Z,1541715569.850 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-11-08T22:19:53.473Z,1541715593.473 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:19:53.473Z,1541715593.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-11-08T22:19:53.473Z,1541715593.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:19:53.474Z,1541715593.474 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:19:53.475Z,1541715593.475 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-08T22:19:53.475Z,1541715593.475 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:19:53.487Z,1541715593.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:19:53.487Z,1541715593.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:19:53.934Z,1541715593.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:19:53.934Z,1541715593.934 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-11-08T22:19:53.934Z,1541715593.934 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:19:53.934Z,1541715593.934 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-08T22:19:54.277Z,1541715594.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:19:54.277Z,1541715594.277 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:19:54.278Z,1541715594.278 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:19:54.278Z,1541715594.278 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:19:54.279Z,1541715594.279 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:19:54.279Z,1541715594.279 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:20:00.278Z,1541715600.278 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:20:00.278Z,1541715600.278 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-08T22:20:10.655Z,1541715610.655 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:20:10.655Z,1541715610.655 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-08T22:20:10.655Z,1541715610.655 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:20:10.711Z,1541715610.711 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:20:13.929Z,1541715613.929 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:20:14.450Z,1541715614.450 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:20:14.450Z,1541715614.450 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-08T22:20:25.188Z,1541715625.188 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:20:25.189Z,1541715625.189 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-08T22:20:25.189Z,1541715625.189 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:20:25.215Z,1541715625.215 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:20:25.709Z,1541715625.709 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:20:26.427Z,1541715626.427 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:20:26.427Z,1541715626.427 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-08T22:20:37.187Z,1541715637.187 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:20:37.187Z,1541715637.187 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-08T22:20:37.187Z,1541715637.187 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:20:37.204Z,1541715637.204 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:20:37.205Z,1541715637.205 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-08T22:20:37.709Z,1541715637.709 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:21:03.164Z,1541715663.164 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-11-08T22:21:54.333Z,1541715714.333 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:21:54.333Z,1541715714.333 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-11-08T22:21:54.333Z,1541715714.333 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:21:54.335Z,1541715714.335 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:21:54.335Z,1541715714.335 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-11-08T22:21:54.335Z,1541715714.335 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:21:54.348Z,1541715714.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:21:54.348Z,1541715714.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:21:54.744Z,1541715714.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:21:54.744Z,1541715714.744 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-11-08T22:21:54.744Z,1541715714.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:21:54.744Z,1541715714.744 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-11-08T22:21:55.132Z,1541715715.132 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:21:55.137Z,1541715715.137 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:21:55.137Z,1541715715.137 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:21:55.138Z,1541715715.138 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:21:55.138Z,1541715715.138 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:21:55.139Z,1541715715.139 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:22:03.515Z,1541715723.515 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:22:03.515Z,1541715723.515 [RDI_Pathfinder] No Fault, FailCount= 3
2018-11-08T22:22:16.287Z,1541715736.287 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2018-11-08T22:22:16.287Z,1541715736.287 [RDI_Pathfinder] Data Fault, FailCount= 1
2018-11-08T22:22:16.287Z,1541715736.287 [RDI_Pathfinder](ERROR): Data Fault
2018-11-08T22:22:16.335Z,1541715736.335 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2018-11-08T22:22:16.753Z,1541715736.753 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:22:17.510Z,1541715737.510 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:22:17.510Z,1541715737.510 [RDI_Pathfinder] No Fault, FailCount= 1
2018-11-08T22:22:26.691Z,1541715746.691 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-08T22:17:26.7Z
2018-11-08T22:22:26.691Z,1541715746.691 [Default:CheckIn:Read_GPS] Stopped
2018-11-08T22:22:26.692Z,1541715746.692 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-08T22:22:27.082Z,1541715747.082 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-08T22:22:31.032Z,1541715751.032 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181108T220551/Courier0004.lzma
2018-11-08T22:22:31.827Z,1541715751.827 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220551/Courier0004.lzma.bak
2018-11-08T22:22:31.827Z,1541715751.827 [DataOverHttps](INFO): SBD MOMSN=8820058
2018-11-08T22:22:40.504Z,1541715760.504 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20181108T221100/Courier0000.lzma
2018-11-08T22:22:41.303Z,1541715761.303 [DataOverHttps](INFO): Moved sent file to Logs/20181108T221100/Courier0000.lzma.bak
2018-11-08T22:22:41.303Z,1541715761.303 [DataOverHttps](INFO): SBD MOMSN=8820060
2018-11-08T22:22:41.365Z,1541715761.365 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:22:41.365Z,1541715761.365 [RDI_Pathfinder] Communications Fault, FailCount= 2
2018-11-08T22:22:41.365Z,1541715761.365 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:22:41.384Z,1541715761.384 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:22:41.813Z,1541715761.813 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:22:45.229Z,1541715765.229 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:22:45.229Z,1541715765.229 [RDI_Pathfinder] No Fault, FailCount= 2
2018-11-08T22:22:49.997Z,1541715769.997 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20181108T221337/Courier0000.lzma
2018-11-08T22:22:50.795Z,1541715770.795 [DataOverHttps](INFO): Moved sent file to Logs/20181108T221337/Courier0000.lzma.bak
2018-11-08T22:22:50.795Z,1541715770.795 [DataOverHttps](INFO): SBD MOMSN=8820068
2018-11-08T22:22:59.497Z,1541715779.497 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20181108T221337/Courier0004.lzma
2018-11-08T22:23:00.295Z,1541715780.295 [DataOverHttps](INFO): Moved sent file to Logs/20181108T221337/Courier0004.lzma.bak
2018-11-08T22:23:00.295Z,1541715780.295 [DataOverHttps](INFO): SBD MOMSN=8820075
2018-11-08T22:23:08.992Z,1541715788.992 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:23:08.992Z,1541715788.992 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-08T22:23:09.015Z,1541715789.015 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20181108T214955/Express0008.lzma
2018-11-08T22:23:09.803Z,1541715789.803 [DataOverHttps](INFO): Moved sent file to Logs/20181108T214955/Express0008.lzma.bak
2018-11-08T22:23:09.803Z,1541715789.803 [DataOverHttps](INFO): SBD MOMSN=8820078
2018-11-08T22:23:09.860Z,1541715789.860 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:23:09.860Z,1541715789.860 [RDI_Pathfinder] Communications Fault, FailCount= 3
2018-11-08T22:23:09.860Z,1541715789.860 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:23:09.908Z,1541715789.908 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:23:09.908Z,1541715789.908 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:23:10.337Z,1541715790.337 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:23:10.360Z,1541715790.360 [CBIT](INFO): Critical error at 20181108T222309
2018-11-08T22:23:18.462Z,1541715798.462 [DataOverHttps](INFO): Sending 730 bytes from file Logs/20181108T220054/Express0001.lzma
2018-11-08T22:23:19.259Z,1541715799.259 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220054/Express0001.lzma.bak
2018-11-08T22:23:19.259Z,1541715799.259 [DataOverHttps](INFO): SBD MOMSN=8820091
2018-11-08T22:23:19.307Z,1541715799.307 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:23:19.307Z,1541715799.307 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-08T22:23:19.307Z,1541715799.307 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:23:19.344Z,1541715799.344 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:23:19.849Z,1541715799.849 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:23:23.175Z,1541715803.175 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:23:23.175Z,1541715803.175 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-08T22:23:27.916Z,1541715807.916 [DataOverHttps](INFO): Sending 970 bytes from file Logs/20181108T220152/Express0001.lzma
2018-11-08T22:23:28.715Z,1541715808.715 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220152/Express0001.lzma.bak
2018-11-08T22:23:28.715Z,1541715808.715 [DataOverHttps](INFO): SBD MOMSN=8820118
2018-11-08T22:23:33.504Z,1541715813.504 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:23:33.504Z,1541715813.504 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-08T22:23:33.505Z,1541715813.505 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:23:33.522Z,1541715813.522 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:23:34.013Z,1541715814.013 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:23:37.413Z,1541715817.413 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:23:37.413Z,1541715817.413 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-08T22:23:37.426Z,1541715817.426 [DataOverHttps](INFO): Sending 1062 bytes from file Logs/20181108T220327/Express0001.lzma
2018-11-08T22:23:38.223Z,1541715818.223 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220327/Express0001.lzma.bak
2018-11-08T22:23:38.223Z,1541715818.223 [DataOverHttps](INFO): SBD MOMSN=8820148
2018-11-08T22:23:46.918Z,1541715826.918 [DataOverHttps](INFO): Sending 906 bytes from file Logs/20181108T220551/Express0001.lzma
2018-11-08T22:23:47.715Z,1541715827.715 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220551/Express0001.lzma.bak
2018-11-08T22:23:47.715Z,1541715827.715 [DataOverHttps](INFO): SBD MOMSN=8820178
2018-11-08T22:23:47.768Z,1541715827.768 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:23:47.768Z,1541715827.768 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-08T22:23:47.768Z,1541715827.768 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:23:47.820Z,1541715827.820 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:23:47.820Z,1541715827.820 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-08T22:23:48.297Z,1541715828.297 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:23:56.382Z,1541715836.382 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:23:56.382Z,1541715836.382 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-11-08T22:23:56.382Z,1541715836.382 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:23:56.384Z,1541715836.384 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:23:56.384Z,1541715836.384 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-11-08T22:23:56.384Z,1541715836.384 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:23:56.437Z,1541715836.437 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:23:56.438Z,1541715836.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:23:56.449Z,1541715836.449 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181108T220551/Express0005.lzma
2018-11-08T22:23:56.513Z,1541715836.513 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:23:56.513Z,1541715836.513 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-11-08T22:23:56.513Z,1541715836.513 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:23:56.514Z,1541715836.514 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-11-08T22:23:56.884Z,1541715836.884 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:23:56.885Z,1541715836.885 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:23:56.885Z,1541715836.885 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:23:56.886Z,1541715836.886 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:23:56.886Z,1541715836.886 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:23:56.886Z,1541715836.886 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:23:57.247Z,1541715837.247 [DataOverHttps](INFO): Moved sent file to Logs/20181108T220551/Express0005.lzma.bak
2018-11-08T22:23:57.247Z,1541715837.247 [DataOverHttps](INFO): SBD MOMSN=8820207
2018-11-08T22:24:05.964Z,1541715845.964 [DataOverHttps](INFO): Sending 845 bytes from file Logs/20181108T221100/Express0001.lzma
2018-11-08T22:24:06.759Z,1541715846.759 [DataOverHttps](INFO): Moved sent file to Logs/20181108T221100/Express0001.lzma.bak
2018-11-08T22:24:06.759Z,1541715846.759 [DataOverHttps](INFO): SBD MOMSN=8820209
2018-11-08T22:24:15.466Z,1541715855.466 [DataOverHttps](INFO): Sending 950 bytes from file Logs/20181108T221337/Express0001.lzma
2018-11-08T22:24:16.263Z,1541715856.263 [DataOverHttps](INFO): Moved sent file to Logs/20181108T221337/Express0001.lzma.bak
2018-11-08T22:24:16.263Z,1541715856.263 [DataOverHttps](INFO): SBD MOMSN=8820233
2018-11-08T22:24:24.985Z,1541715864.985 [DataOverHttps](INFO): Sending 495 bytes from file Logs/20181108T221337/Express0005.lzma
2018-11-08T22:24:25.767Z,1541715865.767 [DataOverHttps](INFO): Moved sent file to Logs/20181108T221337/Express0005.lzma.bak
2018-11-08T22:24:25.767Z,1541715865.767 [DataOverHttps](INFO): SBD MOMSN=8820260
2018-11-08T22:24:26.251Z,1541715866.251 [Default:CheckIn:Read_Iridium] Stopped
2018-11-08T22:24:26.251Z,1541715866.251 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-08T22:24:26.251Z,1541715866.251 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-08T22:24:30.106Z,1541715870.106 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-11-08T22:24:30.106Z,1541715870.106 [NAL9602] Data Fault, FailCount= 1
2018-11-08T22:24:30.106Z,1541715870.106 [NAL9602](ERROR): Data Fault
2018-11-08T22:24:30.175Z,1541715870.175 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-08T22:24:30.506Z,1541715870.506 [NAL9602](INFO): Powering down
2018-11-08T22:24:31.334Z,1541715871.334 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-08T22:24:31.334Z,1541715871.334 [NAL9602] No Fault, FailCount= 1
2018-11-08T22:25:00.898Z,1541715900.898 [NAL9602](INFO): Powering up NAL9602
2018-11-08T22:25:12.090Z,1541715912.090 [NAL9602](INFO): NAL9602 initialized
2018-11-08T22:25:56.893Z,1541715956.893 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:25:56.893Z,1541715956.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2018-11-08T22:25:56.893Z,1541715956.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:25:56.895Z,1541715956.895 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:25:56.895Z,1541715956.895 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-11-08T22:25:56.895Z,1541715956.895 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:25:56.908Z,1541715956.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:25:56.908Z,1541715956.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:25:57.304Z,1541715957.304 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:25:57.304Z,1541715957.304 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2018-11-08T22:25:57.304Z,1541715957.304 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:25:57.304Z,1541715957.304 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-11-08T22:25:57.694Z,1541715957.694 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:25:57.694Z,1541715957.694 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:25:57.694Z,1541715957.694 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:25:57.695Z,1541715957.695 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:25:57.695Z,1541715957.695 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:25:57.696Z,1541715957.696 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:26:10.115Z,1541715970.115 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:26:10.115Z,1541715970.115 [RDI_Pathfinder] No Fault, FailCount= 3
2018-11-08T22:26:18.054Z,1541715978.054 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:26:18.054Z,1541715978.054 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-08T22:26:23.231Z,1541715983.231 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2018-11-08T22:26:23.232Z,1541715983.232 [RDI_Pathfinder] Data Fault, FailCount= 1
2018-11-08T22:26:23.232Z,1541715983.232 [RDI_Pathfinder](ERROR): Data Fault
2018-11-08T22:26:23.251Z,1541715983.251 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2018-11-08T22:26:23.705Z,1541715983.705 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:26:24.458Z,1541715984.458 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:26:24.458Z,1541715984.458 [RDI_Pathfinder] No Fault, FailCount= 1
2018-11-08T22:26:28.842Z,1541715988.842 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:26:28.843Z,1541715988.843 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-08T22:26:28.843Z,1541715988.843 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:26:28.900Z,1541715988.900 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:26:29.365Z,1541715989.365 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:26:30.064Z,1541715990.064 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:26:30.064Z,1541715990.064 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-08T22:26:40.443Z,1541716000.443 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:26:40.443Z,1541716000.443 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-08T22:26:40.443Z,1541716000.443 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:26:40.502Z,1541716000.502 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:26:40.957Z,1541716000.957 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:26:41.670Z,1541716001.670 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:26:41.670Z,1541716001.670 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-08T22:26:47.240Z,1541716007.240 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:26:47.240Z,1541716007.240 [RDI_Pathfinder] Communications Fault, FailCount= 2
2018-11-08T22:26:47.240Z,1541716007.240 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:26:47.305Z,1541716007.305 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:26:50.397Z,1541716010.397 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:26:50.890Z,1541716010.890 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:26:50.890Z,1541716010.890 [RDI_Pathfinder] No Fault, FailCount= 2
2018-11-08T22:26:52.082Z,1541716012.082 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:26:52.083Z,1541716012.083 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-08T22:26:52.083Z,1541716012.083 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:26:52.106Z,1541716012.106 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:26:52.106Z,1541716012.106 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-08T22:26:52.601Z,1541716012.601 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:27:13.675Z,1541716033.675 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:27:13.676Z,1541716033.676 [RDI_Pathfinder] Communications Fault, FailCount= 3
2018-11-08T22:27:13.676Z,1541716033.676 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:27:13.725Z,1541716033.725 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:27:13.726Z,1541716033.726 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:27:14.133Z,1541716034.133 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:27:14.156Z,1541716034.156 [CBIT](INFO): Critical error at 20181108T222713
2018-11-08T22:28:00.717Z,1541716080.717 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:28:00.717Z,1541716080.717 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2018-11-08T22:28:00.717Z,1541716080.717 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:28:00.719Z,1541716080.719 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:28:00.719Z,1541716080.719 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-11-08T22:28:00.719Z,1541716080.719 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:28:00.793Z,1541716080.793 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:28:00.794Z,1541716080.794 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:28:00.878Z,1541716080.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:28:00.878Z,1541716080.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2018-11-08T22:28:00.879Z,1541716080.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:28:00.879Z,1541716080.879 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-11-08T22:28:01.253Z,1541716081.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:28:01.253Z,1541716081.253 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:28:01.254Z,1541716081.254 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:28:01.254Z,1541716081.254 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:28:01.254Z,1541716081.254 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:28:01.255Z,1541716081.255 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:28:50.785Z,1541716130.785 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-08T22:28:50.785Z,1541716130.785 [DropWeight] No Fault, FailCount= 1
2018-11-08T22:29:22.338Z,1541716162.338 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:29:22.338Z,1541716162.338 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-08T22:29:26.711Z,1541716166.711 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-08T22:29:26.712Z,1541716166.712 [Default:CheckIn:C.Wait] Stopped
2018-11-08T22:29:26.712Z,1541716166.712 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-08T22:29:26.712Z,1541716166.712 [Default:CheckIn:D] Running Loop=1
2018-11-08T22:29:27.105Z,1541716167.105 [Default:CheckIn:D] Stopped
2018-11-08T22:29:27.105Z,1541716167.105 [Default:CheckIn:E] Running Loop=1
2018-11-08T22:29:27.496Z,1541716167.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.232258 min 
2018-11-08T22:29:27.498Z,1541716167.498 [Default:CheckIn:E] Stopped
2018-11-08T22:29:27.498Z,1541716167.498 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-08T22:29:27.498Z,1541716167.498 [Default:CheckIn] Stopped
2018-11-08T22:29:27.498Z,1541716167.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-08T22:29:27.499Z,1541716167.499 [Default:CheckIn](INFO): Running loop #2
2018-11-08T22:29:27.499Z,1541716167.499 [Default:CheckIn] Running Loop=2
2018-11-08T22:29:27.499Z,1541716167.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-08T22:29:27.499Z,1541716167.499 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-08T22:29:32.696Z,1541716172.696 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:29:32.696Z,1541716172.696 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-08T22:29:32.696Z,1541716172.696 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:29:32.754Z,1541716172.754 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:29:33.205Z,1541716173.205 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:29:33.925Z,1541716173.925 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:29:33.925Z,1541716173.925 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-08T22:29:46.169Z,1541716186.169 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:29:46.169Z,1541716186.169 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-08T22:29:46.169Z,1541716186.169 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:29:46.188Z,1541716186.188 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:29:46.405Z,1541716186.405 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:29:47.077Z,1541716187.077 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-08T22:29:47.077Z,1541716187.077 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-08T22:29:57.425Z,1541716197.425 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-08T22:29:57.425Z,1541716197.425 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-08T22:29:57.426Z,1541716197.426 [Aanderaa_O2](ERROR): Communications Fault
2018-11-08T22:29:57.449Z,1541716197.449 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-08T22:29:57.450Z,1541716197.450 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-08T22:29:57.945Z,1541716197.945 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:30:01.449Z,1541716201.449 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:30:01.449Z,1541716201.449 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2018-11-08T22:30:01.449Z,1541716201.449 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-08T22:30:01.450Z,1541716201.450 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-08T22:30:01.451Z,1541716201.451 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-11-08T22:30:01.451Z,1541716201.451 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-08T22:30:01.463Z,1541716201.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-08T22:30:01.463Z,1541716201.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-08T22:30:01.869Z,1541716201.869 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-08T22:30:01.869Z,1541716201.869 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2018-11-08T22:30:01.869Z,1541716201.869 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-08T22:30:01.870Z,1541716201.870 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-11-08T22:30:02.238Z,1541716202.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-08T22:30:02.238Z,1541716202.238 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:30:02.238Z,1541716202.238 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:30:02.239Z,1541716202.239 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-08T22:30:02.239Z,1541716202.239 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-08T22:30:02.240Z,1541716202.240 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-08T22:30:13.862Z,1541716213.862 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:30:13.862Z,1541716213.862 [RDI_Pathfinder] No Fault, FailCount= 3
2018-11-08T22:30:26.963Z,1541716226.963 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2018-11-08T22:30:26.963Z,1541716226.963 [RDI_Pathfinder] Data Fault, FailCount= 1
2018-11-08T22:30:26.964Z,1541716226.964 [RDI_Pathfinder](ERROR): Data Fault
2018-11-08T22:30:26.982Z,1541716226.982 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2018-11-08T22:30:27.433Z,1541716227.433 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:30:28.178Z,1541716228.178 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:30:28.178Z,1541716228.178 [RDI_Pathfinder] No Fault, FailCount= 1
2018-11-08T22:30:50.971Z,1541716250.971 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-11-08T22:30:50.971Z,1541716250.971 [RDI_Pathfinder] Communications Fault, FailCount= 2
2018-11-08T22:30:50.971Z,1541716250.971 [RDI_Pathfinder](ERROR): Communications Fault
2018-11-08T22:30:50.999Z,1541716250.999 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-11-08T22:30:51.437Z,1541716251.437 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:30:52.183Z,1541716252.183 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-11-08T22:30:52.183Z,1541716252.183 [RDI_Pathfinder] No Fault, FailCount= 2
2018-11-08T22:30:55.686Z,1541716255.686 [CommandLine](IMPORTANT): got command quit
2018-11-08T22:30:56.692Z,1541716256.692 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:56.692Z,1541716256.692 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:56.717Z,1541716256.717 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-08T22:30:56.717Z,1541716256.717 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:56.718Z,1541716256.718 [CommandLine](INFO): Join timeout helper Thread ID is 965
2018-11-08T22:30:56.719Z,1541716256.719 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-08T22:30:56.719Z,1541716256.719 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:56.719Z,1541716256.719 [NavChartDb](INFO): Join timeout helper Thread ID is 966
2018-11-08T22:30:56.989Z,1541716256.989 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:56.989Z,1541716256.989 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.009Z,1541716257.009 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-11-08T22:30:57.009Z,1541716257.009 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.009Z,1541716257.009 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 967
2018-11-08T22:30:57.381Z,1541716257.381 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:57.381Z,1541716257.381 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-11-08T22:30:57.382Z,1541716257.382 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.397Z,1541716257.397 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-08T22:30:57.397Z,1541716257.397 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.397Z,1541716257.397 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 968
2018-11-08T22:30:57.581Z,1541716257.581 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:57.581Z,1541716257.581 [CTD_NeilBrown](INFO): Powering down
2018-11-08T22:30:57.593Z,1541716257.593 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.597Z,1541716257.597 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-08T22:30:57.597Z,1541716257.597 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.597Z,1541716257.597 [Radio_Surface](INFO): Join timeout helper Thread ID is 969
2018-11-08T22:30:57.785Z,1541716257.785 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:57.785Z,1541716257.785 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.806Z,1541716257.806 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-08T22:30:57.806Z,1541716257.806 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.806Z,1541716257.806 [logger](INFO): Join timeout helper Thread ID is 970
2018-11-08T22:30:57.809Z,1541716257.809 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:57.809Z,1541716257.809 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.826Z,1541716257.826 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-08T22:30:57.826Z,1541716257.826 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.826Z,1541716257.826 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-08T22:30:57.826Z,1541716257.826 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:57.826Z,1541716257.826 [controlThread](INFO): Join timeout helper Thread ID is 971
2018-11-08T22:30:58.085Z,1541716258.085 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-08T22:30:58.085Z,1541716258.085 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-08T22:30:58.086Z,1541716258.086 [NAL9602](INFO): Powering down
2018-11-08T22:30:58.157Z,1541716258.157 [RDI_Pathfinder](INFO): Powering down
2018-11-08T22:30:58.158Z,1541716258.158 [RDI_PathfinderUp](INFO): Powering down
2018-11-08T22:30:58.159Z,1541716258.159 [Aanderaa_O2](INFO): Powering down
2018-11-08T22:30:58.160Z,1541716258.160 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-08T22:30:58.161Z,1541716258.161 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-08T22:30:58.162Z,1541716258.162 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-08T22:30:58.162Z,1541716258.162 [MissionManager](INFO): Uninitializing Mission Default
2018-11-08T22:30:58.162Z,1541716258.162 [Default] Stopped
2018-11-08T22:30:58.162Z,1541716258.162 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-08T22:30:58.162Z,1541716258.162 [Default:B.GoToSurface] Stopped
2018-11-08T22:30:58.162Z,1541716258.162 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-08T22:30:58.163Z,1541716258.163 [Default:CheckIn] Stopped
2018-11-08T22:30:58.163Z,1541716258.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-08T22:30:58.163Z,1541716258.163 [Default:CheckIn:Read_GPS] Stopped
2018-11-08T22:30:58.166Z,1541716258.166 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-08T22:30:58.167Z,1541716258.167 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-08T22:30:58.167Z,1541716258.167 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-08T22:30:58.167Z,1541716258.167 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-08T22:30:58.167Z,1541716258.167 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-08T22:30:58.168Z,1541716258.168 [BuoyancyServo](INFO): Powering down
2018-11-08T22:30:58.181Z,1541716258.181 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-08T22:30:58.181Z,1541716258.181 [ElevatorServo](INFO): Powering down
2018-11-08T22:30:58.182Z,1541716258.182 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-08T22:30:58.182Z,1541716258.182 [MassServo](INFO): Powering down
2018-11-08T22:30:58.183Z,1541716258.183 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-08T22:30:58.183Z,1541716258.183 [RudderServo](INFO): Powering down
2018-11-08T22:30:58.184Z,1541716258.184 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-08T22:30:58.184Z,1541716258.184 [ThrusterServo](INFO): Powering down
2018-11-08T22:30:58.185Z,1541716258.185 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-08T22:30:58.185Z,1541716258.185 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-08T22:30:58.186Z,1541716258.186 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-08T22:30:58.186Z,1541716258.186 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:58.294Z,1541716258.294 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:58.334Z,1541716258.334 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:58.336Z,1541716258.336 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:58.358Z,1541716258.358 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-08T22:30:58.416Z,1541716258.416 [logger ThreadHandler](INFO): Thread cancelled.