hadoop-pig-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: Pig-trunk #660
Date Thu, 07 Jan 2010 02:24:57 GMT
See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/660/changes>

Changes:

[daijy] PIG-1180: Piggybank should compile even if we only have "pig-withouthadoop.jar" but
no "pig.jar" in the pig home directory

[pradeepkth] This is to cleanup the local mode code after switching to using hadoop local
mode

------------------------------------------
[...truncated 240452 lines...]
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: Receiving block blk_4529900263997112706_1017
src: /127.0.0.1:38624 dest: /127.0.0.1:50773
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: Receiving block blk_4529900263997112706_1017
src: /127.0.0.1:55172 dest: /127.0.0.1:34653
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: Receiving block blk_4529900263997112706_1017
src: /127.0.0.1:43946 dest: /127.0.0.1:32866
    [junit] 10/01/07 02:23:09 INFO DataNode.clienttrace: src: /127.0.0.1:43946, dest: /127.0.0.1:32866,
bytes: 48857, op: HDFS_WRITE, cliID: DFSClient_1941479077, srvID: DS-1000068095-127.0.1.1-32866-1262830956384,
blockid: blk_4529900263997112706_1017
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: PacketResponder 0 for block blk_4529900263997112706_1017
terminating
    [junit] 10/01/07 02:23:09 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:32866 is added to blk_4529900263997112706_1017 size 48857
    [junit] 10/01/07 02:23:09 INFO DataNode.clienttrace: src: /127.0.0.1:55172, dest: /127.0.0.1:34653,
bytes: 48857, op: HDFS_WRITE, cliID: DFSClient_1941479077, srvID: DS-75923346-127.0.1.1-34653-1262830957336,
blockid: blk_4529900263997112706_1017
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: PacketResponder 1 for block blk_4529900263997112706_1017
terminating
    [junit] 10/01/07 02:23:09 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:34653 is added to blk_4529900263997112706_1017 size 48857
    [junit] 10/01/07 02:23:09 INFO DataNode.clienttrace: src: /127.0.0.1:38624, dest: /127.0.0.1:50773,
bytes: 48857, op: HDFS_WRITE, cliID: DFSClient_1941479077, srvID: DS-1435789546-127.0.1.1-50773-1262830956883,
blockid: blk_4529900263997112706_1017
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: PacketResponder 2 for block blk_4529900263997112706_1017
terminating
    [junit] 10/01/07 02:23:09 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50773 is added to blk_4529900263997112706_1017 size 48857
    [junit] 10/01/07 02:23:09 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1306912187/tmp-891082635/_logs/history/localhost_1262830957897_job_20100107022237874_0002_conf.xml
is closed by DFSClient_1941479077
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: Deleting block blk_-4766788746537872722_1006
file build/test/data/dfs/data/data3/current/blk_-4766788746537872722
    [junit] 10/01/07 02:23:09 INFO datanode.DataNode: Deleting block blk_6737284167407333489_1007
file build/test/data/dfs/data/data4/current/blk_6737284167407333489
    [junit] 10/01/07 02:23:09 INFO mapReduceLayer.MapReduceLauncher: Submitting job: job_20100107022237874_0002
to execution engine.
    [junit] 10/01/07 02:23:09 INFO mapReduceLayer.MapReduceLauncher: More information at:
http://localhost:54010/jobdetails.jsp?jobid=job_20100107022237874_0002
    [junit] 10/01/07 02:23:09 INFO mapReduceLayer.MapReduceLauncher: To kill this job, use:
kill job_20100107022237874_0002
    [junit] 10/01/07 02:23:10 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100107022237874_0002/job.split	dst=null	perm=null
    [junit] 10/01/07 02:23:10 INFO DataNode.clienttrace: src: /127.0.0.1:45812, dest: /127.0.0.1:34580,
bytes: 1605, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-1109484639-127.0.1.1-45812-1262830957804,
blockid: blk_-1244555586616345040_1014
    [junit] 10/01/07 02:23:10 INFO mapred.JobInProgress: Input size for job job_20100107022237874_0002
= 12. Number of splits = 2
    [junit] 10/01/07 02:23:10 INFO mapred.JobInProgress: tip:task_20100107022237874_0002_m_000000
