Tethys Path

2011-03-01T14:22:05.50130Z,1298989325.5013 [Startup:StartupSatComms:0_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:0_Timeout

2011-03-01T14:22:05.50160Z,1298989325.5016 [Startup:StartupSatComms:0_Timeout](INFO): Completed Startup:StartupSatComms:0_Timeout

2011-03-01T14:22:05.50190Z,1298989325.5019 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms

2011-03-01T14:22:05.50220Z,1298989325.5022 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms

2011-03-01T14:22:05.89700Z,1298989325.897 [MissionManager](IMPORTANT): Started mission Default

2011-03-01T14:22:05.91210Z,1298989325.9121 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium

2011-03-01T14:22:05.91260Z,1298989325.9126 [Default:CallIridium:0](INFO): Aggregate::initialize Default:CallIridium:0

2011-03-01T14:22:06.39390Z,1298989326.3939 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium

2011-03-01T14:26:28.35430Z,1298989588.3543 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium

2011-03-01T14:26:28.58300Z,1298989588.583 [Default:CallIridium:0](INFO): Completed Default:CallIridium:0

2011-03-01T14:26:28.58320Z,1298989588.5832 [Default:CallIridium:0](INFO): Aggregate::uninitialize Default:CallIridium:0

2011-03-01T14:26:28.58350Z,1298989588.5835 [Default:CallIridium](INFO): Completed Default:CallIridium

2011-03-01T14:26:28.58370Z,1298989588.5837 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium

2011-03-01T14:27:13.65750Z,1298989633.6575 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS

2011-03-01T14:27:13.65790Z,1298989633.6579 [Default:CallGPS:0](INFO): Aggregate::initialize Default:CallGPS:0

2011-03-01T14:28:26.57820Z,1298989706.5782 [Default:CallGPS:0](INFO): Aggregate::uninitialize Default:CallGPS:0

2011-03-01T14:28:26.57880Z,1298989706.5788 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS

2011-03-01T14:31:31.65240Z,1298989891.6524 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium

2011-03-01T14:31:31.65280Z,1298989891.6528 [Default:CallIridium:0](INFO): Aggregate::initialize Default:CallIridium:0

2011-03-01T14:31:36.65650Z,1298989896.6565 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium

2011-03-01T14:33:21.41840Z,1298990001.4184 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium

2011-03-01T14:33:21.66570Z,1298990001.6657 [Default:CallIridium:0](INFO): Completed Default:CallIridium:0

2011-03-01T14:33:21.66600Z,1298990001.666 [Default:CallIridium:0](INFO): Aggregate::uninitialize Default:CallIridium:0

2011-03-01T14:33:21.66620Z,1298990001.6662 [Default:CallIridium](INFO): Completed Default:CallIridium

2011-03-01T14:33:21.66650Z,1298990001.6665 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium

2011-03-01T14:33:26.80180Z,1298990006.8018 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS

2011-03-01T14:33:26.80220Z,1298990006.8022 [Default:CallGPS:0](INFO): Aggregate::initialize Default:CallGPS:0

2011-03-01T14:34:39.67010Z,1298990079.6701 [Default:CallGPS:0](INFO): Aggregate::uninitialize Default:CallGPS:0

2011-03-01T14:34:39.67060Z,1298990079.6706 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS

2011-03-01T14:38:24.78180Z,1298990304.7818 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium

2011-03-01T14:38:24.78220Z,1298990304.7822 [Default:CallIridium:0](INFO): Aggregate::initialize Default:CallIridium:0

2011-03-01T14:38:29.78260Z,1298990309.7826 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium

2011-03-01T14:39:39.26220Z,1298990379.2622 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS

2011-03-01T14:39:39.26260Z,1298990379.2626 [Default:CallGPS:0](INFO): Aggregate::initialize Default:CallGPS:0

2011-03-01T14:40:45.24540Z,1298990445.2454 [NAL9601](INFO): Received command:run Maintenance/calibrateSparton.xml

2011-03-01T14:40:45.26390Z,1298990445.2639 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/calibrateSparton.xml

2011-03-01T14:40:45.26440Z,1298990445.2644 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/calibrateSparton.xml

2011-03-01T14:40:45.44420Z,1298990445.4442 [MissionManager](INFO): DefineArg MagNorthHeading = 15 arcdeg

2011-03-01T14:40:47.46590Z,1298990447.4659 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/calibrateSparton.xml

2011-03-01T14:40:47.50630Z,1298990447.5063 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium

2011-03-01T14:40:47.50680Z,1298990447.5068 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS

2011-03-01T14:40:47.50710Z,1298990447.5071 [Default:CallGPS:0](INFO): Aggregate::uninitialize Default:CallGPS:0

2011-03-01T14:40:47.50730Z,1298990447.5073 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium

2011-03-01T14:40:47.50760Z,1298990447.5076 [Default:CallIridium:0](INFO): Aggregate::uninitialize Default:CallIridium:0

2011-03-01T14:40:47.50830Z,1298990447.5083 [MissionManager](IMPORTANT): Started mission calibrateSparton

2011-03-01T14:40:47.50860Z,1298990447.5086 [calibrateSparton](INFO): Aggregate::initialize calibrateSparton

2011-03-01T14:44:39.79860Z,1298990679.7986 [calibrateSparton:MassOff](INFO): Aggregate::initialize calibrateSparton:MassOff

2011-03-01T14:44:39.79940Z,1298990679.7994 [calibrateSparton:MassOff:Initialization](INFO): Aggregate::initialize calibrateSparton:MassOff:Initialization

2011-03-01T14:45:28.24180Z,1298990728.2418 [calibrateSparton:MassOff:Initialization:1.Wait](INFO): Done Waiting.

2011-03-01T14:45:28.24280Z,1298990728.2428 [calibrateSparton:MassOff:Initialization](INFO): Completed calibrateSparton:MassOff:Initialization

2011-03-01T14:45:28.24310Z,1298990728.2431 [calibrateSparton:MassOff:Initialization](INFO): Aggregate::uninitialize calibrateSparton:MassOff:Initialization

2011-03-01T14:45:28.24360Z,1298990728.2436 [calibrateSparton:MassOff:PreCircles](INFO): Aggregate::initialize calibrateSparton:MassOff:PreCircles

2011-03-01T14:45:48.64190Z,1298990748.6419 [calibrateSparton:MassOff:PreCircles:1.Wait](INFO): Done Waiting.

2011-03-01T14:46:47.47060Z,1298990807.4706 [calibrateSparton:MassOff:PreCircles](INFO): Completed calibrateSparton:MassOff:PreCircles

2011-03-01T14:46:47.47090Z,1298990807.4709 [calibrateSparton:MassOff:PreCircles](INFO): Aggregate::uninitialize calibrateSparton:MassOff:PreCircles

2011-03-01T14:46:47.47130Z,1298990807.4713 [calibrateSparton:MassOff:PreCircles](INFO): Running loop #2

2011-03-01T14:46:47.47160Z,1298990807.4716 [calibrateSparton:MassOff:PreCircles](INFO): Aggregate::initialize calibrateSparton:MassOff:PreCircles

2011-03-01T14:47:07.87000Z,1298990827.87 [calibrateSparton:MassOff:PreCircles:1.Wait](INFO): Done Waiting.

2011-03-01T14:48:03.91880Z,1298990883.9188 [calibrateSparton:MassOff:PreCircles](INFO): Completed calibrateSparton:MassOff:PreCircles

2011-03-01T14:48:03.91910Z,1298990883.9191 [calibrateSparton:MassOff:PreCircles](INFO): Aggregate::uninitialize calibrateSparton:MassOff:PreCircles

2011-03-01T14:48:03.91960Z,1298990883.9196 [calibrateSparton:MassOff:Calibration](INFO): Aggregate::initialize calibrateSparton:MassOff:Calibration

2011-03-01T14:48:03.98910Z,1298990883.9891 [calibrateSparton:MassOff:Calibration:Manual Loop](INFO): Aggregate::initialize calibrateSparton:MassOff:Calibration:Manual Loop

2011-03-01T14:48:24.24530Z,1298990904.2453 [calibrateSparton:MassOff:Calibration:Manual Loop:1.Wait](INFO): Done Waiting.

2011-03-01T14:49:23.83150Z,1298990963.8315 [calibrateSparton:MassOff:Calibration:Manual Loop](INFO): Completed calibrateSparton:MassOff:Calibration:Manual Loop

2011-03-01T14:49:23.83180Z,1298990963.8318 [calibrateSparton:MassOff:Calibration:Manual Loop](INFO): Aggregate::uninitialize calibrateSparton:MassOff:Calibration:Manual Loop

2011-03-01T14:49:24.29180Z,1298990964.2918 [calibrateSparton:MassOff:Calibration:1.Wait](INFO): Done Waiting.

2011-03-01T14:49:24.29810Z,1298990964.2981 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Aggregate::initialize calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:49:45.04780Z,1298990985.0478 [calibrateSparton:MassOff:Calibration:Auto Loop:1.Wait](INFO): Done Waiting.

2011-03-01T14:50:39.39260Z,1298991039.3926 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Completed calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:50:39.39280Z,1298991039.3928 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Aggregate::uninitialize calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:50:39.39320Z,1298991039.3932 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Running loop #2

2011-03-01T14:50:39.39350Z,1298991039.3935 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Aggregate::initialize calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:50:59.79270Z,1298991059.7927 [calibrateSparton:MassOff:Calibration:Auto Loop:1.Wait](INFO): Done Waiting.

2011-03-01T14:51:54.38170Z,1298991114.3817 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Completed calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:51:54.38200Z,1298991114.382 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Aggregate::uninitialize calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:51:54.38230Z,1298991114.3823 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Running loop #3

2011-03-01T14:51:54.38270Z,1298991114.3827 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Aggregate::initialize calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:52:14.78530Z,1298991134.7853 [calibrateSparton:MassOff:Calibration:Auto Loop:1.Wait](INFO): Done Waiting.

2011-03-01T14:53:10.79530Z,1298991190.7953 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Completed calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:53:10.79560Z,1298991190.7956 [calibrateSparton:MassOff:Calibration:Auto Loop](INFO): Aggregate::uninitialize calibrateSparton:MassOff:Calibration:Auto Loop

2011-03-01T14:53:10.79600Z,1298991190.796 [calibrateSparton:MassOff:Calibration](INFO): Completed calibrateSparton:MassOff:Calibration

2011-03-01T14:53:10.79620Z,1298991190.7962 [calibrateSparton:MassOff:Calibration](INFO): Aggregate::uninitialize calibrateSparton:MassOff:Calibration

2011-03-01T14:53:10.84070Z,1298991190.8407 [calibrateSparton:MassOff:PostCircles](INFO): Aggregate::initialize calibrateSparton:MassOff:PostCircles

2011-03-01T14:53:31.22160Z,1298991211.2216 [calibrateSparton:MassOff:PostCircles:1.Wait](INFO): Done Waiting.

2011-03-01T14:54:21.62260Z,1298991261.6226 [calibrateSparton:MassOff:PostCircles](INFO): Completed calibrateSparton:MassOff:PostCircles

2011-03-01T14:54:21.62290Z,1298991261.6229 [calibrateSparton:MassOff:PostCircles](INFO): Aggregate::uninitialize calibrateSparton:MassOff:PostCircles

2011-03-01T14:54:21.62330Z,1298991261.6233 [calibrateSparton:MassOff:PostCircles](INFO): Running loop #2

2011-03-01T14:54:21.62360Z,1298991261.6236 [calibrateSparton:MassOff:PostCircles](INFO): Aggregate::initialize calibrateSparton:MassOff:PostCircles

2011-03-01T14:54:42.08580Z,1298991282.0858 [calibrateSparton:MassOff:PostCircles:1.Wait](INFO): Done Waiting.

2011-03-01T14:55:37.61050Z,1298991337.6105 [calibrateSparton:MassOff:PostCircles](INFO): Completed calibrateSparton:MassOff:PostCircles

2011-03-01T14:55:37.61080Z,1298991337.6108 [calibrateSparton:MassOff:PostCircles](INFO): Aggregate::uninitialize calibrateSparton:MassOff:PostCircles

2011-03-01T14:55:37.61160Z,1298991337.6116 [calibrateSparton:MassOff](INFO): Completed calibrateSparton:MassOff

2011-03-01T14:55:37.61190Z,1298991337.6119 [calibrateSparton:MassOff](INFO): Aggregate::uninitialize calibrateSparton:MassOff

2011-03-01T14:55:37.61240Z,1298991337.6124 [calibrateSparton:PostCirclesMass](INFO): Aggregate::initialize calibrateSparton:PostCirclesMass

2011-03-01T14:55:58.40550Z,1298991358.4055 [calibrateSparton:PostCirclesMass:1.Wait](INFO): Done Waiting.

2011-03-01T14:56:53.60630Z,1298991413.6063 [calibrateSparton:PostCirclesMass](INFO): Completed calibrateSparton:PostCirclesMass

2011-03-01T14:56:53.60660Z,1298991413.6066 [calibrateSparton:PostCirclesMass](INFO): Aggregate::uninitialize calibrateSparton:PostCirclesMass

2011-03-01T14:56:53.60720Z,1298991413.6072 [calibrateSparton:PostCirclesMass](INFO): Running loop #2

2011-03-01T14:56:53.60750Z,1298991413.6075 [calibrateSparton:PostCirclesMass](INFO): Aggregate::initialize calibrateSparton:PostCirclesMass

2011-03-01T14:57:14.19360Z,1298991434.1936 [calibrateSparton:PostCirclesMass:1.Wait](INFO): Done Waiting.

2011-03-01T14:58:09.41080Z,1298991489.4108 [calibrateSparton:PostCirclesMass](INFO): Completed calibrateSparton:PostCirclesMass

2011-03-01T14:58:09.41110Z,1298991489.4111 [calibrateSparton:PostCirclesMass](INFO): Aggregate::uninitialize calibrateSparton:PostCirclesMass

2011-03-01T14:58:09.41420Z,1298991489.4142 [calibrateSparton](INFO): Completed calibrateSparton

2011-03-01T14:58:09.41450Z,1298991489.4145 [calibrateSparton](INFO): Aggregate::uninitialize calibrateSparton

2011-03-01T14:58:09.79300Z,1298991489.793 [MissionManager](IMPORTANT): Started mission Default

2011-03-01T15:06:27.05740Z,1298991987.0574 [Default:CallIridium:0](INFO): Completed Default:CallIridium:0

2011-03-01T15:06:27.05800Z,1298991987.058 [Default:CallIridium](INFO): Completed Default:CallIridium

2011-03-01T15:21:13.38500Z,1298992873.385 [NAL9601](INFO): Received command:run Engineering/ell_cal_validation.xml

2011-03-01T15:21:13.40900Z,1298992873.409 [CommandLine](IMPORTANT): got command run ./Missions/Engineering/ell_cal_validation.xml

2011-03-01T15:21:13.40940Z,1298992873.4094 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/ell_cal_validation.xml

2011-03-01T15:21:13.72710Z,1298992873.7271 [MissionManager](INFO): DefineArg CompassNorthHeading = 0 arcdeg

2011-03-01T15:21:13.73060Z,1298992873.7306 [MissionManager](INFO): DefineArg CompassWestHeading = 270 arcdeg

2011-03-01T15:21:13.73400Z,1298992873.734 [MissionManager](INFO): DefineArg CompassSouthHeading = 180 arcdeg

2011-03-01T15:21:13.73760Z,1298992873.7376 [MissionManager](INFO): DefineArg CompassEastHeading = 90 arcdeg

2011-03-01T15:21:13.83240Z,1298992873.8324 [MissionManager](INFO): DefineArg SurfaceGPSFixes = 20 count

2011-03-01T15:21:14.14080Z,1298992874.1408 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceSpiral.xml

2011-03-01T15:21:14.37990Z,1298992874.3799 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml

2011-03-01T15:21:14.45620Z,1298992874.4562 [MissionManager](INFO): DefineArg PeakDetectChlActive = 0 bool

2011-03-01T15:21:14.45950Z,1298992874.4595 [MissionManager](INFO): DefineArg PeakDetectNO3Active = 0 bool

2011-03-01T15:21:16.60450Z,1298992876.6045 [CommandLine](IMPORTANT): Running ./Missions/Engineering/ell_cal_validation.xml

2011-03-01T15:21:16.84780Z,1298992876.8478 [MissionManager](IMPORTANT): Started mission square_cal_validation

2011-03-01T15:21:16.84810Z,1298992876.8481 [square_cal_validation](INFO): Aggregate::initialize square_cal_validation

2011-03-01T15:21:16.89050Z,1298992876.8905 [square_cal_validation:Science](INFO): Aggregate::initialize square_cal_validation:Science

2011-03-01T15:21:16.89140Z,1298992876.8914 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Aggregate::initialize square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T15:21:16.92740Z,1298992876.9274 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Aggregate::initialize square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T15:23:48.51010Z,1298993028.5101 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Completed square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T15:23:48.51040Z,1298993028.5104 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Aggregate::uninitialize square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T15:23:48.51130Z,1298993028.5113 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Completed square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T15:23:48.51160Z,1298993028.5116 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Aggregate::uninitialize square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T15:23:48.51220Z,1298993028.5122 [square_cal_validation:WaypointOne](INFO): Aggregate::initialize square_cal_validation:WaypointOne

2011-03-01T15:36:23.03190Z,1298993783.0319 [BuoyancyServo](ERROR): EZ Servo:6 reading outside of valid range:0

2011-03-01T15:36:23.03210Z,1298993783.0321 [BuoyancyServo](FAULT): Buoyancy engine reporting null position

2011-03-01T15:36:23.09020Z,1298993783.0902 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo

2011-03-01T15:36:23.48240Z,1298993783.4824 [CBIT](INFO): Clearing failed state for component BuoyancyServo

2011-03-01T15:39:37.28740Z,1298993977.2874 [BuoyancyServo](ERROR): EZ Servo:6 reading outside of valid range:0

2011-03-01T15:39:37.28790Z,1298993977.2879 [BuoyancyServo](FAULT): Buoyancy engine reporting null position

2011-03-01T15:39:37.34540Z,1298993977.3454 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo

2011-03-01T15:39:37.70130Z,1298993977.7013 [CBIT](INFO): Clearing failed state for component BuoyancyServo

2011-03-01T15:40:54.10760Z,1298994054.1076 [BuoyancyServo](ERROR): EZ Servo:6 reading outside of valid range:0

2011-03-01T15:40:54.10790Z,1298994054.1079 [BuoyancyServo](FAULT): Buoyancy engine reporting null position

2011-03-01T15:40:54.16540Z,1298994054.1654 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo

2011-03-01T15:40:54.56910Z,1298994054.5691 [CBIT](INFO): Clearing failed state for component BuoyancyServo

2011-03-01T15:49:40.71900Z,1298994580.719 [BuoyancyServo](ERROR): getPosition uart error serial timeout

2011-03-01T15:49:40.71920Z,1298994580.7192 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout

2011-03-01T15:49:40.76950Z,1298994580.7695 [CBIT](ERROR): Communications Fault in component: BuoyancyServo

2011-03-01T15:55:01.43090Z,1298994901.4309 [BuoyancyServo](ERROR): getPosition uart error serial timeout

2011-03-01T15:55:01.43120Z,1298994901.4312 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout

2011-03-01T15:55:01.48100Z,1298994901.481 [CBIT](ERROR): Communications Fault in component: BuoyancyServo

2011-03-01T15:56:12.31170Z,1298994972.3117 [square_cal_validation:WaypointOne](INFO): Completed square_cal_validation:WaypointOne

2011-03-01T15:56:12.31200Z,1298994972.312 [square_cal_validation:WaypointOne](INFO): Aggregate::uninitialize square_cal_validation:WaypointOne

2011-03-01T15:56:12.31300Z,1298994972.313 [square_cal_validation:11](INFO): Aggregate::initialize square_cal_validation:11

2011-03-01T15:56:12.70800Z,1298994972.708 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Aggregate::initialize square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T15:57:57.85840Z,1298995077.8584 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Aggregate::initialize square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T16:03:48.31230Z,1298995428.3123 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Completed square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T16:03:48.31260Z,1298995428.3126 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Aggregate::uninitialize square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T16:03:48.31320Z,1298995428.3132 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Completed square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T16:03:48.31350Z,1298995428.3135 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Aggregate::uninitialize square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T16:03:48.71050Z,1298995428.7105 [square_cal_validation:11](INFO): Aggregate::uninitialize square_cal_validation:11

2011-03-01T16:03:48.71090Z,1298995428.7109 [square_cal_validation:North](INFO): Aggregate::initialize square_cal_validation:North

2011-03-01T16:03:48.71320Z,1298995428.7132 [square_cal_validation:North:0](INFO): Aggregate::initialize square_cal_validation:North:0

2011-03-01T16:03:49.32870Z,1298995429.3287 [square_cal_validation:SurfaceGPS](INFO): Aggregate::initialize square_cal_validation:SurfaceGPS

2011-03-01T16:03:49.32960Z,1298995429.3296 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Aggregate::initialize square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:03:50.54690Z,1298995430.5469 [BuoyancyServo](ERROR): getPosition uart error serial timeout

2011-03-01T16:03:50.54720Z,1298995430.5472 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout

2011-03-01T16:03:50.59720Z,1298995430.5972 [CBIT](ERROR): Communications Fault in component: BuoyancyServo

2011-03-01T16:03:50.74560Z,1298995430.7456 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop:0](INFO): Running loop #2

