2011-08-09 13:24:40,026-0500 DEBUG Loader arguments: [-config, cf, -tc.file, tc, -sites.file, sites.pecos.xml, catsnsleep.swift, -s=20, -n=10] 2011-08-09 13:24:40,026-0500 DEBUG Loader Max heap: 257294336 2011-08-09 13:24:40,027-0500 DEBUG textfiles BEGIN SWIFTSCRIPT: type file; app (file o) catnap (string delay, file i) { catnap delay @i stdout=@o; } string some_delay=@arg("s","1"); file out[]; foreach j in [1:@toint(@arg("n","1"))] { file data<"data.txt">; out[j] = catnap(some_delay, data); } 2011-08-09 13:24:40,027-0500 DEBUG textfiles END SWIFTSCRIPT: 2011-08-09 13:24:40,028-0500 DEBUG Loader kmlversion is >d242e65e-08c4-499f-8f67-36b74cef480f< 2011-08-09 13:24:40,028-0500 DEBUG Loader build version is >d242e65e-08c4-499f-8f67-36b74cef480f< 2011-08-09 13:24:40,028-0500 DEBUG Loader Recompilation suppressed. 2011-08-09 13:24:40,164-0500 DEBUG Loader setting: sites.file to: sites.pecos.xml 2011-08-09 13:24:40,165-0500 DEBUG Loader setting: tc.file to: tc 2011-08-09 13:24:40,165-0500 DEBUG textfiles using default sites file 2011-08-09 13:24:40,170-0500 INFO VDL2ExecutionContext Stack dump: Level 1 [iA = 0, iB = 0, bA = false, bB = false] vdl:instanceconfigfile = cf vdl:instanceconfig = Swift configuration [cf] vdl:operation = run PATH_SEPARATOR = / swift.home = /home/ketan/swift-install/0.93/cog/modules/swift/dist/swift-svn 2011-08-09 13:24:40,690-0500 INFO unknown Using sites file: sites.pecos.xml 2011-08-09 13:24:40,717-0500 INFO unknown Using tc.data: tc 2011-08-09 13:24:40,804-0500 INFO AbstractScheduler Setting resources to: {localhost=localhost} 2011-08-09 13:24:41,216-0500 INFO unknown Swift svn swift-r4968 cog-r3225 2011-08-09 13:24:41,217-0500 INFO unknown RUNID id=run:20110809-1324-ouf3x44c 2011-08-09 13:24:41,304-0500 INFO SetFieldValue Set: swift#mapper#17006=.out 2011-08-09 13:24:41,304-0500 INFO SetFieldValue Set: swift#mapper#17002=outdir 2011-08-09 13:24:41,305-0500 INFO VDLFunction FUNCTION: arg() 2011-08-09 13:24:41,305-0500 INFO SetFieldValue Set: some_delay=20 2011-08-09 13:24:41,305-0500 INFO VDLFunction FUNCTION: arg() 2011-08-09 13:24:41,305-0500 INFO VDLFunction FUNCTION: toint() 2011-08-09 13:24:41,305-0500 INFO SetFieldValue Set: swift#mapper#17004=f. 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-2 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-0 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-1 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-3 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-5 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-6 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains SCOPE thread=0-4-2 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-4 2011-08-09 13:24:41,312-0500 DEBUG vdl:mains SCOPE thread=0-4-1 2011-08-09 13:24:41,312-0500 DEBUG vdl:mains SCOPE thread=0-4-3 2011-08-09 13:24:41,312-0500 DEBUG vdl:mains SCOPE thread=0-4-5 2011-08-09 13:24:41,312-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-9 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains SCOPE thread=0-4-0 2011-08-09 13:24:41,313-0500 DEBUG vdl:mains SCOPE thread=0-4-9 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-7 2011-08-09 13:24:41,311-0500 DEBUG vdl:mains FOREACH_IT_START line=11 thread=0-4-8 2011-08-09 13:24:41,313-0500 DEBUG vdl:mains SCOPE thread=0-4-7 2011-08-09 13:24:41,314-0500 DEBUG vdl:mains SCOPE thread=0-4-8 2011-08-09 13:24:41,312-0500 DEBUG vdl:mains SCOPE thread=0-4-4 2011-08-09 13:24:41,312-0500 DEBUG vdl:mains SCOPE thread=0-4-6 2011-08-09 13:24:41,316-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,316-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,316-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,317-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,317-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,316-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,317-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,317-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,317-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,317-0500 INFO RootDataNode Found data data:file - Closed.$ 2011-08-09 13:24:41,323-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-7-1 name=catnap 2011-08-09 13:24:41,323-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-6-1 name=catnap 2011-08-09 13:24:41,324-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-8-1 name=catnap 2011-08-09 13:24:41,324-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-9-1 name=catnap 2011-08-09 13:24:41,325-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-1-1 name=catnap 2011-08-09 13:24:41,325-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-5-1 name=catnap 2011-08-09 13:24:41,323-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-2-1 name=catnap 2011-08-09 13:24:41,325-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-0-1 name=catnap 2011-08-09 13:24:41,323-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-4-1 name=catnap 2011-08-09 13:24:41,323-0500 DEBUG catnap PROCEDURE line=3 thread=0-4-3-1 name=catnap 2011-08-09 13:24:41,332-0500 INFO vdl:execute START thread=0-4-3-1 tr=catnap 2011-08-09 13:24:41,332-0500 INFO vdl:execute START thread=0-4-4-1 tr=catnap 2011-08-09 13:24:41,332-0500 INFO vdl:execute START thread=0-4-6-1 tr=catnap 2011-08-09 13:24:41,332-0500 INFO vdl:execute START thread=0-4-0-1 tr=catnap 2011-08-09 13:24:41,332-0500 INFO vdl:execute START thread=0-4-2-1 tr=catnap 2011-08-09 13:24:41,332-0500 INFO vdl:execute START thread=0-4-7-1 tr=catnap 2011-08-09 13:24:41,333-0500 INFO vdl:execute START thread=0-4-1-1 tr=catnap 2011-08-09 13:24:41,333-0500 INFO vdl:execute START thread=0-4-8-1 tr=catnap 2011-08-09 13:24:41,333-0500 INFO vdl:execute START thread=0-4-9-1 tr=catnap 2011-08-09 13:24:41,333-0500 INFO vdl:execute START thread=0-4-5-1 tr=catnap 2011-08-09 13:24:41,344-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,344-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,344-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,345-0500 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=99.854 2011-08-09 13:24:41,347-0500 INFO GlobalSubmitQueue No global submit throttle set. Using default (1024) 2011-08-09 13:24:41,348-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:24:41,350-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:24:41,352-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-feemg7ek thread=0-4-0-1-1 host=localhost replicationGroup=zdemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-9eemg7ek thread=0-4-6-1-1 host=localhost replicationGroup=0eemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-ceemg7ek thread=0-4-9-1-1 host=localhost replicationGroup=2eemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-8eemg7ek thread=0-4-4-1-1 host=localhost replicationGroup=6eemg7ek 2011-08-09 13:24:41,352-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-geemg7ek thread=0-4-1-1-1 host=localhost replicationGroup=ydemg7ek 2011-08-09 13:24:41,352-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-deemg7ek thread=0-4-8-1-1 host=localhost replicationGroup=4eemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-eeemg7ek thread=0-4-7-1-1 host=localhost replicationGroup=3eemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-aeemg7ek thread=0-4-5-1-1 host=localhost replicationGroup=xdemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-7eemg7ek thread=0-4-2-1-1 host=localhost replicationGroup=1eemg7ek 2011-08-09 13:24:41,353-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=catnap-beemg7ek thread=0-4-3-1-1 host=localhost replicationGroup=5eemg7ek 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-deemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/d/catnap-deemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-beemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/b/catnap-beemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-aeemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/a/catnap-aeemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-eeemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/e/catnap-eeemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-8eemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/8/catnap-8eemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-feemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/f/catnap-feemg7ek host=localhost 2011-08-09 13:24:41,363-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-9eemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/9/catnap-9eemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-7eemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/7/catnap-7eemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-ceemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/c/catnap-ceemg7ek host=localhost 2011-08-09 13:24:41,362-0500 DEBUG vdl:execute2 JOB_START jobid=catnap-geemg7ek tr=catnap arguments=[20, data.txt] tmpdir=catsnsleep-20110809-1324-ouf3x44c/jobs/g/catnap-geemg7ek host=localhost 2011-08-09 13:24:41,365-0500 DEBUG textfiles BEGIN TC: localhost sh /bin/sh null null null localhost cat /bin/cat null null null pbs cat /bin/cat null null null RANGER cat /bin/cat null null null RANGER list /bin/list null null null #localhost catnap /share/home/01739/ketan/catnap.sh null null null localhost catnap /home/ketan/coasters-lab/scripts/catnap.sh null null null localhost list /bin/ls null null null 2011-08-09 13:24:41,365-0500 DEBUG textfiles END TC: 2011-08-09 13:24:41,394-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-9-1-1-1312914280733) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0010.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0010.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,395-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-0-1-1-1312914280731) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0001.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0001.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,394-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-1-1-1-1312914280732) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0002.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0002.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,395-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-2-1-1-1312914280728) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0003.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0003.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,394-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-6-1-1-1312914280734) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0007.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0007.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,394-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-7-1-1-1312914280735) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0008.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0008.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,395-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-5-1-1-1312914280730) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0006.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0006.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,395-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-8-1-1-1312914280727) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0009.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0009.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,395-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-4-1-1-1312914280729) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0005.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0005.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,395-0500 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-4-3-1-1-1312914280726) is /bin/bash _swiftwrap.staging -e /home/ketan/coasters-lab/scripts/catnap.sh -out outdir/f.0004.out -err stderr.txt -i -d outdir -if data.txt -of outdir/f.0004.out -k -cdmfile -status provider -a 20 data.txt 2011-08-09 13:24:41,416-0500 WARN RemoteConfiguration Find: http://trounce.mcs.anl.gov:44087 2011-08-09 13:24:41,416-0500 WARN RemoteConfiguration Find: keepalive(120), reconnect - http://trounce.mcs.anl.gov:44087 2011-08-09 13:24:41,419-0500 INFO AbstractKarajanChannel MetaChannel: 1617829356[1567531625: {}] -> null: Disabling heartbeats (disabled in config) 2011-08-09 13:24:41,431-0500 INFO AbstractStreamKarajanChannel$Multiplexer Multiplexer 0 started 2011-08-09 13:24:41,431-0500 INFO AbstractStreamKarajanChannel$Multiplexer (0) Scheduling SC-null for addition 2011-08-09 13:24:41,431-0500 INFO AbstractStreamKarajanChannel$Multiplexer Multiplexer 1 started 2011-08-09 13:24:41,431-0500 INFO AbstractStreamKarajanChannel nullChannel started 2011-08-09 13:24:41,471-0500 INFO ChannelConfigurationCommand Got reply 2011-08-09 13:24:41,471-0500 INFO AbstractStreamKarajanChannel Channel configured 2011-08-09 13:24:41,472-0500 INFO MetaChannel MetaChannel: 1617829356[1567531625: {}] -> null.bind -> SC-null 2011-08-09 13:24:51,441-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:01,454-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:02,198-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-e-catnap-eeemg7ek/wrapper.log 2011-08-09 13:25:02,198-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-eeemg7ek.info 2011-08-09 13:25:02,200-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-e-catnap-eeemg7ek/./outdir/f.0008.out 2011-08-09 13:25:02,200-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0008.out 2011-08-09 13:25:02,201-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:25:02,201-0500 INFO Buffers elapsedTime=20455, buffersUsed[min,avg,max]=0, 0, 16 2011-08-09 13:25:02,202-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:25:02,203-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-eeemg7ek 2011-08-09 13:25:02,203-0500 INFO vdl:execute END_SUCCESS thread=0-4-7-1 tr=catnap 2011-08-09 13:25:02,205-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:25:02,205-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-7 2011-08-09 13:25:02,404-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-9-catnap-9eemg7ek/wrapper.log 2011-08-09 13:25:02,405-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-9eemg7ek.info 2011-08-09 13:25:02,405-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-9-catnap-9eemg7ek/./outdir/f.0007.out 2011-08-09 13:25:02,405-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0007.out 2011-08-09 13:25:02,467-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:25:02,468-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-9eemg7ek 2011-08-09 13:25:02,469-0500 INFO vdl:execute END_SUCCESS thread=0-4-6-1 tr=catnap 2011-08-09 13:25:02,469-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:25:02,470-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-6 2011-08-09 13:25:11,457-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:18,584-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:25:21,468-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:26,184-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-g-catnap-geemg7ek/./outdir/f.0002.out 2011-08-09 13:25:26,184-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0002.out 2011-08-09 13:25:26,185-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-c-catnap-ceemg7ek/wrapper.log 2011-08-09 13:25:26,185-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-ceemg7ek.info 2011-08-09 13:25:26,187-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-g-catnap-geemg7ek/wrapper.log 2011-08-09 13:25:26,187-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-geemg7ek.info 2011-08-09 13:25:26,188-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-c-catnap-ceemg7ek/./outdir/f.0010.out 2011-08-09 13:25:26,188-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0010.out 2011-08-09 13:25:26,188-0500 INFO Buffers elapsedTime=23987, buffersUsed[min,avg,max]=0, 0, 16 2011-08-09 13:25:26,190-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:25:26,190-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-ceemg7ek 2011-08-09 13:25:26,191-0500 INFO vdl:execute END_SUCCESS thread=0-4-9-1 tr=catnap 2011-08-09 13:25:26,191-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:25:26,191-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-9 2011-08-09 13:25:26,271-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:25:26,271-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-geemg7ek 2011-08-09 13:25:26,272-0500 INFO vdl:execute END_SUCCESS thread=0-4-1-1 tr=catnap 2011-08-09 13:25:26,272-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:25:26,273-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-1 2011-08-09 13:25:31,470-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:38,312-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:25:41,481-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:49,048-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:25:51,492-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:25:58,453-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-f-catnap-feemg7ek/wrapper.log 2011-08-09 13:25:58,453-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-feemg7ek.info 2011-08-09 13:25:58,456-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-f-catnap-feemg7ek/./outdir/f.0001.out 2011-08-09 13:25:58,456-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0001.out 2011-08-09 13:25:58,457-0500 INFO Buffers elapsedTime=32268, buffersUsed[min,avg,max]=0, 0, 16 2011-08-09 13:25:58,538-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-b-catnap-beemg7ek/./outdir/f.0004.out 2011-08-09 13:25:58,538-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0004.out 2011-08-09 13:25:58,539-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-b-catnap-beemg7ek/wrapper.log 2011-08-09 13:25:58,540-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-beemg7ek.info 2011-08-09 13:25:58,541-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:25:58,541-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-beemg7ek 2011-08-09 13:25:58,542-0500 INFO vdl:execute END_SUCCESS thread=0-4-3-1 tr=catnap 2011-08-09 13:25:58,542-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:25:58,542-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-3 2011-08-09 13:25:58,643-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:25:58,644-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-feemg7ek 2011-08-09 13:25:58,644-0500 INFO vdl:execute END_SUCCESS thread=0-4-0-1 tr=catnap 2011-08-09 13:25:58,645-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:25:58,645-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-0 2011-08-09 13:26:01,495-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:26:11,505-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:26:19,208-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-8-catnap-8eemg7ek/./outdir/f.0005.out 2011-08-09 13:26:19,208-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0005.out 2011-08-09 13:26:19,217-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-8-catnap-8eemg7ek/wrapper.log 2011-08-09 13:26:19,217-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-8eemg7ek.info 2011-08-09 13:26:19,217-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:26:19,218-0500 INFO Buffers elapsedTime=20761, buffersUsed[min,avg,max]=0, 0, 16 2011-08-09 13:26:19,219-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:26:19,220-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-8eemg7ek 2011-08-09 13:26:19,220-0500 INFO vdl:execute END_SUCCESS thread=0-4-4-1 tr=catnap 2011-08-09 13:26:19,221-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:26:19,221-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-4 2011-08-09 13:26:19,284-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-d-catnap-deemg7ek/wrapper.log 2011-08-09 13:26:19,284-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-deemg7ek.info 2011-08-09 13:26:19,286-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-d-catnap-deemg7ek/./outdir/f.0009.out 2011-08-09 13:26:19,286-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0009.out 2011-08-09 13:26:21,514-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:26:29,359-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:26:29,359-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-deemg7ek 2011-08-09 13:26:29,359-0500 INFO vdl:execute END_SUCCESS thread=0-4-8-1 tr=catnap 2011-08-09 13:26:29,360-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:26:29,360-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-8 2011-08-09 13:26:29,399-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:26:29,841-0500 INFO Buffers elapsedTime=10623, buffersUsed[min,avg,max]=0, 0, 16 2011-08-09 13:26:31,528-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:26:41,537-0500 INFO AbstractStreamKarajanChannel$Multiplexer Avg stream buf: 0 2011-08-09 13:26:49,978-0500 INFO AbstractStreamKarajanChannel Sender 1364844884 queue size: 0 2011-08-09 13:26:50,704-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-a-catnap-aeemg7ek/./outdir/f.0006.out 2011-08-09 13:26:50,704-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0006.out 2011-08-09 13:26:50,705-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-a-catnap-aeemg7ek/wrapper.log 2011-08-09 13:26:50,705-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-aeemg7ek.info 2011-08-09 13:26:50,706-0500 INFO Buffers elapsedTime=20864, buffersUsed[min,avg,max]=0, 0, 16 2011-08-09 13:26:50,787-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:26:50,788-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-7-catnap-7eemg7ek/./outdir/f.0003.out 2011-08-09 13:26:50,788-0500 INFO PutFileHandler Destination: file://localhost/outdir/f.0003.out 2011-08-09 13:26:50,788-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-aeemg7ek 2011-08-09 13:26:50,788-0500 INFO vdl:execute END_SUCCESS thread=0-4-5-1 tr=catnap 2011-08-09 13:26:50,789-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:26:50,789-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-5 2011-08-09 13:26:50,789-0500 INFO PutFileHandler Source: /home/ketan/swiftwork/catsnsleep-20110809-1324-ouf3x44c-7-catnap-7eemg7ek/wrapper.log 2011-08-09 13:26:50,789-0500 INFO PutFileHandler Destination: file://localhost/catsnsleep-20110809-1324-ouf3x44c.d/catnap-7eemg7ek.info 2011-08-09 13:26:50,791-0500 INFO LateBindingScheduler JobQueue: 0 2011-08-09 13:26:50,792-0500 DEBUG vdl:execute2 JOB_END jobid=catnap-7eemg7ek 2011-08-09 13:26:50,792-0500 INFO vdl:execute END_SUCCESS thread=0-4-2-1 tr=catnap 2011-08-09 13:26:50,792-0500 DEBUG catnap PROCEDURE_END line=3 2011-08-09 13:26:50,792-0500 DEBUG vdl:mains FOREACH_IT_END line=11 thread=0-4-2 2011-08-09 13:26:50,804-0500 DEBUG vdl:mains Starting cleanups 2011-08-09 13:26:50,805-0500 INFO vdl:cleanups START cleanups=[] 2011-08-09 13:26:50,806-0500 DEBUG vdl:mains Ending cleanups 2011-08-09 13:26:50,817-0500 INFO Loader Swift finished with no errors