has split on node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 10/01/07 02:23:10 INFO mapred.JobInProgress: tip:task_20100107022237874_0002_m_000001
has split on node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 10/01/07 02:23:10 INFO mapred.JobTracker: Adding task 'attempt_20100107022237874_0002_m_000003_0'
to tip task_20100107022237874_0002_m_000003, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:40200'
    [junit] 10/01/07 02:23:10 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100107022237874_0002_m_000003_0
task's state:UNASSIGNED
    [junit] 10/01/07 02:23:10 INFO mapred.TaskTracker: Trying to launch : attempt_20100107022237874_0002_m_000003_0
    [junit] 10/01/07 02:23:10 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20100107022237874_0002_m_000003_0
    [junit] 10/01/07 02:23:10 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100107022237874_0002/job.xml	dst=null	perm=null
    [junit] 10/01/07 02:23:10 INFO DataNode.clienttrace: src: /127.0.0.1:45812, dest: /127.0.0.1:34581,
bytes: 49211, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-1109484639-127.0.1.1-45812-1262830957804,
blockid: blk_598049288569262501_1015
    [junit] 10/01/07 02:23:10 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100107022237874_0002/job.jar	dst=null	perm=null
    [junit] 10/01/07 02:23:10 INFO DataNode.clienttrace: src: /127.0.0.1:32866, dest: /127.0.0.1:43949,
bytes: 2662792, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-1000068095-127.0.1.1-32866-1262830956384,
blockid: blk_2459023371967361811_1013
    [junit] 10/01/07 02:23:10 INFO datanode.DataNode: Deleting block blk_-4766788746537872722_1006
file build/test/data/dfs/data/data6/current/blk_-4766788746537872722
    [junit] 10/01/07 02:23:10 INFO datanode.DataNode: Deleting block blk_3536463256133158543_1005
file build/test/data/dfs/data/data5/current/blk_3536463256133158543
    [junit] 10/01/07 02:23:10 INFO mapReduceLayer.MapReduceLauncher: 0% complete
    [junit] 10/01/07 02:23:10 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100107022237874_0002_m_-165400950
    [junit] 10/01/07 02:23:10 INFO mapred.JvmManager: JVM Runner jvm_20100107022237874_0002_m_-165400950
spawned.
    [junit] 10/01/07 02:23:10 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:45812 to delete
 blk_2459023371967361811_1013
    [junit] 10/01/07 02:23:11 INFO mapred.TaskTracker: JVM with ID: jvm_20100107022237874_0002_m_-165400950
given task: attempt_20100107022237874_0002_m_000003_0
    [junit] 10/01/07 02:23:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-1306912187/tmp-891082635/_temporary	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 10/01/07 02:23:11 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000003_0
0.0% setup
    [junit] 10/01/07 02:23:11 INFO mapred.TaskTracker: Task attempt_20100107022237874_0002_m_000003_0
is done.
    [junit] 10/01/07 02:23:11 INFO mapred.TaskTracker: reported output size for attempt_20100107022237874_0002_m_000003_0
 was 0
    [junit] 10/01/07 02:23:11 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 10/01/07 02:23:11 INFO mapred.JvmManager: JVM : jvm_20100107022237874_0002_m_-165400950
exited. Number of tasks it ran: 1
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20100107022237874_0002/attempt_20100107022237874_0002_m_000003_0/output/file.out
in any of the configured local directories
    [junit] 10/01/07 02:23:13 INFO mapred.JobInProgress: Task 'attempt_20100107022237874_0002_m_000003_0'
has completed task_20100107022237874_0002_m_000003 successfully.
    [junit] 10/01/07 02:23:13 INFO mapred.JobTracker: Adding task 'attempt_20100107022237874_0002_m_000000_0'
to tip task_20100107022237874_0002_m_000000, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:40200'
    [junit] 10/01/07 02:23:13 INFO mapred.JobInProgress: Choosing rack-local task task_20100107022237874_0002_m_000000
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100107022237874_0002_m_000000_0
task's state:UNASSIGNED
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: Trying to launch : attempt_20100107022237874_0002_m_000000_0
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_20100107022237874_0002_m_000003_0
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20100107022237874_0002_m_000000_0
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: About to purge task: attempt_20100107022237874_0002_m_000003_0
    [junit] 10/01/07 02:23:13 INFO mapred.TaskRunner: attempt_20100107022237874_0002_m_000003_0