2011-03-01T16:03:52.79680Z,1298995432.7968 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop:0](INFO): Running loop #3

2011-03-01T16:03:54.74480Z,1298995434.7448 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop:0](INFO): Running loop #4

2011-03-01T16:04:29.67280Z,1298995469.6728 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Completed square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:04:29.67300Z,1298995469.673 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Aggregate::uninitialize square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:04:29.67330Z,1298995469.6733 [square_cal_validation:SurfaceGPS](INFO): Completed square_cal_validation:SurfaceGPS

2011-03-01T16:04:29.67350Z,1298995469.6735 [square_cal_validation:SurfaceGPS](INFO): Aggregate::uninitialize square_cal_validation:SurfaceGPS

2011-03-01T16:04:30.08810Z,1298995470.0881 [square_cal_validation:North:0](INFO): Completed square_cal_validation:North:0

2011-03-01T16:04:30.08840Z,1298995470.0884 [square_cal_validation:North:0](INFO): Aggregate::uninitialize square_cal_validation:North:0

2011-03-01T16:04:30.08880Z,1298995470.0888 [square_cal_validation:North:1](INFO): Aggregate::initialize square_cal_validation:North:1

2011-03-01T16:04:30.48730Z,1298995470.4873 [square_cal_validation:DiveandPump](INFO): Aggregate::initialize square_cal_validation:DiveandPump

