2011-06-21 15:20:07,374-0500 DEBUG Loader Max heap: 3817799680
2011-06-21 15:20:07,378-0500 DEBUG textfiles BEGIN SWIFTSCRIPT:
import "SwiftMontage";

Header header <"header.hdr">;
Table images_tbl <"images.tbl">;
Table difference_tbl <"diffs.tbl">;
Table fits_images_tbl <"fits.tbl">;
Table corrections_tbl <"corrections.tbl">;
Table stat_tbl <"stats.tbl">;
Image rectified_mos <"final/rectified.fits">;
JPEG rectified_jpg_img <"final/rectified.jpg">;
Image projected_images[];
Image difference_images[];
Image rectified_images[];

Image raw_image_files[] <filesys_mapper; location = "raw_dir", suffix = ".fits">;

projected_images = mProjectBatch( "proj_dir", raw_image_files, header );

images_tbl = mImgtbl( projected_images );

difference_tbl = mOverlaps( images_tbl );

( difference_images, fits_images_tbl ) = mDiffFitBatch( "proj_dir", "diff_dir", "stat_dir", @filenames(projected_images), difference_tbl, header );

corrections_tbl = mBgModel( images_tbl, fits_images_tbl );

rectified_images = mBackgroundBatch( "corr_dir", projected_images, images_tbl, corrections_tbl );

rectified_mos = mAdd( rectified_images, images_tbl, header );

rectified_jpg_img = mJPEG( rectified_mos );


2011-06-21 15:20:07,379-0500 DEBUG textfiles END SWIFTSCRIPT:
2011-06-21 15:20:07,382-0500 INFO  Loader montage.swift: source file is new. Recompiling.
2011-06-21 15:20:07,979-0500 INFO  Karajan Validation of XML intermediate file was successful
2011-06-21 15:20:08,016-0500 INFO  Karajan Validation of XML intermediate file was successful
2011-06-21 15:20:08,122-0500 INFO  Karajan Validation of XML intermediate file was successful
2011-06-21 15:20:08,187-0500 INFO  Karajan Validation of XML intermediate file was successful
2011-06-21 15:20:08,213-0500 INFO  Karajan Validation of XML intermediate file was successful
2011-06-21 15:20:08,663-0500 DEBUG Loader setting: sites.file to: /autonfs/gpfs-pads/projects/CI-CCR000013/jonmon/Swift/Montage/m101_tutorial/run.0009/sites.xml
2011-06-21 15:20:08,663-0500 DEBUG Loader setting: tc.file to: /autonfs/gpfs-pads/projects/CI-CCR000013/jonmon/Swift/Montage/m101_tutorial/run.0009/tc.data
2011-06-21 15:20:08,666-0500 DEBUG textfiles BEGIN SITES:
<config>
<pool handle="localhost">
   <execution provider="local" />
   <filesystem provider="local" />
   <workdirectory>/gpfs/pads/swift/jonmon/Swift/work/localhost</workdirectory>
 
   <profile namespace="karajan" key="jobThrottle">.05</profile>
 
   <profile namespace="env" key="SWIFT_GEN_SCRIPTS">KEEP</profile>
 </pool>
 <pool handle="pads">
     <execution provider="coaster" jobmanager="ssh:pbs" url="login.pads.ci.uchicago.edu" />
     <filesystem provider="local" />
     <profile namespace="globus" key="project">CI-CCR000013</profile>
     <workdirectory>/gpfs/pads/swift/jonmon/Swift/work/pads</workdirectory>
 
     <profile namespace="globus" key="maxtime">3600</profile>
     <profile namespace="globus" key="jobsPerNode">1</profile>
     <profile namespace="globus" key="slots">500</profile>
     <profile namespace="globus" key="nodeGranularity">1</profile>
     <profile namespace="globus" key="maxNodes">1</profile>
     <profile namespace="globus" key="queue">fast</profile>
 
     <profile namespace="karajan" key="jobThrottle">5</profile>
     <profile namespace="karajan" key="initialScore">10000</profile>
 
     <profile namespace="env" key="SWIFT_GEN_SCRIPTS">KEEP</profile>
 </pool>
</config>


2011-06-21 15:20:08,666-0500 DEBUG textfiles END SITES:
2011-06-21 15:20:08,683-0500 INFO  VDL2ExecutionContext Stack dump:
Level 1
[iA = 0, iB = 0, bA = false, bB = false]
	vdl:instanceconfig = Swift configuration []
	vdl:operation = run
	PATH_SEPARATOR = /
	swift.home = /home/jonmon/Library/Swift/bin/..
	#vdl:futureWrapperMap = org.griphyn.vdl.karajan.WrapperMap@6a47a0d4


2011-06-21 15:20:09,165-0500 INFO  unknown Using sites file: /autonfs/gpfs-pads/projects/CI-CCR000013/jonmon/Swift/Montage/m101_tutorial/run.0009/sites.xml
2011-06-21 15:20:09,197-0500 INFO  unknown Using tc.data: /autonfs/gpfs-pads/projects/CI-CCR000013/jonmon/Swift/Montage/m101_tutorial/run.0009/tc.data
2011-06-21 15:20:09,296-0500 INFO  AbstractScheduler Setting resources to: {pads=pads, localhost=localhost}
2011-06-21 15:20:09,666-0500 INFO  unknown Swift svn swift-r4651 cog-r3167