done; removing files.
    [junit] 10/01/07 02:23:13 INFO mapred.IndexCache: Map ID attempt_20100107022237874_0002_m_000003_0
not found in cache
    [junit] 10/01/07 02:23:13 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100107022237874_0002_m_867899312
    [junit] 10/01/07 02:23:13 INFO mapred.JvmManager: JVM Runner jvm_20100107022237874_0002_m_867899312
spawned.
    [junit] 10/01/07 02:23:13 INFO mapred.JobTracker: Adding task 'attempt_20100107022237874_0002_m_000001_0'
to tip task_20100107022237874_0002_m_000001, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:45874'
    [junit] 10/01/07 02:23:13 INFO mapred.JobInProgress: Choosing rack-local task task_20100107022237874_0002_m_000001
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100107022237874_0002_m_000001_0
task's state:UNASSIGNED
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: Trying to launch : attempt_20100107022237874_0002_m_000001_0
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20100107022237874_0002_m_000001_0
    [junit] 10/01/07 02:23:13 INFO mapred.TaskTracker: JVM with ID: jvm_20100107022237874_0002_m_867899312
given task: attempt_20100107022237874_0002_m_000000_0
    [junit] 10/01/07 02:23:13 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100107022237874_0002/job.xml	dst=null	perm=null
    [junit] 10/01/07 02:23:13 INFO DataNode.clienttrace: src: /127.0.0.1:34653, dest: /127.0.0.1:55185,
bytes: 49211, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-75923346-127.0.1.1-34653-1262830957336,
blockid: blk_598049288569262501_1015
    [junit] 10/01/07 02:23:13 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100107022237874_0002/job.jar	dst=null	perm=null
    [junit] 10/01/07 02:23:13 INFO DataNode.clienttrace: src: /127.0.0.1:50773, dest: /127.0.0.1:38639,
bytes: 2662792, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-1435789546-127.0.1.1-50773-1262830956883,
blockid: blk_2459023371967361811_1013
    [junit] 10/01/07 02:23:13 INFO datanode.DataNode: Deleting block blk_2459023371967361811_1013
file build/test/data/dfs/data/data8/current/blk_2459023371967361811
    [junit] 10/01/07 02:23:14 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100107022237874_0002_m_504177350
    [junit] 10/01/07 02:23:14 INFO mapred.JvmManager: JVM Runner jvm_20100107022237874_0002_m_504177350
spawned.
    [junit] 10/01/07 02:23:14 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/user/hudson/input2.txt	dst=null	perm=null
    [junit] 10/01/07 02:23:14 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000000_0/part-00000
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 10/01/07 02:23:14 INFO DataNode.clienttrace: src: /127.0.0.1:50773, dest: /127.0.0.1:38642,
bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20100107022237874_0002_m_000000_0, srvID:
DS-1435789546-127.0.1.1-50773-1262830956883, blockid: blk_4072390750056558653_1012
    [junit] 10/01/07 02:23:14 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000000_0/part-00000.
blk_-1989217488548178433_1018
    [junit] 10/01/07 02:23:14 INFO datanode.DataNode: Receiving block blk_-1989217488548178433_1018
src: /127.0.0.1:43963 dest: /127.0.0.1:32866
    [junit] 10/01/07 02:23:14 INFO datanode.DataNode: Receiving block blk_-1989217488548178433_1018
src: /127.0.0.1:38644 dest: /127.0.0.1:50773
    [junit] 10/01/07 02:23:14 INFO datanode.DataNode: Receiving block blk_-1989217488548178433_1018
src: /127.0.0.1:55192 dest: /127.0.0.1:34653
    [junit] 10/01/07 02:23:14 INFO DataNode.clienttrace: src: /127.0.0.1:55192, dest: /127.0.0.1:34653,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100107022237874_0002_m_000000_0, srvID:
DS-75923346-127.0.1.1-34653-1262830957336, blockid: blk_-1989217488548178433_1018
    [junit] 10/01/07 02:23:14 INFO datanode.DataNode: PacketResponder 0 for block blk_-1989217488548178433_1018
