2014-08-06T22:00:03.396Z,1407362403.396 [Supervisor](DEBUG): Initializing supervisor.
2014-08-06T22:00:03.399Z,1407362403.399 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-08-06T22:00:03.400Z,1407362403.400 [SyncHandler](INFO): Protected caller Thread ID is 920
2014-08-06T22:00:03.400Z,1407362403.400 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-08-06T22:00:03.401Z,1407362403.401 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-08-06T22:00:03.402Z,1407362403.402 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 921
2014-08-06T22:00:03.404Z,1407362403.404 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-08-06T22:00:03.416Z,1407362403.416 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-08-06T22:00:03.417Z,1407362403.417 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-08-06T22:00:03.417Z,1407362403.417 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 922
2014-08-06T22:00:03.418Z,1407362403.418 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-08-06T22:00:03.419Z,1407362403.419 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-08-06T22:00:03.419Z,1407362403.419 [logger ThreadHandler](INFO): Protected caller Thread ID is 923
2014-08-06T22:00:03.422Z,1407362403.422 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-08-06T22:00:03.422Z,1407362403.422 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-08-06T22:00:03.424Z,1407362403.424 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-08-06T22:00:03.761Z,1407362403.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-08-06T22:00:03.761Z,1407362403.761 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-08-06T22:00:03.920Z,1407362403.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-08-06T22:00:03.921Z,1407362403.921 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-08-06T22:00:04.029Z,1407362404.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-08-06T22:00:04.029Z,1407362404.029 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-08-06T22:00:04.152Z,1407362404.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-08-06T22:00:04.153Z,1407362404.153 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-08-06T22:00:04.238Z,1407362404.238 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-08-06T22:00:04.373Z,1407362404.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-08-06T22:00:04.373Z,1407362404.373 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-08-06T22:00:04.458Z,1407362404.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-08-06T22:00:04.459Z,1407362404.459 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-08-06T22:00:04.639Z,1407362404.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-08-06T22:00:04.639Z,1407362404.639 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-08-06T22:00:04.930Z,1407362404.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-08-06T22:00:04.930Z,1407362404.930 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-08-06T22:00:05.235Z,1407362405.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-08-06T22:00:05.235Z,1407362405.235 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-08-06T22:00:05.735Z,1407362405.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-08-06T22:00:05.735Z,1407362405.735 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-08-06T22:00:05.923Z,1407362405.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-08-06T22:00:05.924Z,1407362405.924 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-08-06T22:00:06.027Z,1407362406.027 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-08-06T22:00:06.027Z,1407362406.027 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-08-06T22:00:06.428Z,1407362406.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-08-06T22:00:06.429Z,1407362406.429 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-08-06T22:00:06.538Z,1407362406.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-08-06T22:00:06.540Z,1407362406.540 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-08-06T22:00:06.541Z,1407362406.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-08-06T22:00:06.645Z,1407362406.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-08-06T22:00:06.762Z,1407362406.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-08-06T22:00:06.860Z,1407362406.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-08-06T22:00:06.968Z,1407362406.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-08-06T22:00:07.069Z,1407362407.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-08-06T22:00:07.199Z,1407362407.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-08-06T22:00:07.342Z,1407362407.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-08-06T22:00:07.462Z,1407362407.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-08-06T22:00:07.547Z,1407362407.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-08-06T22:00:07.643Z,1407362407.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-08-06T22:00:07.752Z,1407362407.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-08-06T22:00:07.839Z,1407362407.839 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-08-06T22:00:07.841Z,1407362407.841 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-08-06T22:00:07.890Z,1407362407.890 [VerticalControl](DEBUG): Construct VerticalControl.
2014-08-06T22:00:08.008Z,1407362408.008 [VerticalControl] Loaded
2014-08-06T22:00:08.009Z,1407362408.009 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-08-06T22:00:08.009Z,1407362408.009 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-08-06T22:00:08.080Z,1407362408.080 [HorizontalControl] Loaded
2014-08-06T22:00:08.080Z,1407362408.080 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-08-06T22:00:08.080Z,1407362408.080 [SpeedControl](DEBUG): Construct SpeedControl.
2014-08-06T22:00:08.086Z,1407362408.086 [SpeedControl] Loaded
2014-08-06T22:00:08.086Z,1407362408.086 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-08-06T22:00:08.087Z,1407362408.087 [LoopControl](DEBUG): Construct LoopControl.
2014-08-06T22:00:08.087Z,1407362408.087 [LoopControl] Loaded
2014-08-06T22:00:08.088Z,1407362408.088 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-08-06T22:00:08.088Z,1407362408.088 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-08-06T22:00:08.089Z,1407362408.089 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-08-06T22:00:08.183Z,1407362408.183 [SBIT](DEBUG): Construct Startup Built In Test.
2014-08-06T22:00:08.195Z,1407362408.195 [SBIT] Loaded
2014-08-06T22:00:08.195Z,1407362408.195 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-08-06T22:00:08.196Z,1407362408.196 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-08-06T22:00:08.225Z,1407362408.225 [IBIT] Loaded
2014-08-06T22:00:08.225Z,1407362408.225 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-08-06T22:00:08.228Z,1407362408.228 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-08-06T22:00:08.373Z,1407362408.373 [CBIT] Loaded
2014-08-06T22:00:08.373Z,1407362408.373 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-08-06T22:00:08.374Z,1407362408.374 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-08-06T22:00:08.374Z,1407362408.374 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-08-06T22:00:08.393Z,1407362408.393 [DepthRateCalculator] Loaded
2014-08-06T22:00:08.393Z,1407362408.393 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-08-06T22:00:08.398Z,1407362408.398 [PitchRateCalculator] Loaded
2014-08-06T22:00:08.399Z,1407362408.399 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-08-06T22:00:08.410Z,1407362408.410 [SpeedCalculator] Loaded
2014-08-06T22:00:08.410Z,1407362408.410 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-08-06T22:00:08.426Z,1407362408.426 [TempGradientCalculator] Loaded
2014-08-06T22:00:08.426Z,1407362408.426 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-08-06T22:00:08.432Z,1407362408.432 [YawRateCalculator] Loaded
2014-08-06T22:00:08.432Z,1407362408.432 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-08-06T22:00:08.432Z,1407362408.432 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-08-06T22:00:08.433Z,1407362408.433 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-08-06T22:00:12.642Z,1407362412.642 [HFRadarCompactModelForecaster] Loaded
2014-08-06T22:00:12.642Z,1407362412.642 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-08-06T22:00:13.794Z,1407362413.794 [HFRCMSpaceInterpolator] Loaded
2014-08-06T22:00:13.794Z,1407362413.794 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-08-06T22:00:13.797Z,1407362413.797 [HFRCMTimeInterpolator] Loaded
2014-08-06T22:00:13.797Z,1407362413.797 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-08-06T22:00:13.809Z,1407362413.809 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-08-06T22:00:13.810Z,1407362413.810 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-08-06T22:00:13.836Z,1407362413.836 [HFRCMVirtualSurfaceDrifter] Loaded
2014-08-06T22:00:13.836Z,1407362413.836 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-08-06T22:00:13.837Z,1407362413.837 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-08-06T22:00:13.837Z,1407362413.837 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-08-06T22:00:14.065Z,1407362414.065 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-08-06T22:00:14.065Z,1407362414.065 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-08-06T22:00:14.144Z,1407362414.144 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-08-06T22:00:14.144Z,1407362414.144 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-08-06T22:00:14.189Z,1407362414.189 [DeadReckonWithRespectToWater] Loaded
2014-08-06T22:00:14.189Z,1407362414.189 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-08-06T22:00:14.235Z,1407362414.235 [DeadReckonWithRespectToSeafloor] Loaded
2014-08-06T22:00:14.236Z,1407362414.236 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-08-06T22:00:14.295Z,1407362414.295 [DeadReckonUsingDVLWaterTrack] Loaded
2014-08-06T22:00:14.295Z,1407362414.295 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-08-06T22:00:14.342Z,1407362414.342 [DeadReckonUsingCompactModelForecast] Loaded
2014-08-06T22:00:14.342Z,1407362414.342 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-08-06T22:00:14.358Z,1407362414.358 [NavChart] Loaded
2014-08-06T22:00:14.359Z,1407362414.359 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-08-06T22:00:14.363Z,1407362414.363 [UniversalFixResidualReporter] Loaded
2014-08-06T22:00:14.363Z,1407362414.363 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-08-06T22:00:14.364Z,1407362414.364 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-08-06T22:00:14.364Z,1407362414.364 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-08-06T22:00:14.370Z,1407362414.370 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-08-06T22:00:14.371Z,1407362414.371 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-08-06T22:00:14.541Z,1407362414.541 [CTD_NeilBrown] Loaded
2014-08-06T22:00:14.542Z,1407362414.542 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-08-06T22:00:14.543Z,1407362414.543 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4064C4E0
2014-08-06T22:00:14.543Z,1407362414.543 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1005
2014-08-06T22:00:14.558Z,1407362414.558 [PAR_Licor] Loaded
2014-08-06T22:00:14.558Z,1407362414.558 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-08-06T22:00:14.594Z,1407362414.594 [WetLabsBB2FL] Loaded
2014-08-06T22:00:14.594Z,1407362414.594 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-08-06T22:00:14.595Z,1407362414.595 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4067C4E0
2014-08-06T22:00:14.596Z,1407362414.596 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1006
2014-08-06T22:00:14.596Z,1407362414.596 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-08-06T22:00:14.597Z,1407362414.597 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-08-06T22:00:14.871Z,1407362414.871 [AHRS_sp3003D] Loaded
2014-08-06T22:00:14.871Z,1407362414.871 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-08-06T22:00:15.128Z,1407362415.128 [Batt_Ocean_Server] Loaded
2014-08-06T22:00:15.128Z,1407362415.128 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-08-06T22:00:15.142Z,1407362415.142 [Depth_Keller] Loaded
2014-08-06T22:00:15.142Z,1407362415.142 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-08-06T22:00:15.147Z,1407362415.147 [DropWeight] Loaded
2014-08-06T22:00:15.148Z,1407362415.148 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-08-06T22:00:15.277Z,1407362415.277 [DVL_micro] Loaded
2014-08-06T22:00:15.277Z,1407362415.277 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-08-06T22:00:15.369Z,1407362415.369 [NAL9602] Loaded
2014-08-06T22:00:15.370Z,1407362415.370 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-08-06T22:00:15.411Z,1407362415.411 [Onboard] Loaded
2014-08-06T22:00:15.412Z,1407362415.412 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-08-06T22:00:15.419Z,1407362415.419 [Radio_Freewave] Loaded
2014-08-06T22:00:15.419Z,1407362415.419 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-08-06T22:00:15.420Z,1407362415.420 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 408B34E0
2014-08-06T22:00:15.421Z,1407362415.421 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 1007
2014-08-06T22:00:15.547Z,1407362415.547 [DAT] Loaded
2014-08-06T22:00:15.548Z,1407362415.548 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-08-06T22:00:15.554Z,1407362415.554 [SCPI] Loaded
2014-08-06T22:00:15.555Z,1407362415.555 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-08-06T22:00:15.555Z,1407362415.555 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-08-06T22:00:15.556Z,1407362415.556 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-08-06T22:00:15.655Z,1407362415.655 [BuoyancyServo] Loaded
2014-08-06T22:00:15.655Z,1407362415.655 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-08-06T22:00:15.667Z,1407362415.667 [ElevatorServo] Loaded
2014-08-06T22:00:15.667Z,1407362415.667 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-08-06T22:00:15.679Z,1407362415.679 [MassServo] Loaded
2014-08-06T22:00:15.679Z,1407362415.679 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-08-06T22:00:15.691Z,1407362415.691 [RudderServo] Loaded
2014-08-06T22:00:15.692Z,1407362415.692 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-08-06T22:00:15.703Z,1407362415.703 [ThrusterServo] Loaded
2014-08-06T22:00:15.704Z,1407362415.704 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-08-06T22:00:15.704Z,1407362415.704 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-08-06T22:00:15.705Z,1407362415.705 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-08-06T22:00:15.859Z,1407362415.859 [InternalSim] Loaded
2014-08-06T22:00:15.859Z,1407362415.859 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-08-06T22:00:15.860Z,1407362415.860 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-08-06T22:00:15.860Z,1407362415.860 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-08-06T22:00:15.884Z,1407362415.884 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-08-06T22:00:15.886Z,1407362415.886 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-08-06T22:00:15.887Z,1407362415.887 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-08-06T22:00:15.893Z,1407362415.893 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-08-06T22:00:15.894Z,1407362415.894 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409644E0
2014-08-06T22:00:15.895Z,1407362415.895 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1008
2014-08-06T22:00:15.900Z,1407362415.900 [Supervisor](INFO): Main Thread ID is 762
2014-08-06T22:00:15.900Z,1407362415.900 [Supervisor](DEBUG): Running supervisor.
2014-08-06T22:00:15.901Z,1407362415.901 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1009
2014-08-06T22:00:15.903Z,1407362415.903 [controlThread ThreadHandler](INFO): Handler Thread ID is 1010
2014-08-06T22:00:15.904Z,1407362415.904 [controlThread](DEBUG): Initializing ControlThread
2014-08-06T22:00:15.905Z,1407362415.905 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-08-06T22:00:15.907Z,1407362415.907 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-08-06T22:00:15.907Z,1407362415.907 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-08-06T22:00:15.908Z,1407362415.908 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-08-06T22:00:15.908Z,1407362415.908 [SBIT](INFO): Initialize SBIT Component.
2014-08-06T22:00:15.909Z,1407362415.909 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11481
2014-08-06T22:00:15.909Z,1407362415.909 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-08-06T22:00:15.909Z,1407362415.909 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-08-06T22:00:15.910Z,1407362415.910 [IBIT](INFO): Initialize IBIT Component.
2014-08-06T22:00:15.911Z,1407362415.911 [CBIT](DEBUG): Initialize CBIT Component.
2014-08-06T22:00:15.911Z,1407362415.911 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-08-06T22:00:15.912Z,1407362415.912 [logger ThreadHandler](INFO): Handler Thread ID is 1011
2014-08-06T22:00:15.931Z,1407362415.931 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1012
2014-08-06T22:00:15.931Z,1407362415.931 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-08-06T22:00:15.934Z,1407362415.934 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-06T22:00:15.935Z,1407362415.935 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-08-06T22:00:15.935Z,1407362415.935 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-08-06T22:00:15.935Z,1407362415.935 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-08-06T22:00:15.936Z,1407362415.936 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-08-06T22:00:15.943Z,1407362415.943 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-08-06T22:00:15.943Z,1407362415.943 [HFRadarCompactModelForecaster](ERROR): Most recent ECs are more than six hours old.
2014-08-06T22:00:15.955Z,1407362415.955 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-08-06T22:00:15.974Z,1407362415.974 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1014
2014-08-06T22:00:15.975Z,1407362415.975 [WetLabsBB2FL](INFO): Powering down
2014-08-06T22:00:16.003Z,1407362416.003 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 1015
2014-08-06T22:00:16.019Z,1407362416.019 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1016
2014-08-06T22:00:16.021Z,1407362416.021 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-08-06T22:00:16.023Z,1407362416.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-08-06T22:00:16.023Z,1407362416.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-08-06T22:00:16.023Z,1407362416.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-08-06T22:00:16.023Z,1407362416.023 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-08-06T22:00:16.024Z,1407362416.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-08-06T22:00:16.024Z,1407362416.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-08-06T22:00:16.024Z,1407362416.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-08-06T22:00:16.024Z,1407362416.024 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-08-06T22:00:24.779Z,1407362424.779 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201408061400: published 50 modes in rows, 61 hours in columns
2014-08-06T22:00:24.783Z,1407362424.783 [HFRadarCompactModelForecaster](IMPORTANT): expansionCoefficients_[0][48] = 24.140034 for 2014-08-06T14:00:00.000Z
2014-08-06T22:00:24.788Z,1407362424.788 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-08-06T22:00:24.790Z,1407362424.790 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-08-06T22:00:24.790Z,1407362424.790 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-08-06T22:00:24.790Z,1407362424.790 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-08-06T22:00:24.791Z,1407362424.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-06T22:00:24.791Z,1407362424.791 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-08-06T22:00:24.792Z,1407362424.792 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-08-06T22:00:24.800Z,1407362424.800 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-08-06T22:00:24.800Z,1407362424.800 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-08-06T22:00:24.801Z,1407362424.801 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-08-06T22:00:24.801Z,1407362424.801 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-06T22:00:26.307Z,1407362426.307 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-08-06T22:00:26.339Z,1407362426.339 [InternalSim](DEBUG):  InternalSim initializing...
2014-08-06T22:00:26.504Z,1407362426.504 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-08-06T22:00:26.542Z,1407362426.542 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-06T22:00:26.584Z,1407362426.584 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2014-08-06T22:00:26.585Z,1407362426.585 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-08-06T22:00:26.663Z,1407362426.663 [Radio_Freewave](INFO): Powering up
2014-08-06T22:00:26.679Z,1407362426.679 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-06T22:00:26.696Z,1407362426.696 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-08-06T22:00:26.718Z,1407362426.718 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-08-06T22:00:26.722Z,1407362426.722 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-08-06T22:00:26.751Z,1407362426.751 [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">

    <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="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-08-06T22:00:26.758Z,1407362426.758 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-08-06T22:00:26.959Z,1407362426.959 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-08-06T22:00:27.147Z,1407362427.147 [DVL_micro](INFO): Initializing
2014-08-06T22:00:27.170Z,1407362427.170 [NAL9602](INFO): Powering up NAL9602
2014-08-06T22:00:27.220Z,1407362427.220 [DAT](INFO): Powering up
2014-08-06T22:00:27.220Z,1407362427.220 [DAT](DEBUG): Initializing DAT.
2014-08-06T22:00:27.476Z,1407362427.476 [Radio_Freewave](IMPORTANT): Starting PPPD with command:/sbin/pppd nocrtscts /dev/ttyS1 noauth 115200 10.1.1.2:10.1.1.1 persist maxfail 0
2014-08-06T22:00:27.649Z,1407362427.649 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-08-06T22:00:27.657Z,1407362427.657 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-08-06T22:00:27.687Z,1407362427.687 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-08-06T22:00:27.697Z,1407362427.697 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-08-06T22:00:27.703Z,1407362427.703 [MassServo](DEBUG): Initializing EZServoServo.
2014-08-06T22:00:27.713Z,1407362427.713 [MassServo](DEBUG): Initializing MassServo.
2014-08-06T22:00:27.744Z,1407362427.744 [RudderServo](DEBUG): Initializing EZServoServo.
2014-08-06T22:00:27.745Z,1407362427.745 [RudderServo](DEBUG): Initializing RudderServo.
2014-08-06T22:00:27.765Z,1407362427.765 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-08-06T22:00:27.777Z,1407362427.777 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-08-06T22:00:32.749Z,1407362432.749 [SBIT](IMPORTANT): Beginning Startup BIT
2014-08-06T22:00:32.762Z,1407362432.762 [CBIT](IMPORTANT): Beginning GF scan
2014-08-06T22:00:38.157Z,1407362438.157 [NAL9602](INFO): NAL9602 initialized
2014-08-06T22:00:47.466Z,1407362447.466 [DAT](INFO): Powering down
2014-08-06T22:00:59.969Z,1407362459.969 [CBIT](FAULT): Chan 4 High side GF detected
mA:
CHAN 5 (24V): 0.030991
CHAN 4 (Batt): 0.026496
CHAN 2 (12V): 0.025635
CHAN 1 (5V): 0.021426
CHAN 0 (3.3V): 0.021809
OPEN: 0.000765
Full Scale Calc: 0.392
2014-08-06T22:01:04.729Z,1407362464.729 [NAL9602](IMPORTANT): GPS fix at: 1407362461.00
2014-08-06T22:01:05.334Z,1407362465.334 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802021, -121.787178) is outside the bounding box
2014-08-06T22:01:05.867Z,1407362465.867 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802025, -121.787178) is outside the bounding box
2014-08-06T22:01:06.351Z,1407362466.351 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802025, -121.787186) is outside the bounding box
2014-08-06T22:01:06.913Z,1407362466.913 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802025, -121.787186) is outside the bounding box
2014-08-06T22:01:07.466Z,1407362467.466 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802029, -121.787193) is outside the bounding box
2014-08-06T22:01:07.929Z,1407362467.929 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802029, -121.787201) is outside the bounding box
2014-08-06T22:01:08.402Z,1407362468.402 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802032, -121.787209) is outside the bounding box
2014-08-06T22:01:08.981Z,1407362468.981 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802032, -121.787209) is outside the bounding box
2014-08-06T22:01:09.449Z,1407362469.449 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802036, -121.787216) is outside the bounding box
2014-08-06T22:01:09.944Z,1407362469.944 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787216) is outside the bounding box
2014-08-06T22:01:10.504Z,1407362470.504 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787224) is outside the bounding box
2014-08-06T22:01:10.946Z,1407362470.946 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787231) is outside the bounding box
2014-08-06T22:01:11.441Z,1407362471.441 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787231) is outside the bounding box
2014-08-06T22:01:11.872Z,1407362471.872 [NAL9602](INFO): Powering down
2014-08-06T22:01:11.917Z,1407362471.917 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787239) is outside the bounding box
2014-08-06T22:01:12.501Z,1407362472.501 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787239) is outside the bounding box
2014-08-06T22:01:12.909Z,1407362472.909 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787247) is outside the bounding box
2014-08-06T22:01:13.330Z,1407362473.330 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787247) is outside the bounding box
2014-08-06T22:01:13.783Z,1407362473.783 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787254) is outside the bounding box
2014-08-06T22:01:14.227Z,1407362474.227 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802044, -121.787254) is outside the bounding box
2014-08-06T22:01:14.644Z,1407362474.644 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802044, -121.787262) is outside the bounding box
2014-08-06T22:01:15.222Z,1407362475.222 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787262) is outside the bounding box
2014-08-06T22:01:16.115Z,1407362476.115 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787262) is outside the bounding box
2014-08-06T22:01:16.620Z,1407362476.620 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787270) is outside the bounding box
2014-08-06T22:01:17.140Z,1407362477.140 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787270) is outside the bounding box
2014-08-06T22:01:17.697Z,1407362477.697 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787277) is outside the bounding box
2014-08-06T22:01:18.286Z,1407362478.286 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787285) is outside the bounding box
2014-08-06T22:01:18.981Z,1407362478.981 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802059, -121.787285) is outside the bounding box
2014-08-06T22:01:19.430Z,1407362479.430 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802059, -121.787292) is outside the bounding box
2014-08-06T22:01:19.862Z,1407362479.862 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802059, -121.787292) is outside the bounding box
2014-08-06T22:01:20.360Z,1407362480.360 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802059, -121.787300) is outside the bounding box
2014-08-06T22:01:20.802Z,1407362480.802 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787300) is outside the bounding box
2014-08-06T22:01:21.249Z,1407362481.249 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787308) is outside the bounding box
2014-08-06T22:01:21.679Z,1407362481.679 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787308) is outside the bounding box
2014-08-06T22:01:22.260Z,1407362482.260 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787315) is outside the bounding box
2014-08-06T22:01:22.729Z,1407362482.729 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787323) is outside the bounding box
2014-08-06T22:01:23.170Z,1407362483.170 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787323) is outside the bounding box
2014-08-06T22:01:23.652Z,1407362483.652 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787331) is outside the bounding box
2014-08-06T22:01:24.077Z,1407362484.077 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787331) is outside the bounding box
2014-08-06T22:01:24.503Z,1407362484.503 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787338) is outside the bounding box
2014-08-06T22:01:24.936Z,1407362484.936 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787338) is outside the bounding box
2014-08-06T22:01:25.393Z,1407362485.393 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787338) is outside the bounding box
2014-08-06T22:01:25.804Z,1407362485.804 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787346) is outside the bounding box
2014-08-06T22:01:26.237Z,1407362486.237 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787346) is outside the bounding box
2014-08-06T22:01:26.730Z,1407362486.730 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787354) is outside the bounding box
2014-08-06T22:01:26.749Z,1407362486.749 [SBIT](IMPORTANT): SBIT PASSED
2014-08-06T22:01:27.151Z,1407362487.151 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802071, -121.787354) is outside the bounding box
2014-08-06T22:01:27.165Z,1407362487.165 [MissionManager](IMPORTANT): Started mission Startup
2014-08-06T22:01:27.165Z,1407362487.165 [Startup] Running Loop=1
2014-08-06T22:01:27.165Z,1407362487.165 [Startup](INFO): Aggregate::initialize Startup
2014-08-06T22:01:27.165Z,1407362487.165 [Startup:A.GoToSurface] Running Loop=1
2014-08-06T22:01:27.165Z,1407362487.165 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-08-06T22:01:27.166Z,1407362487.166 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-08-06T22:01:27.166Z,1407362487.166 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-08-06T22:01:27.167Z,1407362487.167 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-08-06T22:01:27.193Z,1407362487.193 [Startup:StartupSatComms] Running Loop=1
2014-08-06T22:01:27.193Z,1407362487.193 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-08-06T22:01:27.193Z,1407362487.193 [Startup:StartupSatComms:A] Running Loop=1
2014-08-06T22:01:27.665Z,1407362487.665 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802071, -121.787361) is outside the bounding box
2014-08-06T22:01:27.680Z,1407362487.680 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-08-06T22:01:28.073Z,1407362488.073 [NAL9602](INFO): Powering up
2014-08-06T22:01:28.088Z,1407362488.088 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802071, -121.787369) is outside the bounding box
2014-08-06T22:01:28.515Z,1407362488.515 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802071, -121.787376) is outside the bounding box
2014-08-06T22:01:28.971Z,1407362488.971 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802071, -121.787376) is outside the bounding box
2014-08-06T22:01:29.394Z,1407362489.394 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802071, -121.787384) is outside the bounding box
2014-08-06T22:01:29.842Z,1407362489.842 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802074, -121.787392) is outside the bounding box
2014-08-06T22:01:30.284Z,1407362490.284 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802074, -121.787392) is outside the bounding box
2014-08-06T22:01:30.709Z,1407362490.709 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802074, -121.787399) is outside the bounding box
2014-08-06T22:01:31.134Z,1407362491.134 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802078, -121.787407) is outside the bounding box
2014-08-06T22:01:31.554Z,1407362491.554 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802078, -121.787407) is outside the bounding box
2014-08-06T22:01:32.043Z,1407362492.043 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802078, -121.787415) is outside the bounding box
2014-08-06T22:01:32.461Z,1407362492.461 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802082, -121.787422) is outside the bounding box
2014-08-06T22:01:32.973Z,1407362492.973 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802082, -121.787422) is outside the bounding box
2014-08-06T22:01:33.413Z,1407362493.413 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802082, -121.787430) is outside the bounding box
2014-08-06T22:01:33.834Z,1407362493.834 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802086, -121.787430) is outside the bounding box
2014-08-06T22:01:34.260Z,1407362494.260 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802086, -121.787437) is outside the bounding box
2014-08-06T22:01:34.683Z,1407362494.683 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802086, -121.787437) is outside the bounding box
2014-08-06T22:01:35.112Z,1407362495.112 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802086, -121.787437) is outside the bounding box
2014-08-06T22:01:35.571Z,1407362495.571 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802090, -121.787445) is outside the bounding box
2014-08-06T22:01:36.056Z,1407362496.056 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802090, -121.787445) is outside the bounding box
2014-08-06T22:01:36.478Z,1407362496.478 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802090, -121.787453) is outside the bounding box
2014-08-06T22:01:36.916Z,1407362496.916 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802094, -121.787453) is outside the bounding box
2014-08-06T22:01:37.326Z,1407362497.326 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802094, -121.787453) is outside the bounding box
2014-08-06T22:01:37.826Z,1407362497.826 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787453) is outside the bounding box
2014-08-06T22:01:38.241Z,1407362498.241 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787460) is outside the bounding box
2014-08-06T22:01:38.743Z,1407362498.743 [NAL9602](INFO): NAL9602 initialized
2014-08-06T22:01:38.760Z,1407362498.760 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787460) is outside the bounding box
2014-08-06T22:01:39.203Z,1407362499.203 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802101, -121.787460) is outside the bounding box
2014-08-06T22:01:39.654Z,1407362499.654 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802101, -121.787468) is outside the bounding box
2014-08-06T22:01:40.093Z,1407362500.093 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802101, -121.787468) is outside the bounding box
2014-08-06T22:01:40.541Z,1407362500.541 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787468) is outside the bounding box
2014-08-06T22:01:40.965Z,1407362500.965 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787476) is outside the bounding box
2014-08-06T22:01:41.401Z,1407362501.401 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787476) is outside the bounding box
2014-08-06T22:01:41.865Z,1407362501.865 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787483) is outside the bounding box
2014-08-06T22:01:42.370Z,1407362502.370 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787483) is outside the bounding box
2014-08-06T22:01:42.815Z,1407362502.815 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787483) is outside the bounding box
2014-08-06T22:01:43.320Z,1407362503.320 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787491) is outside the bounding box
2014-08-06T22:01:43.744Z,1407362503.744 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787491) is outside the bounding box
2014-08-06T22:01:44.177Z,1407362504.177 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787498) is outside the bounding box
2014-08-06T22:01:44.599Z,1407362504.599 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787498) is outside the bounding box
2014-08-06T22:01:45.039Z,1407362505.039 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787506) is outside the bounding box
2014-08-06T22:01:45.571Z,1407362505.571 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787506) is outside the bounding box
2014-08-06T22:01:46.027Z,1407362506.027 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787514) is outside the bounding box
2014-08-06T22:01:46.438Z,1407362506.438 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787514) is outside the bounding box
2014-08-06T22:01:46.862Z,1407362506.862 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787514) is outside the bounding box
2014-08-06T22:01:47.290Z,1407362507.290 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787521) is outside the bounding box
2014-08-06T22:01:47.720Z,1407362507.720 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787521) is outside the bounding box
2014-08-06T22:01:48.219Z,1407362508.219 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787529) is outside the bounding box
2014-08-06T22:01:48.745Z,1407362508.745 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802097, -121.787529) is outside the bounding box
2014-08-06T22:01:49.200Z,1407362509.200 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802101, -121.787537) is outside the bounding box
2014-08-06T22:01:49.607Z,1407362509.607 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802101, -121.787537) is outside the bounding box
2014-08-06T22:01:50.034Z,1407362510.034 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802105, -121.787537) is outside the bounding box
2014-08-06T22:01:50.460Z,1407362510.460 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802105, -121.787544) is outside the bounding box
2014-08-06T22:01:50.910Z,1407362510.910 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802105, -121.787544) is outside the bounding box
2014-08-06T22:01:51.336Z,1407362511.336 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802109, -121.787552) is outside the bounding box
2014-08-06T22:01:51.845Z,1407362511.845 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802109, -121.787552) is outside the bounding box
2014-08-06T22:01:52.319Z,1407362512.319 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802113, -121.787560) is outside the bounding box
2014-08-06T22:01:52.732Z,1407362512.732 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802113, -121.787560) is outside the bounding box
2014-08-06T22:01:53.146Z,1407362513.146 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802113, -121.787560) is outside the bounding box
2014-08-06T22:01:53.642Z,1407362513.642 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802116, -121.787567) is outside the bounding box
2014-08-06T22:01:54.085Z,1407362514.085 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802116, -121.787567) is outside the bounding box
2014-08-06T22:01:54.537Z,1407362514.537 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802116, -121.787567) is outside the bounding box
2014-08-06T22:01:55.022Z,1407362515.022 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802116, -121.787575) is outside the bounding box
2014-08-06T22:01:55.480Z,1407362515.480 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802120, -121.787575) is outside the bounding box
2014-08-06T22:01:55.915Z,1407362515.915 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802120, -121.787582) is outside the bounding box
2014-08-06T22:01:56.360Z,1407362516.360 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802120, -121.787582) is outside the bounding box
2014-08-06T22:01:56.770Z,1407362516.770 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802124, -121.787582) is outside the bounding box
2014-08-06T22:01:57.226Z,1407362517.226 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802124, -121.787590) is outside the bounding box
2014-08-06T22:01:57.670Z,1407362517.670 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802124, -121.787590) is outside the bounding box
2014-08-06T22:01:58.163Z,1407362518.163 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787598) is outside the bounding box
2014-08-06T22:01:58.684Z,1407362518.684 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787598) is outside the bounding box
2014-08-06T22:01:59.108Z,1407362519.108 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787605) is outside the bounding box
2014-08-06T22:01:59.539Z,1407362519.539 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787605) is outside the bounding box
2014-08-06T22:01:59.995Z,1407362519.995 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787613) is outside the bounding box
2014-08-06T22:02:00.450Z,1407362520.450 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787621) is outside the bounding box
2014-08-06T22:02:00.899Z,1407362520.899 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787621) is outside the bounding box
2014-08-06T22:02:01.388Z,1407362521.388 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802128, -121.787628) is outside the bounding box
2014-08-06T22:02:01.862Z,1407362521.862 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802132, -121.787628) is outside the bounding box
2014-08-06T22:02:02.274Z,1407362522.274 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802132, -121.787636) is outside the bounding box
2014-08-06T22:02:02.697Z,1407362522.697 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802132, -121.787643) is outside the bounding box
2014-08-06T22:02:03.150Z,1407362523.150 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802135, -121.787643) is outside the bounding box
2014-08-06T22:02:03.625Z,1407362523.625 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802135, -121.787651) is outside the bounding box
2014-08-06T22:02:04.076Z,1407362524.076 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802135, -121.787659) is outside the bounding box
2014-08-06T22:02:04.526Z,1407362524.526 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802139, -121.787659) is outside the bounding box
2014-08-06T22:02:04.961Z,1407362524.961 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802139, -121.787666) is outside the bounding box
2014-08-06T22:02:05.434Z,1407362525.434 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802139, -121.787666) is outside the bounding box
2014-08-06T22:02:05.870Z,1407362525.870 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802139, -121.787674) is outside the bounding box
2014-08-06T22:02:06.316Z,1407362526.316 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802139, -121.787674) is outside the bounding box
2014-08-06T22:02:06.720Z,1407362526.720 [NAL9602](IMPORTANT): GPS fix at: 1407362523.00
2014-08-06T22:02:06.733Z,1407362526.733 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802135, -121.787682) is outside the bounding box
2014-08-06T22:02:06.784Z,1407362526.784 [Startup:StartupSatComms:A] Stopped
2014-08-06T22:02:06.784Z,1407362526.784 [Startup:StartupSatComms:B] Running Loop=1
2014-08-06T22:02:07.193Z,1407362527.193 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802006, -121.787193) is outside the bounding box
2014-08-06T22:02:07.237Z,1407362527.237 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-08-06T22:02:07.984Z,1407362527.984 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802006, -121.787193) is outside the bounding box
2014-08-06T22:02:08.433Z,1407362528.433 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802010, -121.787201) is outside the bounding box
2014-08-06T22:02:08.984Z,1407362528.984 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802010, -121.787209) is outside the bounding box
2014-08-06T22:02:09.468Z,1407362529.468 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787216) is outside the bounding box
2014-08-06T22:02:09.933Z,1407362529.933 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787216) is outside the bounding box
2014-08-06T22:02:10.434Z,1407362530.434 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787224) is outside the bounding box
2014-08-06T22:02:10.894Z,1407362530.894 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802017, -121.787231) is outside the bounding box
2014-08-06T22:02:11.464Z,1407362531.464 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802017, -121.787231) is outside the bounding box
2014-08-06T22:02:12.008Z,1407362532.008 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802021, -121.787239) is outside the bounding box
2014-08-06T22:02:12.504Z,1407362532.504 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802021, -121.787247) is outside the bounding box
2014-08-06T22:02:12.982Z,1407362532.982 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802021, -121.787247) is outside the bounding box
2014-08-06T22:02:13.455Z,1407362533.455 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802021, -121.787254) is outside the bounding box
2014-08-06T22:02:13.956Z,1407362533.956 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802021, -121.787262) is outside the bounding box
2014-08-06T22:02:14.406Z,1407362534.406 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802025, -121.787262) is outside the bounding box
2014-08-06T22:02:14.907Z,1407362534.907 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802025, -121.787270) is outside the bounding box
2014-08-06T22:02:15.416Z,1407362535.416 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802025, -121.787277) is outside the bounding box
2014-08-06T22:02:16.347Z,1407362536.347 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802029, -121.787285) is outside the bounding box
2014-08-06T22:02:16.914Z,1407362536.914 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802029, -121.787292) is outside the bounding box
2014-08-06T22:02:19.366Z,1407362539.366 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802032, -121.787300) is outside the bounding box
2014-08-06T22:02:20.006Z,1407362540.006 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787331) is outside the bounding box
2014-08-06T22:02:20.489Z,1407362540.489 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802040, -121.787338) is outside the bounding box
2014-08-06T22:02:20.978Z,1407362540.978 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802044, -121.787346) is outside the bounding box
2014-08-06T22:02:21.431Z,1407362541.431 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802044, -121.787346) is outside the bounding box
2014-08-06T22:02:21.891Z,1407362541.891 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787354) is outside the bounding box
2014-08-06T22:02:22.339Z,1407362542.339 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787361) is outside the bounding box
2014-08-06T22:02:22.745Z,1407362542.745 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787361) is outside the bounding box
2014-08-06T22:02:23.215Z,1407362543.215 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787369) is outside the bounding box
2014-08-06T22:02:23.675Z,1407362543.675 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787369) is outside the bounding box
2014-08-06T22:02:24.229Z,1407362544.229 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787376) is outside the bounding box
2014-08-06T22:02:24.666Z,1407362544.666 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787376) is outside the bounding box
2014-08-06T22:02:25.201Z,1407362545.201 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787384) is outside the bounding box
2014-08-06T22:02:25.673Z,1407362545.673 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787384) is outside the bounding box
2014-08-06T22:02:26.130Z,1407362546.130 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787392) is outside the bounding box
2014-08-06T22:02:26.651Z,1407362546.651 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787392) is outside the bounding box
2014-08-06T22:02:27.090Z,1407362547.090 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787399) is outside the bounding box
2014-08-06T22:02:27.513Z,1407362547.513 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787399) is outside the bounding box
2014-08-06T22:02:27.966Z,1407362547.966 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802048, -121.787407) is outside the bounding box
2014-08-06T22:02:28.460Z,1407362548.460 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787407) is outside the bounding box
2014-08-06T22:02:28.981Z,1407362548.981 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787407) is outside the bounding box
2014-08-06T22:02:29.418Z,1407362549.418 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787415) is outside the bounding box
2014-08-06T22:02:29.845Z,1407362549.845 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802052, -121.787415) is outside the bounding box
2014-08-06T22:02:30.271Z,1407362550.271 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=36750, MT Status=1, MTMSN=2477
2014-08-06T22:02:30.316Z,1407362550.316 [NAL9602](INFO): Sent 45 bytes from file Logs/20140806T215437/Courier0008.lzma
2014-08-06T22:02:30.317Z,1407362550.317 [NAL9602](INFO): Packets left to send: 0
2014-08-06T22:02:30.319Z,1407362550.319 [NAL9602](INFO): Stored copy of sent data in Logs/20140806T215437/Courier0008.lzma.parts/0000.sbd
2014-08-06T22:02:30.319Z,1407362550.319 [NAL9602](INFO): Completed sending Logs/20140806T215437/Courier0008.lzma
2014-08-06T22:02:30.337Z,1407362550.337 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787422) is outside the bounding box
2014-08-06T22:02:30.852Z,1407362550.852 [NAL9602](INFO): Received command:restart sys
2014-08-06T22:02:30.897Z,1407362550.897 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787430) is outside the bounding box
2014-08-06T22:02:30.920Z,1407362550.920 [CommandLine](IMPORTANT): got command restart system
2014-08-06T22:02:31.337Z,1407362551.337 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787430) is outside the bounding box
2014-08-06T22:02:31.793Z,1407362551.793 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787437) is outside the bounding box
2014-08-06T22:02:32.254Z,1407362552.254 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787437) is outside the bounding box
2014-08-06T22:02:32.697Z,1407362552.697 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802055, -121.787445) is outside the bounding box
2014-08-06T22:02:33.177Z,1407362553.177 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802059, -121.787453) is outside the bounding box
2014-08-06T22:02:33.210Z,1407362553.210 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:33.211Z,1407362553.211 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:33.391Z,1407362553.391 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-08-06T22:02:33.391Z,1407362553.391 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:33.392Z,1407362553.392 [CommandLine](INFO): Join timeout helper Thread ID is 1047
2014-08-06T22:02:33.393Z,1407362553.393 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-08-06T22:02:33.393Z,1407362553.393 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:33.393Z,1407362553.393 [NavChartDb](INFO): Join timeout helper Thread ID is 1048
2014-08-06T22:02:33.623Z,1407362553.623 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802059, -121.787453) is outside the bounding box
2014-08-06T22:02:33.672Z,1407362553.672 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:33.672Z,1407362553.672 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:33.692Z,1407362553.692 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-08-06T22:02:33.692Z,1407362553.692 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:33.692Z,1407362553.692 [Radio_Freewave](INFO): Join timeout helper Thread ID is 1049
2014-08-06T22:02:33.880Z,1407362553.880 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:33.974Z,1407362553.974 [Radio_Freewave](INFO): Powering down
2014-08-06T22:02:34.129Z,1407362554.129 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787453) is outside the bounding box
2014-08-06T22:02:34.377Z,1407362554.377 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.393Z,1407362554.393 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-08-06T22:02:34.393Z,1407362554.393 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.394Z,1407362554.394 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1051
2014-08-06T22:02:34.445Z,1407362554.445 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:34.445Z,1407362554.445 [WetLabsBB2FL](INFO): Powering down
2014-08-06T22:02:34.446Z,1407362554.446 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.455Z,1407362554.455 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-08-06T22:02:34.456Z,1407362554.456 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.456Z,1407362554.456 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1052
2014-08-06T22:02:34.570Z,1407362554.570 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802063, -121.787460) is outside the bounding box
2014-08-06T22:02:34.714Z,1407362554.714 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:34.714Z,1407362554.714 [CTD_NeilBrown](INFO): Powering down
2014-08-06T22:02:34.715Z,1407362554.715 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.734Z,1407362554.734 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-08-06T22:02:34.734Z,1407362554.734 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.735Z,1407362554.735 [logger](INFO): Join timeout helper Thread ID is 1053
2014-08-06T22:02:34.754Z,1407362554.754 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:34.754Z,1407362554.754 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.774Z,1407362554.774 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-08-06T22:02:34.774Z,1407362554.774 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.775Z,1407362554.775 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-08-06T22:02:34.775Z,1407362554.775 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:34.775Z,1407362554.775 [controlThread](INFO): Join timeout helper Thread ID is 1054
2014-08-06T22:02:35.539Z,1407362555.539 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802067, -121.787460) is outside the bounding box
2014-08-06T22:02:35.564Z,1407362555.564 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-06T22:02:35.564Z,1407362555.564 [controlThread](DEBUG): Uninitializing ControlThread
2014-08-06T22:02:35.565Z,1407362555.565 [AHRS_sp3003D](INFO): Powering down
2014-08-06T22:02:35.566Z,1407362555.566 [DVL_micro](INFO): uninitialize:Powering down
2014-08-06T22:02:35.567Z,1407362555.567 [NAL9602](INFO): Powering down
2014-08-06T22:02:35.568Z,1407362555.568 [DAT](INFO): Powering down
2014-08-06T22:02:35.570Z,1407362555.570 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-06T22:02:35.572Z,1407362555.572 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-08-06T22:02:35.575Z,1407362555.575 [Startup] Stopped
2014-08-06T22:02:35.575Z,1407362555.575 [Startup](INFO): Aggregate::uninitialize Startup
2014-08-06T22:02:35.575Z,1407362555.575 [Startup:A.GoToSurface] Stopped
2014-08-06T22:02:35.575Z,1407362555.575 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-08-06T22:02:35.576Z,1407362555.576 [Startup:StartupSatComms] Stopped
2014-08-06T22:02:35.576Z,1407362555.576 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-08-06T22:02:35.576Z,1407362555.576 [Startup:StartupSatComms:B] Stopped
2014-08-06T22:02:35.577Z,1407362555.577 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-08-06T22:02:35.577Z,1407362555.577 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-08-06T22:02:35.578Z,1407362555.578 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-08-06T22:02:35.578Z,1407362555.578 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-08-06T22:02:35.578Z,1407362555.578 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-08-06T22:02:35.579Z,1407362555.579 [BuoyancyServo](INFO): Powering down
2014-08-06T22:02:35.591Z,1407362555.591 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-08-06T22:02:35.591Z,1407362555.591 [ElevatorServo](INFO): Powering down
2014-08-06T22:02:35.592Z,1407362555.592 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-08-06T22:02:35.592Z,1407362555.592 [MassServo](INFO): Powering down
2014-08-06T22:02:35.593Z,1407362555.593 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-08-06T22:02:35.593Z,1407362555.593 [RudderServo](INFO): Powering down
2014-08-06T22:02:35.594Z,1407362555.594 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-08-06T22:02:35.594Z,1407362555.594 [ThrusterServo](INFO): Powering down
2014-08-06T22:02:35.595Z,1407362555.595 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-08-06T22:02:35.595Z,1407362555.595 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-08-06T22:02:35.596Z,1407362555.596 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-08-06T22:02:35.639Z,1407362555.639 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:35.710Z,1407362555.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:35.714Z,1407362555.714 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:35.723Z,1407362555.723 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:35.769Z,1407362555.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-06T22:02:35.816Z,1407362555.816 [logger ThreadHandler](INFO): Thread cancelled.