2011-03-01T16:04:30.50770Z,1298995470.5077 [square_cal_validation:DiveandPump](INFO): Completed square_cal_validation:DiveandPump

2011-03-01T16:04:30.50800Z,1298995470.508 [square_cal_validation:DiveandPump](INFO): Aggregate::uninitialize square_cal_validation:DiveandPump

2011-03-01T16:04:30.90020Z,1298995470.9002 [square_cal_validation:North:1](INFO): Completed square_cal_validation:North:1

2011-03-01T16:04:30.90290Z,1298995470.9029 [square_cal_validation:North:1](INFO): Aggregate::uninitialize square_cal_validation:North:1

2011-03-01T16:21:11.40370Z,1298996471.4037 [square_cal_validation:North:3](INFO): Aggregate::initialize square_cal_validation:North:3

2011-03-01T16:21:11.77770Z,1298996471.7777 [square_cal_validation:SurfaceGPS](INFO): Aggregate::initialize square_cal_validation:SurfaceGPS

2011-03-01T16:21:11.77890Z,1298996471.7789 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Aggregate::initialize square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:23:56.92790Z,1298996636.9279 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Completed square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:23:56.92820Z,1298996636.9282 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Aggregate::uninitialize square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:23:56.92850Z,1298996636.9285 [square_cal_validation:SurfaceGPS](INFO): Completed square_cal_validation:SurfaceGPS