terminating
    [junit] 10/01/07 02:23:14 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:34653 is added to blk_-1989217488548178433_1018 size 39
    [junit] 10/01/07 02:23:14 INFO DataNode.clienttrace: src: /127.0.0.1:38644, dest: /127.0.0.1:50773,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100107022237874_0002_m_000000_0, srvID:
DS-1435789546-127.0.1.1-50773-1262830956883, blockid: blk_-1989217488548178433_1018
    [junit] 10/01/07 02:23:14 INFO datanode.DataNode: PacketResponder 1 for block blk_-1989217488548178433_1018
terminating
    [junit] 10/01/07 02:23:14 INFO DataNode.clienttrace: src: /127.0.0.1:43963, dest: /127.0.0.1:32866,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100107022237874_0002_m_000000_0, srvID:
DS-1000068095-127.0.1.1-32866-1262830956384, blockid: blk_-1989217488548178433_1018
    [junit] 10/01/07 02:23:14 INFO datanode.DataNode: PacketResponder 2 for block blk_-1989217488548178433_1018
terminating
    [junit] 10/01/07 02:23:14 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50773 is added to blk_-1989217488548178433_1018 size 39
    [junit] 10/01/07 02:23:14 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000000_0/part-00000
is closed by DFSClient_attempt_20100107022237874_0002_m_000000_0
    [junit] 10/01/07 02:23:14 INFO mapred.TaskTracker: Task attempt_20100107022237874_0002_m_000000_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 10/01/07 02:23:14 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000000_0
0.0% 
    [junit] 10/01/07 02:23:14 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:32866 is added to blk_-1989217488548178433_1018 size 39
    [junit] 10/01/07 02:23:14 INFO mapred.TaskTracker: JVM with ID: jvm_20100107022237874_0002_m_504177350
given task: attempt_20100107022237874_0002_m_000001_0
    [junit] 10/01/07 02:23:14 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/user/hudson/input1.txt	dst=null	perm=null
    [junit] 10/01/07 02:23:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000001_0/part-00001
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 10/01/07 02:23:15 INFO DataNode.clienttrace: src: /127.0.0.1:50773, dest: /127.0.0.1:38651,
bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20100107022237874_0002_m_000001_0, srvID:
DS-1435789546-127.0.1.1-50773-1262830956883, blockid: blk_-5724209759967542730_1011
    [junit] 10/01/07 02:23:15 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000001_0/part-00001.
blk_8385431618728352978_1019
    [junit] 10/01/07 02:23:15 INFO datanode.DataNode: Receiving block blk_8385431618728352978_1019
src: /127.0.0.1:43972 dest: /127.0.0.1:32866
    [junit] 10/01/07 02:23:15 INFO datanode.DataNode: Receiving block blk_8385431618728352978_1019
src: /127.0.0.1:55200 dest: /127.0.0.1:34653
    [junit] 10/01/07 02:23:15 INFO datanode.DataNode: Receiving block blk_8385431618728352978_1019
src: /127.0.0.1:38654 dest: /127.0.0.1:50773
    [junit] 10/01/07 02:23:15 INFO DataNode.clienttrace: src: /127.0.0.1:38654, dest: /127.0.0.1:50773,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100107022237874_0002_m_000001_0, srvID:
DS-1435789546-127.0.1.1-50773-1262830956883, blockid: blk_8385431618728352978_1019
    [junit] 10/01/07 02:23:15 INFO datanode.DataNode: PacketResponder 0 for block blk_8385431618728352978_1019
terminating
    [junit] 10/01/07 02:23:15 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50773 is added to blk_8385431618728352978_1019 size 39
    [junit] 10/01/07 02:23:15 INFO DataNode.clienttrace: src: /127.0.0.1:55200, dest: /127.0.0.1:34653,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100107022237874_0002_m_000001_0, srvID:
DS-75923346-127.0.1.1-34653-1262830957336, blockid: blk_8385431618728352978_1019
    [junit] 10/01/07 02:23:15 INFO datanode.DataNode: PacketResponder 1 for block blk_8385431618728352978_1019
