2014-09-13T00:57:42.119Z,1410569862.119 [Supervisor](DEBUG): Initializing supervisor.
2014-09-13T00:57:42.122Z,1410569862.122 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-13T00:57:42.122Z,1410569862.122 [SyncHandler](INFO): Protected caller Thread ID is 6121
2014-09-13T00:57:42.123Z,1410569862.123 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-13T00:57:42.124Z,1410569862.124 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-13T00:57:42.124Z,1410569862.124 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6122
2014-09-13T00:57:42.127Z,1410569862.127 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-13T00:57:42.147Z,1410569862.147 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-13T00:57:42.148Z,1410569862.148 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-13T00:57:42.149Z,1410569862.149 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6123
2014-09-13T00:57:42.149Z,1410569862.149 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-13T00:57:42.151Z,1410569862.151 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-13T00:57:42.151Z,1410569862.151 [logger ThreadHandler](INFO): Protected caller Thread ID is 6124
2014-09-13T00:57:42.153Z,1410569862.153 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-13T00:57:42.154Z,1410569862.154 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-13T00:57:42.155Z,1410569862.155 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-13T00:57:42.480Z,1410569862.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-13T00:57:42.481Z,1410569862.481 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-13T00:57:42.639Z,1410569862.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-13T00:57:42.640Z,1410569862.640 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-13T00:57:42.753Z,1410569862.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-13T00:57:42.753Z,1410569862.753 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-13T00:57:42.878Z,1410569862.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-13T00:57:42.879Z,1410569862.879 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-13T00:57:42.966Z,1410569862.966 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-13T00:57:43.111Z,1410569863.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-13T00:57:43.112Z,1410569863.112 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-13T00:57:43.199Z,1410569863.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-13T00:57:43.200Z,1410569863.200 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-13T00:57:43.399Z,1410569863.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-13T00:57:43.400Z,1410569863.400 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-13T00:57:43.735Z,1410569863.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-13T00:57:43.736Z,1410569863.736 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-13T00:57:44.033Z,1410569864.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-13T00:57:44.033Z,1410569864.033 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-13T00:57:44.520Z,1410569864.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-13T00:57:44.520Z,1410569864.520 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-13T00:57:44.707Z,1410569864.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-13T00:57:44.708Z,1410569864.708 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-13T00:57:44.814Z,1410569864.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-13T00:57:44.815Z,1410569864.815 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-13T00:57:45.223Z,1410569865.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-13T00:57:45.223Z,1410569865.223 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-13T00:57:45.335Z,1410569865.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-13T00:57:45.337Z,1410569865.337 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-13T00:57:45.338Z,1410569865.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-13T00:57:45.576Z,1410569865.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-13T00:57:45.577Z,1410569865.577 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-13T00:57:45.694Z,1410569865.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-13T00:57:45.793Z,1410569865.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-13T00:57:45.890Z,1410569865.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-13T00:57:45.989Z,1410569865.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-13T00:57:46.128Z,1410569866.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-13T00:57:46.290Z,1410569866.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-13T00:57:46.392Z,1410569866.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-13T00:57:46.480Z,1410569866.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-13T00:57:46.574Z,1410569866.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-13T00:57:46.671Z,1410569866.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-13T00:57:46.862Z,1410569866.862 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/
2014-09-13T00:57:46.862Z,1410569866.862 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-13T00:57:46.864Z,1410569866.864 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-13T00:57:46.914Z,1410569866.914 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-13T00:57:47.031Z,1410569867.031 [VerticalControl] Loaded
2014-09-13T00:57:47.032Z,1410569867.032 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-13T00:57:47.032Z,1410569867.032 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-13T00:57:47.103Z,1410569867.103 [HorizontalControl] Loaded
2014-09-13T00:57:47.103Z,1410569867.103 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-13T00:57:47.103Z,1410569867.103 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-13T00:57:47.109Z,1410569867.109 [SpeedControl] Loaded
2014-09-13T00:57:47.110Z,1410569867.110 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-13T00:57:47.110Z,1410569867.110 [LoopControl](DEBUG): Construct LoopControl.
2014-09-13T00:57:47.111Z,1410569867.111 [LoopControl] Loaded
2014-09-13T00:57:47.111Z,1410569867.111 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-13T00:57:47.112Z,1410569867.112 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-13T00:57:47.112Z,1410569867.112 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-13T00:57:47.207Z,1410569867.207 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-13T00:57:47.218Z,1410569867.218 [SBIT] Loaded
2014-09-13T00:57:47.218Z,1410569867.218 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-13T00:57:47.219Z,1410569867.219 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-13T00:57:47.248Z,1410569867.248 [IBIT] Loaded
2014-09-13T00:57:47.249Z,1410569867.249 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-13T00:57:47.251Z,1410569867.251 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-13T00:57:47.371Z,1410569867.371 [CBIT] Loaded
2014-09-13T00:57:47.371Z,1410569867.371 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-13T00:57:47.372Z,1410569867.372 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-13T00:57:47.372Z,1410569867.372 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-13T00:57:47.391Z,1410569867.391 [DepthRateCalculator] Loaded
2014-09-13T00:57:47.391Z,1410569867.391 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-13T00:57:47.396Z,1410569867.396 [PitchRateCalculator] Loaded
2014-09-13T00:57:47.397Z,1410569867.397 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-13T00:57:47.408Z,1410569867.408 [SpeedCalculator] Loaded
2014-09-13T00:57:47.408Z,1410569867.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-13T00:57:47.424Z,1410569867.424 [TempGradientCalculator] Loaded
2014-09-13T00:57:47.425Z,1410569867.425 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-13T00:57:47.430Z,1410569867.430 [YawRateCalculator] Loaded
2014-09-13T00:57:47.430Z,1410569867.430 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-13T00:57:47.431Z,1410569867.431 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-13T00:57:47.431Z,1410569867.431 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-13T00:57:48.567Z,1410569868.567 [HFRCMReconstructedInterpolator] Loaded
2014-09-13T00:57:48.567Z,1410569868.567 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-13T00:57:48.568Z,1410569868.568 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-13T00:57:48.569Z,1410569868.569 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-13T00:57:48.794Z,1410569868.794 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-13T00:57:48.794Z,1410569868.794 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-13T00:57:48.874Z,1410569868.874 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-09-13T00:57:48.874Z,1410569868.874 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-09-13T00:57:48.928Z,1410569868.928 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-13T00:57:48.928Z,1410569868.928 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-13T00:57:48.973Z,1410569868.973 [DeadReckonWithRespectToWater] Loaded
2014-09-13T00:57:48.973Z,1410569868.973 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-13T00:57:49.018Z,1410569869.018 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-13T00:57:49.018Z,1410569869.018 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-13T00:57:49.078Z,1410569869.078 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-13T00:57:49.078Z,1410569869.078 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-13T00:57:49.095Z,1410569869.095 [NavChart] Loaded
2014-09-13T00:57:49.095Z,1410569869.095 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-13T00:57:49.100Z,1410569869.100 [UniversalFixResidualReporter] Loaded
2014-09-13T00:57:49.100Z,1410569869.100 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-13T00:57:49.100Z,1410569869.100 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-13T00:57:49.101Z,1410569869.101 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-13T00:57:49.107Z,1410569869.107 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-13T00:57:49.107Z,1410569869.107 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-13T00:57:49.276Z,1410569869.276 [Aanderaa_O2] Loaded
2014-09-13T00:57:49.277Z,1410569869.277 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-13T00:57:49.333Z,1410569869.333 [CTD_NeilBrown] Loaded
2014-09-13T00:57:49.333Z,1410569869.333 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-09-13T00:57:49.334Z,1410569869.334 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405DB4E0
2014-09-13T00:57:49.335Z,1410569869.335 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6206
2014-09-13T00:57:49.355Z,1410569869.355 [rhodamine] Loaded
2014-09-13T00:57:49.355Z,1410569869.355 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-13T00:57:49.390Z,1410569869.390 [WetLabsBB2FL] Loaded
2014-09-13T00:57:49.391Z,1410569869.391 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-13T00:57:49.392Z,1410569869.392 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4060B4E0
2014-09-13T00:57:49.392Z,1410569869.392 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6207
2014-09-13T00:57:49.393Z,1410569869.393 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-13T00:57:49.393Z,1410569869.393 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-13T00:57:49.679Z,1410569869.679 [AHRS_sp3003D] Loaded
2014-09-13T00:57:49.679Z,1410569869.679 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-13T00:57:49.750Z,1410569869.750 [DataOverHttps] Loaded
2014-09-13T00:57:49.751Z,1410569869.751 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-13T00:57:49.764Z,1410569869.764 [Depth_Keller] Loaded
2014-09-13T00:57:49.765Z,1410569869.765 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-13T00:57:49.770Z,1410569869.770 [DropWeight] Loaded
2014-09-13T00:57:49.770Z,1410569869.770 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-13T00:57:49.900Z,1410569869.900 [DVL_micro] Loaded
2014-09-13T00:57:49.900Z,1410569869.900 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-13T00:57:49.995Z,1410569869.995 [NAL9602] Loaded
2014-09-13T00:57:49.995Z,1410569869.995 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-13T00:57:50.037Z,1410569870.037 [Onboard] Loaded
2014-09-13T00:57:50.037Z,1410569870.037 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-13T00:57:51.921Z,1410569871.921 [BPC1] Loaded
2014-09-13T00:57:51.921Z,1410569871.921 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-13T00:57:51.922Z,1410569871.922 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-13T00:57:51.922Z,1410569871.922 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-13T00:57:52.036Z,1410569872.036 [BuoyancyServo] Loaded
2014-09-13T00:57:52.037Z,1410569872.037 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-13T00:57:52.049Z,1410569872.049 [ElevatorServo] Loaded
2014-09-13T00:57:52.050Z,1410569872.050 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-13T00:57:52.061Z,1410569872.061 [MassServo] Loaded
2014-09-13T00:57:52.061Z,1410569872.061 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-13T00:57:52.073Z,1410569872.073 [RudderServo] Loaded
2014-09-13T00:57:52.074Z,1410569872.074 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-13T00:57:52.085Z,1410569872.085 [ThrusterServo] Loaded
2014-09-13T00:57:52.086Z,1410569872.086 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-13T00:57:52.086Z,1410569872.086 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-13T00:57:52.087Z,1410569872.087 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-13T00:57:52.314Z,1410569872.314 [ExternalSim] Loaded
2014-09-13T00:57:52.314Z,1410569872.314 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2014-09-13T00:57:52.368Z,1410569872.368 [InternalSim] Loaded
2014-09-13T00:57:52.368Z,1410569872.368 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-13T00:57:52.369Z,1410569872.369 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-13T00:57:52.369Z,1410569872.369 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-13T00:57:52.393Z,1410569872.393 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-13T00:57:52.395Z,1410569872.395 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-13T00:57:52.396Z,1410569872.396 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-13T00:57:52.402Z,1410569872.402 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-13T00:57:52.403Z,1410569872.403 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0
2014-09-13T00:57:52.404Z,1410569872.404 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6208
2014-09-13T00:57:52.409Z,1410569872.409 [Supervisor](INFO): Main Thread ID is 6120
2014-09-13T00:57:52.409Z,1410569872.409 [Supervisor](DEBUG): Running supervisor.
2014-09-13T00:57:52.409Z,1410569872.409 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6209
2014-09-13T00:57:52.412Z,1410569872.412 [controlThread ThreadHandler](INFO): Handler Thread ID is 6210
2014-09-13T00:57:52.412Z,1410569872.412 [controlThread](DEBUG): Initializing ControlThread
2014-09-13T00:57:52.413Z,1410569872.413 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-13T00:57:52.415Z,1410569872.415 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-13T00:57:52.416Z,1410569872.416 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-13T00:57:52.416Z,1410569872.416 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-13T00:57:52.417Z,1410569872.417 [SBIT](INFO): Initialize SBIT Component.
2014-09-13T00:57:52.417Z,1410569872.417 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11616
2014-09-13T00:57:52.417Z,1410569872.417 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-09-13T00:57:52.418Z,1410569872.418 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-13T00:57:52.418Z,1410569872.418 [IBIT](INFO): Initialize IBIT Component.
2014-09-13T00:57:52.419Z,1410569872.419 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-13T00:57:52.419Z,1410569872.419 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-13T00:57:52.420Z,1410569872.420 [logger ThreadHandler](INFO): Handler Thread ID is 6211
2014-09-13T00:57:52.438Z,1410569872.438 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6212
2014-09-13T00:57:52.439Z,1410569872.439 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-09-13T00:57:52.442Z,1410569872.442 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-13T00:57:52.442Z,1410569872.442 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-13T00:57:52.443Z,1410569872.443 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-13T00:57:52.443Z,1410569872.443 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-13T00:57:52.444Z,1410569872.444 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-13T00:57:52.445Z,1410569872.445 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-13T00:57:52.446Z,1410569872.446 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-09-13T00:57:52.447Z,1410569872.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-13T00:57:52.447Z,1410569872.447 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-13T00:57:52.448Z,1410569872.448 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-13T00:57:52.448Z,1410569872.448 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-13T00:57:52.448Z,1410569872.448 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-13T00:57:52.449Z,1410569872.449 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-13T00:57:52.457Z,1410569872.457 [ExternalSim](INFO): ExternalSim initializing...
2014-09-13T00:57:52.462Z,1410569872.462 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-09-13T00:57:52.476Z,1410569872.476 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6214
2014-09-13T00:57:52.478Z,1410569872.478 [WetLabsBB2FL](INFO): Powering down
2014-09-13T00:57:52.511Z,1410569872.511 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6215
2014-09-13T00:57:52.514Z,1410569872.514 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2014-09-13T00:58:02.732Z,1410569882.732 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-13T00:58:02.736Z,1410569882.736 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2014-09-13T00:58:02.737Z,1410569882.737 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2014-09-13T00:58:02.739Z,1410569882.739 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2014-09-13T00:58:02.741Z,1410569882.741 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2014-09-13T00:58:02.743Z,1410569882.743 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2014-09-13T00:58:02.744Z,1410569882.744 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2014-09-13T00:58:02.746Z,1410569882.746 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2014-09-13T00:58:02.748Z,1410569882.748 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2014-09-13T00:58:02.934Z,1410569882.934 [NavChartDb](INFO): Setup scan of Resources/US5CA83M.000
2014-09-13T00:58:12.587Z,1410569892.587 [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-13T00:58:12.626Z,1410569892.626 [ExternalSim](DEBUG): beaconLat = 36.81340
2014-09-13T00:58:12.626Z,1410569892.626 [ExternalSim](DEBUG): beaconLon = -121.82240
2014-09-13T00:58:12.626Z,1410569892.626 [ExternalSim](DEBUG): beaconDepth = 25.00
2014-09-13T00:58:12.629Z,1410569892.629 [ExternalSim](DEBUG): Simulator initialized
2014-09-13T00:58:12.632Z,1410569892.632 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-13T00:58:12.668Z,1410569892.668 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-13T00:58:12.699Z,1410569892.699 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-13T00:58:12.708Z,1410569892.708 [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-13T00:58:12.710Z,1410569892.710 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-13T00:58:12.765Z,1410569892.765 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-13T00:58:12.768Z,1410569892.768 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-13T00:58:12.790Z,1410569892.790 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-13T00:58:12.799Z,1410569892.799 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-13T00:58:12.806Z,1410569892.806 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-13T00:58:12.814Z,1410569892.814 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-13T00:58:12.844Z,1410569892.844 [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-13T00:58:12.852Z,1410569892.852 [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-13T00:58:12.926Z,1410569892.926 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-13T00:58:13.066Z,1410569893.066 [DVL_micro](INFO): Initializing
2014-09-13T00:58:13.074Z,1410569893.074 [NAL9602](INFO): Powering up NAL9602
2014-09-13T00:58:13.211Z,1410569893.211 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:13.216Z,1410569893.216 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.224Z,1410569893.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.251Z,1410569893.251 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.274Z,1410569893.274 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.281Z,1410569893.281 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.359Z,1410569893.359 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:13.367Z,1410569893.367 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-13T00:58:13.373Z,1410569893.373 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:13.379Z,1410569893.379 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-13T00:58:13.385Z,1410569893.385 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:13.391Z,1410569893.391 [MassServo](DEBUG): Initializing MassServo.
2014-09-13T00:58:13.396Z,1410569893.396 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:13.411Z,1410569893.411 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-13T00:58:13.416Z,1410569893.416 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:13.423Z,1410569893.423 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-13T00:58:13.574Z,1410569893.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:13.574Z,1410569893.574 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.575Z,1410569893.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.576Z,1410569893.576 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.577Z,1410569893.577 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.578Z,1410569893.578 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:13.587Z,1410569893.587 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-13T00:58:13.589Z,1410569893.589 [CBIT](IMPORTANT): Beginning GF scan
2014-09-13T00:58:14.004Z,1410569894.004 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:14.005Z,1410569894.005 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:14.006Z,1410569894.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:14.007Z,1410569894.007 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:14.008Z,1410569894.008 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:14.009Z,1410569894.009 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:58:14.736Z,1410569894.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:15.137Z,1410569895.137 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:15.553Z,1410569895.553 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:15.958Z,1410569895.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:16.448Z,1410569896.448 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:16.913Z,1410569896.913 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:17.520Z,1410569897.520 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:18.307Z,1410569898.307 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:18.979Z,1410569898.979 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:19.608Z,1410569899.608 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:20.275Z,1410569900.275 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:20.849Z,1410569900.849 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:21.284Z,1410569901.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:21.739Z,1410569901.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:21.912Z,1410569901.912 [CommandLine](IMPORTANT): got command report touch DVL_micro.platform_orientation
2014-09-13T00:58:22.168Z,1410569902.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:22.597Z,1410569902.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:23.072Z,1410569903.072 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:23.150Z,1410569903.150 [Reporter](INFO): DVL_micro.platform_orientation 6.400000 arcdeg
2014-09-13T00:58:23.165Z,1410569903.165 [Aanderaa_O2](FAULT): Timed out starting
2014-09-13T00:58:23.165Z,1410569903.165 [Aanderaa_O2] Communications Fault, FailCount= 1
2014-09-13T00:58:23.165Z,1410569903.165 [Aanderaa_O2](ERROR): Communications Fault
2014-09-13T00:58:23.514Z,1410569903.514 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:23.570Z,1410569903.570 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2014-09-13T00:58:23.707Z,1410569903.707 [Aanderaa_O2](INFO): Powering down
2014-09-13T00:58:24.099Z,1410569904.099 [NAL9602](INFO): NAL9602 initialized
2014-09-13T00:58:24.114Z,1410569904.114 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:24.635Z,1410569904.635 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:25.059Z,1410569905.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:25.075Z,1410569905.075 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2014-09-13T00:58:25.075Z,1410569905.075 [Aanderaa_O2] No Fault, FailCount= 1
2014-09-13T00:58:25.459Z,1410569905.459 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:25.925Z,1410569905.925 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:26.330Z,1410569906.330 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:26.346Z,1410569906.346 [Reporter](INFO): DVL_micro.platform_orientation 6.600000 arcdeg
2014-09-13T00:58:26.765Z,1410569906.765 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:27.138Z,1410569907.138 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:27.584Z,1410569907.584 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:28.013Z,1410569908.013 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:28.417Z,1410569908.417 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:28.839Z,1410569908.839 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:29.736Z,1410569909.736 [CommandLine](IMPORTANT): got command report clear
2014-09-13T00:58:29.789Z,1410569909.789 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:30.286Z,1410569910.286 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:30.773Z,1410569910.773 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:31.244Z,1410569911.244 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:31.746Z,1410569911.746 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:32.237Z,1410569912.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:32.699Z,1410569912.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:33.218Z,1410569913.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:33.720Z,1410569913.720 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:34.216Z,1410569914.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:34.772Z,1410569914.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:34.816Z,1410569914.816 [ElevatorServo](FAULT): Overload Error
2014-09-13T00:58:34.816Z,1410569914.816 [ElevatorServo] Hardware Fault, FailCount= 1
2014-09-13T00:58:34.816Z,1410569914.816 [ElevatorServo](ERROR): Hardware Fault
2014-09-13T00:58:34.916Z,1410569914.916 [RudderServo](FAULT): Overload Error
2014-09-13T00:58:34.916Z,1410569914.916 [RudderServo] Hardware Fault, FailCount= 1
2014-09-13T00:58:34.916Z,1410569914.916 [RudderServo](ERROR): Hardware Fault
2014-09-13T00:58:34.955Z,1410569914.955 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2014-09-13T00:58:34.956Z,1410569914.956 [CBIT](ERROR): Hardware Fault in component: RudderServo
2014-09-13T00:58:35.333Z,1410569915.333 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:35.346Z,1410569915.346 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-13T00:58:35.346Z,1410569915.346 [ElevatorServo](INFO): Powering down
2014-09-13T00:58:35.390Z,1410569915.390 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-13T00:58:35.390Z,1410569915.390 [RudderServo](INFO): Powering down
2014-09-13T00:58:35.418Z,1410569915.418 [Aanderaa_O2](FAULT): Timed out starting
2014-09-13T00:58:35.418Z,1410569915.418 [Aanderaa_O2] Communications Fault, FailCount= 2
2014-09-13T00:58:35.418Z,1410569915.418 [Aanderaa_O2](ERROR): Communications Fault
2014-09-13T00:58:35.767Z,1410569915.767 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:35.825Z,1410569915.825 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2014-09-13T00:58:35.958Z,1410569915.958 [Aanderaa_O2](INFO): Powering down
2014-09-13T00:58:36.353Z,1410569916.353 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:36.374Z,1410569916.374 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:36.495Z,1410569916.495 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-13T00:58:36.536Z,1410569916.536 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:36.657Z,1410569916.657 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-13T00:58:36.661Z,1410569916.661 [CBIT](INFO): Clearing failed state for component ElevatorServo
2014-09-13T00:58:36.661Z,1410569916.661 [ElevatorServo] No Fault, FailCount= 1
2014-09-13T00:58:36.661Z,1410569916.661 [CBIT](INFO): Clearing failed state for component RudderServo
2014-09-13T00:58:36.661Z,1410569916.661 [RudderServo] No Fault, FailCount= 1
2014-09-13T00:58:37.046Z,1410569917.046 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:37.472Z,1410569917.472 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2014-09-13T00:58:37.472Z,1410569917.472 [RudderServo](FAULT): Rudder failed to initialize
2014-09-13T00:58:37.472Z,1410569917.472 [RudderServo] Communications Fault, FailCount= 2
2014-09-13T00:58:37.472Z,1410569917.472 [RudderServo](ERROR): Communications Fault
2014-09-13T00:58:37.476Z,1410569917.476 [CBIT](ERROR): Communications Fault in component: RudderServo
2014-09-13T00:58:37.848Z,1410569917.848 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:37.921Z,1410569917.921 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-13T00:58:37.922Z,1410569917.922 [RudderServo](INFO): Powering down
2014-09-13T00:58:37.925Z,1410569917.925 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2014-09-13T00:58:37.925Z,1410569917.925 [Aanderaa_O2] No Fault, FailCount= 2
2014-09-13T00:58:38.296Z,1410569918.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:38.727Z,1410569918.727 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:38.811Z,1410569918.811 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:38.931Z,1410569918.931 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-13T00:58:38.934Z,1410569918.934 [CBIT](INFO): Clearing failed state for component RudderServo
2014-09-13T00:58:38.935Z,1410569918.935 [RudderServo] No Fault, FailCount= 2
2014-09-13T00:58:39.279Z,1410569919.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:39.730Z,1410569919.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:40.305Z,1410569920.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:40.836Z,1410569920.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:41.034Z,1410569921.034 [CBIT](IMPORTANT): No ground fault detected
2014-09-13T00:58:41.438Z,1410569921.438 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:41.473Z,1410569921.473 [ElevatorServo](FAULT): Overload Error
2014-09-13T00:58:41.473Z,1410569921.473 [ElevatorServo] Hardware Fault, FailCount= 2
2014-09-13T00:58:41.473Z,1410569921.473 [ElevatorServo](ERROR): Hardware Fault
2014-09-13T00:58:41.541Z,1410569921.541 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2014-09-13T00:58:41.926Z,1410569921.926 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:41.938Z,1410569921.938 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-13T00:58:41.938Z,1410569921.938 [ElevatorServo](INFO): Powering down
2014-09-13T00:58:42.322Z,1410569922.322 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:42.775Z,1410569922.775 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:42.788Z,1410569922.788 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:42.905Z,1410569922.905 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-13T00:58:42.936Z,1410569922.936 [CBIT](INFO): Clearing failed state for component ElevatorServo
2014-09-13T00:58:42.936Z,1410569922.936 [ElevatorServo] No Fault, FailCount= 2
2014-09-13T00:58:43.329Z,1410569923.329 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:43.799Z,1410569923.799 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:43.886Z,1410569923.886 [RudderServo](FAULT): Overload Error
2014-09-13T00:58:43.886Z,1410569923.886 [RudderServo] Hardware Fault, FailCount= 3
2014-09-13T00:58:43.886Z,1410569923.886 [RudderServo](ERROR): Hardware Fault
2014-09-13T00:58:43.926Z,1410569923.926 [CBIT](ERROR): Hardware Fault in component: RudderServo
2014-09-13T00:58:43.926Z,1410569923.926 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2014-09-13T00:58:44.309Z,1410569924.309 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:44.340Z,1410569924.340 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-13T00:58:44.340Z,1410569924.340 [RudderServo](INFO): Powering down
2014-09-13T00:58:44.343Z,1410569924.343 [CommandLine](FAULT): Scheduling is paused
2014-09-13T00:58:44.706Z,1410569924.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:45.104Z,1410569925.104 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:45.136Z,1410569925.136 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:45.257Z,1410569925.257 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-13T00:58:45.628Z,1410569925.628 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:46.106Z,1410569926.106 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:46.561Z,1410569926.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:47.060Z,1410569927.060 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:47.507Z,1410569927.507 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:47.961Z,1410569927.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:48.018Z,1410569928.018 [ElevatorServo](FAULT): Overload Error
2014-09-13T00:58:48.019Z,1410569928.019 [ElevatorServo] Hardware Fault, FailCount= 3
2014-09-13T00:58:48.019Z,1410569928.019 [ElevatorServo](ERROR): Hardware Fault
2014-09-13T00:58:48.060Z,1410569928.060 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2014-09-13T00:58:48.061Z,1410569928.061 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2014-09-13T00:58:48.099Z,1410569928.099 [Aanderaa_O2](FAULT): Timed out starting
2014-09-13T00:58:48.099Z,1410569928.099 [Aanderaa_O2] Communications Fault, FailCount= 3
2014-09-13T00:58:48.099Z,1410569928.099 [Aanderaa_O2](ERROR): Communications Fault
2014-09-13T00:58:48.432Z,1410569928.432 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:48.444Z,1410569928.444 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-13T00:58:48.444Z,1410569928.444 [ElevatorServo](INFO): Powering down
2014-09-13T00:58:48.448Z,1410569928.448 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2014-09-13T00:58:48.449Z,1410569928.449 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2014-09-13T00:58:48.622Z,1410569928.622 [Aanderaa_O2](INFO): Powering down
2014-09-13T00:58:48.959Z,1410569928.959 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:49.350Z,1410569929.350 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:49.362Z,1410569929.362 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:58:49.479Z,1410569929.479 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-13T00:58:49.914Z,1410569929.914 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:50.323Z,1410569930.323 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:50.714Z,1410569930.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:51.106Z,1410569931.106 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:51.511Z,1410569931.511 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:51.907Z,1410569931.907 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:52.301Z,1410569932.301 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:52.727Z,1410569932.727 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:53.180Z,1410569933.180 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:53.603Z,1410569933.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:53.970Z,1410569933.970 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:54.410Z,1410569934.410 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:54.805Z,1410569934.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:55.235Z,1410569935.235 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:55.682Z,1410569935.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:56.521Z,1410569936.521 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:56.973Z,1410569936.973 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:57.413Z,1410569937.413 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:57.858Z,1410569937.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:58.269Z,1410569938.269 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:58.696Z,1410569938.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:59.131Z,1410569939.131 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:58:59.597Z,1410569939.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:00.030Z,1410569940.030 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:00.466Z,1410569940.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:00.878Z,1410569940.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:01.290Z,1410569941.290 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:01.724Z,1410569941.724 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:02.151Z,1410569942.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:02.702Z,1410569942.702 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:03.087Z,1410569943.087 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:03.494Z,1410569943.494 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:03.896Z,1410569943.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:04.049Z,1410569944.049 [CommandLine](IMPORTANT): got command failComponent
2014-09-13T00:59:04.050Z,1410569944.050 [CommandLine](IMPORTANT): Failed components:
2014-09-13T00:59:04.050Z,1410569944.050 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2014-09-13T00:59:04.050Z,1410569944.050 [CommandLine](IMPORTANT): ElevatorServo: Hardware Fault
2014-09-13T00:59:04.051Z,1410569944.051 [CommandLine](IMPORTANT): RudderServo: Hardware Fault
2014-09-13T00:59:04.295Z,1410569944.295 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:04.713Z,1410569944.713 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:05.114Z,1410569945.114 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:05.515Z,1410569945.515 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:05.922Z,1410569945.922 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:06.348Z,1410569946.348 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:06.751Z,1410569946.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:07.155Z,1410569947.155 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:07.167Z,1410569947.167 [SBIT](IMPORTANT): SBIT PASSED
2014-09-13T00:59:07.574Z,1410569947.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:07.580Z,1410569947.580 [MissionManager](IMPORTANT): Started mission Startup
2014-09-13T00:59:07.580Z,1410569947.580 [Startup] Running Loop=1
2014-09-13T00:59:07.581Z,1410569947.581 [Startup](INFO): Aggregate::initialize Startup
2014-09-13T00:59:07.581Z,1410569947.581 [Startup:A.GoToSurface] Running Loop=1
2014-09-13T00:59:07.581Z,1410569947.581 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-13T00:59:07.581Z,1410569947.581 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-13T00:59:07.582Z,1410569947.582 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-13T00:59:07.582Z,1410569947.582 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-13T00:59:07.618Z,1410569947.618 [Startup:StartupSatComms] Running Loop=1
2014-09-13T00:59:07.619Z,1410569947.619 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-09-13T00:59:07.619Z,1410569947.619 [Startup:StartupSatComms:A] Running Loop=1
2014-09-13T00:59:08.019Z,1410569948.019 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:08.026Z,1410569948.026 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-13T00:59:08.435Z,1410569948.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:08.809Z,1410569948.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:09.232Z,1410569949.232 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:09.679Z,1410569949.679 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:10.062Z,1410569950.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:10.519Z,1410569950.519 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:10.917Z,1410569950.917 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:11.351Z,1410569951.351 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:11.714Z,1410569951.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:12.152Z,1410569952.152 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:12.590Z,1410569952.590 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:12.967Z,1410569952.967 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:13.364Z,1410569953.364 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:13.774Z,1410569953.774 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:14.198Z,1410569954.198 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:14.600Z,1410569954.600 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:14.970Z,1410569954.970 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:15.364Z,1410569955.364 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:15.782Z,1410569955.782 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:16.195Z,1410569956.195 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:16.624Z,1410569956.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:17.021Z,1410569957.021 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:17.423Z,1410569957.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:17.819Z,1410569957.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:18.216Z,1410569958.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:18.618Z,1410569958.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:19.037Z,1410569959.037 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:19.447Z,1410569959.447 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:19.851Z,1410569959.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:20.269Z,1410569960.269 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:20.639Z,1410569960.639 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:21.036Z,1410569961.036 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:21.176Z,1410569961.176 [CommandLine](IMPORTANT): got command failComponent
2014-09-13T00:59:21.176Z,1410569961.176 [CommandLine](IMPORTANT): Failed components:
2014-09-13T00:59:21.177Z,1410569961.177 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2014-09-13T00:59:21.177Z,1410569961.177 [CommandLine](IMPORTANT): ElevatorServo: Hardware Fault
2014-09-13T00:59:21.177Z,1410569961.177 [CommandLine](IMPORTANT): RudderServo: Hardware Fault
2014-09-13T00:59:21.445Z,1410569961.445 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:21.850Z,1410569961.850 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:22.284Z,1410569962.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:22.701Z,1410569962.701 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:23.102Z,1410569963.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:23.494Z,1410569963.494 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:23.931Z,1410569963.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:24.332Z,1410569964.332 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:24.726Z,1410569964.726 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:25.158Z,1410569965.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:25.601Z,1410569965.601 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:26.026Z,1410569966.026 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:26.444Z,1410569966.444 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:26.830Z,1410569966.830 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:27.235Z,1410569967.235 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:27.636Z,1410569967.636 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:28.035Z,1410569968.035 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:28.458Z,1410569968.458 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:28.891Z,1410569968.891 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:29.298Z,1410569969.298 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:29.691Z,1410569969.691 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:30.088Z,1410569970.088 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:30.492Z,1410569970.492 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:30.889Z,1410569970.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:31.290Z,1410569971.290 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:31.706Z,1410569971.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:32.166Z,1410569972.166 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:32.569Z,1410569972.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:32.951Z,1410569972.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:33.347Z,1410569973.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:33.749Z,1410569973.749 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:34.144Z,1410569974.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:34.564Z,1410569974.564 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:34.985Z,1410569974.985 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:35.421Z,1410569975.421 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:35.805Z,1410569975.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:36.197Z,1410569976.197 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:36.602Z,1410569976.602 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:37.005Z,1410569977.005 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:37.428Z,1410569977.428 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:37.828Z,1410569977.828 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:38.270Z,1410569978.270 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:38.700Z,1410569978.700 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:39.098Z,1410569979.098 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:39.536Z,1410569979.536 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:39.911Z,1410569979.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:40.347Z,1410569980.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:40.739Z,1410569980.739 [CommandLine](IMPORTANT): got command quit
2014-09-13T00:59:40.792Z,1410569980.792 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:41.198Z,1410569981.198 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:41.599Z,1410569981.599 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:41.743Z,1410569981.743 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:59:41.743Z,1410569981.743 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:41.776Z,1410569981.776 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-09-13T00:59:41.776Z,1410569981.776 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:41.777Z,1410569981.777 [CommandLine](INFO): Join timeout helper Thread ID is 6236
2014-09-13T00:59:41.777Z,1410569981.777 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-13T00:59:41.777Z,1410569981.777 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:41.778Z,1410569981.778 [NavChartDb](INFO): Join timeout helper Thread ID is 6237
2014-09-13T00:59:41.799Z,1410569981.799 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:59:41.800Z,1410569981.800 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:41.816Z,1410569981.816 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-09-13T00:59:41.816Z,1410569981.816 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:41.816Z,1410569981.816 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6238
2014-09-13T00:59:42.010Z,1410569982.010 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:42.201Z,1410569982.201 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:59:42.201Z,1410569982.201 [WetLabsBB2FL](INFO): Powering down
2014-09-13T00:59:42.202Z,1410569982.202 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.217Z,1410569982.217 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-09-13T00:59:42.217Z,1410569982.217 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.217Z,1410569982.217 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6239
2014-09-13T00:59:42.221Z,1410569982.221 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:59:42.221Z,1410569982.221 [CTD_NeilBrown](INFO): Powering down
2014-09-13T00:59:42.222Z,1410569982.222 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.237Z,1410569982.237 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-13T00:59:42.237Z,1410569982.237 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.237Z,1410569982.237 [logger](INFO): Join timeout helper Thread ID is 6240
2014-09-13T00:59:42.241Z,1410569982.241 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:59:42.242Z,1410569982.242 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.258Z,1410569982.258 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-13T00:59:42.258Z,1410569982.258 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.258Z,1410569982.258 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-13T00:59:42.258Z,1410569982.258 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.258Z,1410569982.258 [controlThread](INFO): Join timeout helper Thread ID is 6241
2014-09-13T00:59:42.396Z,1410569982.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:59:42.413Z,1410569982.413 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:59:42.413Z,1410569982.413 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-13T00:59:42.414Z,1410569982.414 [Aanderaa_O2](INFO): Powering down
2014-09-13T00:59:42.415Z,1410569982.415 [AHRS_sp3003D](INFO): Powering down
2014-09-13T00:59:42.574Z,1410569982.574 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:59:42.575Z,1410569982.575 [NAL9602](INFO): Powering down
2014-09-13T00:59:42.576Z,1410569982.576 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-13T00:59:42.579Z,1410569982.579 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-13T00:59:42.582Z,1410569982.582 [Startup] Stopped
2014-09-13T00:59:42.582Z,1410569982.582 [Startup](INFO): Aggregate::uninitialize Startup
2014-09-13T00:59:42.582Z,1410569982.582 [Startup:A.GoToSurface] Stopped
2014-09-13T00:59:42.582Z,1410569982.582 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-09-13T00:59:42.582Z,1410569982.582 [Startup:StartupSatComms] Stopped
2014-09-13T00:59:42.582Z,1410569982.582 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-09-13T00:59:42.582Z,1410569982.582 [Startup:StartupSatComms:A] Stopped
2014-09-13T00:59:42.583Z,1410569982.583 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-13T00:59:42.584Z,1410569982.584 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-13T00:59:42.584Z,1410569982.584 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-13T00:59:42.584Z,1410569982.584 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-13T00:59:42.585Z,1410569982.585 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-13T00:59:42.585Z,1410569982.585 [BuoyancyServo](INFO): Powering down
2014-09-13T00:59:42.597Z,1410569982.597 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-13T00:59:42.597Z,1410569982.597 [ElevatorServo](INFO): Powering down
2014-09-13T00:59:42.598Z,1410569982.598 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-13T00:59:42.598Z,1410569982.598 [MassServo](INFO): Powering down
2014-09-13T00:59:42.599Z,1410569982.599 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-13T00:59:42.599Z,1410569982.599 [RudderServo](INFO): Powering down
2014-09-13T00:59:42.600Z,1410569982.600 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-13T00:59:42.600Z,1410569982.600 [ThrusterServo](INFO): Powering down
2014-09-13T00:59:42.601Z,1410569982.601 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-13T00:59:42.602Z,1410569982.602 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-13T00:59:42.602Z,1410569982.602 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-13T00:59:42.634Z,1410569982.634 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.689Z,1410569982.689 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:42.693Z,1410569982.693 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:43.034Z,1410569983.034 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:59:43.069Z,1410569983.069 [logger ThreadHandler](INFO): Thread cancelled.