2014-08-21T06:08:02.634Z,1408601282.634 [Supervisor](DEBUG): Initializing supervisor.
2014-08-21T06:08:02.638Z,1408601282.638 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-08-21T06:08:02.638Z,1408601282.638 [SyncHandler](INFO): Protected caller Thread ID is 4945
2014-08-21T06:08:02.639Z,1408601282.639 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-08-21T06:08:02.640Z,1408601282.640 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-08-21T06:08:02.640Z,1408601282.640 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4946
2014-08-21T06:08:02.644Z,1408601282.644 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-08-21T06:08:02.657Z,1408601282.657 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-08-21T06:08:02.658Z,1408601282.658 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-08-21T06:08:02.658Z,1408601282.658 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4947
2014-08-21T06:08:02.659Z,1408601282.659 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-08-21T06:08:02.660Z,1408601282.660 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-08-21T06:08:02.661Z,1408601282.661 [logger ThreadHandler](INFO): Protected caller Thread ID is 4948
2014-08-21T06:08:02.663Z,1408601282.663 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-08-21T06:08:02.663Z,1408601282.663 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-08-21T06:08:02.665Z,1408601282.665 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-08-21T06:08:03.015Z,1408601283.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-08-21T06:08:03.015Z,1408601283.015 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-08-21T06:08:03.180Z,1408601283.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-08-21T06:08:03.181Z,1408601283.181 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-08-21T06:08:03.294Z,1408601283.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-08-21T06:08:03.295Z,1408601283.295 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-08-21T06:08:03.425Z,1408601283.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-08-21T06:08:03.425Z,1408601283.425 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-08-21T06:08:03.516Z,1408601283.516 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-08-21T06:08:03.666Z,1408601283.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-08-21T06:08:03.667Z,1408601283.667 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-08-21T06:08:03.757Z,1408601283.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-08-21T06:08:03.758Z,1408601283.758 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-08-21T06:08:03.947Z,1408601283.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-08-21T06:08:03.948Z,1408601283.948 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-08-21T06:08:04.260Z,1408601284.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-08-21T06:08:04.260Z,1408601284.260 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-08-21T06:08:04.580Z,1408601284.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-08-21T06:08:04.581Z,1408601284.581 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-08-21T06:08:05.094Z,1408601285.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-08-21T06:08:05.095Z,1408601285.095 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-08-21T06:08:05.297Z,1408601285.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-08-21T06:08:05.298Z,1408601285.298 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-08-21T06:08:05.406Z,1408601285.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-08-21T06:08:05.407Z,1408601285.407 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-08-21T06:08:05.822Z,1408601285.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-08-21T06:08:05.822Z,1408601285.822 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-08-21T06:08:05.938Z,1408601285.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-08-21T06:08:05.940Z,1408601285.940 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-08-21T06:08:05.941Z,1408601285.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-08-21T06:08:06.051Z,1408601286.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-08-21T06:08:06.175Z,1408601286.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-08-21T06:08:06.278Z,1408601286.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-08-21T06:08:06.394Z,1408601286.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-08-21T06:08:06.496Z,1408601286.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-08-21T06:08:06.631Z,1408601286.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-08-21T06:08:06.795Z,1408601286.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-08-21T06:08:06.945Z,1408601286.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-08-21T06:08:07.040Z,1408601287.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-08-21T06:08:07.124Z,1408601287.124 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-08-21T06:08:07.227Z,1408601287.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-08-21T06:08:07.344Z,1408601287.344 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-08-21T06:08:07.344Z,1408601287.344 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-08-21T06:08:07.349Z,1408601287.349 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-08-21T06:08:07.402Z,1408601287.402 [VerticalControl](DEBUG): Construct VerticalControl.
2014-08-21T06:08:07.524Z,1408601287.524 [VerticalControl] Loaded
2014-08-21T06:08:07.524Z,1408601287.524 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-08-21T06:08:07.525Z,1408601287.525 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-08-21T06:08:07.599Z,1408601287.599 [HorizontalControl] Loaded
2014-08-21T06:08:07.600Z,1408601287.600 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-08-21T06:08:07.600Z,1408601287.600 [SpeedControl](DEBUG): Construct SpeedControl.
2014-08-21T06:08:07.606Z,1408601287.606 [SpeedControl] Loaded
2014-08-21T06:08:07.606Z,1408601287.606 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-08-21T06:08:07.607Z,1408601287.607 [LoopControl](DEBUG): Construct LoopControl.
2014-08-21T06:08:07.608Z,1408601287.608 [LoopControl] Loaded
2014-08-21T06:08:07.608Z,1408601287.608 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-08-21T06:08:07.608Z,1408601287.608 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-08-21T06:08:07.609Z,1408601287.609 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-08-21T06:08:07.713Z,1408601287.713 [SBIT](DEBUG): Construct Startup Built In Test.
2014-08-21T06:08:07.725Z,1408601287.725 [SBIT] Loaded
2014-08-21T06:08:07.725Z,1408601287.725 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-08-21T06:08:07.726Z,1408601287.726 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-08-21T06:08:07.756Z,1408601287.756 [IBIT] Loaded
2014-08-21T06:08:07.757Z,1408601287.757 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-08-21T06:08:07.759Z,1408601287.759 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-08-21T06:08:07.880Z,1408601287.880 [CBIT] Loaded
2014-08-21T06:08:07.880Z,1408601287.880 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-08-21T06:08:07.880Z,1408601287.880 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-08-21T06:08:07.881Z,1408601287.881 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-08-21T06:08:07.900Z,1408601287.900 [DepthRateCalculator] Loaded
2014-08-21T06:08:07.901Z,1408601287.901 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-08-21T06:08:07.906Z,1408601287.906 [PitchRateCalculator] Loaded
2014-08-21T06:08:07.907Z,1408601287.907 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-08-21T06:08:07.918Z,1408601287.918 [SpeedCalculator] Loaded
2014-08-21T06:08:07.918Z,1408601287.918 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-08-21T06:08:07.936Z,1408601287.936 [TempGradientCalculator] Loaded
2014-08-21T06:08:07.936Z,1408601287.936 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-08-21T06:08:07.941Z,1408601287.941 [YawRateCalculator] Loaded
2014-08-21T06:08:07.942Z,1408601287.942 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-08-21T06:08:07.942Z,1408601287.942 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-08-21T06:08:07.943Z,1408601287.943 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-08-21T06:08:12.408Z,1408601292.408 [HFRadarCompactModelForecaster] Loaded
2014-08-21T06:08:12.409Z,1408601292.409 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-08-21T06:08:13.564Z,1408601293.564 [HFRCMSpaceInterpolator] Loaded
2014-08-21T06:08:13.564Z,1408601293.564 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-08-21T06:08:13.566Z,1408601293.566 [HFRCMTimeInterpolator] Loaded
2014-08-21T06:08:13.566Z,1408601293.566 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-08-21T06:08:14.648Z,1408601294.648 [HFRCMReconstructedInterpolator] Loaded
2014-08-21T06:08:14.648Z,1408601294.648 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-08-21T06:08:14.660Z,1408601294.660 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-08-21T06:08:14.661Z,1408601294.661 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-08-21T06:08:14.661Z,1408601294.661 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-08-21T06:08:14.662Z,1408601294.662 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-08-21T06:08:14.899Z,1408601294.899 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-08-21T06:08:14.900Z,1408601294.900 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-08-21T06:08:14.982Z,1408601294.982 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-08-21T06:08:14.982Z,1408601294.982 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-08-21T06:08:15.039Z,1408601295.039 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-08-21T06:08:15.040Z,1408601295.040 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-08-21T06:08:15.086Z,1408601295.086 [DeadReckonWithRespectToWater] Loaded
2014-08-21T06:08:15.086Z,1408601295.086 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-08-21T06:08:15.134Z,1408601295.134 [DeadReckonWithRespectToSeafloor] Loaded
2014-08-21T06:08:15.134Z,1408601295.134 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-08-21T06:08:15.195Z,1408601295.195 [DeadReckonUsingDVLWaterTrack] Loaded
2014-08-21T06:08:15.195Z,1408601295.195 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-08-21T06:08:15.243Z,1408601295.243 [DeadReckonUsingCompactModelForecast] Loaded
2014-08-21T06:08:15.243Z,1408601295.243 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-08-21T06:08:15.261Z,1408601295.261 [NavChart] Loaded
2014-08-21T06:08:15.262Z,1408601295.262 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-08-21T06:08:15.266Z,1408601295.266 [UniversalFixResidualReporter] Loaded
2014-08-21T06:08:15.266Z,1408601295.266 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-08-21T06:08:15.267Z,1408601295.267 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-08-21T06:08:15.267Z,1408601295.267 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-08-21T06:08:15.273Z,1408601295.273 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-08-21T06:08:15.274Z,1408601295.274 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-08-21T06:08:15.453Z,1408601295.453 [CTD_NeilBrown] Loaded
2014-08-21T06:08:15.454Z,1408601295.454 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-08-21T06:08:15.455Z,1408601295.455 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406C64E0
2014-08-21T06:08:15.455Z,1408601295.455 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5030
2014-08-21T06:08:15.471Z,1408601295.471 [PAR_Licor] Loaded
2014-08-21T06:08:15.472Z,1408601295.472 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-08-21T06:08:15.508Z,1408601295.508 [WetLabsBB2FL] Loaded
2014-08-21T06:08:15.509Z,1408601295.509 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-08-21T06:08:15.510Z,1408601295.510 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406F64E0
2014-08-21T06:08:15.510Z,1408601295.510 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5031
2014-08-21T06:08:15.511Z,1408601295.511 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-08-21T06:08:15.511Z,1408601295.511 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-08-21T06:08:15.812Z,1408601295.812 [AHRS_sp3003D] Loaded
2014-08-21T06:08:15.812Z,1408601295.812 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-08-21T06:08:16.078Z,1408601296.078 [Batt_Ocean_Server] Loaded
2014-08-21T06:08:16.079Z,1408601296.079 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-08-21T06:08:16.154Z,1408601296.154 [DataOverHttps] Loaded
2014-08-21T06:08:16.155Z,1408601296.155 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-08-21T06:08:16.169Z,1408601296.169 [Depth_Keller] Loaded
2014-08-21T06:08:16.169Z,1408601296.169 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-08-21T06:08:16.306Z,1408601296.306 [DVL_micro] Loaded
2014-08-21T06:08:16.306Z,1408601296.306 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-08-21T06:08:16.403Z,1408601296.403 [NAL9602] Loaded
2014-08-21T06:08:16.403Z,1408601296.403 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-08-21T06:08:16.446Z,1408601296.446 [Onboard] Loaded
2014-08-21T06:08:16.447Z,1408601296.447 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-08-21T06:08:16.454Z,1408601296.454 [Radio_Freewave] Loaded
2014-08-21T06:08:16.454Z,1408601296.454 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-08-21T06:08:16.456Z,1408601296.456 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2014-08-21T06:08:16.456Z,1408601296.456 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 5032
2014-08-21T06:08:16.586Z,1408601296.586 [DAT] Loaded
2014-08-21T06:08:16.586Z,1408601296.586 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-08-21T06:08:16.593Z,1408601296.593 [SCPI] Loaded
2014-08-21T06:08:16.593Z,1408601296.593 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-08-21T06:08:16.594Z,1408601296.594 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-08-21T06:08:16.594Z,1408601296.594 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-08-21T06:08:16.697Z,1408601296.697 [BuoyancyServo] Loaded
2014-08-21T06:08:16.697Z,1408601296.697 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-08-21T06:08:16.710Z,1408601296.710 [ElevatorServo] Loaded
2014-08-21T06:08:16.710Z,1408601296.710 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-08-21T06:08:16.722Z,1408601296.722 [MassServo] Loaded
2014-08-21T06:08:16.722Z,1408601296.722 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-08-21T06:08:16.734Z,1408601296.734 [RudderServo] Loaded
2014-08-21T06:08:16.735Z,1408601296.735 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-08-21T06:08:16.746Z,1408601296.746 [ThrusterServo] Loaded
2014-08-21T06:08:16.747Z,1408601296.747 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-08-21T06:08:16.747Z,1408601296.747 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-08-21T06:08:16.748Z,1408601296.748 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-08-21T06:08:16.913Z,1408601296.913 [InternalSim] Loaded
2014-08-21T06:08:16.914Z,1408601296.914 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-08-21T06:08:16.914Z,1408601296.914 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-08-21T06:08:16.915Z,1408601296.915 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-08-21T06:08:16.939Z,1408601296.939 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-08-21T06:08:16.941Z,1408601296.941 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-08-21T06:08:16.942Z,1408601296.942 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-08-21T06:08:16.949Z,1408601296.949 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-08-21T06:08:16.950Z,1408601296.950 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2014-08-21T06:08:16.951Z,1408601296.951 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5033
2014-08-21T06:08:16.956Z,1408601296.956 [Supervisor](INFO): Main Thread ID is 768
2014-08-21T06:08:16.956Z,1408601296.956 [Supervisor](DEBUG): Running supervisor.
2014-08-21T06:08:16.957Z,1408601296.957 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5034
2014-08-21T06:08:16.961Z,1408601296.961 [controlThread ThreadHandler](INFO): Handler Thread ID is 5035
2014-08-21T06:08:16.962Z,1408601296.962 [controlThread](DEBUG): Initializing ControlThread
2014-08-21T06:08:16.963Z,1408601296.963 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-08-21T06:08:16.965Z,1408601296.965 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-08-21T06:08:16.965Z,1408601296.965 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-08-21T06:08:16.966Z,1408601296.966 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-08-21T06:08:16.966Z,1408601296.966 [SBIT](INFO): Initialize SBIT Component.
2014-08-21T06:08:16.967Z,1408601296.967 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11562
2014-08-21T06:08:16.967Z,1408601296.967 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-08-21T06:08:16.967Z,1408601296.967 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-08-21T06:08:16.968Z,1408601296.968 [IBIT](INFO): Initialize IBIT Component.
2014-08-21T06:08:16.969Z,1408601296.969 [CBIT](DEBUG): Initialize CBIT Component.
2014-08-21T06:08:16.969Z,1408601296.969 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-08-21T06:08:16.970Z,1408601296.970 [logger ThreadHandler](INFO): Handler Thread ID is 5036
2014-08-21T06:08:16.989Z,1408601296.989 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5037
2014-08-21T06:08:16.990Z,1408601296.990 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-08-21T06:08:16.993Z,1408601296.993 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-21T06:08:16.993Z,1408601296.993 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-08-21T06:08:16.994Z,1408601296.994 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-08-21T06:08:16.994Z,1408601296.994 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-08-21T06:08:16.995Z,1408601296.995 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-08-21T06:08:17.002Z,1408601297.002 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-08-21T06:08:17.013Z,1408601297.013 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-08-21T06:08:17.022Z,1408601297.022 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5039
2014-08-21T06:08:17.023Z,1408601297.023 [WetLabsBB2FL](INFO): Powering down
2014-08-21T06:08:17.058Z,1408601297.058 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 5040
2014-08-21T06:08:17.094Z,1408601297.094 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5042
2014-08-21T06:08:17.097Z,1408601297.097 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-08-21T06:08:17.098Z,1408601297.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-08-21T06:08:17.098Z,1408601297.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-08-21T06:08:17.099Z,1408601297.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-08-21T06:08:17.099Z,1408601297.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-08-21T06:08:17.099Z,1408601297.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-08-21T06:08:17.099Z,1408601297.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-08-21T06:08:17.100Z,1408601297.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-08-21T06:08:17.100Z,1408601297.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-08-21T06:08:17.408Z,1408601297.408 [Radio_Freewave](INFO): Powering up
2014-08-21T06:08:18.220Z,1408601298.220 [Radio_Freewave](IMPORTANT): Starting PPPD with command:/sbin/pppd nocrtscts /dev/ttyS1 noauth 115200 10.1.1.2:10.1.1.1 persist maxfail 0
2014-08-21T06:08:26.441Z,1408601306.441 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201408210200 published
2014-08-21T06:08:26.465Z,1408601306.465 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-08-21T06:08:26.466Z,1408601306.466 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-08-21T06:08:26.467Z,1408601306.467 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-08-21T06:08:26.469Z,1408601306.469 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-08-21T06:08:26.470Z,1408601306.470 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-08-21T06:08:26.470Z,1408601306.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-21T06:08:26.470Z,1408601306.470 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-08-21T06:08:26.471Z,1408601306.471 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-08-21T06:08:26.471Z,1408601306.471 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-08-21T06:08:26.472Z,1408601306.472 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-08-21T06:08:26.472Z,1408601306.472 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-08-21T06:08:26.473Z,1408601306.473 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-21T06:08:28.544Z,1408601308.544 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-08-21T06:08:28.574Z,1408601308.574 [InternalSim](DEBUG):  InternalSim initializing...
2014-08-21T06:08:28.693Z,1408601308.693 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-08-21T06:08:28.728Z,1408601308.728 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-21T06:08:28.753Z,1408601308.753 [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://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.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>


2014-08-21T06:08:28.754Z,1408601308.754 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-08-21T06:08:28.856Z,1408601308.856 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-08-21T06:08:28.859Z,1408601308.859 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-08-21T06:08:28.888Z,1408601308.888 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-21T06:08:28.909Z,1408601308.909 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-08-21T06:08:28.915Z,1408601308.915 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-08-21T06:08:28.959Z,1408601308.959 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-08-21T06:08:28.965Z,1408601308.965 [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://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="TimeMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <DefineArg Name="ElapsedSinceMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <Aggregate Id="StartClock">

        <Sequence/>

        <Assign><Arg Name="TimeMissionWasStarted"/><Universal:time/></Assign>

    </Aggregate>

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

    <Aggregate Id="CheckIn">

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or><Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

<!-- TODO: Do we really want this When operating on platform_communications? or on something else like time_fix? -->

<!-- Run application at high speed -->

        <Guidance:SetSpeed>
            <Parallel/>
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
<!-- NOTE: Original Default.xml didn't have a timeout on the GPS read, so theoretically could have hung indefinitely there... -->
            <Universal:latitude_fix/>
        </ReadDatum>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

        <When>
            <Elapsed><Arg Name="ElapsedSinceMissionWasStarted"/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
        </When>

        <Assign><Arg Name="ElapsedSinceMissionWasStarted"/><Universal:time/><Sub><Arg Name="TimeMissionWasStarted"/></Sub></Assign>

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

        <Guidance:SetSpeed Id="RunApplicationAtLowSpeed">
            <Parallel/>
            <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
        </Guidance:SetSpeed>

    </Aggregate>

</Mission>


2014-08-21T06:08:28.978Z,1408601308.978 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,DAT,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-08-21T06:08:29.160Z,1408601309.160 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-08-21T06:08:29.324Z,1408601309.324 [DVL_micro](INFO): Initializing
2014-08-21T06:08:29.354Z,1408601309.354 [NAL9602](INFO): Powering up NAL9602
2014-08-21T06:08:29.400Z,1408601309.400 [DAT](INFO): Powering up
2014-08-21T06:08:29.401Z,1408601309.401 [DAT](DEBUG): Initializing DAT.
2014-08-21T06:08:40.759Z,1408601320.759 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:40.800Z,1408601320.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:40.832Z,1408601320.832 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:40.868Z,1408601320.868 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:40.902Z,1408601320.902 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:40.921Z,1408601320.921 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:41.076Z,1408601321.076 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-08-21T06:08:41.083Z,1408601321.083 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-08-21T06:08:41.105Z,1408601321.105 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-08-21T06:08:41.111Z,1408601321.111 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-08-21T06:08:41.133Z,1408601321.133 [MassServo](DEBUG): Initializing EZServoServo.
2014-08-21T06:08:41.139Z,1408601321.139 [MassServo](DEBUG): Initializing MassServo.
2014-08-21T06:08:41.145Z,1408601321.145 [RudderServo](DEBUG): Initializing EZServoServo.
2014-08-21T06:08:41.151Z,1408601321.151 [RudderServo](DEBUG): Initializing RudderServo.
2014-08-21T06:08:41.172Z,1408601321.172 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-08-21T06:08:41.179Z,1408601321.179 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-08-21T06:08:41.884Z,1408601321.884 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-08-21T06:08:41.884Z,1408601321.884 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-08-21T06:08:41.884Z,1408601321.884 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2014-08-21T06:08:41.884Z,1408601321.884 [AHRS_sp3003D](ERROR): Hardware Fault
2014-08-21T06:08:42.571Z,1408601322.571 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:42.577Z,1408601322.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:42.578Z,1408601322.578 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:42.579Z,1408601322.579 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:42.579Z,1408601322.579 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:42.580Z,1408601322.580 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:42.906Z,1408601322.906 [SBIT](IMPORTANT): Beginning Startup BIT
2014-08-21T06:08:42.909Z,1408601322.909 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-08-21T06:08:42.909Z,1408601322.909 [CBIT](IMPORTANT): Beginning GF scan
2014-08-21T06:08:43.078Z,1408601323.078 [AHRS_sp3003D](INFO): Powering down
2014-08-21T06:08:44.833Z,1408601324.833 [NAL9602](INFO): NAL9602 initialized
2014-08-21T06:08:44.900Z,1408601324.900 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:44.901Z,1408601324.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:44.903Z,1408601324.903 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:44.903Z,1408601324.903 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:44.912Z,1408601324.912 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:44.913Z,1408601324.913 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.360Z,1408601325.360 [CommandLine](IMPORTANT): got command restart application
2014-08-21T06:08:45.449Z,1408601325.449 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.450Z,1408601325.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.451Z,1408601325.451 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.452Z,1408601325.452 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.453Z,1408601325.453 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.453Z,1408601325.453 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.657Z,1408601325.657 [DAT](INFO): entering command mode
2014-08-21T06:08:45.678Z,1408601325.678 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.679Z,1408601325.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.680Z,1408601325.680 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.681Z,1408601325.681 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.702Z,1408601325.702 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.704Z,1408601325.704 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:45.739Z,1408601325.739 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-08-21T06:08:45.740Z,1408601325.740 [AHRS_sp3003D] No Fault, FailCount= 1
2014-08-21T06:08:46.031Z,1408601326.031 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-08-21T06:08:46.048Z,1408601326.048 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003906
2014-08-21T06:08:46.081Z,1408601326.081 [DAT](DEBUG): checking for command mode acknowledgment
2014-08-21T06:08:46.081Z,1408601326.081 [DAT](DEBUG): read user prompt 1: user:1>
2014-08-21T06:08:46.081Z,1408601326.081 [DAT](INFO): command mode acknowledged
2014-08-21T06:08:46.115Z,1408601326.115 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.116Z,1408601326.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.117Z,1408601326.117 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.118Z,1408601326.118 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.123Z,1408601326.123 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.124Z,1408601326.124 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.372Z,1408601326.372 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:46.373Z,1408601326.373 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:46.422Z,1408601326.422 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-08-21T06:08:46.423Z,1408601326.423 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:46.423Z,1408601326.423 [CommandLine](INFO): Join timeout helper Thread ID is 5066
2014-08-21T06:08:46.447Z,1408601326.447 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-08-21T06:08:46.447Z,1408601326.447 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:46.447Z,1408601326.447 [NavChartDb](INFO): Join timeout helper Thread ID is 5067
2014-08-21T06:08:46.468Z,1408601326.468 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:46.530Z,1408601326.530 [DAT](INFO): setting local address to 2
2014-08-21T06:08:46.571Z,1408601326.571 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.572Z,1408601326.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.573Z,1408601326.573 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.583Z,1408601326.583 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.584Z,1408601326.584 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:46.585Z,1408601326.585 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.448Z,1408601327.448 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-08-21T06:08:47.448Z,1408601327.448 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-08-21T06:08:47.448Z,1408601327.448 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2014-08-21T06:08:47.448Z,1408601327.448 [AHRS_sp3003D](ERROR): Hardware Fault
2014-08-21T06:08:47.454Z,1408601327.454 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:47.467Z,1408601327.467 [DAT](DEBUG): checking for local address setting acknowledgment
2014-08-21T06:08:47.484Z,1408601327.484 [DAT](INFO): set local address to 2
2014-08-21T06:08:47.501Z,1408601327.501 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.502Z,1408601327.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.503Z,1408601327.503 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.504Z,1408601327.504 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.509Z,1408601327.509 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.510Z,1408601327.510 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.623Z,1408601327.623 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-08-21T06:08:47.638Z,1408601327.638 [AHRS_sp3003D](INFO): Powering down
2014-08-21T06:08:47.786Z,1408601327.786 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:47.815Z,1408601327.815 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.835Z,1408601327.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.840Z,1408601327.840 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.841Z,1408601327.841 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.842Z,1408601327.842 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:47.843Z,1408601327.843 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.060Z,1408601328.060 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:48.060Z,1408601328.060 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:48.065Z,1408601328.065 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-08-21T06:08:48.065Z,1408601328.065 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:48.066Z,1408601328.066 [Radio_Freewave](INFO): Join timeout helper Thread ID is 5068
2014-08-21T06:08:48.085Z,1408601328.085 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:48.116Z,1408601328.116 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.122Z,1408601328.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.123Z,1408601328.123 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.124Z,1408601328.124 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.125Z,1408601328.125 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.130Z,1408601328.130 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.450Z,1408601328.450 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:48.454Z,1408601328.454 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:48.584Z,1408601328.584 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.594Z,1408601328.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.595Z,1408601328.595 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.596Z,1408601328.596 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.597Z,1408601328.597 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.607Z,1408601328.607 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.699Z,1408601328.699 [Radio_Freewave](INFO): Powering down
2014-08-21T06:08:48.908Z,1408601328.908 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:48.962Z,1408601328.962 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.963Z,1408601328.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.964Z,1408601328.964 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.965Z,1408601328.965 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.966Z,1408601328.966 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:48.967Z,1408601328.967 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.103Z,1408601329.103 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.111Z,1408601329.111 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-08-21T06:08:49.111Z,1408601329.111 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.111Z,1408601329.111 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5071
2014-08-21T06:08:49.252Z,1408601329.252 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:49.299Z,1408601329.299 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.300Z,1408601329.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.301Z,1408601329.301 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.302Z,1408601329.302 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.303Z,1408601329.303 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.304Z,1408601329.304 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.459Z,1408601329.459 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:49.459Z,1408601329.459 [WetLabsBB2FL](INFO): Powering down
2014-08-21T06:08:49.460Z,1408601329.460 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.463Z,1408601329.463 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-08-21T06:08:49.463Z,1408601329.463 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.464Z,1408601329.464 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5072
2014-08-21T06:08:49.532Z,1408601329.532 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-08-21T06:08:49.532Z,1408601329.532 [AHRS_sp3003D] No Fault, FailCount= 2
2014-08-21T06:08:49.642Z,1408601329.642 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-08-21T06:08:49.646Z,1408601329.646 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T06:08:49.662Z,1408601329.662 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.663Z,1408601329.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.664Z,1408601329.664 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.665Z,1408601329.665 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.665Z,1408601329.665 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.666Z,1408601329.666 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T06:08:49.796Z,1408601329.796 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:49.796Z,1408601329.796 [CTD_NeilBrown](INFO): Powering down
2014-08-21T06:08:49.797Z,1408601329.797 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.809Z,1408601329.809 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-08-21T06:08:49.809Z,1408601329.809 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.809Z,1408601329.809 [logger](INFO): Join timeout helper Thread ID is 5074
2014-08-21T06:08:49.828Z,1408601329.828 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:49.828Z,1408601329.828 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.849Z,1408601329.849 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-08-21T06:08:49.849Z,1408601329.849 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.849Z,1408601329.849 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-08-21T06:08:49.850Z,1408601329.850 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:49.850Z,1408601329.850 [controlThread](INFO): Join timeout helper Thread ID is 5075
2014-08-21T06:08:49.865Z,1408601329.865 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T06:08:49.866Z,1408601329.866 [controlThread](DEBUG): Uninitializing ControlThread
2014-08-21T06:08:49.866Z,1408601329.866 [AHRS_sp3003D](INFO): Powering down
2014-08-21T06:08:50.024Z,1408601330.024 [DVL_micro](INFO): uninitialize:Powering down
2014-08-21T06:08:50.025Z,1408601330.025 [NAL9602](INFO): Powering down
2014-08-21T06:08:50.026Z,1408601330.026 [DAT](INFO): Powering down
2014-08-21T06:08:50.165Z,1408601330.165 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-21T06:08:50.168Z,1408601330.168 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-08-21T06:08:50.172Z,1408601330.172 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-08-21T06:08:50.173Z,1408601330.173 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-08-21T06:08:50.173Z,1408601330.173 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-08-21T06:08:50.173Z,1408601330.173 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-08-21T06:08:50.174Z,1408601330.174 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-08-21T06:08:50.174Z,1408601330.174 [BuoyancyServo](INFO): Powering down
2014-08-21T06:08:50.188Z,1408601330.188 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-08-21T06:08:50.189Z,1408601330.189 [ElevatorServo](INFO): Powering down
2014-08-21T06:08:50.189Z,1408601330.189 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-08-21T06:08:50.190Z,1408601330.190 [MassServo](INFO): Powering down
2014-08-21T06:08:50.209Z,1408601330.209 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-08-21T06:08:50.209Z,1408601330.209 [RudderServo](INFO): Powering down
2014-08-21T06:08:50.210Z,1408601330.210 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-08-21T06:08:50.210Z,1408601330.210 [ThrusterServo](INFO): Powering down
2014-08-21T06:08:50.211Z,1408601330.211 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-08-21T06:08:50.211Z,1408601330.211 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-08-21T06:08:50.212Z,1408601330.212 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-08-21T06:08:50.245Z,1408601330.245 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:50.325Z,1408601330.325 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:50.329Z,1408601330.329 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:50.338Z,1408601330.338 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:50.512Z,1408601330.512 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-21T06:08:50.552Z,1408601330.552 [logger ThreadHandler](INFO): Thread cancelled.