terminating
    [junit] 10/01/07 02:23:15 INFO DataNode.clienttrace: src: /127.0.0.1:43972, dest: /127.0.0.1:32866,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100107022237874_0002_m_000001_0, srvID:
DS-1000068095-127.0.1.1-32866-1262830956384, blockid: blk_8385431618728352978_1019
    [junit] 10/01/07 02:23:15 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:34653 is added to blk_8385431618728352978_1019 size 39
    [junit] 10/01/07 02:23:15 INFO datanode.DataNode: PacketResponder 2 for block blk_8385431618728352978_1019
terminating
    [junit] 10/01/07 02:23:15 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:32866 is added to blk_8385431618728352978_1019 size 39
    [junit] 10/01/07 02:23:15 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000001_0/part-00001
is closed by DFSClient_attempt_20100107022237874_0002_m_000001_0
    [junit] 10/01/07 02:23:15 INFO mapred.TaskTracker: Task attempt_20100107022237874_0002_m_000001_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 10/01/07 02:23:15 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000001_0
0.0% 
    [junit] 10/01/07 02:23:16 INFO mapred.TaskTracker: Received commit task action for attempt_20100107022237874_0002_m_000000_0
    [junit] 10/01/07 02:23:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000000_0
dst=null	perm=null
    [junit] 10/01/07 02:23:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-1306912187/tmp-891082635	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 10/01/07 02:23:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000000_0/part-00000
dst=/tmp/temp-1306912187/tmp-891082635/part-00000	perm=hudson:supergroup:rw-r--r--
    [junit] 10/01/07 02:23:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000000_0
dst=null	perm=null
    [junit] 10/01/07 02:23:16 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000000_0
1.0% 
    [junit] 10/01/07 02:23:16 INFO mapred.TaskTracker: Task attempt_20100107022237874_0002_m_000000_0
is done.
    [junit] 10/01/07 02:23:16 INFO mapred.TaskTracker: reported output size for attempt_20100107022237874_0002_m_000000_0
 was 0
    [junit] 10/01/07 02:23:16 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 10/01/07 02:23:16 INFO mapred.JvmManager: JVM : jvm_20100107022237874_0002_m_867899312
exited. Number of tasks it ran: 1
    [junit] 10/01/07 02:23:16 INFO mapred.TaskTracker: Received commit task action for attempt_20100107022237874_0002_m_000001_0
    [junit] 10/01/07 02:23:17 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000001_0
dst=null	perm=null
    [junit] 10/01/07 02:23:17 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-1306912187/tmp-891082635	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 10/01/07 02:23:17 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000001_0/part-00001
dst=/tmp/temp-1306912187/tmp-891082635/part-00001	perm=hudson:supergroup:rw-r--r--
    [junit] 10/01/07 02:23:17 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-1306912187/tmp-891082635/_temporary/_attempt_20100107022237874_0002_m_000001_0
dst=null	perm=null
    [junit] 10/01/07 02:23:17 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000001_0
1.0% 
    [junit] 10/01/07 02:23:17 INFO mapred.TaskTracker: Task attempt_20100107022237874_0002_m_000001_0
is done.
    [junit] 10/01/07 02:23:17 INFO mapred.TaskTracker: reported output size for attempt_20100107022237874_0002_m_000001_0
 was 0
    [junit] 10/01/07 02:23:17 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 10/01/07 02:23:17 INFO mapred.JvmManager: JVM : jvm_20100107022237874_0002_m_504177350
exited. Number of tasks it ran: 1
    [junit] 10/01/07 02:23:19 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20100107022237874_0002/attempt_20100107022237874_0002_m_000000_0/output/file.out
in any of the configured local directories
    [junit] 10/01/07 02:23:19 INFO mapred.JobInProgress: Task 'attempt_20100107022237874_0002_m_000000_0'
has completed task_20100107022237874_0002_m_000000 successfully.
    [junit] 10/01/07 02:23:19 INFO mapReduceLayer.MapReduceLauncher: 25% complete
    [junit] 10/01/07 02:23:19 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20100107022237874_0002/attempt_20100107022237874_0002_m_000001_0/output/file.out
in any of the configured local directories
    [junit] 10/01/07 02:23:19 INFO mapred.JobInProgress: Task 'attempt_20100107022237874_0002_m_000001_0'