2011-03-01T16:23:56.92870Z,1298996636.9287 [square_cal_validation:SurfaceGPS](INFO): Aggregate::uninitialize square_cal_validation:SurfaceGPS

2011-03-01T16:23:57.40810Z,1298996637.4081 [square_cal_validation:North:3](INFO): Completed square_cal_validation:North:3

2011-03-01T16:23:57.40830Z,1298996637.4083 [square_cal_validation:North:3](INFO): Aggregate::uninitialize square_cal_validation:North:3

2011-03-01T16:23:57.40860Z,1298996637.4086 [square_cal_validation:North](INFO): Completed square_cal_validation:North

2011-03-01T16:23:57.40890Z,1298996637.4089 [square_cal_validation:North](INFO): Aggregate::uninitialize square_cal_validation:North

2011-03-01T16:23:57.40960Z,1298996637.4096 [square_cal_validation:13](INFO): Aggregate::initialize square_cal_validation:13

2011-03-01T16:23:57.75150Z,1298996637.7515 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Aggregate::initialize square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T16:23:57.75690Z,1298996637.7569 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Aggregate::initialize square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T16:27:35.10180Z,1298996855.1018 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Completed square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T16:27:35.10210Z,1298996855.1021 [square_cal_validation:SURFACESPIRALCOMMS:2](INFO): Aggregate::uninitialize square_cal_validation:SURFACESPIRALCOMMS:2