2011-06-21 15:20:09,667-0500 INFO  unknown RUNID id=run:20110621-1520-hj2se96g
2011-06-21 15:20:09,726-0500 INFO  AbstractDataNode Found data header:Header - Closed.$
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[0]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[1]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[2]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[3]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[4]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[5]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[6]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[7]
2011-06-21 15:20:09,806-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[8]
2011-06-21 15:20:09,807-0500 INFO  AbstractDataNode Found data raw_image_files:Image[] - Open.[9]
2011-06-21 15:20:09,840-0500 INFO  SetFieldValue Set: swift#mapper#17036=?:string = raw_dir - Closed
2011-06-21 15:20:09,840-0500 INFO  SetFieldValue Set: swift#mapper#17038=?:string = .fits - Closed
2011-06-21 15:20:09,848-0500 DEBUG mimgtbl PROCEDURE line=43 thread=0-3 name=mImgtbl
2011-06-21 15:20:09,848-0500 DEBUG moverlaps PROCEDURE line=61 thread=0-4 name=mOverlaps
2011-06-21 15:20:09,849-0500 DEBUG mjpeg PROCEDURE line=55 thread=0-9 name=mJPEG
2011-06-21 15:20:09,850-0500 DEBUG mbgmodel PROCEDURE line=13 thread=0-6 name=mBgModel
2011-06-21 15:20:09,851-0500 DEBUG madd PROCEDURE line=1 thread=0-8 name=mAdd
2011-06-21 15:20:09,851-0500 INFO  mbackgroundbatch STARTCOMPOUND thread=0-7 name=mBackgroundBatch
2011-06-21 15:20:09,854-0500 DEBUG mbackgroundbatch INTERNALPROC_START thread=0-7-1 name=readData2
2011-06-21 15:20:09,855-0500 INFO  mprojectbatch STARTCOMPOUND thread=0-2 name=mProjectBatch
2011-06-21 15:20:09,857-0500 DEBUG background_list PROCEDURE line=89 thread=0-7-0 name=Background_list
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-2
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-0
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-3
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-5
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-6
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-7
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-8
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-9
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-2
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-0
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-5
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-3
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-7
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-1
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-8
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-1
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-9
2011-06-21 15:20:09,862-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-6
2011-06-21 15:20:09,877-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,878-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,878-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,879-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_8.fits - Closed
2011-06-21 15:20:09,879-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_9.fits - Closed
2011-06-21 15:20:09,861-0500 DEBUG mprojectbatch FOREACH_IT_START line=80 thread=0-2-1-4
2011-06-21 15:20:09,880-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_10.fits - Closed
2011-06-21 15:20:09,880-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,880-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,881-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,881-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,879-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_2.fits - Closed
2011-06-21 15:20:09,879-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_7.fits - Closed
2011-06-21 15:20:09,878-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,878-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,882-0500 DEBUG mprojectbatch SCOPE thread=0-2-1-4
2011-06-21 15:20:09,882-0500 INFO  SetFieldValue Set: projected_images[1]=proj_img:Image - Open
2011-06-21 15:20:09,880-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,882-0500 INFO  SetFieldValue Set: projected_images[4]=proj_img:Image - Open
2011-06-21 15:20:09,882-0500 INFO  SetFieldValue Set: projected_images[9]=proj_img:Image - Open
2011-06-21 15:20:09,887-0500 INFO  SetFieldValue Set: projected_images[0]=proj_img:Image - Open
2011-06-21 15:20:09,887-0500 INFO  SetFieldValue Set: projected_images[2]=proj_img:Image - Open
2011-06-21 15:20:09,887-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,888-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,888-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_3.fits - Closed
2011-06-21 15:20:09,888-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,888-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_4.fits - Closed
2011-06-21 15:20:09,888-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,889-0500 INFO  SetFieldValue Set: projected_images[3]=proj_img:Image - Open
2011-06-21 15:20:09,889-0500 INFO  SetFieldValue Set: projected_images[6]=proj_img:Image - Open
2011-06-21 15:20:09,891-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-7-3 name=mProject
2011-06-21 15:20:09,891-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-3-3 name=mProject
2011-06-21 15:20:09,892-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-8-3 name=mProject
2011-06-21 15:20:09,892-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-5-3 name=mProject
2011-06-21 15:20:09,892-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-2-3 name=mProject
2011-06-21 15:20:09,891-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-1-3 name=mProject
2011-06-21 15:20:09,894-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,894-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_6.fits - Closed
2011-06-21 15:20:09,894-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,895-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,895-0500 INFO  SetFieldValue Set: projected_images[8]=proj_img:Image - Open
2011-06-21 15:20:09,895-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_5.fits - Closed
2011-06-21 15:20:09,896-0500 INFO  SetFieldValue Set: projected_images[5]=proj_img:Image - Open
2011-06-21 15:20:09,896-0500 INFO  SetFieldValue Set: swift#mapper#17033=?:string = .*\/(.*) - Closed
2011-06-21 15:20:09,896-0500 INFO  SetFieldValue Set: swift#mapper#17032=?:string = raw_dir/raw_image_1.fits - Closed
2011-06-21 15:20:09,896-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,897-0500 INFO  SetFieldValue Set: projected_images[7]=proj_img:Image - Open
2011-06-21 15:20:09,897-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-9-3 name=mProject
2011-06-21 15:20:09,892-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-0-3 name=mProject
2011-06-21 15:20:09,897-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-6-3 name=mProject
2011-06-21 15:20:09,898-0500 DEBUG mproject PROCEDURE line=73 thread=0-2-1-4-3 name=mProject
2011-06-21 15:20:09,895-0500 INFO  SetFieldValue Set: swift#mapper#17034=?:string = proj_dir/proj_\1 - Closed
2011-06-21 15:20:09,899-0500 INFO  vdl:execute START thread=0-2-1-3-3 tr=mProject
2011-06-21 15:20:09,900-0500 INFO  vdl:execute START thread=0-2-1-9-3 tr=mProject
2011-06-21 15:20:09,900-0500 INFO  vdl:execute START thread=0-2-1-0-3 tr=mProject
2011-06-21 15:20:09,900-0500 INFO  vdl:execute START thread=0-2-1-2-3 tr=mProject
2011-06-21 15:20:09,901-0500 INFO  vdl:execute START thread=0-2-1-8-3 tr=mProject
2011-06-21 15:20:09,901-0500 INFO  vdl:execute START thread=0-2-1-4-3 tr=mProject
2011-06-21 15:20:09,901-0500 INFO  vdl:execute START thread=0-2-1-6-3 tr=mProject
2011-06-21 15:20:09,902-0500 INFO  vdl:execute START thread=0-2-1-1-3 tr=mProject
2011-06-21 15:20:09,902-0500 INFO  vdl:execute START thread=0-2-1-5-3 tr=mProject
2011-06-21 15:20:09,901-0500 INFO  vdl:execute START thread=0-2-1-7-3 tr=mProject
2011-06-21 15:20:09,914-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,915-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,915-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,915-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,915-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,916-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,916-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,916-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,916-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,916-0500 INFO  WeightedHostScoreScheduler CONTACT_SELECTED host=pads, score=99.854
2011-06-21 15:20:09,918-0500 INFO  GlobalSubmitQueue No global submit throttle set. Using default (1024)
2011-06-21 15:20:09,923-0500 INFO  LateBindingScheduler JobQueue: 0
2011-06-21 15:20:09,931-0500 INFO  LateBindingScheduler JobQueue: 0
2011-06-21 15:20:09,934-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-bb510xbk thread=0-2-1-5-3-1 host=pads replicationGroup=8b510xbk
2011-06-21 15:20:09,934-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-db510xbk thread=0-2-1-3-3-1 host=pads replicationGroup=1b510xbk
2011-06-21 15:20:09,935-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-eb510xbk thread=0-2-1-8-3-1 host=pads replicationGroup=4b510xbk
2011-06-21 15:20:09,936-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-kb510xbk thread=0-2-1-1-3-1 host=pads replicationGroup=7b510xbk
2011-06-21 15:20:09,940-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-bb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_4.fits - Closed, ?:string = proj_dir/proj_raw_image_4.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/b/mProject-bb510xbk host=pads
2011-06-21 15:20:09,940-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-kb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_10.fits - Closed, ?:string = proj_dir/proj_raw_image_10.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/k/mProject-kb510xbk host=pads
2011-06-21 15:20:09,940-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-db510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_2.fits - Closed, ?:string = proj_dir/proj_raw_image_2.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/d/mProject-db510xbk host=pads
2011-06-21 15:20:09,940-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-eb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_7.fits - Closed, ?:string = proj_dir/proj_raw_image_7.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/e/mProject-eb510xbk host=pads
2011-06-21 15:20:09,934-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-gb510xbk thread=0-2-1-6-3-1 host=pads replicationGroup=6b510xbk
2011-06-21 15:20:09,934-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-fb510xbk thread=0-2-1-4-3-1 host=pads replicationGroup=5b510xbk
2011-06-21 15:20:09,935-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-jb510xbk thread=0-2-1-0-3-1 host=pads replicationGroup=2b510xbk
2011-06-21 15:20:09,934-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-cb510xbk thread=0-2-1-2-3-1 host=pads replicationGroup=3b510xbk
2011-06-21 15:20:09,935-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-ib510xbk thread=0-2-1-9-3-1 host=pads replicationGroup=ab510xbk
2011-06-21 15:20:09,946-0500 DEBUG textfiles BEGIN TC:
localhost	mImgtbl			/home/jonmon/Library/Montage/bin/mImgtbl				INSTALLED	INTEL32::LINUX	null
localhost	mAdd			/home/jonmon/Library/Montage/bin/mAdd					INSTALLED	INTEL32::LINUX	null
localhost	mJPEG			/home/jonmon/Library/Montage/bin/mJPEG					INSTALLED	INTEL32::LINUX	null
localhost	mOverlaps		/home/jonmon/Library/Montage/bin/mOverlaps				INSTALLED	INTEL32::LINUX	null
localhost	mConcatFit		/home/jonmon/Library/Montage/bin/mConcatFit				INSTALLED	INTEL32::LINUX	null
localhost	mBgModel		/home/jonmon/Library/Montage/bin/mBgModel				INSTALLED	INTEL32::LINUX	null
localhost	mMakeHdr		/home/jonmon/Library/Montage/bin/mMakeHdr				INSTALLED	INTEL32::LINUX	null
localhost	Background_list		/home/jonmon/Library/Swift/apps/Montage/scripts/Background_list.py		INSTALLED	INTEL32::LINUX	null
localhost	create_status_table	/home/jonmon/Library/Swift/apps/Montage/scripts/create_status_table.py	INSTALLED	INTEL32::LINUX	null
pads	mProjectPP	/home/jonmon/Library/Montage/bin/mProjectPP				INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	mProject	/home/jonmon/Library/Montage/bin/mProject				INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:05:00"
pads	mFitplane	/home/jonmon/Library/Montage/bin/mFitplane				INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	mDiff		/home/jonmon/Library/Montage/bin/mDiff					INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	mFitplane_wrap	/home/jonmon/Library/Swift/apps/Montage/scripts/mFitplane_wrap.sh	INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	mDiff_wrap	/home/jonmon/Library/Swift/apps/Montage/scripts/mDiff_wrap.sh		INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	mDiffFit_wrap	/home/jonmon/Library/Swift/apps/Montage/scripts/mDiffFit_wrap.sh	INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	mBackground	/home/jonmon/Library/Montage/bin/mBackground				INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"