has completed task_20100107022237874_0002_m_000001 successfully.
    [junit] 10/01/07 02:23:19 INFO mapred.JobTracker: Adding task 'attempt_20100107022237874_0002_m_000002_0'
to tip task_20100107022237874_0002_m_000002, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:45874'
    [junit] 10/01/07 02:23:19 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100107022237874_0002_m_000002_0
task's state:UNASSIGNED
    [junit] 10/01/07 02:23:19 INFO mapred.TaskTracker: Trying to launch : attempt_20100107022237874_0002_m_000002_0
    [junit] 10/01/07 02:23:19 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20100107022237874_0002_m_000002_0
    [junit] 10/01/07 02:23:19 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100107022237874_0002_m_-1789070178
    [junit] 10/01/07 02:23:19 INFO mapred.JvmManager: JVM Runner jvm_20100107022237874_0002_m_-1789070178
spawned.
    [junit] 10/01/07 02:23:20 INFO mapReduceLayer.MapReduceLauncher: 50% complete
    [junit] 10/01/07 02:23:20 INFO mapred.TaskTracker: JVM with ID: jvm_20100107022237874_0002_m_-1789070178
given task: attempt_20100107022237874_0002_m_000002_0
    [junit] 10/01/07 02:23:20 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000002_0
0.0% 
    [junit] 10/01/07 02:23:20 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-1306912187/tmp-891082635/_temporary	dst=null	perm=null
    [junit] 10/01/07 02:23:20 INFO mapred.TaskTracker: attempt_20100107022237874_0002_m_000002_0
0.0% cleanup
    [junit] 10/01/07 02:23:20 INFO mapred.TaskTracker: Task attempt_20100107022237874_0002_m_000002_0
is done.
    [junit] 10/01/07 02:23:20 INFO mapred.TaskTracker: reported output size for attempt_20100107022237874_0002_m_000002_0
 was 0
    [junit] 10/01/07 02:23:20 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 10/01/07 02:23:20 INFO mapred.JvmManager: JVM : jvm_20100107022237874_0002_m_-1789070178
exited. Number of tasks it ran: 1
    [junit] 10/01/07 02:23:22 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20100107022237874_0002/attempt_20100107022237874_0002_m_000002_0/output/file.out
in any of the configured local directories
    [junit] 10/01/07 02:23:22 INFO mapred.JobInProgress: Task 'attempt_20100107022237874_0002_m_000002_0'
has completed task_20100107022237874_0002_m_000002 successfully.
    [junit] 10/01/07 02:23:22 INFO mapred.JobInProgress: Job job_20100107022237874_0002 has
completed successfully.
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1306912187/tmp-891082635/_logs/history/localhost_1262830957897_job_20100107022237874_0002_hudson_Job5138150877844580692.jar.
blk_-320291470661976495_1019
    [junit] 10/01/07 02:23:22 INFO datanode.DataNode: Receiving block blk_-320291470661976495_1019
src: /127.0.0.1:38660 dest: /127.0.0.1:50773
    [junit] 10/01/07 02:23:22 INFO datanode.DataNode: Receiving block blk_-320291470661976495_1019
src: /127.0.0.1:55208 dest: /127.0.0.1:34653
    [junit] 10/01/07 02:23:22 INFO datanode.DataNode: Receiving block blk_-320291470661976495_1019
src: /127.0.0.1:34615 dest: /127.0.0.1:45812
    [junit] 10/01/07 02:23:22 INFO DataNode.clienttrace: src: /127.0.0.1:34615, dest: /127.0.0.1:45812,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_1941479077, srvID: DS-1109484639-127.0.1.1-45812-1262830957804,
blockid: blk_-320291470661976495_1019
    [junit] 10/01/07 02:23:22 INFO datanode.DataNode: PacketResponder 0 for block blk_-320291470661976495_1019
terminating
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:45812 is added to blk_-320291470661976495_1019 size 6117
    [junit] 10/01/07 02:23:22 INFO DataNode.clienttrace: src: /127.0.0.1:55208, dest: /127.0.0.1:34653,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_1941479077, srvID: DS-75923346-127.0.1.1-34653-1262830957336,