2011-03-01T16:27:35.10280Z,1298996855.1028 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Completed square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T16:27:35.10300Z,1298996855.103 [square_cal_validation:SURFACESPIRALCOMMS](INFO): Aggregate::uninitialize square_cal_validation:SURFACESPIRALCOMMS

2011-03-01T16:27:35.50650Z,1298996855.5065 [square_cal_validation:13](INFO): Aggregate::uninitialize square_cal_validation:13

2011-03-01T16:27:35.50700Z,1298996855.507 [square_cal_validation:WaypointTwo](INFO): Aggregate::initialize square_cal_validation:WaypointTwo

2011-03-01T16:34:15.02240Z,1298997255.0224 [square_cal_validation:WaypointTwo](INFO): Completed square_cal_validation:WaypointTwo

2011-03-01T16:34:15.02310Z,1298997255.0231 [square_cal_validation:WaypointTwo](INFO): Aggregate::uninitialize square_cal_validation:WaypointTwo

2011-03-01T16:34:15.02400Z,1298997255.024 [square_cal_validation:15](INFO): Aggregate::initialize square_cal_validation:15

2011-03-01T16:39:50.03050Z,1298997590.0305 [square_cal_validation:15](INFO): Aggregate::uninitialize square_cal_validation:15

2011-03-01T16:39:50.03100Z,1298997590.031 [square_cal_validation:South](INFO): Aggregate::initialize square_cal_validation:South

