2008-07-14 14:17:36,346-0500 INFO Loader grid-many-dock6-string.swift: source file is new. Recompiling. 2008-07-14 14:17:37,187-0500 INFO Karajan Validation of XML intermediate file was successful 2008-07-14 14:17:37,195-0500 INFO VariableScope New scope 28890871 with no parent. 2008-07-14 14:17:37,202-0500 INFO VariableScope New scope 26171428 with no parent. 2008-07-14 14:17:37,202-0500 INFO VariableScope New scope 31706449 with no parent. 2008-07-14 14:17:37,210-0500 INFO VariableScope Adding variable t to scope 31706449 2008-07-14 14:17:37,211-0500 INFO VariableScope Adding variable ligandsfile to scope 26171428 2008-07-14 14:17:37,211-0500 INFO VariableScope Adding variable targetlist to scope 26171428 2008-07-14 14:17:37,276-0500 INFO VariableScope Adding variable pset to scope 28890871 2008-07-14 14:17:37,278-0500 INFO VariableScope New scope 14780827 with no parent. 2008-07-14 14:17:37,278-0500 INFO VariableScope Adding variable params to scope 14780827 2008-07-14 14:17:37,278-0500 INFO VariableScope Adding variable i to scope 14780827 2008-07-14 14:17:37,291-0500 INFO VariableScope Adding variable sout to scope 14780827 2008-07-14 14:17:37,296-0500 INFO VariableScope thats the declaration for sout 2008-07-14 14:17:38,163-0500 INFO unknown Using sites file: /home/houzx/vdsk-0.4/etc/sites.xml 2008-07-14 14:17:38,164-0500 INFO unknown Using tc.data: /home/houzx/vdsk-0.4/etc/tc.data 2008-07-14 14:17:38,837-0500 INFO unknown Swift v0.4 swift-r1718 cog-r1934 2008-07-14 14:17:38,838-0500 INFO unknown RUNID id=tag:benc@ci.uchicago.edu,2007:swift:run:20080714-1417-pmo8hsjf 2008-07-14 14:17:38,874-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000001 2008-07-14 14:17:38,877-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000001 path=$ 2008-07-14 14:17:38,877-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000001 VALUE=paramslist.txt 2008-07-14 14:17:38,889-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000001 2008-07-14 14:17:38,910-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 2008-07-14 14:17:38,910-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 path=[0].ligandsfile 2008-07-14 14:17:38,910-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 with no value at dataset=pset path=[0].ligandsfile (closed) 2008-07-14 14:17:38,912-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00180.mol2 2008-07-14 14:17:38,912-0500 INFO AbstractDataNode Found data pset.$[]/1.[0].ligandsfile 2008-07-14 14:17:38,912-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 2008-07-14 14:17:38,912-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 path=[0].targetlist 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 with no value at dataset=pset path=[0].targetlist (closed) 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 filename=file://localhost/1F9Y 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode Found data pset.$[]/1.[0].targetlist 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 path=[1].ligandsfile 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 with no value at dataset=pset path=[1].ligandsfile (closed) 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00181.mol2 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode Found data pset.$[]/2.[1].ligandsfile 2008-07-14 14:17:38,913-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 path=[1].targetlist 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 with no value at dataset=pset path=[1].targetlist (closed) 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 filename=file://localhost/1F9Y 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode Found data pset.$[]/2.[1].targetlist 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 path=[2].ligandsfile 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 with no value at dataset=pset path=[2].ligandsfile (closed) 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00182.mol2 2008-07-14 14:17:38,914-0500 INFO AbstractDataNode Found data pset.$[]/3.[2].ligandsfile 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 path=[2].targetlist 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 with no value at dataset=pset path=[2].targetlist (closed) 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 filename=file://localhost/1F9Y 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode Found data pset.$[]/3.[2].targetlist 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 path=$ 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 filename=null 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 2008-07-14 14:17:38,915-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 path=[2] 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00182.mol2 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 path=[2].ligandsfile 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 with no value at dataset=pset path=[2].ligandsfile (closed) 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00182.mol2 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 path=[2].targetlist 2008-07-14 14:17:38,916-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 with no value at dataset=pset path=[2].targetlist (closed) 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 filename=file://localhost/1F9Y 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 path=[0] 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00180.mol2 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 path=[0].ligandsfile 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 with no value at dataset=pset path=[0].ligandsfile (closed) 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00180.mol2 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 2008-07-14 14:17:38,917-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 path=[0].targetlist 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 with no value at dataset=pset path=[0].targetlist (closed) 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 filename=file://localhost/1F9Y 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 path=[1] 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00181.mol2 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 path=[1].ligandsfile 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 with no value at dataset=pset path=[1].ligandsfile (closed) 2008-07-14 14:17:38,918-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00181.mol2 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 path=[1].targetlist 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 with no value at dataset=pset path=[1].targetlist (closed) 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 filename=file://localhost/1F9Y 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 path=[2] 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00182.mol2 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 path=[2].ligandsfile 2008-07-14 14:17:38,919-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 with no value at dataset=pset path=[2].ligandsfile (closed) 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00182.mol2 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000009 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 path=[2].targetlist 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 with no value at dataset=pset path=[2].targetlist (closed) 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 filename=file://localhost/1F9Y 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 path=[0] 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00180.mol2 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 2008-07-14 14:17:38,920-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 path=[0].ligandsfile 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 with no value at dataset=pset path=[0].ligandsfile (closed) 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00180.mol2 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000003 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 path=[0].targetlist 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 with no value at dataset=pset path=[0].targetlist (closed) 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 filename=file://localhost/1F9Y 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 path=[1] 2008-07-14 14:17:38,921-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00181.mol2 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 path=[1].ligandsfile 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 with no value at dataset=pset path=[1].ligandsfile (closed) 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 filename=file://localhost//home/houzx/dock-run/databases/KEGG_and_Drugs/D00181.mol2 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode CONTAINMENT parent=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000006 child=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 path=[1].targetlist 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 VALUE=org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 with no value at dataset=pset path=[1].targetlist (closed) 2008-07-14 14:17:38,922-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 filename=file://localhost/1F9Y 2008-07-14 14:17:38,923-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000002 2008-07-14 14:17:38,938-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000012 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000012 path=$ 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000012 VALUE=0 2008-07-14 14:17:38,939-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000012 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000013 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000013 path=$ 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000013 VALUE=2 2008-07-14 14:17:38,939-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000013 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000014 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000014 path=$ 2008-07-14 14:17:38,939-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000014 VALUE=1 2008-07-14 14:17:38,940-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000014 2008-07-14 14:17:38,940-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000015 2008-07-14 14:17:38,940-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000015 path=$ 2008-07-14 14:17:38,940-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000015 VALUE=/home/houzx/dock-run/databases/results/ 2008-07-14 14:17:38,940-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000015 2008-07-14 14:17:38,940-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000016 2008-07-14 14:17:38,941-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000016 path=$ 2008-07-14 14:17:38,941-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000016 VALUE=/home/houzx/dock-run/databases/results/ 2008-07-14 14:17:38,941-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000016 2008-07-14 14:17:38,941-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000017 2008-07-14 14:17:38,941-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000017 path=$ 2008-07-14 14:17:38,941-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000017 VALUE=/home/houzx/dock-run/databases/results/ 2008-07-14 14:17:38,941-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000017 2008-07-14 14:17:38,942-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000018 2008-07-14 14:17:38,942-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000018 path=$ 2008-07-14 14:17:38,942-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000018 VALUE=- 2008-07-14 14:17:38,942-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000018 2008-07-14 14:17:38,942-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000019 2008-07-14 14:17:38,942-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000019 path=$ 2008-07-14 14:17:38,942-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000019 VALUE=- 2008-07-14 14:17:38,942-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000019 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000020 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000021 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000020 path=$ 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000021 path=$ 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000020 VALUE=- 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000021 VALUE=-stdout.txt 2008-07-14 14:17:38,943-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000020 2008-07-14 14:17:38,943-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000021 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000022 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000022 path=$ 2008-07-14 14:17:38,943-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000022 VALUE=/home/houzx/dock-run/databases/results/null-0-stdout.txt 2008-07-14 14:17:38,944-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000023 2008-07-14 14:17:38,944-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000023 path=$ 2008-07-14 14:17:38,944-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000023 VALUE=-stdout.txt 2008-07-14 14:17:38,944-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000023 2008-07-14 14:17:38,944-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000024 2008-07-14 14:17:38,944-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000024 path=$ 2008-07-14 14:17:38,944-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000024 VALUE=/home/houzx/dock-run/databases/results/null-2-stdout.txt 2008-07-14 14:17:38,947-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000026 2008-07-14 14:17:38,947-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000026 path=$ 2008-07-14 14:17:38,947-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000026 VALUE=-stdout.txt 2008-07-14 14:17:38,947-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000026 2008-07-14 14:17:38,947-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000027 2008-07-14 14:17:38,947-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000027 path=$ 2008-07-14 14:17:38,947-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000027 VALUE=/home/houzx/dock-run/databases/results/null-1-stdout.txt 2008-07-14 14:17:38,958-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 2008-07-14 14:17:38,959-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 2008-07-14 14:17:38,959-0500 INFO New NEW id=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 2008-07-14 14:17:38,962-0500 INFO dockcompute PROCEDURE thread=0-1 name=dockcompute 2008-07-14 14:17:38,962-0500 INFO dockcompute PROCEDURE thread=0-2 name=dockcompute 2008-07-14 14:17:38,962-0500 INFO dockcompute PROCEDURE thread=0-3 name=dockcompute 2008-07-14 14:17:38,963-0500 INFO vdl:parameterlog PARAM thread=0-2 direction=input variable=ligandsfile provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 2008-07-14 14:17:38,963-0500 INFO vdl:parameterlog PARAM thread=0-1 direction=input variable=ligandsfile provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 2008-07-14 14:17:38,963-0500 INFO vdl:parameterlog PARAM thread=0-3 direction=input variable=ligandsfile provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 2008-07-14 14:17:38,964-0500 INFO vdl:parameterlog PARAM thread=0-1 direction=input variable=targetlist provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 2008-07-14 14:17:38,964-0500 INFO vdl:parameterlog PARAM thread=0-2 direction=input variable=targetlist provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 2008-07-14 14:17:38,964-0500 INFO vdl:parameterlog PARAM thread=0-3 direction=input variable=targetlist provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 2008-07-14 14:17:38,965-0500 INFO vdl:parameterlog PARAM thread=0-1 direction=output variable=t provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 2008-07-14 14:17:38,965-0500 INFO vdl:parameterlog PARAM thread=0-2 direction=output variable=t provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 2008-07-14 14:17:38,966-0500 INFO vdl:parameterlog PARAM thread=0-3 direction=output variable=t provenanceid=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 2008-07-14 14:17:38,971-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000030 2008-07-14 14:17:38,971-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000030 path=$ 2008-07-14 14:17:38,971-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000030 VALUE=home/houzx/dock-run/databases/results/null-2-stdout.txt 2008-07-14 14:17:38,971-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000031 2008-07-14 14:17:38,971-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000031 path=$ 2008-07-14 14:17:38,971-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000031 VALUE=home/houzx/dock-run/databases/results/null-0-stdout.txt 2008-07-14 14:17:38,972-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000032 2008-07-14 14:17:38,972-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000032 path=$ 2008-07-14 14:17:38,972-0500 INFO AbstractDataNode VALUE dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000032 VALUE=home/houzx/dock-run/databases/results/null-1-stdout.txt 2008-07-14 14:17:38,978-0500 INFO vdl:execute START thread=0-1 tr=rundock 2008-07-14 14:17:38,978-0500 INFO vdl:execute START thread=0-2 tr=rundock 2008-07-14 14:17:38,978-0500 INFO vdl:execute START thread=0-3 tr=rundock 2008-07-14 14:17:38,992-0500 INFO WeightedHostScoreScheduler Sorted: [localhost:0.000(1.000):0/2] 2008-07-14 14:17:38,992-0500 DEBUG WeightedHostScoreScheduler Rand: 0.14882606670750087, sum: 1.0 2008-07-14 14:17:38,992-0500 DEBUG WeightedHostScoreScheduler Next contact: localhost:0.000(1.000):0/2 2008-07-14 14:17:38,994-0500 INFO WeightedHostScoreScheduler Sorted: [localhost:0.000(1.000):1/2] 2008-07-14 14:17:38,994-0500 DEBUG WeightedHostScoreScheduler Rand: 0.9746567677062962, sum: 1.0 2008-07-14 14:17:38,994-0500 DEBUG WeightedHostScoreScheduler Next contact: localhost:0.000(1.000):1/2 2008-07-14 14:17:38,994-0500 INFO WeightedHostScoreScheduler Sorted: [localhost:0.000(1.000):2/2] 2008-07-14 14:17:38,994-0500 DEBUG WeightedHostScoreScheduler Rand: 0.2728584280311005, sum: 1.0 2008-07-14 14:17:38,994-0500 INFO vdl:initshareddir START host=localhost - Initializing shared directory 2008-07-14 14:17:38,995-0500 DEBUG WeightedHostScoreScheduler Next contact: localhost:0.000(1.000):2/2 2008-07-14 14:17:38,997-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.01) 2008-07-14 14:17:38,997-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.010 2008-07-14 14:17:39,003-0500 INFO GlobalSubmitQueue No global submit throttle set. Using default (100) 2008-07-14 14:17:39,038-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057795) setting status to Submitting 2008-07-14 14:17:39,038-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057795) setting status to Submitted 2008-07-14 14:17:39,038-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057795) setting status to Active 2008-07-14 14:17:39,039-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057795) setting status to Completed 2008-07-14 14:17:39,039-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.010(0.994):3/2 overloaded, 0.01) 2008-07-14 14:17:39,039-0500 DEBUG WeightedHostScoreScheduler Old score: -0.010, new score: 0.000 2008-07-14 14:17:39,039-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1216063057795) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 194M, Max heap: 986M 2008-07-14 14:17:39,042-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.2) 2008-07-14 14:17:39,042-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.200 2008-07-14 14:17:39,054-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057798) setting status to Submitting 2008-07-14 14:17:39,054-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057798) setting status to Submitted 2008-07-14 14:17:39,054-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057798) setting status to Active 2008-07-14 14:17:39,055-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057798) setting status to Completed 2008-07-14 14:17:39,056-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.200(0.889):3/2 overloaded, 0.2) 2008-07-14 14:17:39,056-0500 DEBUG WeightedHostScoreScheduler Old score: -0.200, new score: 0.000 2008-07-14 14:17:39,056-0500 INFO LateBindingScheduler Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057798) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 194M, Max heap: 986M 2008-07-14 14:17:39,057-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.2) 2008-07-14 14:17:39,057-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.200 2008-07-14 14:17:39,057-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057803) setting status to Submitting 2008-07-14 14:17:39,057-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057803) setting status to Submitted 2008-07-14 14:17:39,057-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057803) setting status to Active 2008-07-14 14:17:39,060-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057803) setting status to Completed 2008-07-14 14:17:39,060-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.200(0.889):3/2 overloaded, 0.2) 2008-07-14 14:17:39,060-0500 DEBUG WeightedHostScoreScheduler Old score: -0.200, new score: 0.000 2008-07-14 14:17:39,060-0500 INFO LateBindingScheduler Task(type=FILE_TRANSFER, identity=urn:0-3-1216063057803) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 193M, Max heap: 986M 2008-07-14 14:17:39,061-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.01) 2008-07-14 14:17:39,061-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.010 2008-07-14 14:17:39,061-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057807) setting status to Submitting 2008-07-14 14:17:39,061-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057807) setting status to Submitted 2008-07-14 14:17:39,061-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057807) setting status to Active 2008-07-14 14:17:39,062-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057807) setting status to Completed 2008-07-14 14:17:39,062-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.010(0.994):3/2 overloaded, 0.01) 2008-07-14 14:17:39,062-0500 DEBUG WeightedHostScoreScheduler Old score: -0.010, new score: 0.000 2008-07-14 14:17:39,062-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1216063057807) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 193M, Max heap: 986M 2008-07-14 14:17:39,063-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.01) 2008-07-14 14:17:39,063-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.010 2008-07-14 14:17:39,063-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057809) setting status to Submitting 2008-07-14 14:17:39,063-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057809) setting status to Submitted 2008-07-14 14:17:39,063-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057809) setting status to Active 2008-07-14 14:17:39,063-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057809) setting status to Completed 2008-07-14 14:17:39,063-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.010(0.994):3/2 overloaded, 0.01) 2008-07-14 14:17:39,064-0500 DEBUG WeightedHostScoreScheduler Old score: -0.010, new score: 0.000 2008-07-14 14:17:39,064-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1216063057809) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 193M, Max heap: 986M 2008-07-14 14:17:39,064-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.01) 2008-07-14 14:17:39,064-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.010 2008-07-14 14:17:39,065-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057811) setting status to Submitting 2008-07-14 14:17:39,065-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057811) setting status to Submitted 2008-07-14 14:17:39,065-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057811) setting status to Active 2008-07-14 14:17:39,065-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057811) setting status to Completed 2008-07-14 14:17:39,065-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.010(0.994):3/2 overloaded, 0.01) 2008-07-14 14:17:39,065-0500 DEBUG WeightedHostScoreScheduler Old score: -0.010, new score: 0.000 2008-07-14 14:17:39,065-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1216063057811) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 193M, Max heap: 986M 2008-07-14 14:17:39,066-0500 INFO vdl:initshareddir END host=localhost - Done initializing shared directory 2008-07-14 14:17:39,069-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=rundock-foq1givi thread=0-3 host=localhost 2008-07-14 14:17:39,070-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=rundock-goq1givi thread=0-1 host=localhost 2008-07-14 14:17:39,070-0500 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=rundock-eoq1givi thread=0-2 host=localhost 2008-07-14 14:17:39,078-0500 INFO vdl:createdirset START jobid=rundock-foq1givi host=localhost - Initializing directory structure 2008-07-14 14:17:39,078-0500 INFO vdl:createdirset START jobid=rundock-goq1givi host=localhost - Initializing directory structure 2008-07-14 14:17:39,078-0500 INFO vdl:createdirset START jobid=rundock-eoq1givi host=localhost - Initializing directory structure 2008-07-14 14:17:39,080-0500 INFO vdl:createdirs START path=home/houzx/dock-run/databases/results dir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared - Creating directory structure 2008-07-14 14:17:39,080-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.01) 2008-07-14 14:17:39,081-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.010 2008-07-14 14:17:39,081-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057813) setting status to Submitting 2008-07-14 14:17:39,081-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057813) setting status to Submitted 2008-07-14 14:17:39,081-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057813) setting status to Active 2008-07-14 14:17:39,082-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057813) setting status to Completed 2008-07-14 14:17:39,082-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.010(0.994):3/2 overloaded, 0.01) 2008-07-14 14:17:39,082-0500 DEBUG WeightedHostScoreScheduler Old score: -0.010, new score: 0.000 2008-07-14 14:17:39,082-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-2-1216063057813) Completed. Waiting: 0, Running: 0. Heap size: 216M, Heap free: 193M, Max heap: 986M 2008-07-14 14:17:39,083-0500 INFO vdl:createdirset END jobid=rundock-goq1givi - Done initializing directory structure 2008-07-14 14:17:39,083-0500 INFO vdl:createdirset END jobid=rundock-foq1givi - Done initializing directory structure 2008-07-14 14:17:39,083-0500 INFO vdl:createdirset END jobid=rundock-eoq1givi - Done initializing directory structure 2008-07-14 14:17:39,084-0500 INFO vdl:dostagein START jobid=rundock-goq1givi - Staging in files 2008-07-14 14:17:39,084-0500 INFO vdl:dostagein START jobid=rundock-eoq1givi - Staging in files 2008-07-14 14:17:39,084-0500 INFO vdl:dostagein START jobid=rundock-foq1givi - Staging in files 2008-07-14 14:17:39,084-0500 INFO vdl:dostagein END jobid=rundock-goq1givi - Staging in finished 2008-07-14 14:17:39,084-0500 INFO vdl:dostagein END jobid=rundock-eoq1givi - Staging in finished 2008-07-14 14:17:39,085-0500 INFO vdl:dostagein END jobid=rundock-foq1givi - Staging in finished 2008-07-14 14:17:39,086-0500 DEBUG vdl:execute2 JOB_START jobid=rundock-eoq1givi tr=rundock arguments=[org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000004 with no value at dataset=pset path=[0].ligandsfile (closed), org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000005 with no value at dataset=pset path=[0].targetlist (closed)] tmpdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/jobs/e/rundock-eoq1givi host=localhost 2008-07-14 14:17:39,086-0500 DEBUG vdl:execute2 JOB_START jobid=rundock-goq1givi tr=rundock arguments=[org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000010 with no value at dataset=pset path=[2].ligandsfile (closed), org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000011 with no value at dataset=pset path=[2].targetlist (closed)] tmpdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/jobs/g/rundock-goq1givi host=localhost 2008-07-14 14:17:39,086-0500 DEBUG vdl:execute2 JOB_START jobid=rundock-foq1givi tr=rundock arguments=[org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000007 with no value at dataset=pset path=[1].ligandsfile (closed), org.griphyn.vdl.mapping.DataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000008 with no value at dataset=pset path=[1].targetlist (closed)] tmpdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/jobs/f/rundock-foq1givi host=localhost 2008-07-14 14:17:39,101-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.000(1.000):3/2 overloaded, -0.2) 2008-07-14 14:17:39,101-0500 DEBUG WeightedHostScoreScheduler Old score: 0.000, new score: -0.200 2008-07-14 14:17:39,103-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.200(0.889):3/2 overloaded, -0.2) 2008-07-14 14:17:39,103-0500 DEBUG WeightedHostScoreScheduler Old score: -0.200, new score: -0.400 2008-07-14 14:17:39,105-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.400(0.791):3/2 overloaded, -0.2) 2008-07-14 14:17:39,106-0500 DEBUG WeightedHostScoreScheduler Old score: -0.400, new score: -0.600 2008-07-14 14:17:39,107-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815) setting status to Submitting 2008-07-14 14:17:39,107-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817) setting status to Submitting 2008-07-14 14:17:39,107-0500 INFO JobSubmissionTaskHandler Submitting task Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815) 2008-07-14 14:17:39,107-0500 INFO JobSubmissionTaskHandler Submitting task Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817) 2008-07-14 14:17:39,107-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815) setting status to Submitted 2008-07-14 14:17:39,107-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817) setting status to Submitted 2008-07-14 14:17:39,107-0500 DEBUG WeightedHostScoreScheduler Submission time for Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815): 0ms. Score delta: 0.002564102564102564 2008-07-14 14:17:39,107-0500 DEBUG WeightedHostScoreScheduler Submission time for Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817): 0ms. Score delta: 0.002564102564102564 2008-07-14 14:17:39,107-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.600(0.705):3/2 overloaded, 0.002564102564102564) 2008-07-14 14:17:39,107-0500 DEBUG WeightedHostScoreScheduler Old score: -0.600, new score: -0.597 2008-07-14 14:17:39,107-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.597(0.706):3/2 overloaded, 0.002564102564102564) 2008-07-14 14:17:39,108-0500 DEBUG WeightedHostScoreScheduler Old score: -0.597, new score: -0.595 2008-07-14 14:17:39,108-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819) setting status to Submitting 2008-07-14 14:17:39,109-0500 INFO JobSubmissionTaskHandler Submitting task Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819) 2008-07-14 14:17:39,109-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819) setting status to Submitted 2008-07-14 14:17:39,109-0500 DEBUG WeightedHostScoreScheduler Submission time for Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819): 1ms. Score delta: 0.002558974358974359 2008-07-14 14:17:39,109-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.595(0.707):3/2 overloaded, 0.002558974358974359) 2008-07-14 14:17:39,109-0500 DEBUG WeightedHostScoreScheduler Old score: -0.595, new score: -0.592 2008-07-14 14:17:39,160-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817) setting status to Active 2008-07-14 14:17:39,165-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815) setting status to Active 2008-07-14 14:17:39,166-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819) setting status to Active 2008-07-14 14:17:43,789-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815) setting status to Completed 2008-07-14 14:17:43,790-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817) setting status to Completed 2008-07-14 14:17:43,792-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.592(0.708):3/2 overloaded, 0.2) 2008-07-14 14:17:43,792-0500 DEBUG WeightedHostScoreScheduler Old score: -0.592, new score: -0.392 2008-07-14 14:17:43,793-0500 INFO LateBindingScheduler Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057815) Completed. Waiting: 0, Running: 2. Heap size: 234M, Heap free: 225M, Max heap: 986M 2008-07-14 14:17:43,793-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.392(0.795):3/2 overloaded, 0.2) 2008-07-14 14:17:43,793-0500 DEBUG WeightedHostScoreScheduler Old score: -0.392, new score: -0.192 2008-07-14 14:17:43,793-0500 INFO LateBindingScheduler Task(type=JOB_SUBMISSION, identity=urn:0-2-1216063057817) Completed. Waiting: 0, Running: 1. Heap size: 234M, Heap free: 225M, Max heap: 986M 2008-07-14 14:17:43,794-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819) setting status to Completed 2008-07-14 14:17:43,794-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.192(0.893):3/2 overloaded, 0.2) 2008-07-14 14:17:43,795-0500 DEBUG WeightedHostScoreScheduler Old score: -0.192, new score: 0.008 2008-07-14 14:17:43,795-0500 INFO LateBindingScheduler Task(type=JOB_SUBMISSION, identity=urn:0-3-1216063057819) Completed. Waiting: 0, Running: 0. Heap size: 234M, Heap free: 224M, Max heap: 986M 2008-07-14 14:17:43,795-0500 DEBUG vdl:checkjobstatus START jobid=rundock-goq1givi 2008-07-14 14:17:43,795-0500 DEBUG vdl:checkjobstatus START jobid=rundock-eoq1givi 2008-07-14 14:17:43,796-0500 DEBUG vdl:checkjobstatus START jobid=rundock-foq1givi 2008-07-14 14:17:43,796-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.008(1.005):3/2 overloaded, -0.01) 2008-07-14 14:17:43,797-0500 DEBUG WeightedHostScoreScheduler Old score: 0.008, new score: -0.002 2008-07-14 14:17:43,797-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1216063057821) setting status to Submitting 2008-07-14 14:17:43,797-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.002(0.999):3/2 overloaded, -0.01) 2008-07-14 14:17:43,797-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1216063057821) setting status to Submitted 2008-07-14 14:17:43,797-0500 DEBUG WeightedHostScoreScheduler Old score: -0.002, new score: -0.012 2008-07-14 14:17:43,797-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1216063057821) setting status to Active 2008-07-14 14:17:43,797-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057823) setting status to Submitting 2008-07-14 14:17:43,798-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1216063057821) setting status to Completed 2008-07-14 14:17:43,798-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057823) setting status to Submitted 2008-07-14 14:17:43,798-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.012(0.993):3/2 overloaded, 0.01) 2008-07-14 14:17:43,798-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057823) setting status to Active 2008-07-14 14:17:43,798-0500 DEBUG WeightedHostScoreScheduler Old score: -0.012, new score: -0.002 2008-07-14 14:17:43,798-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-1-1216063057821) Completed. Waiting: 0, Running: 1. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,798-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1216063057823) setting status to Completed 2008-07-14 14:17:43,798-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.002(0.999):3/2 overloaded, 0.01) 2008-07-14 14:17:43,798-0500 DEBUG WeightedHostScoreScheduler Old score: -0.002, new score: 0.008 2008-07-14 14:17:43,798-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-2-1216063057823) Completed. Waiting: 0, Running: 0. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,799-0500 INFO vdl:checkjobstatus SUCCESS jobid=rundock-goq1givi - Success file found 2008-07-14 14:17:43,799-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.008(1.005):3/2 overloaded, -0.01) 2008-07-14 14:17:43,799-0500 INFO vdl:checkjobstatus SUCCESS jobid=rundock-eoq1givi - Success file found 2008-07-14 14:17:43,801-0500 DEBUG vdl:execute2 JOB_END jobid=rundock-eoq1givi 2008-07-14 14:17:43,802-0500 INFO vdl:dostageout START jobid=rundock-eoq1givi - Staging out files 2008-07-14 14:17:43,803-0500 DEBUG vdl:execute2 JOB_END jobid=rundock-goq1givi 2008-07-14 14:17:43,799-0500 DEBUG WeightedHostScoreScheduler Old score: 0.008, new score: -0.002 2008-07-14 14:17:43,804-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057826) setting status to Submitting 2008-07-14 14:17:43,804-0500 INFO vdl:dostageout START jobid=rundock-goq1givi - Staging out files 2008-07-14 14:17:43,805-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057826) setting status to Submitted 2008-07-14 14:17:43,805-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057826) setting status to Active 2008-07-14 14:17:43,805-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1216063057826) setting status to Completed 2008-07-14 14:17:43,805-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.002(0.999):3/2 overloaded, 0.01) 2008-07-14 14:17:43,805-0500 DEBUG WeightedHostScoreScheduler Old score: -0.002, new score: 0.008 2008-07-14 14:17:43,805-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1216063057826) Completed. Waiting: 0, Running: 0. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,806-0500 INFO vdl:checkjobstatus SUCCESS jobid=rundock-foq1givi - Success file found 2008-07-14 14:17:43,807-0500 DEBUG vdl:execute2 JOB_END jobid=rundock-foq1givi 2008-07-14 14:17:43,808-0500 INFO vdl:dostageout START jobid=rundock-foq1givi - Staging out files 2008-07-14 14:17:43,811-0500 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=null-0-stdout.txt srcdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared/home/houzx/dock-run/databases/results srchost=localhost destdir=/home/houzx/dock-run/databases/results desthost=localhost provider=file 2008-07-14 14:17:43,812-0500 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=null-2-stdout.txt srcdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared/home/houzx/dock-run/databases/results srchost=localhost destdir=/home/houzx/dock-run/databases/results desthost=localhost provider=file 2008-07-14 14:17:43,812-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057828) setting status to Submitting 2008-07-14 14:17:43,813-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057828) setting status to Submitted 2008-07-14 14:17:43,813-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057828) setting status to Active 2008-07-14 14:17:43,813-0500 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=null-1-stdout.txt srcdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared/home/houzx/dock-run/databases/results srchost=localhost destdir=/home/houzx/dock-run/databases/results desthost=localhost provider=file 2008-07-14 14:17:43,813-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057828) setting status to Completed 2008-07-14 14:17:43,813-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057828) Completed. Waiting: 0, Running: 1. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,813-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057830) setting status to Submitting 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057835) setting status to Submitting 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057830) setting status to Submitted 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057830) setting status to Active 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057835) setting status to Submitted 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057835) setting status to Active 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057830) setting status to Completed 2008-07-14 14:17:43,814-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057830) Completed. Waiting: 0, Running: 1. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,814-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057835) setting status to Completed 2008-07-14 14:17:43,814-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057835) Completed. Waiting: 0, Running: 0. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,816-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:0.008(1.005):3/2 overloaded, -0.2) 2008-07-14 14:17:43,816-0500 DEBUG WeightedHostScoreScheduler Old score: 0.008, new score: -0.192 2008-07-14 14:17:43,817-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.192(0.893):3/2 overloaded, -0.2) 2008-07-14 14:17:43,817-0500 DEBUG WeightedHostScoreScheduler Old score: -0.192, new score: -0.392 2008-07-14 14:17:43,817-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-1-1-1216063057843) setting status to Submitting 2008-07-14 14:17:43,817-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-1-1-1216063057843) setting status to Submitted 2008-07-14 14:17:43,817-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.392(0.795):3/2 overloaded, -0.2) 2008-07-14 14:17:43,817-0500 DEBUG WeightedHostScoreScheduler Old score: -0.392, new score: -0.592 2008-07-14 14:17:43,817-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-2-1-1216063057840) setting status to Submitting 2008-07-14 14:17:43,817-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1-1216063057846) setting status to Submitting 2008-07-14 14:17:43,818-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-2-1-1216063057840) setting status to Submitted 2008-07-14 14:17:43,818-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1-1216063057846) setting status to Submitted 2008-07-14 14:17:43,818-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-2-1-1216063057840) setting status to Active 2008-07-14 14:17:43,818-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1-1216063057846) setting status to Active 2008-07-14 14:17:43,817-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-1-1-1216063057843) setting status to Active 2008-07-14 14:17:43,819-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-2-1-1216063057840) setting status to Completed 2008-07-14 14:17:43,820-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.592(0.708):3/2 overloaded, 0.2) 2008-07-14 14:17:43,820-0500 DEBUG WeightedHostScoreScheduler Old score: -0.592, new score: -0.392 2008-07-14 14:17:43,820-0500 INFO LateBindingScheduler Task(type=FILE_TRANSFER, identity=urn:0-2-1-1216063057840) Completed. Waiting: 0, Running: 2. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,821-0500 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=null-0-stdout.txt srcdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared/home/houzx/dock-run/databases/results srchost=localhost destdir=/home/houzx/dock-run/databases/results desthost=localhost provider=file 2008-07-14 14:17:43,822-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-3-1-1216063057846) setting status to Completed 2008-07-14 14:17:43,822-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057852) setting status to Submitting 2008-07-14 14:17:43,822-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.392(0.795):3/2 overloaded, 0.2) 2008-07-14 14:17:43,822-0500 DEBUG WeightedHostScoreScheduler Old score: -0.392, new score: -0.192 2008-07-14 14:17:43,822-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057852) setting status to Submitted 2008-07-14 14:17:43,822-0500 INFO LateBindingScheduler Task(type=FILE_TRANSFER, identity=urn:0-3-1-1216063057846) Completed. Waiting: 0, Running: 2. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,822-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057852) setting status to Active 2008-07-14 14:17:43,823-0500 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=null-1-stdout.txt srcdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared/home/houzx/dock-run/databases/results srchost=localhost destdir=/home/houzx/dock-run/databases/results desthost=localhost provider=file 2008-07-14 14:17:43,824-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057856) setting status to Submitting 2008-07-14 14:17:43,824-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057856) setting status to Submitted 2008-07-14 14:17:43,824-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057856) setting status to Active 2008-07-14 14:17:43,825-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057856) setting status to Completed 2008-07-14 14:17:43,825-0500 DEBUG TaskImpl Task(type=FILE_TRANSFER, identity=urn:0-1-1-1216063057843) setting status to Completed 2008-07-14 14:17:43,825-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-3-1-1216063057856) Completed. Waiting: 0, Running: 2. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,825-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:-0.192(0.893):3/2 overloaded, 0.2) 2008-07-14 14:17:43,825-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057852) setting status to Completed 2008-07-14 14:17:43,826-0500 DEBUG WeightedHostScoreScheduler Old score: -0.192, new score: 0.008 2008-07-14 14:17:43,826-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-2-1-1216063057852) Completed. Waiting: 0, Running: 2. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,826-0500 INFO LateBindingScheduler Task(type=FILE_TRANSFER, identity=urn:0-1-1-1216063057843) Completed. Waiting: 0, Running: 1. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,828-0500 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=null-2-stdout.txt srcdir=grid-many-dock6-string-20080714-1417-pmo8hsjf/shared/home/houzx/dock-run/databases/results srchost=localhost destdir=/home/houzx/dock-run/databases/results desthost=localhost provider=file 2008-07-14 14:17:43,830-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057860) setting status to Submitting 2008-07-14 14:17:43,831-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057860) setting status to Submitted 2008-07-14 14:17:43,831-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057860) setting status to Active 2008-07-14 14:17:43,831-0500 DEBUG TaskImpl Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057860) setting status to Completed 2008-07-14 14:17:43,832-0500 INFO LateBindingScheduler Task(type=FILE_OPERATION, identity=urn:0-1-1-1216063057860) Completed. Waiting: 0, Running: 0. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,835-0500 INFO vdl:dostageout END jobid=rundock-foq1givi - Staging out finished 2008-07-14 14:17:43,835-0500 INFO vdl:dostageout END jobid=rundock-eoq1givi - Staging out finished 2008-07-14 14:17:43,836-0500 INFO vdl:dostageout END jobid=rundock-goq1givi - Staging out finished 2008-07-14 14:17:43,838-0500 DEBUG WeightedHostScoreScheduler Releasing contact 4 2008-07-14 14:17:43,839-0500 DEBUG WeightedHostScoreScheduler Releasing contact 3 2008-07-14 14:17:43,839-0500 DEBUG WeightedHostScoreScheduler Releasing contact 2 2008-07-14 14:17:43,842-0500 INFO vdl:execute END_SUCCESS thread=0-2 tr=rundock 2008-07-14 14:17:43,842-0500 INFO vdl:execute END_SUCCESS thread=0-1 tr=rundock 2008-07-14 14:17:43,843-0500 INFO CloseDataset Closing org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 with no value at dataset=sout (not closed) 2008-07-14 14:17:43,843-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 2008-07-14 14:17:43,843-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 path=$ 2008-07-14 14:17:43,844-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 filename=file://localhost//home/houzx/dock-run/databases/results/null-0-stdout.txt 2008-07-14 14:17:43,844-0500 INFO vdl:execute END_SUCCESS thread=0-3 tr=rundock 2008-07-14 14:17:43,844-0500 INFO CloseDataset Partially closing org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 with no value at dataset=sout (closed) for statement 88000 2008-07-14 14:17:43,844-0500 INFO CloseDataset var is org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 with no value at dataset=sout (closed) 2008-07-14 14:17:43,845-0500 INFO CloseDataset var hash is 7329120 2008-07-14 14:17:43,845-0500 INFO CloseDataset dump of what's in cache: 2008-07-14 14:17:43,845-0500 INFO CloseDataset Adding token 88000 with hash 53433136 2008-07-14 14:17:43,845-0500 INFO CloseDataset need to wait for 88000 2008-07-14 14:17:43,845-0500 INFO CloseDataset Container does contain token 88000 2008-07-14 14:17:43,845-0500 INFO CloseDataset hasUnseenToken = false 2008-07-14 14:17:43,845-0500 INFO CloseDataset All partial closes for org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 with no value at dataset=sout (closed) have happened. Closing fully. 2008-07-14 14:17:43,845-0500 INFO CloseDataset Closing org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 with no value at dataset=sout (not closed) 2008-07-14 14:17:43,845-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 2008-07-14 14:17:43,845-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 path=$ 2008-07-14 14:17:43,845-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 filename=file://localhost//home/houzx/dock-run/databases/results/null-2-stdout.txt 2008-07-14 14:17:43,846-0500 INFO CloseDataset Partially closing org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 with no value at dataset=sout (closed) for statement 88000 2008-07-14 14:17:43,846-0500 INFO CloseDataset var is org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 with no value at dataset=sout (closed) 2008-07-14 14:17:43,846-0500 INFO CloseDataset var hash is 28834171 2008-07-14 14:17:43,846-0500 INFO CloseDataset dump of what's in cache: 2008-07-14 14:17:43,846-0500 INFO CloseDataset org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 with no value at dataset=sout (closed) 2008-07-14 14:17:43,846-0500 INFO CloseDataset Adding token 88000 with hash 53433136 2008-07-14 14:17:43,846-0500 INFO CloseDataset need to wait for 88000 2008-07-14 14:17:43,846-0500 INFO CloseDataset Container does contain token 88000 2008-07-14 14:17:43,846-0500 INFO CloseDataset hasUnseenToken = false 2008-07-14 14:17:43,846-0500 INFO CloseDataset All partial closes for org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 with no value at dataset=sout (closed) have happened. Closing fully. 2008-07-14 14:17:43,848-0500 INFO CloseDataset Closing org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 with no value at dataset=sout (not closed) 2008-07-14 14:17:43,849-0500 INFO AbstractDataNode closed tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 2008-07-14 14:17:43,849-0500 INFO AbstractDataNode ROOTPATH dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 path=$ 2008-07-14 14:17:43,849-0500 INFO AbstractDataNode FILENAME dataset=tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 filename=file://localhost//home/houzx/dock-run/databases/results/null-1-stdout.txt 2008-07-14 14:17:43,849-0500 INFO CloseDataset Partially closing org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 with no value at dataset=sout (closed) for statement 88000 2008-07-14 14:17:43,849-0500 INFO CloseDataset var is org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 with no value at dataset=sout (closed) 2008-07-14 14:17:43,849-0500 INFO CloseDataset var hash is 2286508 2008-07-14 14:17:43,849-0500 INFO CloseDataset dump of what's in cache: 2008-07-14 14:17:43,849-0500 INFO CloseDataset org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000025 with no value at dataset=sout (closed) 2008-07-14 14:17:43,849-0500 INFO CloseDataset org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000028 with no value at dataset=sout (closed) 2008-07-14 14:17:43,849-0500 INFO CloseDataset Adding token 88000 with hash 53433136 2008-07-14 14:17:43,849-0500 INFO CloseDataset need to wait for 88000 2008-07-14 14:17:43,849-0500 INFO CloseDataset Container does contain token 88000 2008-07-14 14:17:43,850-0500 INFO CloseDataset hasUnseenToken = false 2008-07-14 14:17:43,850-0500 INFO CloseDataset All partial closes for org.griphyn.vdl.mapping.RootDataNode identifier tag:benc@ci.uchicago.edu,2008:swift:dataset:20080714-1417-l8ugu9ic:720000000029 with no value at dataset=sout (closed) have happened. Closing fully. 2008-07-14 14:17:43,862-0500 INFO vdl:cleanups START cleanups=[[grid-many-dock6-string-20080714-1417-pmo8hsjf, localhost]] 2008-07-14 14:17:43,863-0500 INFO vdl:cleanup START dir=grid-many-dock6-string-20080714-1417-pmo8hsjf host=localhost 2008-07-14 14:17:43,865-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:5.108(10.317):0/4, -0.2) 2008-07-14 14:17:43,865-0500 DEBUG WeightedHostScoreScheduler Old score: 5.108, new score: 4.908 2008-07-14 14:17:43,866-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864) setting status to Submitting 2008-07-14 14:17:43,866-0500 INFO JobSubmissionTaskHandler Submitting task Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864) 2008-07-14 14:17:43,866-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864) setting status to Submitted 2008-07-14 14:17:43,866-0500 DEBUG WeightedHostScoreScheduler Submission time for Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864): 1ms. Score delta: 0.002558974358974359 2008-07-14 14:17:43,866-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:4.908(9.731):0/3, 0.002558974358974359) 2008-07-14 14:17:43,866-0500 DEBUG WeightedHostScoreScheduler Old score: 4.908, new score: 4.910 2008-07-14 14:17:43,866-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864) setting status to Active 2008-07-14 14:17:43,866-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864) setting status to Completed 2008-07-14 14:17:43,866-0500 DEBUG WeightedHostScoreScheduler multiplyScore(localhost:4.910(9.738):0/3, 0.2) 2008-07-14 14:17:43,867-0500 DEBUG WeightedHostScoreScheduler Old score: 4.910, new score: 5.110 2008-07-14 14:17:43,867-0500 INFO LateBindingScheduler Task(type=JOB_SUBMISSION, identity=urn:0-1-1216063057864) Completed. Waiting: 0, Running: 0. Heap size: 234M, Heap free: 223M, Max heap: 986M 2008-07-14 14:17:43,867-0500 INFO vdl:cleanup END dir=grid-many-dock6-string-20080714-1417-pmo8hsjf host=localhost 2008-07-14 14:17:43,875-0500 DEBUG Loader Swift finished with no errors