pads	mDiffFit	/home/jonmon/Library/Montage/bin/mDiffFit				INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"
pads	copy_image	/home/jonmon/Library/Swift/apps/Montage/scripts/copy_image.sh		INSTALLED	INTEL32::LINUX	GLOBUS::maxwalltime="00:00:20"


2011-06-21 15:20:09,946-0500 DEBUG textfiles END TC:
2011-06-21 15:20:09,952-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=mProject-hb510xbk thread=0-2-1-7-3-1 host=pads replicationGroup=9b510xbk
2011-06-21 15:20:09,954-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-ib510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_6.fits - Closed, ?:string = proj_dir/proj_raw_image_6.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/i/mProject-ib510xbk host=pads
2011-06-21 15:20:09,954-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-hb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_8.fits - Closed, ?:string = proj_dir/proj_raw_image_8.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/h/mProject-hb510xbk host=pads
2011-06-21 15:20:09,959-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-cb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_9.fits - Closed, ?:string = proj_dir/proj_raw_image_9.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/c/mProject-cb510xbk host=pads
2011-06-21 15:20:09,960-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-jb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_3.fits - Closed, ?:string = proj_dir/proj_raw_image_3.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/j/mProject-jb510xbk host=pads
2011-06-21 15:20:09,961-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-fb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_1.fits - Closed, ?:string = proj_dir/proj_raw_image_1.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/f/mProject-fb510xbk host=pads
2011-06-21 15:20:09,971-0500 DEBUG vdl:execute2 JOB_START jobid=mProject-gb510xbk tr=mProject arguments=[?:string = -X - Closed, ?:string = raw_dir/raw_image_5.fits - Closed, ?:string = proj_dir/proj_raw_image_5.fits - Closed, ?:string = header.hdr - Closed] tmpdir=montage-20110621-1520-hj2se96g/jobs/g/mProject-gb510xbk host=pads
2011-06-21 15:20:09,974-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-5-3-1-1308687609194) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_4.fits|header.hdr -of proj_dir/proj_raw_image_4.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_4.fits, proj_dir/proj_raw_image_4.fits, header.hdr]
2011-06-21 15:20:09,976-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-0-3-1-1308687609196) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_3.fits|header.hdr -of proj_dir/proj_raw_image_3.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_3.fits, proj_dir/proj_raw_image_3.fits, header.hdr]
2011-06-21 15:20:09,977-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-8-3-1-1308687609198) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_7.fits|header.hdr -of proj_dir/proj_raw_image_7.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_7.fits, proj_dir/proj_raw_image_7.fits, header.hdr]
2011-06-21 15:20:09,978-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-4-3-1-1308687609195) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_1.fits|header.hdr -of proj_dir/proj_raw_image_1.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_1.fits, proj_dir/proj_raw_image_1.fits, header.hdr]
2011-06-21 15:20:09,978-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-6-3-1-1308687609200) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_5.fits|header.hdr -of proj_dir/proj_raw_image_5.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_5.fits, proj_dir/proj_raw_image_5.fits, header.hdr]
2011-06-21 15:20:09,980-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-3-3-1-1308687609202) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_2.fits|header.hdr -of proj_dir/proj_raw_image_2.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_2.fits, proj_dir/proj_raw_image_2.fits, header.hdr]
2011-06-21 15:20:09,982-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-7-3-1-1308687609204) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_8.fits|header.hdr -of proj_dir/proj_raw_image_8.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_8.fits, proj_dir/proj_raw_image_8.fits, header.hdr]
2011-06-21 15:20:09,982-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-9-3-1-1308687609205) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_6.fits|header.hdr -of proj_dir/proj_raw_image_6.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_6.fits, proj_dir/proj_raw_image_6.fits, header.hdr]
2011-06-21 15:20:09,982-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-2-3-1-1308687609207) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_9.fits|header.hdr -of proj_dir/proj_raw_image_9.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_9.fits, proj_dir/proj_raw_image_9.fits, header.hdr]
2011-06-21 15:20:09,982-0500 INFO  GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-2-1-1-3-1-1308687609209) is /bin/bash _swiftwrap.staging -e /home/jonmon/Library/Montage/bin/mProject -out stdout.txt -err stderr.txt -i -d proj_dir -if raw_dir/raw_image_10.fits|header.hdr -of proj_dir/proj_raw_image_10.fits -k -cdmfile  -status provider -a [-X, raw_dir/raw_image_10.fits, proj_dir/proj_raw_image_10.fits, header.hdr]
2011-06-21 15:20:10,030-0500 INFO  LocalService Started local service: 128.135.125.17:47293
2011-06-21 15:20:10,039-0500 INFO  BootstrapService Socket bound. URL is http://communicado.ci.uchicago.edu:41720
2011-06-21 15:20:10,068-0500 INFO  ConfigurationLoader JAVA version is 1.6.0_25
2011-06-21 15:20:10,080-0500 INFO  SshCipherFactory Loading supported cipher algorithms
2011-06-21 15:20:10,083-0500 INFO  SshKeyExchangeFactory Loading key exchange methods
2011-06-21 15:20:10,087-0500 INFO  SshKeyPairFactory Loading public key algorithms
2011-06-21 15:20:10,092-0500 INFO  SshCompressionFactory Loading compression methods
2011-06-21 15:20:10,093-0500 INFO  SshHmacFactory Loading message authentication methods
2011-06-21 15:20:10,098-0500 INFO  HostKeyVerification Determining default host file
2011-06-21 15:20:10,098-0500 INFO  HostKeyVerification Defaulting host file to /home/jonmon/.globus/conf/hosts.xml
2011-06-21 15:20:10,196-0500 INFO  TransportProtocolCommon Starting transport protocol
2011-06-21 15:20:10,198-0500 INFO  TransportProtocolCommon Registering transport protocol messages with inputstream
2011-06-21 15:20:10,355-0500 INFO  TransportProtocolCommon Negotiating protocol version
2011-06-21 15:20:10,355-0500 INFO  TransportProtocolCommon Protocol negotiation complete
2011-06-21 15:20:10,356-0500 INFO  TransportProtocolCommon Sending SSH_MSG_KEX_INIT
2011-06-21 15:20:10,360-0500 INFO  TransportProtocolCommon Received SSH_MSG_KEX_INIT
2011-06-21 15:20:10,360-0500 INFO  TransportProtocolCommon Starting key exchange
2011-06-21 15:20:10,361-0500 INFO  DhGroup1Sha1 Starting client side key exchange.
2011-06-21 15:20:10,400-0500 INFO  TransportProtocolCommon Sending SSH_MSG_KEXDH_INIT
2011-06-21 15:20:10,413-0500 INFO  HostKeyVerification Verifying login.pads.ci.uchicago.edu,192.5.86.5 host key
2011-06-21 15:20:10,414-0500 INFO  TransportProtocolCommon The host key signature is  valid
2011-06-21 15:20:10,414-0500 INFO  TransportProtocolCommon Sending SSH_MSG_NEWKEYS
2011-06-21 15:20:10,414-0500 INFO  TransportProtocolCommon Completing key exchange
2011-06-21 15:20:10,415-0500 INFO  SshCipherFactory Creating new blowfish-cbc cipher instance
2011-06-21 15:20:10,444-0500 INFO  SshCipherFactory Creating new blowfish-cbc cipher instance
2011-06-21 15:20:10,452-0500 INFO  TransportProtocolCommon Sending SSH_MSG_SERVICE_REQUEST
2011-06-21 15:20:10,456-0500 INFO  TransportProtocolCommon Received SSH_MSG_SERVICE_ACCEPT
2011-06-21 15:20:10,469-0500 INFO  SshPrivateKeyFile Parsing private key file
2011-06-21 15:20:10,475-0500 INFO  SshPrivateKeyFile Private key is not in the default format, attempting parse with other supported formats
2011-06-21 15:20:10,491-0500 INFO  PublicKeyAuthenticationClient Generating data to sign
2011-06-21 15:20:10,491-0500 INFO  PublicKeyAuthenticationClient Preparing public key authentication request
2011-06-21 15:20:10,528-0500 INFO  TransportProtocolCommon Sending SSH_MSG_USERAUTH_REQUEST
2011-06-21 15:20:10,574-0500 INFO  TransportProtocolCommon Received SSH_MSG_USERAUTH_SUCCESS
2011-06-21 15:20:10,574-0500 INFO  ConnectionProtocol Registering connection protocol messages
2011-06-21 15:20:10,576-0500 INFO  Service ssh-connection has been requested
2011-06-21 15:20:10,576-0500 INFO  Service Starting ssh-connection service thread
2011-06-21 15:20:10,580-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_OPEN
2011-06-21 15:20:10,583-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_OPEN_CONFIRMATION
2011-06-21 15:20:10,584-0500 INFO  ConnectionProtocol Channel 0 is open [Unnamed Channel]
2011-06-21 15:20:10,816-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_OPEN
2011-06-21 15:20:10,820-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_OPEN_CONFIRMATION
2011-06-21 15:20:10,820-0500 INFO  ConnectionProtocol Channel 1 is open [Unnamed Channel]
2011-06-21 15:20:10,833-0500 INFO  SessionChannelClient Starting sftp subsystem
2011-06-21 15:20:10,834-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_REQUEST
2011-06-21 15:20:10,838-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_WINDOW_ADJUST
2011-06-21 15:20:10,838-0500 INFO  Service Routing SSH_MSG_CHANNEL_WINDOW_ADJUST
2011-06-21 15:20:10,838-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_SUCCESS
2011-06-21 15:20:10,839-0500 INFO  SftpSubsystemClient Initializing SFTP protocol version 3
2011-06-21 15:20:10,839-0500 INFO  SubsystemClient Sending SSH_FXP_INIT subsystem message
2011-06-21 15:20:10,839-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,839-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,872-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,872-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,872-0500 INFO  SubsystemMessageStore Received SSH_FXP_INIT subsystem message
2011-06-21 15:20:10,873-0500 INFO  SftpSubsystemClient Server responded with version 3
2011-06-21 15:20:10,873-0500 INFO  SubsystemClient Sending SSH_FXP_READPATH subsystem message
2011-06-21 15:20:10,873-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,873-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,873-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,873-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,876-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,876-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,879-0500 INFO  SubsystemMessageStore Received SSH_FXP_NAME subsystem message
2011-06-21 15:20:10,879-0500 INFO  SubsystemClient Sending SSH_FXP_STAT subsystem message
2011-06-21 15:20:10,879-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,879-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,880-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,880-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,886-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,886-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,887-0500 INFO  SubsystemMessageStore Received SSH_FXP_ATTRS subsystem message
2011-06-21 15:20:10,887-0500 INFO  SubsystemClient Sending SSH_FXP_READPATH subsystem message
2011-06-21 15:20:10,887-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,887-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,888-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,888-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,891-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,891-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,891-0500 INFO  SubsystemMessageStore Received SSH_FXP_NAME subsystem message
2011-06-21 15:20:10,891-0500 INFO  SubsystemClient Sending SSH_FXP_OPEDIR subsystem message
2011-06-21 15:20:10,891-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,891-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,891-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,891-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,900-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,900-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,900-0500 INFO  SubsystemMessageStore Received SSH_FXP_HANDLE subsystem message
2011-06-21 15:20:10,900-0500 INFO  SubsystemClient Sending SSH_FXP_STAT subsystem message
2011-06-21 15:20:10,900-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,901-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,901-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,901-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,904-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,904-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,904-0500 INFO  SubsystemMessageStore Received SSH_FXP_ATTRS subsystem message
2011-06-21 15:20:10,904-0500 INFO  SubsystemClient Sending SSH_FXP_READDIR subsystem message
2011-06-21 15:20:10,904-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,904-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,905-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,905-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,956-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,956-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,957-0500 INFO  SubsystemMessageStore Received SSH_FXP_NAME subsystem message
2011-06-21 15:20:10,957-0500 INFO  SubsystemClient Sending SSH_FXP_CLOSE subsystem message
2011-06-21 15:20:10,957-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,958-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,958-0500 INFO  SftpSubsystemClient Waiting for response
2011-06-21 15:20:10,958-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,958-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,961-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,961-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,961-0500 INFO  SubsystemMessageStore Received SSH_FXP_STATUS subsystem message
2011-06-21 15:20:10,961-0500 INFO  SftpSubsystemClient Received response
2011-06-21 15:20:10,962-0500 INFO  SubsystemClient Sending SSH_FXP_OPEN subsystem message
2011-06-21 15:20:10,962-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,962-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,962-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,962-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,974-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,974-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,974-0500 INFO  SubsystemMessageStore Received SSH_FXP_HANDLE subsystem message
2011-06-21 15:20:10,974-0500 INFO  SubsystemClient Sending SSH_FXP_FSTAT subsystem message
2011-06-21 15:20:10,974-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,974-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,974-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,974-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,977-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,977-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,977-0500 INFO  SubsystemMessageStore Received SSH_FXP_ATTRS subsystem message
2011-06-21 15:20:10,977-0500 INFO  SubsystemClient Sending SSH_FXP_FSETSTAT subsystem message
2011-06-21 15:20:10,978-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,978-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,978-0500 INFO  SftpSubsystemClient Waiting for response
2011-06-21 15:20:10,978-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,978-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,992-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,992-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,992-0500 INFO  SubsystemMessageStore Received SSH_FXP_STATUS subsystem message
2011-06-21 15:20:10,992-0500 INFO  SftpSubsystemClient Received response
2011-06-21 15:20:10,997-0500 INFO  SubsystemClient Sending SSH_FXP_WRITE subsystem message
2011-06-21 15:20:10,997-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,997-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:10,999-0500 INFO  SftpSubsystemClient Waiting for response
2011-06-21 15:20:10,999-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:10,999-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:11,005-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,005-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,005-0500 INFO  SubsystemMessageStore Received SSH_FXP_STATUS subsystem message
2011-06-21 15:20:11,005-0500 INFO  SftpSubsystemClient Received response
2011-06-21 15:20:11,005-0500 INFO  SubsystemClient Sending SSH_FXP_CLOSE subsystem message
2011-06-21 15:20:11,005-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,006-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,006-0500 INFO  SftpSubsystemClient Waiting for response
2011-06-21 15:20:11,006-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:11,006-0500 INFO  SftpMessageStore Message store has 0 messages
2011-06-21 15:20:11,012-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,012-0500 INFO  Service Routing SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,012-0500 INFO  SubsystemMessageStore Received SSH_FXP_STATUS subsystem message
2011-06-21 15:20:11,012-0500 INFO  SftpSubsystemClient Received response
2011-06-21 15:20:11,013-0500 INFO  ConnectionProtocol Local computer has set channel 1 to EOF [Unnamed Channel]
2011-06-21 15:20:11,013-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_EOF
2011-06-21 15:20:11,013-0500 INFO  ConnectionProtocol Local computer has closed channel 1[Unnamed Channel]
2011-06-21 15:20:11,013-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_CLOSE
2011-06-21 15:20:11,014-0500 INFO  SessionChannelClient Requesting command execution
2011-06-21 15:20:11,014-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_REQUEST
2011-06-21 15:20:11,018-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_REQUEST
2011-06-21 15:20:11,018-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_EOF
2011-06-21 15:20:11,018-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_CLOSE
2011-06-21 15:20:11,018-0500 INFO  Service Routing SSH_MSG_CHANNEL_REQUEST
2011-06-21 15:20:11,018-0500 INFO  Service Routing SSH_MSG_CHANNEL_EOF
2011-06-21 15:20:11,018-0500 INFO  ConnectionProtocol Remote computer has set channel 1 to EOF [Unnamed Channel]
2011-06-21 15:20:11,018-0500 INFO  Service Routing SSH_MSG_CHANNEL_CLOSE
2011-06-21 15:20:11,018-0500 INFO  ConnectionProtocol Remote computer has closed channel 1[Unnamed Channel]
2011-06-21 15:20:11,018-0500 INFO  ConnectionProtocol Freeing channel 1 [Unnamed Channel]
2011-06-21 15:20:11,060-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_WINDOW_ADJUST
2011-06-21 15:20:11,060-0500 INFO  TransportProtocolCommon Received SSH_MSG_CHANNEL_SUCCESS
2011-06-21 15:20:11,060-0500 INFO  Service Routing SSH_MSG_CHANNEL_WINDOW_ADJUST
2011-06-21 15:20:11,060-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,060-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,061-0500 INFO  TransportProtocolCommon Sending SSH_MSG_CHANNEL_DATA
2011-06-21 15:20:11,366-0500 INFO  BootstrapService [/192.5.86.5:60925] GET /coaster-bootstrap.jar HTTP/1.0
2011-06-21 15:20:19,379-0500 INFO  LocalService Got connection
2011-06-21 15:20:19,394-0500 INFO  AbstractKarajanChannel GSSSChannel-null(0)[1721051397: {}]: Disabling heartbeats (config is null)
2011-06-21 15:20:23,071-0500 INFO  LocalService Initialized connection handler
2011-06-21 15:20:23,079-0500 INFO  AbstractStreamKarajanChannel$Multiplexer Multiplexer 0 started
2011-06-21 15:20:23,080-0500 INFO  AbstractStreamKarajanChannel$Multiplexer (1) Scheduling GSSSChannel-null(1)[1721051397: {}] for addition
2011-06-21 15:20:23,081-0500 INFO  AbstractStreamKarajanChannel nullChannel started
2011-06-21 15:20:23,081-0500 INFO  LocalService Connection handler started
2011-06-21 15:20:23,081-0500 INFO  AbstractStreamKarajanChannel$Multiplexer Multiplexer 1 started
2011-06-21 15:20:23,217-0500 INFO  ChannelConfigurationHandler Channel id: u293167f5-130b3dc641f--8000-u271541a0-130b3dc6431--8000
2011-06-21 15:20:23,224-0500 INFO  AbstractKarajanChannel MetaChannel: 457929895[1721051397: {}] -> null: Disabling heartbeats (disabled in config)
2011-06-21 15:20:23,224-0500 INFO  MetaChannel MetaChannel: 457929895[1721051397: {}] -> null.bind -> GSSSChannel-null(1)[1721051397: {}]
2011-06-21 15:20:23,290-0500 INFO  MetaChannel Trying to re-bind current channel
2011-06-21 15:20:23,566-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000000, w=1, h=2400
2011-06-21 15:20:23,566-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000001, w=1, h=2400
2011-06-21 15:20:23,567-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000002, w=1, h=2400
2011-06-21 15:20:23,567-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000003, w=1, h=2400
2011-06-21 15:20:23,568-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000004, w=1, h=2400
2011-06-21 15:20:23,568-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000005, w=1, h=2400
2011-06-21 15:20:23,569-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000006, w=1, h=2400
2011-06-21 15:20:23,569-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000007, w=1, h=2400
2011-06-21 15:20:23,570-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000008, w=1, h=2400
2011-06-21 15:20:25,886-0500 INFO  RemoteLogHandler BLOCK_REQUESTED id=0621-200323-000009, w=1, h=2400
2011-06-21 15:20:29,652-0500 INFO  RemoteLogHandler BLOCK_ACTIVE id=0621-200323-000000
2011-06-21 15:20:31,883-0500 INFO  PutFileHandler Source: /gpfs/pads/swift/jonmon/Swift/work/pads/montage-20110621-1520-hj2se96g-f-mProject-fb510xbk/wrapper.log
2011-06-21 15:20:31,883-0500 INFO  PutFileHandler Destination: file://localhost/montage-20110621-1520-hj2se96g.d/mProject-fb510xbk.info
2011-06-21 15:20:31,896-0500 INFO  PutFileHandler Source: /gpfs/pads/swift/jonmon/Swift/work/pads/montage-20110621-1520-hj2se96g-j-mProject-jb510xbk/wrapper.log
2011-06-21 15:20:31,896-0500 INFO  PutFileHandler Destination: file://localhost/montage-20110621-1520-hj2se96g.d/mProject-jb510xbk.info
2011-06-21 15:20:31,902-0500 INFO  PutFileHandler Source: /gpfs/pads/swift/jonmon/Swift/work/pads/montage-20110621-1520-hj2se96g-b-mProject-bb510xbk/wrapper.log
2011-06-21 15:20:31,902-0500 INFO  PutFileHandler Destination: file://localhost/montage-20110621-1520-hj2se96g.d/mProject-bb510xbk.info
2011-06-21 15:20:31,917-0500 DEBUG vdl:execute2 APPLICATION_EXCEPTION jobid=mProject-fb510xbk - Application exception: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
2011-06-21 15:20:31,919-0500 DEBUG vdl:execute2 APPLICATION_EXCEPTION jobid=mProject-jb510xbk - Application exception: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
2011-06-21 15:20:31,919-0500 DEBUG vdl:execute2 APPLICATION_EXCEPTION jobid=mProject-bb510xbk - Application exception: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
2011-06-21 15:20:31,923-0500 INFO  vdl:execute END_FAILURE thread=0-2-1-5-3 tr=mProject
2011-06-21 15:20:31,926-0500 INFO  vdl:execute END_FAILURE thread=0-2-1-4-3 tr=mProject
2011-06-21 15:20:31,927-0500 INFO  vdl:execute END_FAILURE thread=0-2-1-0-3 tr=mProject
2011-06-21 15:20:31,934-0500 DEBUG VDL2ExecutionContext Exception in mProject:
Arguments: [?:string = -X - Closed, ?:string = raw_dir/raw_image_4.fits - Closed, ?:string = proj_dir/proj_raw_image_4.fits - Closed, ?:string = header.hdr - Closed]
Host: pads
Directory: montage-20110621-1520-hj2se96g/jobs/b/mProject-bb510xbk
- - -