2011-03-01T16:39:50.03320Z,1298997590.0332 [square_cal_validation:South:0](INFO): Aggregate::initialize square_cal_validation:South:0

2011-03-01T16:39:50.64410Z,1298997590.6441 [square_cal_validation:SurfaceGPS](INFO): Aggregate::initialize square_cal_validation:SurfaceGPS

2011-03-01T16:39:50.64490Z,1298997590.6449 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Aggregate::initialize square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:40:30.22840Z,1298997630.2284 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Completed square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:40:30.22870Z,1298997630.2287 [square_cal_validation:SurfaceGPS:SurfaceGPSLoop](INFO): Aggregate::uninitialize square_cal_validation:SurfaceGPS:SurfaceGPSLoop

2011-03-01T16:40:30.22900Z,1298997630.229 [square_cal_validation:SurfaceGPS](INFO): Completed square_cal_validation:SurfaceGPS

2011-03-01T16:40:30.22920Z,1298997630.2292 [square_cal_validation:SurfaceGPS](INFO): Aggregate::uninitialize square_cal_validation:SurfaceGPS

2011-03-01T16:40:30.63120Z,1298997630.6312 [square_cal_validation:South:0](INFO): Completed square_cal_validation:South:0

2011-03-01T16:40:30.63150Z,1298997630.6315 [square_cal_validation:South:0](INFO): Aggregate::uninitialize square_cal_validation:South:0

