See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/701/changes>
Changes:
[daijy] PIG-1272: Column pruner causes wrong results
[yanz] PIG-1269 Restrict schema definition for collection (xuefuz via yanz)
[daijy] PIG-1275: empty bag in PigStorage read as null
[daijy] PIG-1262: Additional findbugs and javac warnings
------------------------------------------
[...truncated 264384 lines...]
[junit] 10/03/12 06:38:17 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.xml
dst=null perm=null
[junit] 10/03/12 06:38:17 INFO DataNode.clienttrace: src: /127.0.0.1:42686,
dest: /127.0.0.1:57774, bytes: 58536, op: HDFS_READ, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_4256873942304067895_1015
[junit] 10/03/12 06:38:17 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.jar
dst=null perm=null
[junit] 10/03/12 06:38:17 INFO DataNode.clienttrace: src: /127.0.0.1:42686,
dest: /127.0.0.1:57775, bytes: 2938558, op: HDFS_READ, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_7259427486260096929_1013
[junit] 10/03/12 06:38:17 INFO mapred.JobTracker: Initializing
job_20100312063745454_0002
[junit] 10/03/12 06:38:17 INFO mapred.JobInProgress: Initializing
job_20100312063745454_0002
[junit] 10/03/12 06:38:17 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-2060163228/tmp-1422986437/_logs/history/localhost_1268375865477_job_20100312063745454_0002_hudson_Job8668352582147286323.jar
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/03/12 06:38:17 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-2060163228/tmp-1422986437/_logs/history/localhost_1268375865477_job_20100312063745454_0002_conf.xml
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/03/12 06:38:17 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-2060163228/tmp-1422986437/_logs/history/localhost_1268375865477_job_20100312063745454_0002_conf.xml.
blk_4377790819458890597_1017
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: Receiving block
blk_4377790819458890597_1017 src: /127.0.0.1:52352 dest: /127.0.0.1:53784
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: Receiving block
blk_4377790819458890597_1017 src: /127.0.0.1:57777 dest: /127.0.0.1:42686
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: Receiving block
blk_4377790819458890597_1017 src: /127.0.0.1:45743 dest: /127.0.0.1:35881
[junit] 10/03/12 06:38:17 INFO DataNode.clienttrace: src: /127.0.0.1:45743,
dest: /127.0.0.1:35881, bytes: 58047, op: HDFS_WRITE, cliID:
DFSClient_-929885692, srvID: DS-2117992871-127.0.1.1-35881-1268375863888,
blockid: blk_4377790819458890597_1017
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: PacketResponder 0 for
block blk_4377790819458890597_1017 terminating
[junit] 10/03/12 06:38:17 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35881 is added to
blk_4377790819458890597_1017 size 58047
[junit] 10/03/12 06:38:17 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42686 is added to
blk_4377790819458890597_1017 size 58047
[junit] 10/03/12 06:38:17 INFO DataNode.clienttrace: src: /127.0.0.1:57777,
dest: /127.0.0.1:42686, bytes: 58047, op: HDFS_WRITE, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_4377790819458890597_1017
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: PacketResponder 1 for
block blk_4377790819458890597_1017 terminating
[junit] 10/03/12 06:38:17 INFO DataNode.clienttrace: src: /127.0.0.1:52352,
dest: /127.0.0.1:53784, bytes: 58047, op: HDFS_WRITE, cliID:
DFSClient_-929885692, srvID: DS-618414809-127.0.1.1-53784-1268375864918,
blockid: blk_4377790819458890597_1017
[junit] 10/03/12 06:38:17 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:53784 is added to
blk_4377790819458890597_1017 size 58047
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: PacketResponder 2 for
block blk_4377790819458890597_1017 terminating
[junit] 10/03/12 06:38:17 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-2060163228/tmp-1422986437/_logs/history/localhost_1268375865477_job_20100312063745454_0002_conf.xml
is closed by DFSClient_-929885692
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: Deleting block
blk_-4258807910952529475_1005 file
build/test/data/dfs/data/data5/current/blk_-4258807910952529475
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: Deleting block
blk_-1047631512374203329_1007 file
build/test/data/dfs/data/data5/current/blk_-1047631512374203329
[junit] 10/03/12 06:38:17 INFO datanode.DataNode: Deleting block
blk_-752231875162437128_1006 file
build/test/data/dfs/data/data6/current/blk_-752231875162437128
[junit] 10/03/12 06:38:17 INFO mapReduceLayer.MapReduceLauncher:
HadoopJobId: job_20100312063745454_0002
[junit] 10/03/12 06:38:17 INFO mapReduceLayer.MapReduceLauncher: More
information at:
http://localhost:54529/jobdetails.jsp?jobid=job_20100312063745454_0002
[junit] 10/03/12 06:38:17 INFO mapReduceLayer.MapReduceLauncher: 0% complete
[junit] 10/03/12 06:38:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.split
dst=null perm=null
[junit] 10/03/12 06:38:18 INFO DataNode.clienttrace: src: /127.0.0.1:35881,
dest: /127.0.0.1:45744, bytes: 871, op: HDFS_READ, cliID: DFSClient_-929885692,
srvID: DS-2117992871-127.0.1.1-35881-1268375863888, blockid:
blk_584934780292262191_1014
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress: Input size for job
job_20100312063745454_0002 = 12. Number of splits = 2
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress:
tip:task_20100312063745454_0002_m_000000 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress:
tip:task_20100312063745454_0002_m_000000 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress:
tip:task_20100312063745454_0002_m_000000 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress:
tip:task_20100312063745454_0002_m_000001 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress:
tip:task_20100312063745454_0002_m_000001 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/03/12 06:38:18 INFO mapred.JobInProgress:
tip:task_20100312063745454_0002_m_000001 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/03/12 06:38:18 INFO mapred.JobTracker: Adding task
'attempt_20100312063745454_0002_m_000003_0' to tip
task_20100312063745454_0002_m_000003, for tracker
'tracker_host3.foo.com:localhost/127.0.0.1:34872'
[junit] 10/03/12 06:38:18 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100312063745454_0002_m_000003_0 task's
state:UNASSIGNED
[junit] 10/03/12 06:38:18 INFO mapred.TaskTracker: Trying to launch :
attempt_20100312063745454_0002_m_000003_0
[junit] 10/03/12 06:38:18 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100312063745454_0002_m_000003_0
[junit] 10/03/12 06:38:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.xml
dst=null perm=null
[junit] 10/03/12 06:38:18 INFO DataNode.clienttrace: src: /127.0.0.1:42686,
dest: /127.0.0.1:57780, bytes: 58536, op: HDFS_READ, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_4256873942304067895_1015
[junit] 10/03/12 06:38:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.jar
dst=null perm=null
[junit] 10/03/12 06:38:18 INFO DataNode.clienttrace: src: /127.0.0.1:42686,
dest: /127.0.0.1:57781, bytes: 2938558, op: HDFS_READ, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_7259427486260096929_1013
[junit] 10/03/12 06:38:18 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100312063745454_0002_m_-1289699987
[junit] 10/03/12 06:38:18 INFO mapred.JvmManager: JVM Runner
jvm_20100312063745454_0002_m_-1289699987 spawned.
[junit] 10/03/12 06:38:19 INFO mapred.TaskTracker: JVM with ID:
jvm_20100312063745454_0002_m_-1289699987 given task:
attempt_20100312063745454_0002_m_000003_0
[junit] 10/03/12 06:38:19 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs
src=/tmp/temp-2060163228/tmp-1422986437/_temporary dst=null
perm=hudson:supergroup:rwxr-xr-x
[junit] 10/03/12 06:38:19 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000003_0 0.0% setup
[junit] 10/03/12 06:38:19 INFO mapred.TaskTracker: Task
attempt_20100312063745454_0002_m_000003_0 is done.
[junit] 10/03/12 06:38:19 INFO mapred.TaskTracker: reported output size for
attempt_20100312063745454_0002_m_000003_0 was 0
[junit] 10/03/12 06:38:19 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/03/12 06:38:19 INFO mapred.JvmManager: JVM :
jvm_20100312063745454_0002_m_-1289699987 exited. Number of tasks it ran: 1
[junit] 10/03/12 06:38:19 INFO datanode.DataNode: Deleting block
blk_-1047631512374203329_1007 file
build/test/data/dfs/data/data2/current/blk_-1047631512374203329
[junit] 10/03/12 06:38:19 INFO datanode.DataNode: Deleting block
blk_-752231875162437128_1006 file
build/test/data/dfs/data/data1/current/blk_-752231875162437128
[junit] 10/03/12 06:38:19 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:42686
to delete blk_7259427486260096929_1013
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100312063745454_0002/attempt_20100312063745454_0002_m_000003_0/output/file.out
in any of the configured local directories
[junit] 10/03/12 06:38:21 INFO mapred.JobInProgress: Task
'attempt_20100312063745454_0002_m_000003_0' has completed
task_20100312063745454_0002_m_000003 successfully.
[junit] 10/03/12 06:38:21 INFO mapred.JobTracker: Adding task
'attempt_20100312063745454_0002_m_000000_0' to tip
task_20100312063745454_0002_m_000000, for tracker
'tracker_host3.foo.com:localhost/127.0.0.1:34872'
[junit] 10/03/12 06:38:21 INFO mapred.JobInProgress: Choosing rack-local
task task_20100312063745454_0002_m_000000
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100312063745454_0002_m_000000_0 task's
state:UNASSIGNED
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker: Trying to launch :
attempt_20100312063745454_0002_m_000000_0
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker: Received KillTaskAction
for task: attempt_20100312063745454_0002_m_000003_0
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100312063745454_0002_m_000000_0
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker: About to purge task:
attempt_20100312063745454_0002_m_000003_0
[junit] 10/03/12 06:38:21 INFO mapred.TaskRunner:
attempt_20100312063745454_0002_m_000003_0 done; removing files.
[junit] 10/03/12 06:38:21 INFO mapred.IndexCache: Map ID
attempt_20100312063745454_0002_m_000003_0 not found in cache
[junit] 10/03/12 06:38:21 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100312063745454_0002_m_2066713311
[junit] 10/03/12 06:38:21 INFO mapred.JvmManager: JVM Runner
jvm_20100312063745454_0002_m_2066713311 spawned.
[junit] 10/03/12 06:38:21 INFO datanode.DataNode: Deleting block
blk_7259427486260096929_1013 file
build/test/data/dfs/data/data7/current/blk_7259427486260096929
[junit] 10/03/12 06:38:21 INFO mapred.TaskTracker: JVM with ID:
jvm_20100312063745454_0002_m_2066713311 given task:
attempt_20100312063745454_0002_m_000000_0
[junit] 10/03/12 06:38:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000000_0/part-m-00000
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/03/12 06:38:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open src=/user/hudson/input2.txt dst=null
perm=null
[junit] 10/03/12 06:38:22 INFO DataNode.clienttrace: src: /127.0.0.1:35881,
dest: /127.0.0.1:45759, bytes: 10, op: HDFS_READ, cliID:
DFSClient_attempt_20100312063745454_0002_m_000000_0, srvID:
DS-2117992871-127.0.1.1-35881-1268375863888, blockid:
blk_-5341303872724648551_1012
[junit] 10/03/12 06:38:22 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000000_0/part-m-00000.
blk_207592183267179096_1018
[junit] 10/03/12 06:38:22 INFO datanode.DataNode: Receiving block
blk_207592183267179096_1018 src: /127.0.0.1:45760 dest: /127.0.0.1:35881
[junit] 10/03/12 06:38:22 INFO datanode.DataNode: Receiving block
blk_207592183267179096_1018 src: /127.0.0.1:52372 dest: /127.0.0.1:53784
[junit] 10/03/12 06:38:22 INFO datanode.DataNode: Receiving block
blk_207592183267179096_1018 src: /127.0.0.1:49341 dest: /127.0.0.1:35230
[junit] 10/03/12 06:38:22 INFO DataNode.clienttrace: src: /127.0.0.1:49341,
dest: /127.0.0.1:35230, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100312063745454_0002_m_000000_0, srvID:
DS-1721044753-127.0.1.1-35230-1268375864441, blockid:
blk_207592183267179096_1018
[junit] 10/03/12 06:38:22 INFO datanode.DataNode: PacketResponder 0 for
block blk_207592183267179096_1018 terminating
[junit] 10/03/12 06:38:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35230 is added to
blk_207592183267179096_1018 size 39
[junit] 10/03/12 06:38:22 INFO DataNode.clienttrace: src: /127.0.0.1:52372,
dest: /127.0.0.1:53784, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100312063745454_0002_m_000000_0, srvID:
DS-618414809-127.0.1.1-53784-1268375864918, blockid: blk_207592183267179096_1018
[junit] 10/03/12 06:38:22 INFO datanode.DataNode: PacketResponder 1 for
block blk_207592183267179096_1018 terminating
[junit] 10/03/12 06:38:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:53784 is added to
blk_207592183267179096_1018 size 39
[junit] 10/03/12 06:38:22 INFO DataNode.clienttrace: src: /127.0.0.1:45760,
dest: /127.0.0.1:35881, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100312063745454_0002_m_000000_0, srvID:
DS-2117992871-127.0.1.1-35881-1268375863888, blockid:
blk_207592183267179096_1018
[junit] 10/03/12 06:38:22 INFO datanode.DataNode: PacketResponder 2 for
block blk_207592183267179096_1018 terminating
[junit] 10/03/12 06:38:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35881 is added to
blk_207592183267179096_1018 size 39
[junit] 10/03/12 06:38:22 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000000_0/part-m-00000
is closed by DFSClient_attempt_20100312063745454_0002_m_000000_0
[junit] 10/03/12 06:38:22 INFO mapred.TaskTracker: Task
attempt_20100312063745454_0002_m_000000_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 10/03/12 06:38:22 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000000_0 0.0%
[junit] 10/03/12 06:38:22 INFO mapred.JobTracker: Adding task
'attempt_20100312063745454_0002_m_000001_0' to tip
task_20100312063745454_0002_m_000001, for tracker
'tracker_host0.foo.com:localhost/127.0.0.1:49055'
[junit] 10/03/12 06:38:22 INFO mapred.JobInProgress: Choosing rack-local
task task_20100312063745454_0002_m_000001
[junit] 10/03/12 06:38:22 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100312063745454_0002_m_000001_0 task's
state:UNASSIGNED
[junit] 10/03/12 06:38:22 INFO mapred.TaskTracker: Trying to launch :
attempt_20100312063745454_0002_m_000001_0
[junit] 10/03/12 06:38:22 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100312063745454_0002_m_000001_0
[junit] 10/03/12 06:38:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.xml
dst=null perm=null
[junit] 10/03/12 06:38:22 INFO DataNode.clienttrace: src: /127.0.0.1:42686,
dest: /127.0.0.1:57798, bytes: 58536, op: HDFS_READ, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_4256873942304067895_1015
[junit] 10/03/12 06:38:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002/job.jar
dst=null perm=null
[junit] 10/03/12 06:38:22 WARN datanode.DataNode:
DatanodeRegistration(127.0.0.1:42686,
storageID=DS-1835402022-127.0.1.1-42686-1268375865381, infoPort=33677,
ipcPort=52830):Got exception while serving blk_7259427486260096929_1013 to
/127.0.0.1:
[junit] java.io.IOException: Block blk_7259427486260096929_1013 is not
valid.
[junit] at
org.apache.hadoop.hdfs.server.datanode.FSDataset.getBlockFile(FSDataset.java:734)
[junit] at
org.apache.hadoop.hdfs.server.datanode.FSDataset.getLength(FSDataset.java:722)
[junit] at
org.apache.hadoop.hdfs.server.datanode.BlockSender.<init>(BlockSender.java:92)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiver.readBlock(DataXceiver.java:172)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiver.run(DataXceiver.java:95)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 10/03/12 06:38:22 ERROR datanode.DataNode:
DatanodeRegistration(127.0.0.1:42686,
storageID=DS-1835402022-127.0.1.1-42686-1268375865381, infoPort=33677,
ipcPort=52830):DataXceiver
[junit] java.io.IOException: Block blk_7259427486260096929_1013 is not
valid.
[junit] at
org.apache.hadoop.hdfs.server.datanode.FSDataset.getBlockFile(FSDataset.java:734)
[junit] at
org.apache.hadoop.hdfs.server.datanode.FSDataset.getLength(FSDataset.java:722)
[junit] at
org.apache.hadoop.hdfs.server.datanode.BlockSender.<init>(BlockSender.java:92)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiver.readBlock(DataXceiver.java:172)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiver.run(DataXceiver.java:95)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 10/03/12 06:38:23 INFO DataNode.clienttrace: src: /127.0.0.1:35881,
dest: /127.0.0.1:45765, bytes: 2938558, op: HDFS_READ, cliID:
DFSClient_-929885692, srvID: DS-2117992871-127.0.1.1-35881-1268375863888,
blockid: blk_7259427486260096929_1013
[junit] 10/03/12 06:38:23 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100312063745454_0002_m_592653225
[junit] 10/03/12 06:38:23 INFO mapred.JvmManager: JVM Runner
jvm_20100312063745454_0002_m_592653225 spawned.
[junit] 10/03/12 06:38:23 INFO mapred.TaskTracker: JVM with ID:
jvm_20100312063745454_0002_m_592653225 given task:
attempt_20100312063745454_0002_m_000001_0
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker: Received commit task
action for attempt_20100312063745454_0002_m_000000_0
[junit] 10/03/12 06:38:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000001_0/part-m-00001
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/03/12 06:38:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000000_0
dst=null perm=null
[junit] 10/03/12 06:38:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-2060163228/tmp-1422986437
dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 10/03/12 06:38:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000000_0/part-m-00000
dst=/tmp/temp-2060163228/tmp-1422986437/part-m-00000
perm=hudson:supergroup:rw-r--r--
[junit] 10/03/12 06:38:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open src=/user/hudson/input1.txt dst=null
perm=null
[junit] 10/03/12 06:38:24 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000000_0
dst=null perm=null
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000000_0 1.0%
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker: Task
attempt_20100312063745454_0002_m_000000_0 is done.
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker: reported output size for
attempt_20100312063745454_0002_m_000000_0 was 0
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/03/12 06:38:24 INFO DataNode.clienttrace: src: /127.0.0.1:35230,
dest: /127.0.0.1:49351, bytes: 10, op: HDFS_READ, cliID:
DFSClient_attempt_20100312063745454_0002_m_000001_0, srvID:
DS-1721044753-127.0.1.1-35230-1268375864441, blockid:
blk_3286427922181647816_1011
[junit] 10/03/12 06:38:24 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000001_0/part-m-00001.
blk_-4854551716908446867_1019
[junit] 10/03/12 06:38:24 INFO mapred.JvmManager: JVM :
jvm_20100312063745454_0002_m_2066713311 exited. Number of tasks it ran: 1
[junit] 10/03/12 06:38:24 INFO datanode.DataNode: Receiving block
blk_-4854551716908446867_1019 src: /127.0.0.1:45773 dest: /127.0.0.1:35881
[junit] 10/03/12 06:38:24 INFO datanode.DataNode: Receiving block
blk_-4854551716908446867_1019 src: /127.0.0.1:57809 dest: /127.0.0.1:42686
[junit] 10/03/12 06:38:24 INFO datanode.DataNode: Receiving block
blk_-4854551716908446867_1019 src: /127.0.0.1:49354 dest: /127.0.0.1:35230
[junit] 10/03/12 06:38:24 INFO DataNode.clienttrace: src: /127.0.0.1:49354,
dest: /127.0.0.1:35230, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100312063745454_0002_m_000001_0, srvID:
DS-1721044753-127.0.1.1-35230-1268375864441, blockid:
blk_-4854551716908446867_1019
[junit] 10/03/12 06:38:24 INFO datanode.DataNode: PacketResponder 0 for
block blk_-4854551716908446867_1019 terminating
[junit] 10/03/12 06:38:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35230 is added to
blk_-4854551716908446867_1019 size 39
[junit] 10/03/12 06:38:24 INFO DataNode.clienttrace: src: /127.0.0.1:57809,
dest: /127.0.0.1:42686, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100312063745454_0002_m_000001_0, srvID:
DS-1835402022-127.0.1.1-42686-1268375865381, blockid:
blk_-4854551716908446867_1019
[junit] 10/03/12 06:38:24 INFO datanode.DataNode: PacketResponder 1 for
block blk_-4854551716908446867_1019 terminating
[junit] 10/03/12 06:38:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42686 is added to
blk_-4854551716908446867_1019 size 39
[junit] 10/03/12 06:38:24 INFO DataNode.clienttrace: src: /127.0.0.1:45773,
dest: /127.0.0.1:35881, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100312063745454_0002_m_000001_0, srvID:
DS-2117992871-127.0.1.1-35881-1268375863888, blockid:
blk_-4854551716908446867_1019
[junit] 10/03/12 06:38:24 INFO datanode.DataNode: PacketResponder 2 for
block blk_-4854551716908446867_1019 terminating
[junit] 10/03/12 06:38:24 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35881 is added to
blk_-4854551716908446867_1019 size 39
[junit] 10/03/12 06:38:24 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000001_0/part-m-00001
is closed by DFSClient_attempt_20100312063745454_0002_m_000001_0
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker: Task
attempt_20100312063745454_0002_m_000001_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 10/03/12 06:38:24 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000001_0 0.0%
[junit] 10/03/12 06:38:25 INFO mapred.TaskTracker: Received commit task
action for attempt_20100312063745454_0002_m_000001_0
[junit] 10/03/12 06:38:26 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000001_0
dst=null perm=null
[junit] 10/03/12 06:38:26 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-2060163228/tmp-1422986437
dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 10/03/12 06:38:26 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000001_0/part-m-00001
dst=/tmp/temp-2060163228/tmp-1422986437/part-m-00001
perm=hudson:supergroup:rw-r--r--
[junit] 10/03/12 06:38:26 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-2060163228/tmp-1422986437/_temporary/_attempt_20100312063745454_0002_m_000001_0
dst=null perm=null
[junit] 10/03/12 06:38:26 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000001_0 1.0%
[junit] 10/03/12 06:38:26 INFO mapred.TaskTracker: Task
attempt_20100312063745454_0002_m_000001_0 is done.
[junit] 10/03/12 06:38:26 INFO mapred.TaskTracker: reported output size for
attempt_20100312063745454_0002_m_000001_0 was 0
[junit] 10/03/12 06:38:26 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/03/12 06:38:26 INFO mapred.JvmManager: JVM :
jvm_20100312063745454_0002_m_592653225 exited. Number of tasks it ran: 1
[junit] 10/03/12 06:38:27 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100312063745454_0002/attempt_20100312063745454_0002_m_000000_0/output/file.out
in any of the configured local directories
[junit] 10/03/12 06:38:27 INFO mapred.JobInProgress: Task
'attempt_20100312063745454_0002_m_000000_0' has completed
task_20100312063745454_0002_m_000000 successfully.
[junit] 10/03/12 06:38:27 INFO mapReduceLayer.MapReduceLauncher: 25%
complete
[junit] 10/03/12 06:38:28 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100312063745454_0002/attempt_20100312063745454_0002_m_000001_0/output/file.out
in any of the configured local directories
[junit] 10/03/12 06:38:28 INFO mapred.JobInProgress: Task
'attempt_20100312063745454_0002_m_000001_0' has completed
task_20100312063745454_0002_m_000001 successfully.
[junit] 10/03/12 06:38:28 INFO mapred.JobTracker: Adding task
'attempt_20100312063745454_0002_m_000002_0' to tip
task_20100312063745454_0002_m_000002, for tracker
'tracker_host0.foo.com:localhost/127.0.0.1:49055'
[junit] 10/03/12 06:38:28 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100312063745454_0002_m_000002_0 task's
state:UNASSIGNED
[junit] 10/03/12 06:38:28 INFO mapred.TaskTracker: Trying to launch :
attempt_20100312063745454_0002_m_000002_0
[junit] 10/03/12 06:38:28 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100312063745454_0002_m_000002_0
[junit] 10/03/12 06:38:28 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100312063745454_0002_m_-285993221
[junit] 10/03/12 06:38:28 INFO mapred.JvmManager: JVM Runner
jvm_20100312063745454_0002_m_-285993221 spawned.
[junit] 10/03/12 06:38:29 INFO mapReduceLayer.MapReduceLauncher: 50%
complete
[junit] 10/03/12 06:38:29 INFO mapred.TaskTracker: JVM with ID:
jvm_20100312063745454_0002_m_-285993221 given task:
attempt_20100312063745454_0002_m_000002_0
[junit] 10/03/12 06:38:29 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000002_0 0.0%
[junit] 10/03/12 06:38:29 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-2060163228/tmp-1422986437/_temporary dst=null
perm=null
[junit] 10/03/12 06:38:29 INFO mapred.TaskTracker:
attempt_20100312063745454_0002_m_000002_0 0.0% cleanup
[junit] 10/03/12 06:38:29 INFO mapred.TaskTracker: Task
attempt_20100312063745454_0002_m_000002_0 is done.
[junit] 10/03/12 06:38:29 INFO mapred.TaskTracker: reported output size for
attempt_20100312063745454_0002_m_000002_0 was 0
[junit] 10/03/12 06:38:29 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/03/12 06:38:29 INFO mapred.JvmManager: JVM :
jvm_20100312063745454_0002_m_-285993221 exited. Number of tasks it ran: 1
[junit] 10/03/12 06:38:31 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100312063745454_0002/attempt_20100312063745454_0002_m_000002_0/output/file.out
in any of the configured local directories
[junit] 10/03/12 06:38:31 INFO mapred.JobInProgress: Task
'attempt_20100312063745454_0002_m_000002_0' has completed
task_20100312063745454_0002_m_000002 successfully.
[junit] 10/03/12 06:38:31 INFO mapred.JobInProgress: Job
job_20100312063745454_0002 has completed successfully.
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-2060163228/tmp-1422986437/_logs/history/localhost_1268375865477_job_20100312063745454_0002_hudson_Job8668352582147286323.jar.
blk_-6923895407776766479_1019
[junit] 10/03/12 06:38:31 INFO datanode.DataNode: Receiving block
blk_-6923895407776766479_1019 src: /127.0.0.1:49361 dest: /127.0.0.1:35230
[junit] 10/03/12 06:38:31 INFO datanode.DataNode: Receiving block
blk_-6923895407776766479_1019 src: /127.0.0.1:52394 dest: /127.0.0.1:53784
[junit] 10/03/12 06:38:31 INFO datanode.DataNode: Receiving block
blk_-6923895407776766479_1019 src: /127.0.0.1:57819 dest: /127.0.0.1:42686
[junit] 10/03/12 06:38:31 INFO DataNode.clienttrace: src: /127.0.0.1:57819,
dest: /127.0.0.1:42686, bytes: 5856, op: HDFS_WRITE, cliID:
DFSClient_-929885692, srvID: DS-1835402022-127.0.1.1-42686-1268375865381,
blockid: blk_-6923895407776766479_1019
[junit] 10/03/12 06:38:31 INFO datanode.DataNode: PacketResponder 0 for
block blk_-6923895407776766479_1019 terminating
[junit] 10/03/12 06:38:31 INFO DataNode.clienttrace: src: /127.0.0.1:52394,
dest: /127.0.0.1:53784, bytes: 5856, op: HDFS_WRITE, cliID:
DFSClient_-929885692, srvID: DS-618414809-127.0.1.1-53784-1268375864918,
blockid: blk_-6923895407776766479_1019
[junit] 10/03/12 06:38:31 INFO datanode.DataNode: PacketResponder 1 for
block blk_-6923895407776766479_1019 terminating
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42686 is added to
blk_-6923895407776766479_1019 size 5856
[junit] 10/03/12 06:38:31 INFO DataNode.clienttrace: src: /127.0.0.1:49361,
dest: /127.0.0.1:35230, bytes: 5856, op: HDFS_WRITE, cliID:
DFSClient_-929885692, srvID: DS-1721044753-127.0.1.1-35230-1268375864441,
blockid: blk_-6923895407776766479_1019
[junit] 10/03/12 06:38:31 INFO datanode.DataNode: PacketResponder 2 for
block blk_-6923895407776766479_1019 terminating
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:53784 is added to
blk_-6923895407776766479_1019 size 5856
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35230 is added to
blk_-6923895407776766479_1019 size 5856
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-2060163228/tmp-1422986437/_logs/history/localhost_1268375865477_job_20100312063745454_0002_hudson_Job8668352582147286323.jar
is closed by DFSClient_-929885692
[junit] 10/03/12 06:38:31 INFO mapred.JobTracker: Removed completed task
'attempt_20100312063745454_0002_m_000001_0' from
'tracker_host0.foo.com:localhost/127.0.0.1:49055'
[junit] 10/03/12 06:38:31 INFO mapred.JobTracker: Removed completed task
'attempt_20100312063745454_0002_m_000002_0' from
'tracker_host0.foo.com:localhost/127.0.0.1:49055'
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7259427486260096929 is added to invalidSet of
127.0.0.1:35881
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7259427486260096929 is added to invalidSet of
127.0.0.1:42686
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7259427486260096929 is added to invalidSet of
127.0.0.1:35230
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_584934780292262191 is added to invalidSet of
127.0.0.1:53784
[junit] 10/03/12 06:38:31 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20100312063745454_0002
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_584934780292262191 is added to invalidSet of
127.0.0.1:35230
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_584934780292262191 is added to invalidSet of
127.0.0.1:35881
[junit] 10/03/12 06:38:31 INFO mapred.TaskRunner:
attempt_20100312063745454_0002_m_000001_0 done; removing files.
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_4256873942304067895 is added to invalidSet of
127.0.0.1:35230
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_4256873942304067895 is added to invalidSet of
127.0.0.1:42686
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_4256873942304067895 is added to invalidSet of
127.0.0.1:35881
[junit] 10/03/12 06:38:31 INFO mapred.IndexCache: Map ID
attempt_20100312063745454_0002_m_000001_0 not found in cache
[junit] 10/03/12 06:38:31 INFO mapred.TaskRunner:
attempt_20100312063745454_0002_m_000002_0 done; removing files.
[junit] 10/03/12 06:38:31 INFO mapred.IndexCache: Map ID
attempt_20100312063745454_0002_m_000002_0 not found in cache
[junit] 10/03/12 06:38:31 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20100312063745454_0002 dst=null
perm=null
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:35230
to delete blk_584934780292262191_1014 blk_4256873942304067895_1015
blk_7259427486260096929_1013
[junit] 10/03/12 06:38:31 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:42686
to delete blk_4256873942304067895_1015 blk_7259427486260096929_1013
[junit] 10/03/12 06:38:32 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20100312063745454_0002
[junit] 10/03/12 06:38:32 WARN mapred.TaskTracker: Unknown job
job_20100312063745454_0002 being deleted.
[junit] 10/03/12 06:38:32 INFO datanode.DataNode: Deleting block
blk_584934780292262191_1014 file
build/test/data/dfs/data/data4/current/blk_584934780292262191
[junit] 10/03/12 06:38:32 INFO datanode.DataNode: Deleting block
blk_4256873942304067895_1015 file
build/test/data/dfs/data/data3/current/blk_4256873942304067895
[junit] 10/03/12 06:38:32 INFO datanode.DataNode: Deleting block
blk_7259427486260096929_1013 file
build/test/data/dfs/data/data3/current/blk_7259427486260096929
[junit] 10/03/12 06:38:32 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20100312063745454_0002
[junit] 10/03/12 06:38:32 WARN mapred.TaskTracker: Unknown job
job_20100312063745454_0002 being deleted.
[junit] 10/03/12 06:38:33 INFO mapReduceLayer.MapReduceLauncher: 100%
complete
[junit] 10/03/12 06:38:33 INFO mapReduceLayer.MapReduceLauncher:
Successfully stored result in:
"hdfs://localhost:32895/tmp/temp-2060163228/tmp-1422986437"
[junit] 10/03/12 06:38:33 INFO mapReduceLayer.MapReduceLauncher: Records
written : 0
[junit] 10/03/12 06:38:33 INFO mapReduceLayer.MapReduceLauncher: Bytes
written : 78
[junit] 10/03/12 06:38:33 INFO mapReduceLayer.MapReduceLauncher: Success!
[junit] 10/03/12 06:38:33 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus src=/tmp/temp-2060163228/tmp-1422986437
dst=null perm=null
[junit] 10/03/12 06:38:33 INFO input.FileInputFormat: Total input paths to
process : 2
[junit] 10/03/12 06:38:33 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-2060163228/tmp-1422986437/part-m-00000 dst=null
perm=null
[junit] 10/03/12 06:38:33 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-2060163228/tmp-1422986437/part-m-00001 dst=null
perm=null
[junit] 10/03/12 06:38:33 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-2060163228/tmp-1422986437/part-m-00000 dst=null
perm=null
[junit] 10/03/12 06:38:33 INFO DataNode.clienttrace: src: /127.0.0.1:35881,
dest: /127.0.0.1:45786, bytes: 43, op: HDFS_READ, cliID: DFSClient_-929885692,
srvID: DS-2117992871-127.0.1.1-35881-1268375863888, blockid:
blk_207592183267179096_1018
[junit] 10/03/12 06:38:33 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-2060163228/tmp-1422986437/part-m-00001 dst=null
perm=null
[junit] 10/03/12 06:38:33 INFO DataNode.clienttrace: src: /127.0.0.1:42686,
dest: /127.0.0.1:57822, bytes: 43, op: HDFS_READ, cliID: DFSClient_-929885692,
srvID: DS-1835402022-127.0.1.1-42686-1268375865381, blockid:
blk_-4854551716908446867_1019
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.884 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:558:
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:491:
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:553:
Tests failed!
Total time: 280 minutes 14 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
