2014-09-16T00:58:25.878Z,1410829105.878 [Supervisor](DEBUG): Initializing supervisor.
2014-09-16T00:58:25.880Z,1410829105.880 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-16T00:58:25.881Z,1410829105.881 [SyncHandler](INFO): Protected caller Thread ID is 8387
2014-09-16T00:58:25.881Z,1410829105.881 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-16T00:58:25.882Z,1410829105.882 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-16T00:58:25.883Z,1410829105.883 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8388
2014-09-16T00:58:25.886Z,1410829105.886 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-16T00:58:25.908Z,1410829105.908 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-16T00:58:25.909Z,1410829105.909 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-16T00:58:25.909Z,1410829105.909 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8389
2014-09-16T00:58:25.910Z,1410829105.910 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-16T00:58:25.911Z,1410829105.911 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-16T00:58:25.911Z,1410829105.911 [logger ThreadHandler](INFO): Protected caller Thread ID is 8390
2014-09-16T00:58:25.914Z,1410829105.914 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-16T00:58:25.914Z,1410829105.914 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-16T00:58:25.915Z,1410829105.915 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-16T00:58:26.263Z,1410829106.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-16T00:58:26.263Z,1410829106.263 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-16T00:58:26.427Z,1410829106.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-16T00:58:26.428Z,1410829106.428 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-16T00:58:26.540Z,1410829106.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-16T00:58:26.541Z,1410829106.541 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-16T00:58:26.670Z,1410829106.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-16T00:58:26.670Z,1410829106.670 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-16T00:58:26.759Z,1410829106.759 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-16T00:58:26.910Z,1410829106.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-16T00:58:26.910Z,1410829106.910 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-16T00:58:26.999Z,1410829106.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-16T00:58:26.000Z,1410829107.000 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-16T00:58:27.208Z,1410829107.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-16T00:58:27.208Z,1410829107.208 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-16T00:58:27.531Z,1410829107.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-16T00:58:27.531Z,1410829107.531 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-16T00:58:27.847Z,1410829107.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-16T00:58:27.847Z,1410829107.847 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-16T00:58:28.363Z,1410829108.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-16T00:58:28.364Z,1410829108.364 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-16T00:58:28.563Z,1410829108.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-16T00:58:28.563Z,1410829108.563 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-16T00:58:28.673Z,1410829108.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-16T00:58:28.674Z,1410829108.674 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-16T00:58:29.109Z,1410829109.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-16T00:58:29.110Z,1410829109.110 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-16T00:58:29.224Z,1410829109.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-16T00:58:29.227Z,1410829109.227 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-16T00:58:29.227Z,1410829109.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-16T00:58:29.476Z,1410829109.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-16T00:58:29.477Z,1410829109.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-16T00:58:29.609Z,1410829109.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-16T00:58:29.710Z,1410829109.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-16T00:58:29.810Z,1410829109.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-16T00:58:29.910Z,1410829109.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-16T00:58:30.055Z,1410829110.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-16T00:58:30.225Z,1410829110.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-16T00:58:30.329Z,1410829110.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-16T00:58:30.419Z,1410829110.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-16T00:58:30.520Z,1410829110.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-16T00:58:30.620Z,1410829110.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-16T00:58:30.793Z,1410829110.793 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/
2014-09-16T00:58:30.793Z,1410829110.793 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-16T00:58:30.795Z,1410829110.795 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-16T00:58:30.848Z,1410829110.848 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-16T00:58:30.970Z,1410829110.970 [VerticalControl] Loaded
2014-09-16T00:58:30.970Z,1410829110.970 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-16T00:58:30.971Z,1410829110.971 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-16T00:58:31.043Z,1410829111.043 [HorizontalControl] Loaded
2014-09-16T00:58:31.043Z,1410829111.043 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-16T00:58:31.043Z,1410829111.043 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-16T00:58:31.050Z,1410829111.050 [SpeedControl] Loaded
2014-09-16T00:58:31.050Z,1410829111.050 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-16T00:58:31.051Z,1410829111.051 [LoopControl](DEBUG): Construct LoopControl.
2014-09-16T00:58:31.051Z,1410829111.051 [LoopControl] Loaded
2014-09-16T00:58:31.051Z,1410829111.051 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-16T00:58:31.052Z,1410829111.052 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-16T00:58:31.053Z,1410829111.053 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-16T00:58:31.152Z,1410829111.152 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-16T00:58:31.163Z,1410829111.163 [SBIT] Loaded
2014-09-16T00:58:31.163Z,1410829111.163 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-16T00:58:31.164Z,1410829111.164 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-16T00:58:31.194Z,1410829111.194 [IBIT] Loaded
2014-09-16T00:58:31.195Z,1410829111.195 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-16T00:58:31.197Z,1410829111.197 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-16T00:58:31.333Z,1410829111.333 [CBIT] Loaded
2014-09-16T00:58:31.333Z,1410829111.333 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-16T00:58:31.334Z,1410829111.334 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-16T00:58:31.334Z,1410829111.334 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-16T00:58:31.353Z,1410829111.353 [DepthRateCalculator] Loaded
2014-09-16T00:58:31.354Z,1410829111.354 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-16T00:58:31.359Z,1410829111.359 [PitchRateCalculator] Loaded
2014-09-16T00:58:31.360Z,1410829111.360 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-16T00:58:31.371Z,1410829111.371 [SpeedCalculator] Loaded
2014-09-16T00:58:31.372Z,1410829111.372 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-16T00:58:31.388Z,1410829111.388 [TempGradientCalculator] Loaded
2014-09-16T00:58:31.389Z,1410829111.389 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-16T00:58:31.394Z,1410829111.394 [YawRateCalculator] Loaded
2014-09-16T00:58:31.394Z,1410829111.394 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-16T00:58:31.395Z,1410829111.395 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-16T00:58:31.395Z,1410829111.395 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-16T00:58:32.539Z,1410829112.539 [HFRCMReconstructedInterpolator] Loaded
2014-09-16T00:58:32.539Z,1410829112.539 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-16T00:58:32.540Z,1410829112.540 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-16T00:58:32.541Z,1410829112.541 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-16T00:58:32.779Z,1410829112.779 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-16T00:58:32.780Z,1410829112.780 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-16T00:58:32.863Z,1410829112.862 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-09-16T00:58:32.863Z,1410829112.863 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-09-16T00:58:32.918Z,1410829112.918 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-16T00:58:32.919Z,1410829112.919 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-16T00:58:32.964Z,1410829112.964 [DeadReckonWithRespectToWater] Loaded
2014-09-16T00:58:32.965Z,1410829112.965 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-16T00:58:33.012Z,1410829113.012 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-16T00:58:33.012Z,1410829113.012 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-16T00:58:33.074Z,1410829113.074 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-16T00:58:33.074Z,1410829113.074 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-16T00:58:33.091Z,1410829113.091 [NavChart] Loaded
2014-09-16T00:58:33.091Z,1410829113.091 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-16T00:58:33.096Z,1410829113.096 [UniversalFixResidualReporter] Loaded
2014-09-16T00:58:33.096Z,1410829113.096 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-16T00:58:33.096Z,1410829113.096 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-16T00:58:33.097Z,1410829113.097 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-16T00:58:33.103Z,1410829113.103 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-16T00:58:33.103Z,1410829113.103 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-16T00:58:33.285Z,1410829113.285 [Aanderaa_O2] Loaded
2014-09-16T00:58:33.286Z,1410829113.286 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-16T00:58:33.323Z,1410829113.323 [CTD_NeilBrown] Loaded
2014-09-16T00:58:33.323Z,1410829113.323 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-09-16T00:58:33.324Z,1410829113.324 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405DB4E0
2014-09-16T00:58:33.324Z,1410829113.324 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8472
2014-09-16T00:58:33.342Z,1410829113.342 [rhodamine] Loaded
2014-09-16T00:58:33.342Z,1410829113.342 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-16T00:58:33.379Z,1410829113.379 [WetLabsBB2FL] Loaded
2014-09-16T00:58:33.379Z,1410829113.379 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-16T00:58:33.380Z,1410829113.380 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4060B4E0
2014-09-16T00:58:33.381Z,1410829113.381 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8473
2014-09-16T00:58:33.381Z,1410829113.381 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-16T00:58:33.382Z,1410829113.382 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-16T00:58:33.669Z,1410829113.669 [AHRS_sp3003D] Loaded
2014-09-16T00:58:33.670Z,1410829113.670 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-16T00:58:33.743Z,1410829113.743 [DataOverHttps] Loaded
2014-09-16T00:58:33.743Z,1410829113.743 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-16T00:58:33.757Z,1410829113.757 [Depth_Keller] Loaded
2014-09-16T00:58:33.757Z,1410829113.757 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-16T00:58:33.763Z,1410829113.763 [DropWeight] Loaded
2014-09-16T00:58:33.763Z,1410829113.763 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-16T00:58:33.898Z,1410829113.898 [DVL_micro] Loaded
2014-09-16T00:58:33.899Z,1410829113.899 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-16T00:58:33.998Z,1410829113.998 [NAL9602] Loaded
2014-09-16T00:58:33.998Z,1410829113.998 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-16T00:58:34.051Z,1410829114.051 [Onboard] Loaded
2014-09-16T00:58:34.051Z,1410829114.051 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-16T00:58:36.003Z,1410829116.003 [BPC1] Loaded
2014-09-16T00:58:36.004Z,1410829116.004 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-16T00:58:36.004Z,1410829116.004 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-16T00:58:36.005Z,1410829116.005 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-16T00:58:36.110Z,1410829116.110 [BuoyancyServo] Loaded
2014-09-16T00:58:36.110Z,1410829116.110 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-16T00:58:36.123Z,1410829116.123 [ElevatorServo] Loaded
2014-09-16T00:58:36.123Z,1410829116.123 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-16T00:58:36.135Z,1410829116.135 [MassServo] Loaded
2014-09-16T00:58:36.135Z,1410829116.135 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-16T00:58:36.147Z,1410829116.147 [RudderServo] Loaded
2014-09-16T00:58:36.147Z,1410829116.147 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-16T00:58:36.159Z,1410829116.159 [ThrusterServo] Loaded
2014-09-16T00:58:36.160Z,1410829116.160 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-16T00:58:36.160Z,1410829116.160 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-16T00:58:36.161Z,1410829116.161 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-16T00:58:36.397Z,1410829116.397 [ExternalSim] Loaded
2014-09-16T00:58:36.397Z,1410829116.397 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2014-09-16T00:58:36.453Z,1410829116.453 [InternalSim] Loaded
2014-09-16T00:58:36.453Z,1410829116.453 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-16T00:58:36.453Z,1410829116.453 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-16T00:58:36.454Z,1410829116.454 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-16T00:58:36.478Z,1410829116.478 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-16T00:58:36.480Z,1410829116.480 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-16T00:58:36.481Z,1410829116.481 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-16T00:58:36.488Z,1410829116.488 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-16T00:58:36.489Z,1410829116.489 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0
2014-09-16T00:58:36.489Z,1410829116.489 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8474
2014-09-16T00:58:36.494Z,1410829116.494 [Supervisor](INFO): Main Thread ID is 8386
2014-09-16T00:58:36.495Z,1410829116.495 [Supervisor](DEBUG): Running supervisor.
2014-09-16T00:58:36.495Z,1410829116.495 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8475
2014-09-16T00:58:36.498Z,1410829116.498 [controlThread ThreadHandler](INFO): Handler Thread ID is 8476
2014-09-16T00:58:36.498Z,1410829116.498 [controlThread](DEBUG): Initializing ControlThread
2014-09-16T00:58:36.499Z,1410829116.499 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-16T00:58:36.501Z,1410829116.501 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-16T00:58:36.501Z,1410829116.501 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-16T00:58:36.502Z,1410829116.502 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-16T00:58:36.502Z,1410829116.502 [SBIT](INFO): Initialize SBIT Component.
2014-09-16T00:58:36.503Z,1410829116.503 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11616
2014-09-16T00:58:36.503Z,1410829116.503 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-09-16T00:58:36.503Z,1410829116.503 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-16T00:58:36.504Z,1410829116.504 [IBIT](INFO): Initialize IBIT Component.
2014-09-16T00:58:36.505Z,1410829116.505 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-16T00:58:36.505Z,1410829116.505 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-16T00:58:36.506Z,1410829116.506 [logger ThreadHandler](INFO): Handler Thread ID is 8477
2014-09-16T00:58:36.527Z,1410829116.527 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8478
2014-09-16T00:58:36.528Z,1410829116.528 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-09-16T00:58:36.531Z,1410829116.531 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-16T00:58:36.531Z,1410829116.531 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-16T00:58:36.532Z,1410829116.532 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-16T00:58:36.532Z,1410829116.532 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-16T00:58:36.533Z,1410829116.533 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-16T00:58:36.534Z,1410829116.534 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-16T00:58:36.535Z,1410829116.535 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-09-16T00:58:36.536Z,1410829116.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-16T00:58:36.536Z,1410829116.536 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-16T00:58:36.537Z,1410829116.537 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-16T00:58:36.537Z,1410829116.537 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-16T00:58:36.537Z,1410829116.537 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-16T00:58:36.538Z,1410829116.538 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-16T00:58:36.546Z,1410829116.546 [ExternalSim](INFO): ExternalSim initializing...
2014-09-16T00:58:36.551Z,1410829116.551 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-09-16T00:58:36.562Z,1410829116.562 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8480
2014-09-16T00:58:36.563Z,1410829116.563 [WetLabsBB2FL](INFO): Powering down
2014-09-16T00:58:36.600Z,1410829116.600 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8481
2014-09-16T00:58:36.603Z,1410829116.603 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2014-09-16T00:58:36.607Z,1410829116.607 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-16T00:58:36.609Z,1410829116.609 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2014-09-16T00:58:36.611Z,1410829116.611 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2014-09-16T00:58:36.612Z,1410829116.612 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2014-09-16T00:58:36.614Z,1410829116.614 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2014-09-16T00:58:36.616Z,1410829116.616 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2014-09-16T00:58:36.618Z,1410829116.618 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2014-09-16T00:58:36.620Z,1410829116.620 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2014-09-16T00:58:36.622Z,1410829116.622 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2014-09-16T00:58:36.807Z,1410829116.807 [NavChartDb](INFO): Setup scan of Resources/US5CA83M.000
2014-09-16T00:58:56.692Z,1410829136.692 [ExternalSim](ERROR): Error making socket connection to simulation server at tellum.shore.mbari.org: Can not connect to invalid network address: tellum.shore.mbari.org
2014-09-16T00:58:56.731Z,1410829136.731 [ExternalSim](DEBUG): beaconLat = 36.81340
2014-09-16T00:58:56.732Z,1410829136.732 [ExternalSim](DEBUG): beaconLon = -121.82240
2014-09-16T00:58:56.732Z,1410829136.732 [ExternalSim](DEBUG): beaconDepth = 25.00
2014-09-16T00:58:56.735Z,1410829136.735 [ExternalSim](DEBUG): Simulator initialized
2014-09-16T00:58:56.738Z,1410829136.738 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-16T00:58:56.780Z,1410829136.780 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-16T00:58:56.789Z,1410829136.789 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-16T00:58:56.815Z,1410829136.815 [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-09-16T00:58:56.816Z,1410829136.816 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-16T00:58:56.869Z,1410829136.869 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-16T00:58:56.873Z,1410829136.873 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-16T00:58:56.877Z,1410829136.877 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-16T00:58:56.886Z,1410829136.886 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-16T00:58:56.909Z,1410829136.909 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-16T00:58:56.918Z,1410829136.918 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-16T00:58:56.944Z,1410829136.944 [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-09-16T00:58:56.950Z,1410829136.950 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalSim,Aanderaa_O2,rhodamine,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-16T00:58:57.027Z,1410829137.027 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-16T00:58:57.154Z,1410829137.154 [DVL_micro](INFO): Initializing
2014-09-16T00:58:57.161Z,1410829137.161 [NAL9602](INFO): Powering up NAL9602
2014-09-16T00:58:57.303Z,1410829137.303 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:58:57.310Z,1410829137.310 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.317Z,1410829137.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.344Z,1410829137.344 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.351Z,1410829137.351 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.371Z,1410829137.371 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.434Z,1410829137.434 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-16T00:58:57.447Z,1410829137.447 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-16T00:58:57.453Z,1410829137.453 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-16T00:58:57.459Z,1410829137.459 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-16T00:58:57.465Z,1410829137.465 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-16T00:58:57.471Z,1410829137.471 [MassServo](DEBUG): Initializing MassServo.
2014-09-16T00:58:57.477Z,1410829137.477 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-16T00:58:57.483Z,1410829137.483 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-16T00:58:57.489Z,1410829137.489 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-16T00:58:57.495Z,1410829137.495 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-16T00:58:57.662Z,1410829137.662 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:58:57.662Z,1410829137.662 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.663Z,1410829137.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.665Z,1410829137.665 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.665Z,1410829137.665 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.666Z,1410829137.666 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:57.675Z,1410829137.675 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-16T00:58:57.677Z,1410829137.677 [CBIT](IMPORTANT): Beginning GF scan
2014-09-16T00:58:58.066Z,1410829138.066 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:58:58.067Z,1410829138.067 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:58.068Z,1410829138.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:58.069Z,1410829138.069 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:58.070Z,1410829138.070 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:58.071Z,1410829138.071 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T00:58:58.396Z,1410829138.396 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-09-16T00:58:58.397Z,1410829138.397 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-09-16T00:58:58.397Z,1410829138.397 [BuoyancyServo] Communications Fault, FailCount= 1
2014-09-16T00:58:58.397Z,1410829138.397 [BuoyancyServo](ERROR): Communications Fault
2014-09-16T00:58:58.615Z,1410829138.615 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-09-16T00:58:58.973Z,1410829138.973 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:58:58.984Z,1410829138.984 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-16T00:58:58.985Z,1410829138.985 [BuoyancyServo](INFO): Powering down
2014-09-16T00:58:59.421Z,1410829139.421 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:58:59.850Z,1410829139.850 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:59:00.242Z,1410829140.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:59:00.512Z,1410829140.512 [Aanderaa_O2](INFO): Powering down
2014-09-16T00:59:00.888Z,1410829140.888 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:59:01.269Z,1410829141.269 [CommandLine](IMPORTANT): got command quit
2014-09-16T00:59:01.279Z,1410829141.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:59:01.480Z,1410829141.480 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-09-16T00:59:01.480Z,1410829141.480 [BuoyancyServo] No Fault, FailCount= 1
2014-09-16T00:59:01.853Z,1410829141.853 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:59:01.887Z,1410829141.887 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-16T00:59:02.003Z,1410829142.003 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-16T00:59:02.274Z,1410829142.274 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T00:59:02.274Z,1410829142.274 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.282Z,1410829142.282 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-09-16T00:59:02.282Z,1410829142.282 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.283Z,1410829142.283 [CommandLine](INFO): Join timeout helper Thread ID is 8491
2014-09-16T00:59:02.284Z,1410829142.284 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-16T00:59:02.284Z,1410829142.284 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.284Z,1410829142.284 [NavChartDb](INFO): Join timeout helper Thread ID is 8492
2014-09-16T00:59:02.307Z,1410829142.307 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T00:59:02.307Z,1410829142.307 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.331Z,1410829142.331 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-09-16T00:59:02.331Z,1410829142.331 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.331Z,1410829142.331 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8493
2014-09-16T00:59:02.503Z,1410829142.503 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T00:59:02.503Z,1410829142.503 [WetLabsBB2FL](INFO): Powering down
2014-09-16T00:59:02.504Z,1410829142.504 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.523Z,1410829142.523 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-09-16T00:59:02.523Z,1410829142.523 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.523Z,1410829142.523 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8494
2014-09-16T00:59:02.692Z,1410829142.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T00:59:02.787Z,1410829142.787 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T00:59:02.788Z,1410829142.788 [CTD_NeilBrown](INFO): Powering down
2014-09-16T00:59:02.789Z,1410829142.789 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.790Z,1410829142.790 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-16T00:59:02.790Z,1410829142.790 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.790Z,1410829142.790 [logger](INFO): Join timeout helper Thread ID is 8495
2014-09-16T00:59:02.811Z,1410829142.811 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T00:59:02.812Z,1410829142.812 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.832Z,1410829142.832 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-16T00:59:02.832Z,1410829142.832 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.833Z,1410829142.833 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-16T00:59:02.833Z,1410829142.833 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:02.833Z,1410829142.833 [controlThread](INFO): Join timeout helper Thread ID is 8496
2014-09-16T00:59:02.901Z,1410829142.901 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T00:59:02.901Z,1410829142.901 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-16T00:59:02.902Z,1410829142.902 [Aanderaa_O2](INFO): Powering down
2014-09-16T00:59:02.903Z,1410829142.903 [AHRS_sp3003D](INFO): Powering down
2014-09-16T00:59:03.064Z,1410829143.064 [DVL_micro](INFO): uninitialize:Powering down
2014-09-16T00:59:03.075Z,1410829143.075 [NAL9602](INFO): Powering down
2014-09-16T00:59:03.097Z,1410829143.097 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-16T00:59:03.098Z,1410829143.098 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-16T00:59:03.103Z,1410829143.103 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-16T00:59:03.103Z,1410829143.103 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-16T00:59:03.103Z,1410829143.103 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-16T00:59:03.104Z,1410829143.104 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-16T00:59:03.104Z,1410829143.104 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-16T00:59:03.105Z,1410829143.105 [BuoyancyServo](INFO): Powering down
2014-09-16T00:59:03.120Z,1410829143.120 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-16T00:59:03.120Z,1410829143.120 [ElevatorServo](INFO): Powering down
2014-09-16T00:59:03.130Z,1410829143.130 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-16T00:59:03.130Z,1410829143.130 [MassServo](INFO): Powering down
2014-09-16T00:59:03.141Z,1410829143.141 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-16T00:59:03.141Z,1410829143.141 [RudderServo](INFO): Powering down
2014-09-16T00:59:03.151Z,1410829143.151 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-16T00:59:03.151Z,1410829143.151 [ThrusterServo](INFO): Powering down
2014-09-16T00:59:03.152Z,1410829143.152 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-16T00:59:03.153Z,1410829143.153 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-16T00:59:03.153Z,1410829143.153 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-16T00:59:03.185Z,1410829143.185 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:03.240Z,1410829143.240 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:03.244Z,1410829143.244 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:03.840Z,1410829143.840 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-16T00:59:03.877Z,1410829143.877 [logger ThreadHandler](INFO): Thread cancelled.