2011-03-01T16:40:30.63190Z,1298997630.6319 [square_cal_validation:South:1](INFO): Aggregate::initialize square_cal_validation:South:1

2011-03-01T16:40:31.02370Z,1298997631.0237 [square_cal_validation:DiveandPump](INFO): Aggregate::initialize square_cal_validation:DiveandPump

2011-03-01T16:40:31.02630Z,1298997631.0263 [square_cal_validation:DiveandPump](INFO): Completed square_cal_validation:DiveandPump

2011-03-01T16:40:31.02660Z,1298997631.0266 [square_cal_validation:DiveandPump](INFO): Aggregate::uninitialize square_cal_validation:DiveandPump

2011-03-01T16:40:31.42370Z,1298997631.4237 [square_cal_validation:South:1](INFO): Completed square_cal_validation:South:1

2011-03-01T16:40:31.42400Z,1298997631.424 [square_cal_validation:South:1](INFO): Aggregate::uninitialize square_cal_validation:South:1

2011-03-01T16:57:12.10810Z,1298998632.1081 [square_cal_validation:South:3](INFO): Aggregate::initialize square_cal_validation:South:3

2011-03-01T16:59:56.31800Z,1298998796.318 [square_cal_validation:South:3](INFO): Completed square_cal_validation:South:3

2011-03-01T16:59:56.31830Z,1298998796.3183 [square_cal_validation:South:3](INFO): Aggregate::uninitialize square_cal_validation:South:3

2011-03-01T16:59:56.31860Z,1298998796.3186 [square_cal_validation:South](INFO): Completed square_cal_validation:South

2011-03-01T16:59:56.31900Z,1298998796.319 [square_cal_validation:South](INFO): Aggregate::uninitialize square_cal_validation:South

2011-03-01T17:04:10.93880Z,1298999050.9388 [square_cal_validation:WaypointThree](INFO): Aggregate::initialize square_cal_validation:WaypointThree

2011-03-01T17:08:28.68660Z,1298999308.6866 [square_cal_validation:WaypointThree](INFO): Completed square_cal_validation:WaypointThree

2011-03-01T17:08:28.68690Z,1298999308.6869 [square_cal_validation:WaypointThree](INFO): Aggregate::uninitialize square_cal_validation:WaypointThree

2011-03-01T17:15:08.04030Z,1298999708.0403 [square_cal_validation](INFO): Aggregate::uninitialize square_cal_validation

2011-03-01T17:15:08.04380Z,1298999708.0438 [square_cal_validation:Science](INFO): Aggregate::uninitialize square_cal_validation:Science

2011-03-01T17:15:08.31620Z,1298999708.3162 [MissionManager](IMPORTANT): Started mission Default

2011-03-01T17:15:55.59120Z,1298999755.5912 [CommandLine](IMPORTANT): got command run ./Missions/Transport/transit_2km.xml

2011-03-01T17:15:55.59160Z,1298999755.5916 [MissionManager](INFO): Loading Mission: ./Missions/Transport/transit_2km.xml

2011-03-01T17:15:55.92610Z,1298999755.9261 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml

2011-03-01T17:15:56.24200Z,1298999756.242 [CommandLine](IMPORTANT): Running ./Missions/Transport/transit_2km.xml

2011-03-01T17:15:56.61440Z,1298999756.6144 [MissionManager](IMPORTANT): Started mission transit_2km

