2014-06-06T20:15:29.522Z,1402085729.522 [Supervisor](DEBUG): Initializing supervisor.
2014-06-06T20:15:29.525Z,1402085729.525 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-06-06T20:15:29.526Z,1402085729.526 [SyncHandler](INFO): Protected caller Thread ID is 778
2014-06-06T20:15:29.526Z,1402085729.526 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-06-06T20:15:29.529Z,1402085729.529 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-06-06T20:15:29.530Z,1402085729.530 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 779
2014-06-06T20:15:29.532Z,1402085729.532 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-06-06T20:15:29.544Z,1402085729.544 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-06-06T20:15:29.545Z,1402085729.545 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-06-06T20:15:29.546Z,1402085729.546 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 780
2014-06-06T20:15:29.547Z,1402085729.547 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-06-06T20:15:29.549Z,1402085729.549 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-06-06T20:15:29.550Z,1402085729.550 [logger ThreadHandler](INFO): Protected caller Thread ID is 781
2014-06-06T20:15:29.552Z,1402085729.552 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-06-06T20:15:29.552Z,1402085729.552 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-06-06T20:15:29.555Z,1402085729.555 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-06-06T20:15:29.947Z,1402085729.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-06-06T20:15:29.950Z,1402085729.950 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-06-06T20:15:30.268Z,1402085730.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-06-06T20:15:30.270Z,1402085730.270 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-06-06T20:15:30.366Z,1402085730.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-06-06T20:15:30.368Z,1402085730.368 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-06-06T20:15:30.578Z,1402085730.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-06-06T20:15:30.580Z,1402085730.580 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-06-06T20:15:30.763Z,1402085730.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-06-06T20:15:30.765Z,1402085730.765 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-06-06T20:15:31.093Z,1402085731.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-06-06T20:15:31.094Z,1402085731.094 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-06-06T20:15:31.297Z,1402085731.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-06-06T20:15:31.298Z,1402085731.298 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-06-06T20:15:31.682Z,1402085731.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-06-06T20:15:31.685Z,1402085731.685 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-06-06T20:15:31.804Z,1402085731.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-06-06T20:15:31.804Z,1402085731.804 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-06-06T20:15:32.363Z,1402085732.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-06-06T20:15:32.366Z,1402085732.366 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-06-06T20:15:32.491Z,1402085732.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-06-06T20:15:32.492Z,1402085732.492 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-06-06T20:15:32.590Z,1402085732.590 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-06-06T20:15:32.731Z,1402085732.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-06-06T20:15:32.732Z,1402085732.732 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-06-06T20:15:33.836Z,1402085733.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-06-06T20:15:33.840Z,1402085733.840 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-06-06T20:15:34.183Z,1402085734.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-06-06T20:15:34.185Z,1402085734.185 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-06-06T20:15:34.189Z,1402085734.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-06-06T20:15:34.316Z,1402085734.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-06-06T20:15:34.458Z,1402085734.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-06-06T20:15:34.566Z,1402085734.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-06-06T20:15:34.672Z,1402085734.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-06-06T20:15:34.828Z,1402085734.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-06-06T20:15:34.960Z,1402085734.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-06-06T20:15:35.056Z,1402085735.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-06-06T20:15:35.172Z,1402085735.172 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-06-06T20:15:35.284Z,1402085735.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-06-06T20:15:35.394Z,1402085735.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-06-06T20:15:35.490Z,1402085735.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-06-06T20:15:35.606Z,1402085735.606 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2014-06-06T20:15:35.611Z,1402085735.611 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-06-06T20:15:36.245Z,1402085736.245 [AHRS_sp3003D] Loaded
2014-06-06T20:15:36.246Z,1402085736.246 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-06-06T20:15:36.576Z,1402085736.576 [Batt_Ocean_Server] Loaded
2014-06-06T20:15:36.576Z,1402085736.576 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-06-06T20:15:36.594Z,1402085736.594 [Depth_Keller] Loaded
2014-06-06T20:15:36.594Z,1402085736.594 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-06-06T20:15:36.599Z,1402085736.599 [DropWeight] Loaded
2014-06-06T20:15:36.599Z,1402085736.599 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-06-06T20:15:36.755Z,1402085736.755 [DVL_micro] Loaded
2014-06-06T20:15:36.755Z,1402085736.755 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-06-06T20:15:36.862Z,1402085736.862 [NAL9602] Loaded
2014-06-06T20:15:36.863Z,1402085736.863 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-06-06T20:15:36.918Z,1402085736.918 [Onboard] Loaded
2014-06-06T20:15:36.919Z,1402085736.919 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-06-06T20:15:36.930Z,1402085736.930 [Radio_Freewave] Loaded
2014-06-06T20:15:36.930Z,1402085736.930 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-06-06T20:15:36.933Z,1402085736.933 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 4060E4E0
2014-06-06T20:15:36.933Z,1402085736.933 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 864
2014-06-06T20:15:36.940Z,1402085736.940 [SCPI] Loaded
2014-06-06T20:15:36.940Z,1402085736.940 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-06-06T20:15:36.941Z,1402085736.941 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-06-06T20:15:36.942Z,1402085736.942 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-06-06T20:15:37.247Z,1402085737.247 [InternalSim] Loaded
2014-06-06T20:15:37.247Z,1402085737.247 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-06-06T20:15:37.248Z,1402085737.248 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-06-06T20:15:37.248Z,1402085737.248 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-06-06T20:15:37.548Z,1402085737.548 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-06-06T20:15:37.551Z,1402085737.551 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-06-06T20:15:37.559Z,1402085737.559 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-06-06T20:15:37.560Z,1402085737.560 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-06-06T20:15:37.667Z,1402085737.667 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-06-06T20:15:37.667Z,1402085737.667 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-06-06T20:15:37.716Z,1402085737.716 [DeadReckonWithRespectToWater] Loaded
2014-06-06T20:15:37.717Z,1402085737.717 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-06-06T20:15:37.767Z,1402085737.767 [DeadReckonWithRespectToSeafloor] Loaded
2014-06-06T20:15:37.767Z,1402085737.767 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-06-06T20:15:37.818Z,1402085737.818 [DeadReckonUsingDVLWaterTrack] Loaded
2014-06-06T20:15:37.818Z,1402085737.818 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-06-06T20:15:37.869Z,1402085737.869 [DeadReckonUsingCompactModelForecast] Loaded
2014-06-06T20:15:37.869Z,1402085737.869 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-06-06T20:15:37.889Z,1402085737.889 [NavChart] Loaded
2014-06-06T20:15:37.890Z,1402085737.890 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-06-06T20:15:37.894Z,1402085737.894 [UniversalFixResidualReporter] Loaded
2014-06-06T20:15:37.894Z,1402085737.894 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-06-06T20:15:37.894Z,1402085737.894 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-06-06T20:15:37.895Z,1402085737.895 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-06-06T20:15:37.978Z,1402085737.978 [VerticalControl](DEBUG): Construct VerticalControl.
2014-06-06T20:15:38.105Z,1402085738.105 [VerticalControl] Loaded
2014-06-06T20:15:38.105Z,1402085738.105 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-06-06T20:15:38.106Z,1402085738.106 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-06-06T20:15:38.183Z,1402085738.183 [HorizontalControl] Loaded
2014-06-06T20:15:38.183Z,1402085738.183 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-06-06T20:15:38.184Z,1402085738.184 [SpeedControl](DEBUG): Construct SpeedControl.
2014-06-06T20:15:38.185Z,1402085738.185 [SpeedControl] Loaded
2014-06-06T20:15:38.186Z,1402085738.186 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-06-06T20:15:38.186Z,1402085738.186 [LoopControl](DEBUG): Construct LoopControl.
2014-06-06T20:15:38.187Z,1402085738.187 [LoopControl] Loaded
2014-06-06T20:15:38.187Z,1402085738.187 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-06-06T20:15:38.187Z,1402085738.187 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-06-06T20:15:38.188Z,1402085738.188 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-06-06T20:15:38.222Z,1402085738.222 [DepthRateCalculator] Loaded
2014-06-06T20:15:38.222Z,1402085738.222 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-06-06T20:15:38.232Z,1402085738.232 [PitchRateCalculator] Loaded
2014-06-06T20:15:38.232Z,1402085738.232 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-06-06T20:15:38.239Z,1402085738.239 [SpeedCalculator] Loaded
2014-06-06T20:15:38.240Z,1402085738.240 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-06-06T20:15:38.260Z,1402085738.260 [TempGradientCalculator] Loaded
2014-06-06T20:15:38.261Z,1402085738.261 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-06-06T20:15:38.266Z,1402085738.266 [YawRateCalculator] Loaded
2014-06-06T20:15:38.266Z,1402085738.266 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-06-06T20:15:38.267Z,1402085738.267 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-06-06T20:15:38.267Z,1402085738.267 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-06-06T20:15:38.410Z,1402085738.410 [CTD_NeilBrown] Loaded
2014-06-06T20:15:38.410Z,1402085738.410 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-06-06T20:15:38.415Z,1402085738.415 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407574E0
2014-06-06T20:15:38.416Z,1402085738.416 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 866
2014-06-06T20:15:38.430Z,1402085738.430 [PAR_Licor] Loaded
2014-06-06T20:15:38.431Z,1402085738.431 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-06-06T20:15:38.472Z,1402085738.472 [WetLabsBB2FL] Loaded
2014-06-06T20:15:38.472Z,1402085738.472 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-06-06T20:15:38.475Z,1402085738.475 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407874E0
2014-06-06T20:15:38.476Z,1402085738.476 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 867
2014-06-06T20:15:38.476Z,1402085738.476 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-06-06T20:15:38.477Z,1402085738.477 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-06-06T20:15:38.609Z,1402085738.609 [SBIT](DEBUG): Construct Startup Built In Test.
2014-06-06T20:15:38.624Z,1402085738.624 [SBIT] Loaded
2014-06-06T20:15:38.625Z,1402085738.625 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-06-06T20:15:38.625Z,1402085738.625 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-06-06T20:15:38.638Z,1402085738.638 [IBIT] Loaded
2014-06-06T20:15:38.638Z,1402085738.638 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-06-06T20:15:38.644Z,1402085738.644 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-06-06T20:15:38.717Z,1402085738.717 [CBIT] Loaded
2014-06-06T20:15:38.717Z,1402085738.717 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-06-06T20:15:38.717Z,1402085738.717 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-06-06T20:15:38.718Z,1402085738.718 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-06-06T20:15:44.293Z,1402085744.293 [HFRadarModelCalc] Loaded
2014-06-06T20:15:44.294Z,1402085744.294 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2014-06-06T20:15:48.495Z,1402085748.495 [HFRadarCompactModelForecaster] Loaded
2014-06-06T20:15:48.496Z,1402085748.496 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-06-06T20:15:49.650Z,1402085749.650 [HFRCMSpaceInterpolator] Loaded
2014-06-06T20:15:49.650Z,1402085749.650 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-06-06T20:15:49.652Z,1402085749.652 [HFRCMTimeInterpolator] Loaded
2014-06-06T20:15:49.653Z,1402085749.653 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-06-06T20:15:50.826Z,1402085750.826 [HFRCMReconstructedInterpolator] Loaded
2014-06-06T20:15:50.827Z,1402085750.827 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-06-06T20:15:50.839Z,1402085750.839 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-06-06T20:15:50.839Z,1402085750.839 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-06-06T20:15:50.870Z,1402085750.870 [HFRCMVirtualSurfaceDrifter] Loaded
2014-06-06T20:15:50.871Z,1402085750.871 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-06-06T20:15:50.871Z,1402085750.871 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-06-06T20:15:50.872Z,1402085750.872 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-06-06T20:15:51.002Z,1402085751.002 [BuoyancyServo] Loaded
2014-06-06T20:15:51.006Z,1402085751.006 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-06-06T20:15:51.018Z,1402085751.018 [ElevatorServo] Loaded
2014-06-06T20:15:51.019Z,1402085751.019 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-06-06T20:15:51.030Z,1402085751.030 [MassServo] Loaded
2014-06-06T20:15:51.031Z,1402085751.031 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-06-06T20:15:51.042Z,1402085751.042 [RudderServo] Loaded
2014-06-06T20:15:51.043Z,1402085751.043 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-06-06T20:15:51.058Z,1402085751.058 [ThrusterServo] Loaded
2014-06-06T20:15:51.058Z,1402085751.058 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-06-06T20:15:51.059Z,1402085751.059 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-06-06T20:15:51.061Z,1402085751.061 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-06-06T20:15:51.104Z,1402085751.104 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-06-06T20:15:51.106Z,1402085751.106 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-06-06T20:15:51.107Z,1402085751.107 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-06-06T20:15:51.114Z,1402085751.114 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-06-06T20:15:51.115Z,1402085751.115 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0
2014-06-06T20:15:51.115Z,1402085751.115 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 868
2014-06-06T20:15:51.120Z,1402085751.120 [Supervisor](INFO): Main Thread ID is 774
2014-06-06T20:15:51.120Z,1402085751.120 [Supervisor](DEBUG): Running supervisor.
2014-06-06T20:15:51.121Z,1402085751.121 [CommandLine ThreadHandler](INFO): Handler Thread ID is 869
2014-06-06T20:15:51.137Z,1402085751.137 [CommandLine](IMPORTANT): got command quit
2014-06-06T20:15:51.143Z,1402085751.143 [controlThread ThreadHandler](INFO): Handler Thread ID is 870
2014-06-06T20:15:51.144Z,1402085751.144 [controlThread](DEBUG): Initializing ControlThread
2014-06-06T20:15:51.147Z,1402085751.147 [logger ThreadHandler](INFO): Handler Thread ID is 871
2014-06-06T20:15:51.184Z,1402085751.184 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 872
2014-06-06T20:15:51.195Z,1402085751.195 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 873
2014-06-06T20:15:51.196Z,1402085751.196 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-06-06T20:15:51.199Z,1402085751.199 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-06-06T20:15:51.201Z,1402085751.201 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 875
2014-06-06T20:15:51.202Z,1402085751.202 [WetLabsBB2FL](INFO): Powering down
2014-06-06T20:15:51.236Z,1402085751.236 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 876
2014-06-06T20:15:51.245Z,1402085751.245 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-06-06T20:15:51.246Z,1402085751.246 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-06-06T20:15:51.246Z,1402085751.246 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-06-06T20:15:51.247Z,1402085751.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-06-06T20:15:51.247Z,1402085751.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-06-06T20:15:51.247Z,1402085751.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-06-06T20:15:51.248Z,1402085751.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-06-06T20:15:51.248Z,1402085751.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-06-06T20:15:51.248Z,1402085751.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-06-06T20:15:52.147Z,1402085752.147 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:15:52.147Z,1402085752.147 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:52.256Z,1402085752.256 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-06-06T20:15:52.256Z,1402085752.256 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:52.257Z,1402085752.257 [CommandLine](INFO): Join timeout helper Thread ID is 877
2014-06-06T20:15:52.273Z,1402085752.273 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-06-06T20:15:52.273Z,1402085752.273 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:52.273Z,1402085752.273 [NavChartDb](INFO): Join timeout helper Thread ID is 878
2014-06-06T20:15:53.025Z,1402085753.025 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-06-06T20:15:53.057Z,1402085753.057 [InternalSim](DEBUG):  InternalSim initializing...
2014-06-06T20:15:53.656Z,1402085753.656 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-06-06T20:15:53.656Z,1402085753.656 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-06-06T20:15:53.656Z,1402085753.656 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-06-06T20:15:53.657Z,1402085753.657 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-06-06T20:15:53.657Z,1402085753.657 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-06-06T20:15:53.658Z,1402085753.658 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-06-06T20:15:53.658Z,1402085753.658 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-06-06T20:15:53.658Z,1402085753.658 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-06-06T20:15:53.660Z,1402085753.660 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-06-06T20:15:53.661Z,1402085753.661 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-06-06T20:15:53.661Z,1402085753.661 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-06-06T20:15:53.662Z,1402085753.662 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-06-06T20:15:53.662Z,1402085753.662 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-06-06T20:15:53.662Z,1402085753.662 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-06-06T20:15:53.663Z,1402085753.663 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-06-06T20:15:53.663Z,1402085753.663 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-06-06T20:15:53.665Z,1402085753.665 [SBIT](INFO): Initialize SBIT Component.
2014-06-06T20:15:53.665Z,1402085753.665 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11413
2014-06-06T20:15:53.665Z,1402085753.665 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-06-06T20:15:53.666Z,1402085753.666 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-06-06T20:15:53.666Z,1402085753.666 [IBIT](INFO): Initialize IBIT Component.
2014-06-06T20:15:53.675Z,1402085753.675 [CBIT](DEBUG): Initialize CBIT Component.
2014-06-06T20:15:53.676Z,1402085753.676 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2014-06-06T20:15:53.676Z,1402085753.676 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-06-06T20:15:53.717Z,1402085753.717 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-06-06T20:15:54.283Z,1402085754.283 [Radio_Freewave](INFO): Powering up
2014-06-06T20:15:55.094Z,1402085755.094 [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-06-06T20:15:59.059Z,1402085759.059 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:15:59.060Z,1402085759.060 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:59.079Z,1402085759.079 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-06-06T20:15:59.079Z,1402085759.079 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:59.080Z,1402085759.080 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 883
2014-06-06T20:15:59.347Z,1402085759.347 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:15:59.348Z,1402085759.348 [WetLabsBB2FL](INFO): Powering down
2014-06-06T20:15:59.348Z,1402085759.348 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:59.355Z,1402085759.355 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-06-06T20:15:59.356Z,1402085759.356 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:59.356Z,1402085759.356 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 884
2014-06-06T20:15:59.516Z,1402085759.516 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:15:59.516Z,1402085759.516 [CTD_NeilBrown](INFO): Powering down
2014-06-06T20:15:59.517Z,1402085759.517 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:59.536Z,1402085759.536 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-06-06T20:15:59.536Z,1402085759.536 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:15:59.536Z,1402085759.536 [Radio_Freewave](INFO): Join timeout helper Thread ID is 885
2014-06-06T20:15:59.797Z,1402085759.797 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:16:00.036Z,1402085760.036 [Radio_Freewave](INFO): Powering down
2014-06-06T20:16:00.441Z,1402085760.441 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:00.446Z,1402085760.446 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-06-06T20:16:00.446Z,1402085760.446 [logger ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:00.447Z,1402085760.447 [logger](INFO): Join timeout helper Thread ID is 887
2014-06-06T20:16:00.454Z,1402085760.454 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:16:00.454Z,1402085760.454 [logger ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:00.455Z,1402085760.455 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-06-06T20:16:00.456Z,1402085760.456 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:00.456Z,1402085760.456 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-06-06T20:16:00.456Z,1402085760.456 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:00.456Z,1402085760.456 [controlThread](INFO): Join timeout helper Thread ID is 888
2014-06-06T20:16:01.245Z,1402085761.245 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201406061700: published 50 modes in rows, 61 hours in columns
2014-06-06T20:16:01.249Z,1402085761.249 [HFRadarCompactModelForecaster](IMPORTANT): expansionCoefficients_[0][48] = 8.409061 for 2014-06-06T17:00:00.000Z
2014-06-06T20:16:01.250Z,1402085761.250 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-06-06T20:16:01.252Z,1402085761.252 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-06-06T20:16:01.253Z,1402085761.253 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-06-06T20:16:01.254Z,1402085761.254 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-06-06T20:16:01.255Z,1402085761.255 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-06-06T20:16:01.257Z,1402085761.257 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-06-06T20:16:01.282Z,1402085761.282 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-06T20:16:01.295Z,1402085761.295 [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-06-06T20:16:01.296Z,1402085761.296 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-06-06T20:16:01.329Z,1402085761.329 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-06-06T20:16:01.331Z,1402085761.331 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-06-06T20:16:01.335Z,1402085761.335 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-06T20:16:01.343Z,1402085761.343 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-06-06T20:16:01.346Z,1402085761.346 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-06T20:16:01.357Z,1402085761.357 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-06-06T20:16:01.367Z,1402085761.367 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-06-06T20:16:01.373Z,1402085761.373 [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="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS">

        <When>
            <Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

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

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS">
            <Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Sequence/><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium">

        <Sequence/>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

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

<!-- 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="CallIridium">

        <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>

        <Assign><Sequence/><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-06-06T20:16:01.380Z,1402085761.380 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-06-06T20:16:01.381Z,1402085761.381 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-06T20:16:01.381Z,1402085761.381 [controlThread](DEBUG): Uninitializing ControlThread
2014-06-06T20:16:01.381Z,1402085761.381 [AHRS_sp3003D](INFO): Powering down
2014-06-06T20:16:01.382Z,1402085761.382 [DVL_micro](INFO): uninitialize:Powering down
2014-06-06T20:16:01.412Z,1402085761.412 [NAL9602](INFO): Powering down
2014-06-06T20:16:01.437Z,1402085761.437 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-06-06T20:16:01.440Z,1402085761.440 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-06-06T20:16:01.444Z,1402085761.444 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-06-06T20:16:01.445Z,1402085761.445 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-06-06T20:16:01.445Z,1402085761.445 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-06-06T20:16:01.445Z,1402085761.445 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-06-06T20:16:01.446Z,1402085761.446 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-06-06T20:16:01.446Z,1402085761.446 [BuoyancyServo](INFO): Powering down
2014-06-06T20:16:01.487Z,1402085761.487 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-06-06T20:16:01.487Z,1402085761.487 [ElevatorServo](INFO): Powering down
2014-06-06T20:16:01.512Z,1402085761.512 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-06-06T20:16:01.512Z,1402085761.512 [MassServo](INFO): Powering down
2014-06-06T20:16:01.535Z,1402085761.535 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-06-06T20:16:01.535Z,1402085761.535 [RudderServo](INFO): Powering down
2014-06-06T20:16:01.558Z,1402085761.558 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-06-06T20:16:01.558Z,1402085761.558 [ThrusterServo](INFO): Powering down
2014-06-06T20:16:01.580Z,1402085761.580 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-06-06T20:16:01.580Z,1402085761.580 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-06-06T20:16:01.580Z,1402085761.580 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-06-06T20:16:01.612Z,1402085761.612 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:01.633Z,1402085761.633 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:01.704Z,1402085761.704 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:01.710Z,1402085761.710 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:01.762Z,1402085761.762 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-06T20:16:01.797Z,1402085761.797 [logger ThreadHandler](INFO): Thread cancelled.