Exception in mProject:
Arguments: [?:string = -X - Closed, ?:string = raw_dir/raw_image_4.fits - Closed, ?:string = proj_dir/proj_raw_image_4.fits - Closed, ?:string = header.hdr - Closed]
Host: pads
Directory: montage-20110621-1520-hj2se96g/jobs/b/mProject-bb510xbk
- - -

Caused by: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
	at org.globus.cog.karajan.workflow.nodes.functions.KException.function(KException.java:29)
	at org.globus.cog.karajan.workflow.nodes.functions.AbstractFunction.post(AbstractFunction.java:27)
	at org.globus.cog.karajan.workflow.nodes.AbstractSequentialWithArguments.completed(AbstractSequentialWithArguments.java:194)
	at org.globus.cog.karajan.workflow.nodes.FlowNode.complete(FlowNode.java:214)
	at org.globus.cog.karajan.workflow.nodes.FlowContainer.post(FlowContainer.java:58)
	at org.globus.cog.karajan.workflow.nodes.functions.AbstractFunction.post(AbstractFunction.java:28)
	at org.globus.cog.karajan.workflow.nodes.Sequential.startNext(Sequential.java:29)
	at org.globus.cog.karajan.workflow.nodes.Sequential.executeChildren(Sequential.java:20)
	at org.globus.cog.karajan.workflow.nodes.FlowContainer.execute(FlowContainer.java:63)
	at org.globus.cog.karajan.workflow.nodes.FlowNode.restart(FlowNode.java:139)
	at org.globus.cog.karajan.workflow.nodes.FlowNode.start(FlowNode.java:197)
	at org.globus.cog.karajan.workflow.events.EventBus.start(EventBus.java:104)
	at org.globus.cog.karajan.workflow.events.EventTargetPair.run(EventTargetPair.java:40)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:662)