2011-03-01T17:15:56.64030Z,1298999756.6403 [transit_2km:SURFACECOMMS](INFO): Aggregate::initialize transit_2km:SURFACECOMMS

2011-03-01T17:15:56.65340Z,1298999756.6534 [transit_2km:SURFACECOMMS:1](INFO): Aggregate::initialize transit_2km:SURFACECOMMS:1

2011-03-01T17:17:13.35400Z,1298999833.354 [transit_2km:SURFACECOMMS:1](INFO): Completed transit_2km:SURFACECOMMS:1

2011-03-01T17:17:13.35430Z,1298999833.3543 [transit_2km:SURFACECOMMS:1](INFO): Aggregate::uninitialize transit_2km:SURFACECOMMS:1

2011-03-01T17:17:13.35530Z,1298999833.3553 [transit_2km:SURFACECOMMS](INFO): Completed transit_2km:SURFACECOMMS

2011-03-01T17:17:13.35560Z,1298999833.3556 [transit_2km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_2km:SURFACECOMMS

2011-03-01T17:17:13.35610Z,1298999833.3561 [transit_2km:WaypointOne](INFO): Aggregate::initialize transit_2km:WaypointOne

2011-03-01T17:45:19.58710Z,1299001519.5871 [transit_2km:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize transit_2km:WaypointOne:PHONEHOMEWPT1

2011-03-01T17:45:19.97760Z,1299001519.9776 [transit_2km:SURFACECOMMS](INFO): Aggregate::initialize transit_2km:SURFACECOMMS

2011-03-01T17:46:50.76760Z,1299001610.7676 [transit_2km:SURFACECOMMS:1](INFO): Aggregate::initialize transit_2km:SURFACECOMMS:1

2011-03-01T17:50:51.53520Z,1299001851.5352 [CommandLine](IMPORTANT): got command run ./Missions/Transport/transit_1km.xml

2011-03-01T17:50:51.53680Z,1299001851.5368 [transit_2km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_2km:SURFACECOMMS

2011-03-01T17:50:51.53740Z,1299001851.5374 [transit_2km:SURFACECOMMS:1](INFO): Aggregate::uninitialize transit_2km:SURFACECOMMS:1

2011-03-01T17:50:51.53800Z,1299001851.538 [transit_2km:WaypointOne](INFO): Aggregate::uninitialize transit_2km:WaypointOne

2011-03-01T17:50:51.53860Z,1299001851.5386 [transit_2km:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize transit_2km:WaypointOne:PHONEHOMEWPT1

2011-03-01T17:50:51.54860Z,1299001851.5486 [MissionManager](INFO): Loading Mission: ./Missions/Transport/transit_1km.xml

2011-03-01T17:50:51.99020Z,1299001851.9902 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml

2011-03-01T17:50:52.27250Z,1299001852.2725 [CommandLine](IMPORTANT): Running ./Missions/Transport/transit_1km.xml

2011-03-01T17:50:54.30070Z,1299001854.3007 [MissionManager](IMPORTANT): Started mission transit_1km

2011-03-01T17:50:54.30630Z,1299001854.3063 [transit_1km:SURFACECOMMS](INFO): Aggregate::initialize transit_1km:SURFACECOMMS

2011-03-01T17:50:54.31910Z,1299001854.3191 [transit_1km:SURFACECOMMS:1](INFO): Aggregate::initialize transit_1km:SURFACECOMMS:1

2011-03-01T17:52:08.58390Z,1299001928.5839 [transit_1km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_1km:SURFACECOMMS

2011-03-01T17:52:08.58430Z,1299001928.5843 [transit_1km:SURFACECOMMS:1](INFO): Aggregate::uninitialize transit_1km:SURFACECOMMS:1

2011-03-01T17:53:02.67930Z,1299001982.6793 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Surface.xml

2011-03-01T17:53:02.67960Z,1299001982.6796 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Surface.xml

2011-03-01T17:53:02.74310Z,1299001982.7431 [CommandLine](IMPORTANT): Running ./Missions/Insert/Surface.xml

2011-03-01T17:53:02.89430Z,1299001982.8943 [MissionManager](IMPORTANT): Started mission SurfaceComms

2011-03-01T17:53:02.89460Z,1299001982.8946 [SurfaceComms](INFO): Aggregate::initialize SurfaceComms

2011-03-01T17:54:11.29750Z,1299002051.2975 [SurfaceComms:1](INFO): Aggregate::uninitialize SurfaceComms:1

2011-03-01T17:54:11.29840Z,1299002051.2984 [SurfaceComms](INFO): Aggregate::uninitialize SurfaceComms