2013-02-27T19:21:58.840Z,1361992918.840 [Supervisor](DEBUG): Initializing supervisor.
2013-02-27T19:21:58.843Z,1361992918.843 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-02-27T19:21:58.843Z,1361992918.843 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-02-27T19:21:58.844Z,1361992918.844 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-02-27T19:21:58.848Z,1361992918.848 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-02-27T19:21:58.859Z,1361992918.859 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-02-27T19:21:58.860Z,1361992918.860 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-02-27T19:21:58.861Z,1361992918.861 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-02-27T19:21:58.862Z,1361992918.862 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-02-27T19:21:58.863Z,1361992918.862 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-02-27T19:21:58.864Z,1361992918.864 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-02-27T19:21:59.141Z,1361992919.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-02-27T19:21:59.142Z,1361992919.142 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-02-27T19:21:59.335Z,1361992919.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-02-27T19:21:59.336Z,1361992919.336 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-02-27T19:21:59.427Z,1361992919.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-02-27T19:21:59.428Z,1361992919.428 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-02-27T19:21:59.624Z,1361992919.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-02-27T19:21:59.624Z,1361992919.625 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-02-27T19:21:59.756Z,1361992919.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-02-27T19:21:59.757Z,1361992919.757 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-02-27T19:21:59.988Z,1361992919.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-02-27T19:21:59.989Z,1361992919.989 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-02-27T19:22:00.158Z,1361992920.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-02-27T19:22:00.158Z,1361992920.158 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-02-27T19:22:00.409Z,1361992920.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-02-27T19:22:00.409Z,1361992920.409 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-02-27T19:22:00.507Z,1361992920.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-02-27T19:22:00.507Z,1361992920.507 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-02-27T19:22:00.905Z,1361992920.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-02-27T19:22:00.906Z,1361992920.906 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-02-27T19:22:01.019Z,1361992921.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-02-27T19:22:01.020Z,1361992921.020 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-02-27T19:22:01.104Z,1361992921.104 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-02-27T19:22:01.105Z,1361992921.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-02-27T19:22:01.205Z,1361992921.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-02-27T19:22:01.333Z,1361992921.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-02-27T19:22:01.417Z,1361992921.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-02-27T19:22:01.517Z,1361992921.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-02-27T19:22:01.616Z,1361992921.616 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-02-27T19:22:01.733Z,1361992921.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-02-27T19:22:01.825Z,1361992921.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-02-27T19:22:01.911Z,1361992921.911 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-02-27T19:22:01.939Z,1361992921.939 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-02-27T19:22:02.094Z,1361992922.094 [InternalSim] Loaded
2013-02-27T19:22:02.094Z,1361992922.094 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-02-27T19:22:02.095Z,1361992922.095 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-02-27T19:22:02.096Z,1361992922.096 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-02-27T19:22:02.154Z,1361992922.154 [SBIT](DEBUG): Construct Startup Built In Test.
2013-02-27T19:22:02.183Z,1361992922.183 [SBIT] Loaded
2013-02-27T19:22:02.183Z,1361992922.183 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-02-27T19:22:02.184Z,1361992922.184 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-02-27T19:22:02.214Z,1361992922.214 [IBIT] Loaded
2013-02-27T19:22:02.214Z,1361992922.214 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-02-27T19:22:02.217Z,1361992922.217 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-02-27T19:22:02.339Z,1361992922.339 [CBIT] Loaded
2013-02-27T19:22:02.339Z,1361992922.339 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-02-27T19:22:02.340Z,1361992922.340 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-02-27T19:22:02.340Z,1361992922.340 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-02-27T19:22:02.491Z,1361992922.491 [BuoyancyServo] Loaded
2013-02-27T19:22:02.491Z,1361992922.491 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-02-27T19:22:02.504Z,1361992922.504 [ElevatorServo] Loaded
2013-02-27T19:22:02.504Z,1361992922.504 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-02-27T19:22:02.516Z,1361992922.516 [MassServo] Loaded
2013-02-27T19:22:02.516Z,1361992922.516 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-02-27T19:22:02.529Z,1361992922.529 [RudderServo] Loaded
2013-02-27T19:22:02.529Z,1361992922.529 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-02-27T19:22:02.541Z,1361992922.541 [ThrusterServo] Loaded
2013-02-27T19:22:02.541Z,1361992922.541 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-02-27T19:22:02.542Z,1361992922.542 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-02-27T19:22:02.542Z,1361992922.542 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-02-27T19:22:02.559Z,1361992922.559 [DepthRateCalculator] Loaded
2013-02-27T19:22:02.559Z,1361992922.559 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-02-27T19:22:05.948Z,1361992925.948 [HFRadarModelCalc] Loaded
2013-02-27T19:22:05.948Z,1361992925.948 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-02-27T19:22:05.964Z,1361992925.964 [NavChart] Loaded
2013-02-27T19:22:05.965Z,1361992925.965 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-02-27T19:22:05.971Z,1361992925.971 [PitchRateCalculator] Loaded
2013-02-27T19:22:05.971Z,1361992925.971 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-02-27T19:22:05.978Z,1361992925.978 [SpeedCalculator] Loaded
2013-02-27T19:22:05.978Z,1361992925.978 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-02-27T19:22:05.993Z,1361992925.993 [TempGradientCalculator] Loaded
2013-02-27T19:22:05.993Z,1361992925.993 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-02-27T19:22:05.999Z,1361992925.999 [YawRateCalculator] Loaded
2013-02-27T19:22:05.000Z,1361992926.000 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-02-27T19:22:06.041Z,1361992926.041 [Navigation] Loaded
2013-02-27T19:22:06.041Z,1361992926.041 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-02-27T19:22:06.042Z,1361992926.042 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-02-27T19:22:06.042Z,1361992926.042 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-02-27T19:22:06.267Z,1361992926.267 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-02-27T19:22:06.267Z,1361992926.267 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-02-27T19:22:06.289Z,1361992926.289 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-02-27T19:22:06.290Z,1361992926.290 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-02-27T19:22:06.329Z,1361992926.329 [VerticalControl](DEBUG): Construct VerticalControl.
2013-02-27T19:22:06.425Z,1361992926.425 [VerticalControl] Loaded
2013-02-27T19:22:06.425Z,1361992926.425 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-02-27T19:22:06.426Z,1361992926.426 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-02-27T19:22:06.484Z,1361992926.484 [HorizontalControl] Loaded
2013-02-27T19:22:06.484Z,1361992926.484 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-02-27T19:22:06.485Z,1361992926.485 [SpeedControl](DEBUG): Construct SpeedControl.
2013-02-27T19:22:06.487Z,1361992926.487 [SpeedControl] Loaded
2013-02-27T19:22:06.487Z,1361992926.487 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-02-27T19:22:06.488Z,1361992926.488 [LoopControl](DEBUG): Construct LoopControl.
2013-02-27T19:22:06.488Z,1361992926.489 [LoopControl] Loaded
2013-02-27T19:22:06.489Z,1361992926.489 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-02-27T19:22:06.489Z,1361992926.489 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-02-27T19:22:06.490Z,1361992926.490 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-02-27T19:22:06.495Z,1361992926.495 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-02-27T19:22:06.500Z,1361992926.500 [AsyncPiEstimator] Loaded
2013-02-27T19:22:06.500Z,1361992926.500 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-02-27T19:22:06.502Z,1361992926.501 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0
2013-02-27T19:22:06.502Z,1361992926.502 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-02-27T19:22:06.503Z,1361992926.503 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-02-27T19:22:06.632Z,1361992926.632 [AHRS_sp3003D] Loaded
2013-02-27T19:22:06.632Z,1361992926.632 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-02-27T19:22:06.897Z,1361992926.897 [Batt_Ocean_Server] Loaded
2013-02-27T19:22:06.897Z,1361992926.897 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-02-27T19:22:06.911Z,1361992926.911 [Depth_Keller] Loaded
2013-02-27T19:22:06.911Z,1361992926.911 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-02-27T19:22:06.917Z,1361992926.917 [DropWeight] Loaded
2013-02-27T19:22:06.918Z,1361992926.918 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-02-27T19:22:07.082Z,1361992927.082 [DVL_micro] Loaded
2013-02-27T19:22:07.082Z,1361992927.082 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-02-27T19:22:07.083Z,1361992927.083 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0
2013-02-27T19:22:07.168Z,1361992927.168 [NAL9602] Loaded
2013-02-27T19:22:07.168Z,1361992927.168 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-02-27T19:22:07.220Z,1361992927.220 [Onboard] Loaded
2013-02-27T19:22:07.220Z,1361992927.220 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-02-27T19:22:07.227Z,1361992927.227 [Radio_Freewave] Loaded
2013-02-27T19:22:07.227Z,1361992927.227 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-02-27T19:22:07.228Z,1361992927.228 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-02-27T19:22:07.228Z,1361992927.228 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-02-27T19:22:07.285Z,1361992927.285 [Aanderaa_O2] Loaded
2013-02-27T19:22:07.285Z,1361992927.286 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2013-02-27T19:22:07.319Z,1361992927.319 [CTD_NeilBrown] Loaded
2013-02-27T19:22:07.320Z,1361992927.320 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-02-27T19:22:07.321Z,1361992927.321 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2013-02-27T19:22:07.335Z,1361992927.335 [ISUS] Loaded
2013-02-27T19:22:07.335Z,1361992927.335 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2013-02-27T19:22:07.351Z,1361992927.351 [PAR_Licor] Loaded
2013-02-27T19:22:07.351Z,1361992927.351 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-02-27T19:22:07.381Z,1361992927.381 [WetLabsBB2FL] Loaded
2013-02-27T19:22:07.382Z,1361992927.382 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-02-27T19:22:07.383Z,1361992927.383 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2013-02-27T19:22:07.383Z,1361992927.383 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-02-27T19:22:07.386Z,1361992927.386 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-02-27T19:22:07.387Z,1361992927.387 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-02-27T19:22:07.393Z,1361992927.393 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-02-27T19:22:07.394Z,1361992927.395 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2013-02-27T19:22:07.399Z,1361992927.399 [Supervisor](DEBUG): Running supervisor.
2013-02-27T19:22:07.400Z,1361992927.400 [CommandLine](INFO): Thread ID is 8055
2013-02-27T19:22:07.403Z,1361992927.403 [controlThread](INFO): Thread ID is 8054
2013-02-27T19:22:07.403Z,1361992927.403 [controlThread](DEBUG): Initializing ControlThread
2013-02-27T19:22:07.403Z,1361992927.403 [CycleStarter](INFO): Thread ID is 8053
2013-02-27T19:22:07.404Z,1361992927.404 [InternalSim](DEBUG): InternalSim initializing...
2013-02-27T19:22:07.437Z,1361992927.437 [logger](INFO): Thread ID is 8056
2013-02-27T19:22:07.464Z,1361992927.464 [SBIT](INFO): Initialize SBIT Component.
2013-02-27T19:22:07.464Z,1361992927.464 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10150
2013-02-27T19:22:07.465Z,1361992927.465 [IBIT](INFO): Initialize IBIT Component.
2013-02-27T19:22:07.466Z,1361992927.466 [CBIT](DEBUG): Initialize CBIT Component.
2013-02-27T19:22:07.466Z,1361992927.466 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-02-27T19:22:07.477Z,1361992927.477 [AsyncPiEstimator](INFO): Thread ID is 8117
2013-02-27T19:22:07.477Z,1361992927.477 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-02-27T19:22:07.491Z,1361992927.491 [DVL_micro](INFO): Thread ID is 8118
2013-02-27T19:22:07.497Z,1361992927.497 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-27T19:22:07.498Z,1361992927.498 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-02-27T19:22:07.498Z,1361992927.498 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-02-27T19:22:07.499Z,1361992927.499 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-02-27T19:22:07.499Z,1361992927.499 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-02-27T19:22:07.501Z,1361992927.501 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-02-27T19:22:07.501Z,1361992927.501 [Navigation](DEBUG): Initializing Navigation.
2013-02-27T19:22:07.502Z,1361992927.502 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-02-27T19:22:07.503Z,1361992927.503 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-02-27T19:22:07.504Z,1361992927.504 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-02-27T19:22:07.505Z,1361992927.505 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-02-27T19:22:07.511Z,1361992927.511 [DVL_micro](INFO): Initializing
2013-02-27T19:22:07.512Z,1361992927.511 [DVL_micro](INFO): start:Powering up
2013-02-27T19:22:07.512Z,1361992927.513 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-27T19:22:07.513Z,1361992927.513 [DVL_micro](INFO): Cycling power to configure device.
2013-02-27T19:22:07.521Z,1361992927.521 [CTD_NeilBrown](INFO): Thread ID is 8119
2013-02-27T19:22:07.521Z,1361992927.521 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-02-27T19:22:07.524Z,1361992927.524 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-02-27T19:22:07.534Z,1361992927.534 [WetLabsBB2FL](INFO): Thread ID is 8120
2013-02-27T19:22:07.535Z,1361992927.535 [WetLabsBB2FL](INFO): Powering down
2013-02-27T19:22:07.569Z,1361992927.569 [NavChartDb](INFO): Thread ID is 8121
2013-02-27T19:22:07.571Z,1361992927.571 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2013-02-27T19:22:07.578Z,1361992927.578 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-02-27T19:22:07.581Z,1361992927.580 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2013-02-27T19:22:07.582Z,1361992927.582 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2013-02-27T19:22:07.584Z,1361992927.584 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2013-02-27T19:22:07.586Z,1361992927.586 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2013-02-27T19:22:07.587Z,1361992927.587 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2013-02-27T19:22:07.589Z,1361992927.589 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2013-02-27T19:22:07.591Z,1361992927.591 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2013-02-27T19:22:07.602Z,1361992927.602 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2013-02-27T19:22:07.780Z,1361992927.780 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000
2013-02-27T19:22:09.121Z,1361992929.121 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-02-27T19:22:09.182Z,1361992929.182 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-02-27T19:22:09.198Z,1361992929.198 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-27T19:22:09.209Z,1361992929.209 [MissionManager](DEBUG):
2013-02-27T19:22:09.210Z,1361992929.210 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-02-27T19:22:09.238Z,1361992929.239 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-02-27T19:22:09.257Z,1361992929.257 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-02-27T19:22:09.264Z,1361992929.264 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-27T19:22:09.281Z,1361992929.281 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-02-27T19:22:09.284Z,1361992929.284 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-27T19:22:09.290Z,1361992929.290 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-02-27T19:22:09.301Z,1361992929.301 [Default:D.SetSpeed](DEBUG): Construct.
2013-02-27T19:22:09.305Z,1361992929.305 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-27T19:22:09.309Z,1361992929.309 [Default:F.Wait](DEBUG): Construct Wait.
2013-02-27T19:22:09.313Z,1361992929.313 [MissionManager](DEBUG):
400
400
Burn 300
Dropped drop weight due to communications timeout
5.0
1.0
5
2013-02-27T19:22:09.318Z,1361992929.318 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,Aanderaa_O2,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-02-27T19:22:09.338Z,1361992929.338 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-02-27T19:22:09.512Z,1361992929.512 [Radio_Freewave](INFO): Powering up
2013-02-27T19:22:09.617Z,1361992929.617 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-27T19:22:09.617Z,1361992929.617 [DVL_micro](INFO): Querying output modes
2013-02-27T19:22:09.618Z,1361992929.618 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606
2013-02-27T19:22:09.632Z,1361992929.632 [DVL_micro](DEBUG): cmdResponse: 01
2013-02-27T19:22:09.632Z,1361992929.632 [DVL_micro](INFO): NQ1 output enabled
2013-02-27T19:22:09.633Z,1361992929.633 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525
2013-02-27T19:22:09.644Z,1361992929.644 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON
2013-02-27T19:22:09.656Z,1361992929.656 [DVL_micro](INFO): pause:Powering down
2013-02-27T19:22:09.702Z,1361992929.702 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:09.703Z,1361992929.703 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-27T19:22:09.710Z,1361992929.710 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:09.711Z,1361992929.711 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-27T19:22:09.717Z,1361992929.717 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:09.718Z,1361992929.718 [MassServo](DEBUG): Initializing MassServo.
2013-02-27T19:22:09.724Z,1361992929.724 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:09.725Z,1361992929.725 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-27T19:22:09.731Z,1361992929.731 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:09.732Z,1361992929.732 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-27T19:22:10.864Z,1361992930.864 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-27T19:22:10.864Z,1361992930.864 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-27T19:22:10.864Z,1361992930.864 [BuoyancyServo] Communications Fault, FailCount= 1
2013-02-27T19:22:10.864Z,1361992930.864 [BuoyancyServo](ERROR): Communications Fault
2013-02-27T19:22:11.066Z,1361992931.066 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-27T19:22:11.299Z,1361992931.299 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-27T19:22:11.300Z,1361992931.300 [BuoyancyServo](INFO): Powering down
2013-02-27T19:22:12.188Z,1361992932.188 [Aanderaa_O2](INFO): Powering down
2013-02-27T19:22:12.732Z,1361992932.732 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-02-27T19:22:12.732Z,1361992932.732 [MassServo] Communications Fault, FailCount= 1
2013-02-27T19:22:12.732Z,1361992932.732 [MassServo](ERROR): Communications Fault
2013-02-27T19:22:12.735Z,1361992932.735 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-27T19:22:12.975Z,1361992932.975 [NAL9602](INFO): Powering up NAL9602
2013-02-27T19:22:13.021Z,1361992933.020 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-27T19:22:13.021Z,1361992933.021 [MassServo](INFO): Powering down
2013-02-27T19:22:13.358Z,1361992933.358 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-27T19:22:13.358Z,1361992933.358 [BuoyancyServo] No Fault, FailCount= 1
2013-02-27T19:22:13.758Z,1361992933.758 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:13.877Z,1361992933.877 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-27T19:22:13.879Z,1361992933.879 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:13.999Z,1361992933.999 [MassServo](DEBUG): Initializing MassServo.
2013-02-27T19:22:14.098Z,1361992934.098 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-27T19:22:14.098Z,1361992934.098 [MassServo] No Fault, FailCount= 1
2013-02-27T19:22:14.912Z,1361992934.912 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-27T19:22:14.912Z,1361992934.912 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-27T19:22:14.912Z,1361992934.912 [BuoyancyServo] Communications Fault, FailCount= 2
2013-02-27T19:22:14.912Z,1361992934.912 [BuoyancyServo](ERROR): Communications Fault
2013-02-27T19:22:15.179Z,1361992935.179 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-27T19:22:15.385Z,1361992935.385 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-27T19:22:15.386Z,1361992935.386 [BuoyancyServo](INFO): Powering down
2013-02-27T19:22:16.688Z,1361992936.688 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-02-27T19:22:16.688Z,1361992936.688 [MassServo] Communications Fault, FailCount= 2
2013-02-27T19:22:16.688Z,1361992936.688 [MassServo](ERROR): Communications Fault
2013-02-27T19:22:16.691Z,1361992936.691 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-27T19:22:16.913Z,1361992936.913 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-27T19:22:16.913Z,1361992936.913 [MassServo](INFO): Powering down
2013-02-27T19:22:17.301Z,1361992937.301 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-27T19:22:17.301Z,1361992937.301 [BuoyancyServo] No Fault, FailCount= 2
2013-02-27T19:22:17.692Z,1361992937.692 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:17.809Z,1361992937.809 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-27T19:22:17.811Z,1361992937.811 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:17.933Z,1361992937.932 [MassServo](DEBUG): Initializing MassServo.
2013-02-27T19:22:17.936Z,1361992937.936 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-27T19:22:17.936Z,1361992937.936 [MassServo] No Fault, FailCount= 2
2013-02-27T19:22:19.080Z,1361992939.080 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-27T19:22:19.080Z,1361992939.080 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-27T19:22:19.080Z,1361992939.080 [BuoyancyServo] Communications Fault, FailCount= 3
2013-02-27T19:22:19.080Z,1361992939.080 [BuoyancyServo](ERROR): Communications Fault
2013-02-27T19:22:19.103Z,1361992939.103 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-27T19:22:19.409Z,1361992939.409 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-27T19:22:19.410Z,1361992939.410 [BuoyancyServo](INFO): Powering down
2013-02-27T19:22:20.624Z,1361992940.624 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-02-27T19:22:20.624Z,1361992940.624 [MassServo] Communications Fault, FailCount= 3
2013-02-27T19:22:20.624Z,1361992940.624 [MassServo](ERROR): Communications Fault
2013-02-27T19:22:20.627Z,1361992940.627 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-27T19:22:20.859Z,1361992940.859 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-27T19:22:20.859Z,1361992940.859 [MassServo](INFO): Powering down
2013-02-27T19:22:21.633Z,1361992941.633 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:21.749Z,1361992941.749 [MassServo](DEBUG): Initializing MassServo.
2013-02-27T19:22:21.752Z,1361992941.752 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-27T19:22:21.752Z,1361992941.752 [BuoyancyServo] No Fault, FailCount= 3
2013-02-27T19:22:21.752Z,1361992941.752 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-27T19:22:21.752Z,1361992941.753 [MassServo] No Fault, FailCount= 3
2013-02-27T19:22:22.081Z,1361992942.081 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:22.197Z,1361992942.197 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-27T19:22:23.364Z,1361992943.364 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-27T19:22:23.364Z,1361992943.364 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-27T19:22:23.364Z,1361992943.364 [BuoyancyServo] Communications Fault, FailCount= 4
2013-02-27T19:22:23.364Z,1361992943.364 [BuoyancyServo](ERROR): Communications Fault
2013-02-27T19:22:23.367Z,1361992943.367 [SBIT](IMPORTANT): Beginning Startup BIT
2013-02-27T19:22:23.369Z,1361992943.369 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-27T19:22:23.370Z,1361992943.370 [CBIT](IMPORTANT): Beginning GF scan
2013-02-27T19:22:23.575Z,1361992943.575 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-27T19:22:23.576Z,1361992943.576 [BuoyancyServo](INFO): Powering down
2013-02-27T19:22:24.164Z,1361992944.164 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-02-27T19:22:24.164Z,1361992944.164 [MassServo] Communications Fault, FailCount= 4
2013-02-27T19:22:24.164Z,1361992944.164 [MassServo](ERROR): Communications Fault
2013-02-27T19:22:24.168Z,1361992944.168 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-27T19:22:24.441Z,1361992944.441 [NAL9602](INFO): NAL9602 initialized
2013-02-27T19:22:24.521Z,1361992944.521 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-27T19:22:24.521Z,1361992944.521 [MassServo](INFO): Powering down
2013-02-27T19:22:25.261Z,1361992945.261 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:25.382Z,1361992945.382 [MassServo](DEBUG): Initializing MassServo.
2013-02-27T19:22:25.447Z,1361992945.447 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-27T19:22:25.447Z,1361992945.447 [MassServo] No Fault, FailCount= 4
2013-02-27T19:22:25.739Z,1361992945.739 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-27T19:22:25.739Z,1361992945.740 [BuoyancyServo] No Fault, FailCount= 4
2013-02-27T19:22:26.068Z,1361992946.068 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:26.193Z,1361992946.193 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-27T19:22:27.400Z,1361992947.400 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-27T19:22:27.400Z,1361992947.400 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-27T19:22:27.400Z,1361992947.400 [BuoyancyServo] Communications Fault, FailCount= 5
2013-02-27T19:22:27.400Z,1361992947.400 [BuoyancyServo](ERROR): Communications Fault
2013-02-27T19:22:27.401Z,1361992947.401 [CommandLine](IMPORTANT): got command restart application
2013-02-27T19:22:28.024Z,1361992948.024 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-02-27T19:22:28.024Z,1361992948.024 [MassServo] Communications Fault, FailCount= 5
2013-02-27T19:22:28.024Z,1361992948.024 [MassServo](ERROR): Communications Fault
2013-02-27T19:22:28.051Z,1361992948.051 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-27T19:22:28.052Z,1361992948.052 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-02-27T19:22:28.052Z,1361992948.052 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-27T19:22:28.052Z,1361992948.052 [CBIT](CRITICAL): Communications Fault in component: MassServo
2013-02-27T19:22:28.268Z,1361992948.268 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-27T19:22:28.269Z,1361992948.269 [BuoyancyServo](INFO): Powering down
2013-02-27T19:22:28.285Z,1361992948.285 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-27T19:22:28.285Z,1361992948.285 [MassServo](INFO): Powering down
2013-02-27T19:22:28.412Z,1361992948.412 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2013-02-27T19:22:28.412Z,1361992948.412 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-02-27T19:22:28.492Z,1361992948.492 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-02-27T19:22:28.620Z,1361992948.620 [WetLabsBB2FL](INFO): Powering down
2013-02-27T19:22:28.636Z,1361992948.636 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-02-27T19:22:28.708Z,1361992948.708 [CTD_NeilBrown](INFO): Powering down
2013-02-27T19:22:28.712Z,1361992948.712 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-02-27T19:22:29.048Z,1361992949.048 [DVL_micro](INFO): uninitialize:Powering down
2013-02-27T19:22:29.056Z,1361992949.056 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-02-27T19:22:29.077Z,1361992949.077 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-27T19:22:29.144Z,1361992949.144 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-02-27T19:22:29.161Z,1361992949.161 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-02-27T19:22:29.197Z,1361992949.197 [MassServo](DEBUG): Initializing MassServo.
2013-02-27T19:22:29.262Z,1361992949.261 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-02-27T19:22:29.277Z,1361992949.277 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-02-27T19:22:29.545Z,1361992949.545 [controlThread](DEBUG): Uninitializing ControlThread
2013-02-27T19:22:29.545Z,1361992949.545 [AHRS_sp3003D](INFO): Powering down
2013-02-27T19:22:29.633Z,1361992949.633 [NAL9602](INFO): Powering down
2013-02-27T19:22:29.635Z,1361992949.635 [Aanderaa_O2](INFO): Powering down
2013-02-27T19:22:29.637Z,1361992949.637 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-27T19:22:29.637Z,1361992949.637 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-02-27T19:22:29.642Z,1361992949.642 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-02-27T19:22:29.643Z,1361992949.643 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-02-27T19:22:29.643Z,1361992949.643 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-02-27T19:22:29.643Z,1361992949.643 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-02-27T19:22:29.644Z,1361992949.644 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-27T19:22:29.644Z,1361992949.644 [BuoyancyServo](INFO): Powering down
2013-02-27T19:22:29.656Z,1361992949.656 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-27T19:22:29.656Z,1361992949.656 [ElevatorServo](INFO): Powering down
2013-02-27T19:22:29.657Z,1361992949.657 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-27T19:22:29.657Z,1361992949.657 [MassServo](INFO): Powering down
2013-02-27T19:22:29.658Z,1361992949.658 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-27T19:22:29.658Z,1361992949.658 [RudderServo](INFO): Powering down
2013-02-27T19:22:29.659Z,1361992949.659 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-27T19:22:29.659Z,1361992949.659 [ThrusterServo](INFO): Powering down
2013-02-27T19:22:29.660Z,1361992949.660 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-02-27T19:22:29.660Z,1361992949.660 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-02-27T19:22:29.661Z,1361992949.661 [CBIT](DEBUG): Uninitialize CBIT Component.