Caused by: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
	at org.globus.cog.karajan.workflow.nodes.FlowNode.failImmediately(FlowNode.java:101)
	at org.globus.cog.karajan.workflow.nodes.grid.GridExec.taskFailed(GridExec.java:379)
	at org.globus.cog.karajan.workflow.nodes.grid.AbstractGridNode.statusChanged(AbstractGridNode.java:268)
	at org.griphyn.vdl.karajan.lib.Execute.statusChanged(Execute.java:113)
	at org.globus.cog.karajan.scheduler.AbstractScheduler.fireJobStatusChangeEvent(AbstractScheduler.java:168)
	at org.globus.cog.karajan.scheduler.LateBindingScheduler.statusChanged(LateBindingScheduler.java:674)
	at org.globus.cog.karajan.scheduler.WeightedHostScoreScheduler.statusChanged(WeightedHostScoreScheduler.java:422)
	at org.griphyn.vdl.karajan.VDSAdaptiveScheduler.statusChanged(VDSAdaptiveScheduler.java:410)
	at org.globus.cog.abstraction.impl.common.task.TaskImpl.notifyListeners(TaskImpl.java:240)
	at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:228)
	at org.globus.cog.abstraction.impl.execution.coaster.NotificationManager.setStatus(NotificationManager.java:111)
	at org.globus.cog.abstraction.impl.execution.coaster.NotificationManager.notificationReceived(NotificationManager.java:77)
	at org.globus.cog.abstraction.coaster.service.local.JobStatusHandler.requestComplete(JobStatusHandler.java:45)
	at org.globus.cog.karajan.workflow.service.handlers.RequestHandler.receiveCompleted(RequestHandler.java:84)
	at org.globus.cog.karajan.workflow.service.channels.AbstractKarajanChannel.handleRequest(AbstractKarajanChannel.java:390)
	at org.globus.cog.karajan.workflow.service.channels.AbstractStreamKarajanChannel.step(AbstractStreamKarajanChannel.java:158)
	at org.globus.cog.karajan.workflow.service.channels.AbstractStreamKarajanChannel$Multiplexer.run(AbstractStreamKarajanChannel.java:377)
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
	at org.globus.cog.abstraction.coaster.service.local.JobStatusHandler.requestComplete(JobStatusHandler.java:37)
	... 4 more