blockid: blk_-320291470661976495_1019
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:34653 is added to blk_-320291470661976495_1019 size 6117
    [junit] 10/01/07 02:23:22 INFO datanode.DataNode: PacketResponder 1 for block blk_-320291470661976495_1019
terminating
    [junit] 10/01/07 02:23:22 INFO DataNode.clienttrace: src: /127.0.0.1:38660, dest: /127.0.0.1:50773,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_1941479077, srvID: DS-1435789546-127.0.1.1-50773-1262830956883,
blockid: blk_-320291470661976495_1019
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50773 is added to blk_-320291470661976495_1019 size 6117
    [junit] 10/01/07 02:23:22 INFO datanode.DataNode: PacketResponder 2 for block blk_-320291470661976495_1019
terminating
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1306912187/tmp-891082635/_logs/history/localhost_1262830957897_job_20100107022237874_0002_hudson_Job5138150877844580692.jar
is closed by DFSClient_1941479077
    [junit] 10/01/07 02:23:22 INFO mapred.JobTracker: Removed completed task 'attempt_20100107022237874_0002_m_000001_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:45874'
    [junit] 10/01/07 02:23:22 INFO mapred.JobTracker: Removed completed task 'attempt_20100107022237874_0002_m_000002_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:45874'
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2459023371967361811
is added to invalidSet of 127.0.0.1:45812
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2459023371967361811
is added to invalidSet of 127.0.0.1:32866
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2459023371967361811
is added to invalidSet of 127.0.0.1:50773
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-1244555586616345040
is added to invalidSet of 127.0.0.1:50773
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-1244555586616345040
is added to invalidSet of 127.0.0.1:32866
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-1244555586616345040
is added to invalidSet of 127.0.0.1:45812
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_598049288569262501
is added to invalidSet of 127.0.0.1:34653
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_598049288569262501
is added to invalidSet of 127.0.0.1:32866
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_598049288569262501
is added to invalidSet of 127.0.0.1:45812
    [junit] 10/01/07 02:23:22 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100107022237874_0002
    [junit] 10/01/07 02:23:22 INFO mapred.TaskRunner: attempt_20100107022237874_0002_m_000001_0
done; removing files.
    [junit] 10/01/07 02:23:22 INFO mapred.IndexCache: Map ID attempt_20100107022237874_0002_m_000001_0
not found in cache
    [junit] 10/01/07 02:23:22 INFO mapred.TaskRunner: attempt_20100107022237874_0002_m_000002_0
done; removing files.
    [junit] 10/01/07 02:23:22 INFO mapred.IndexCache: Map ID attempt_20100107022237874_0002_m_000002_0
not found in cache
    [junit] 10/01/07 02:23:22 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20100107022237874_0002	dst=null	perm=null
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:32866 to delete
 blk_2459023371967361811_1013 blk_-1244555586616345040_1014 blk_598049288569262501_1015
    [junit] 10/01/07 02:23:22 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:45812 to delete
 blk_2459023371967361811_1013 blk_-1244555586616345040_1014 blk_598049288569262501_1015
    [junit] 10/01/07 02:23:24 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100107022237874_0002
    [junit] 10/01/07 02:23:24 WARN mapred.TaskTracker: Unknown job job_20100107022237874_0002
being deleted.
    [junit] 10/01/07 02:23:24 INFO datanode.DataNode: Deleting block blk_-1244555586616345040_1014
file build/test/data/dfs/data/data2/current/blk_-1244555586616345040
    [junit] 10/01/07 02:23:24 INFO datanode.DataNode: Deleting block blk_598049288569262501_1015
file build/test/data/dfs/data/data1/current/blk_598049288569262501
    [junit] 10/01/07 02:23:24 INFO datanode.DataNode: Deleting block blk_2459023371967361811_1013
file build/test/data/dfs/data/data1/current/blk_2459023371967361811
    [junit] 10/01/07 02:23:24 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100107022237874_0002
    [junit] 10/01/07 02:23:24 WARN mapred.TaskTracker: Unknown job job_20100107022237874_0002
being deleted.
    [junit] 10/01/07 02:23:25 INFO mapReduceLayer.MapReduceLauncher: 100% complete
    [junit] 10/01/07 02:23:25 INFO mapReduceLayer.MapReduceLauncher: Successfully stored result