2011-06-21 15:20:31,959-0500 INFO  ExecutionContext Detailed exception: 
Exception in mProject:
Arguments: [?:string = -X - Closed, ?:string = raw_dir/raw_image_4.fits - Closed, ?:string = proj_dir/proj_raw_image_4.fits - Closed, ?:string = header.hdr - Closed]
Host: pads
Directory: montage-20110621-1520-hj2se96g/jobs/b/mProject-bb510xbk
- - -

Caused by: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
	at org.globus.cog.karajan.workflow.nodes.functions.KException.function(KException.java:29)
	at org.globus.cog.karajan.workflow.nodes.functions.AbstractFunction.post(AbstractFunction.java:27)
	at org.globus.cog.karajan.workflow.nodes.AbstractSequentialWithArguments.completed(AbstractSequentialWithArguments.java:194)
	at org.globus.cog.karajan.workflow.nodes.FlowNode.complete(FlowNode.java:214)
	at org.globus.cog.karajan.workflow.nodes.FlowContainer.post(FlowContainer.java:58)
	at org.globus.cog.karajan.workflow.nodes.functions.AbstractFunction.post(AbstractFunction.java:28)
	at org.globus.cog.karajan.workflow.nodes.Sequential.startNext(Sequential.java:29)
	at org.globus.cog.karajan.workflow.nodes.Sequential.executeChildren(Sequential.java:20)
	at org.globus.cog.karajan.workflow.nodes.FlowContainer.execute(FlowContainer.java:63)
	at org.globus.cog.karajan.workflow.nodes.FlowNode.restart(FlowNode.java:139)
	at org.globus.cog.karajan.workflow.nodes.FlowNode.start(FlowNode.java:197)
	at org.globus.cog.karajan.workflow.events.EventBus.start(EventBus.java:104)
	at org.globus.cog.karajan.workflow.events.EventTargetPair.run(EventTargetPair.java:40)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
	at java.util.concurrent.FutureTask.run(FutureTask.java:138)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
	at java.lang.Thread.run(Thread.java:662)
Caused by: null
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
	at org.globus.cog.karajan.workflow.nodes.FlowNode.failImmediately(FlowNode.java:101)
	at org.globus.cog.karajan.workflow.nodes.grid.GridExec.taskFailed(GridExec.java:379)
	at org.globus.cog.karajan.workflow.nodes.grid.AbstractGridNode.statusChanged(AbstractGridNode.java:268)
	at org.griphyn.vdl.karajan.lib.Execute.statusChanged(Execute.java:113)
	at org.globus.cog.karajan.scheduler.AbstractScheduler.fireJobStatusChangeEvent(AbstractScheduler.java:168)
	at org.globus.cog.karajan.scheduler.LateBindingScheduler.statusChanged(LateBindingScheduler.java:674)
	at org.globus.cog.karajan.scheduler.WeightedHostScoreScheduler.statusChanged(WeightedHostScoreScheduler.java:422)
	at org.griphyn.vdl.karajan.VDSAdaptiveScheduler.statusChanged(VDSAdaptiveScheduler.java:410)
	at org.globus.cog.abstraction.impl.common.task.TaskImpl.notifyListeners(TaskImpl.java:240)
	at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:228)
	at org.globus.cog.abstraction.impl.execution.coaster.NotificationManager.setStatus(NotificationManager.java:111)
	at org.globus.cog.abstraction.impl.execution.coaster.NotificationManager.notificationReceived(NotificationManager.java:77)
	at org.globus.cog.abstraction.coaster.service.local.JobStatusHandler.requestComplete(JobStatusHandler.java:45)
	at org.globus.cog.karajan.workflow.service.handlers.RequestHandler.receiveCompleted(RequestHandler.java:84)
	at org.globus.cog.karajan.workflow.service.channels.AbstractKarajanChannel.handleRequest(AbstractKarajanChannel.java:390)
	at org.globus.cog.karajan.workflow.service.channels.AbstractStreamKarajanChannel.step(AbstractStreamKarajanChannel.java:158)
	at org.globus.cog.karajan.workflow.service.channels.AbstractStreamKarajanChannel$Multiplexer.run(AbstractStreamKarajanChannel.java:377)
Caused by: org.globus.cog.abstraction.impl.common.execution.JobException: Job failed with an exit code of 1
	at org.globus.cog.abstraction.coaster.service.local.JobStatusHandler.requestComplete(JobStatusHandler.java:37)
	... 4 more
2011-06-21 15:20:31,959-0500 INFO  Loader Swift finished with errors
2011-06-21 15:20:31,960-0500 INFO  ServiceManager Cleaning up...
2011-06-21 15:20:31,960-0500 INFO  ServiceManager Shutting down service at https://192.5.86.6:53744