in: "hdfs://localhost:50023/tmp/temp-1306912187/tmp-891082635"
    [junit] 10/01/07 02:23:25 INFO mapReduceLayer.MapReduceLauncher: Records written : 2
    [junit] 10/01/07 02:23:25 INFO mapReduceLayer.MapReduceLauncher: Bytes written : 78
    [junit] 10/01/07 02:23:25 INFO mapReduceLayer.MapReduceLauncher: Success!
    [junit] 10/01/07 02:23:25 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-1306912187/tmp-891082635	dst=null	perm=null
    [junit] 10/01/07 02:23:25 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/temp-1306912187/tmp-891082635/part-00000	dst=null	perm=null
    [junit] 10/01/07 02:23:25 INFO DataNode.clienttrace: src: /127.0.0.1:34653, dest: /127.0.0.1:55210,
bytes: 43, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-75923346-127.0.1.1-34653-1262830957336,
blockid: blk_-1989217488548178433_1018
    [junit] 10/01/07 02:23:25 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/temp-1306912187/tmp-891082635/part-00001	dst=null	perm=null
    [junit] 10/01/07 02:23:25 INFO DataNode.clienttrace: src: /127.0.0.1:50773, dest: /127.0.0.1:38664,
bytes: 43, op: HDFS_READ, cliID: DFSClient_1941479077, srvID: DS-1435789546-127.0.1.1-50773-1262830956883,
blockid: blk_8385431618728352978_1019
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.429 sec
    [junit] 10/01/07 02:23:25 INFO mapred.JobTracker: Removed completed task 'attempt_20100107022237874_0002_m_000000_0'
from 'tracker_host2.foo.com:localhost/127.0.0.1:40200'
    [junit] 10/01/07 02:23:25 INFO mapred.JobTracker: Removed completed task 'attempt_20100107022237874_0002_m_000003_0'
from 'tracker_host2.foo.com:localhost/127.0.0.1:40200'
    [junit] 10/01/07 02:23:25 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100107022237874_0002
    [junit] 10/01/07 02:23:25 INFO mapred.TaskRunner: attempt_20100107022237874_0002_m_000000_0
done; removing files.
    [junit] 10/01/07 02:23:25 INFO mapred.TaskTracker: Error cleaning up task runner: java.lang.IllegalStateException:
Shutdown in progress
    [junit] 	at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
    [junit] 	at java.lang.Runtime.addShutdownHook(Runtime.java:192)
    [junit] 	at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1387)
    [junit] 	at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:191)
    [junit] 	at org.apache.hadoop.fs.FileSystem.getLocal(FileSystem.java:167)
    [junit] 	at org.apache.hadoop.mapred.JobConf.deleteLocalFiles(JobConf.java:270)
    [junit] 	at org.apache.hadoop.mapred.MapOutputFile.removeAll(MapOutputFile.java:168)
    [junit] 	at org.apache.hadoop.mapred.MapTaskRunner.close(MapTaskRunner.java:44)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker$TaskInProgress.cleanup(TaskTracker.java:2426)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker$TaskInProgress.jobHasFinished(TaskTracker.java:2318)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.purgeJob(TaskTracker.java:1351)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.access$100(TaskTracker.java:97)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker$1.run(TaskTracker.java:319)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 10/01/07 02:23:25 INFO mapred.IndexCache: Map ID attempt_20100107022237874_0002_m_000000_0
not found in cache
    [junit] 10/01/07 02:23:25 WARN mapred.TaskTracker: java.lang.IllegalStateException: Shutdown
in progress
    [junit] 	at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
    [junit] 	at java.lang.Runtime.addShutdownHook(Runtime.java:192)
    [junit] 	at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1387)
    [junit] 	at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:191)
    [junit] 	at org.apache.hadoop.fs.FileSystem.getLocal(FileSystem.java:167)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.getLocalFiles(TaskTracker.java:2986)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.purgeJob(TaskTracker.java:1360)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.access$100(TaskTracker.java:97)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker$1.run(TaskTracker.java:319)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 

BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:560: The following
error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:493: The following
error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:555: Tests
failed!

Total time: 253 minutes 12 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


Mime
View raw message