See <https://builds.apache.org/job/Pig-trunk/1052/changes>
Changes:
[daijy] PIG-2125: Make Pig work with hadoop .NEXT (remove empty files)
[thejas] PIG-2179: tests in TestLoad are failing
------------------------------------------
[...truncated 39880 lines...]
[junit] 11/07/22 01:36:56 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20110722005425547_0013_m_000001_0
[junit] 11/07/22 01:37:06 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20110722005425547_0013_m_1310160433
[junit] 11/07/22 01:37:06 INFO mapred.JvmManager: JVM Runner
jvm_20110722005425547_0013_m_1310160433 spawned.
[junit] 11/07/22 01:37:17 INFO mapred.TaskTracker: JVM with ID:
jvm_20110722005425547_0013_m_1310160433 given task:
attempt_20110722005425547_0013_m_000001_0
[junit] 11/07/22 01:37:17 INFO mapred.TaskTracker:
attempt_20110722005425547_0013_m_000001_0 0.0%
[junit] 11/07/22 01:37:17 INFO namenode.FSNamesystem: Number of
transactions: 605 Total time for transactions(ms): 16Number of transactions
batched in Syncs: 96 Number of syncs: 419 SyncTimes(ms): 1307 235
[junit] 11/07/22 01:37:17 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/TestStore-output--1293221001145632294.txt_1/_temporary dst=null
perm=null
[junit] 11/07/22 01:37:17 INFO mapred.TaskTracker:
attempt_20110722005425547_0013_m_000001_0 0.0% cleanup
[junit] 11/07/22 01:37:17 INFO mapred.TaskTracker: Task
attempt_20110722005425547_0013_m_000001_0 is done.
[junit] 11/07/22 01:37:17 INFO mapred.TaskTracker: reported output size for
attempt_20110722005425547_0013_m_000001_0 was 0
[junit] 11/07/22 01:37:17 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 11/07/22 01:37:17 INFO mapred.JvmManager: JVM :
jvm_20110722005425547_0013_m_1310160433 exited. Number of tasks it ran: 1
[junit] 11/07/22 01:37:18 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20110722005425547_0013/attempt_20110722005425547_0013_m_000001_0/output/file.out
in any of the configured local directories
[junit] 11/07/22 01:37:18 INFO mapred.JobInProgress: Task
'attempt_20110722005425547_0013_m_000001_0' has completed
task_20110722005425547_0013_m_000001 successfully.
[junit] 11/07/22 01:37:18 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/TestStore-output--1293221001145632294.txt_1/_logs/history/localhost_1311296065575_job_20110722005425547_0013_hudson_PigLatin%3ADefaultJobName.
blk_-1117289598324678566_1112
[junit] 11/07/22 01:37:18 INFO datanode.DataNode: Receiving block
blk_-1117289598324678566_1112 src: /127.0.0.1:52033 dest: /127.0.0.1:51016
[junit] 11/07/22 01:37:18 INFO datanode.DataNode: Receiving block
blk_-1117289598324678566_1112 src: /127.0.0.1:47405 dest: /127.0.0.1:41558
[junit] 11/07/22 01:37:18 INFO datanode.DataNode: Receiving block
blk_-1117289598324678566_1112 src: /127.0.0.1:47208 dest: /127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO DataNode.clienttrace: src: /127.0.0.1:47208,
dest: /127.0.0.1:42511, bytes: 6925, op: HDFS_WRITE, cliID:
DFSClient_-1650800025, srvID: DS-687389278-127.0.1.1-42511-1311296055429,
blockid: blk_-1117289598324678566_1112
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: PacketResponder 0 for
block blk_-1117289598324678566_1112 terminating
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42511 is added to
blk_-1117289598324678566_1112 size 6925
[junit] 11/07/22 01:37:22 INFO DataNode.clienttrace: src: /127.0.0.1:47405,
dest: /127.0.0.1:41558, bytes: 6925, op: HDFS_WRITE, cliID:
DFSClient_-1650800025, srvID: DS-448864010-127.0.1.1-41558-1311296045100,
blockid: blk_-1117289598324678566_1112
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: PacketResponder 1 for
block blk_-1117289598324678566_1112 terminating
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41558 is added to
blk_-1117289598324678566_1112 size 6925
[junit] 11/07/22 01:37:22 INFO DataNode.clienttrace: src: /127.0.0.1:52033,
dest: /127.0.0.1:51016, bytes: 6925, op: HDFS_WRITE, cliID:
DFSClient_-1650800025, srvID: DS-2126937689-127.0.1.1-51016-1311296050405,
blockid: blk_-1117289598324678566_1112
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: PacketResponder 2 for
block blk_-1117289598324678566_1112 terminating
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51016 is added to
blk_-1117289598324678566_1112 size 6925
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/TestStore-output--1293221001145632294.txt_1/_logs/history/localhost_1311296065575_job_20110722005425547_0013_hudson_PigLatin%3ADefaultJobName
is closed by DFSClient_-1650800025
[junit] 11/07/22 01:37:22 INFO mapred.JobTracker: Removed completed task
'attempt_20110722005425547_0013_m_000000_0' from
'tracker_host3.foo.com:localhost/127.0.0.1:58112'
[junit] 11/07/22 01:37:22 INFO mapred.JobTracker: Removed completed task
'attempt_20110722005425547_0013_m_000001_0' from
'tracker_host3.foo.com:localhost/127.0.0.1:58112'
[junit] 11/07/22 01:37:22 INFO mapred.JobTracker: Removed completed task
'attempt_20110722005425547_0013_m_000002_0' from
'tracker_host3.foo.com:localhost/127.0.0.1:58112'
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_1247146579982373405 is added to invalidSet of
127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_1247146579982373405 is added to invalidSet of
127.0.0.1:44560
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_1247146579982373405 is added to invalidSet of
127.0.0.1:41558
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_6858688704163705742 is added to invalidSet of
127.0.0.1:44560
[junit] 11/07/22 01:37:22 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20110722005425547_0013
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_6858688704163705742 is added to invalidSet of
127.0.0.1:42511
[junit] 11/07/22 01:37:22 WARN mapred.TaskTracker: Unknown job
job_20110722005425547_0013 being deleted.
[junit] 11/07/22 01:37:22 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20110722005425547_0013
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_6858688704163705742 is added to invalidSet of
127.0.0.1:41558
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_133115624376423742 is added to invalidSet of
127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20110722005425547_0013
[junit] 11/07/22 01:37:22 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20110722005425547_0013
[junit] 11/07/22 01:37:22 INFO mapred.TaskRunner:
attempt_20110722005425547_0013_m_000001_0 done; removing files.
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_133115624376423742 is added to invalidSet of
127.0.0.1:44560
[junit] 11/07/22 01:37:22 WARN mapred.TaskTracker: Unknown job
job_20110722005425547_0013 being deleted.
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_133115624376423742 is added to invalidSet of
127.0.0.1:51016
[junit] 11/07/22 01:37:22 WARN mapred.TaskTracker: Unknown job
job_20110722005425547_0013 being deleted.
[junit] 11/07/22 01:37:22 INFO mapred.IndexCache: Map ID
attempt_20110722005425547_0013_m_000001_0 not found in cache
[junit] 11/07/22 01:37:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20110722005425547_0013 dst=null
perm=null
[junit] 11/07/22 01:37:22 INFO mapred.JobTracker: Killing job
job_20110722005425547_0013
[junit] 11/07/22 01:37:22 INFO mapReduceLayer.MapReduceLauncher: job
job_20110722005425547_0013 has failed! Stop running all dependent jobs
[junit] 11/07/22 01:37:22 INFO mapReduceLayer.MapReduceLauncher: 100%
complete
[junit] 11/07/22 01:37:22 WARN mapReduceLayer.Launcher: There is no log
file to write to.
[junit] 11/07/22 01:37:22 ERROR mapReduceLayer.Launcher: Backend error
message
[junit] org.apache.pig.backend.executionengine.ExecException: ERROR 2078:
Caught error from UDF: org.apache.pig.test.TestStore$FailUDF [FailUDFException]
[junit] at
org.apache.pig.backend.hadoop.executionengine.physicalLayer.expressionOperators.POUserFunc.getNext(POUserFunc.java:242)
[junit] at
org.apache.pig.backend.hadoop.executionengine.physicalLayer.expressionOperators.POUserFunc.getNext(POUserFunc.java:305)
[junit] at
org.apache.pig.backend.hadoop.executionengine.physicalLayer.PhysicalOperator.getNext(PhysicalOperator.java:322)
[junit] at
org.apache.pig.backend.hadoop.executionengine.physicalLayer.relationalOperators.POForEach.processPlan(POForEach.java:332)
[junit] at
org.apache.pig.backend.hadoop.executionengine.physicalLayer.relationalOperators.POForEach.getNext(POForEach.java:284)
[junit] at
org.apache.pig.backend.hadoop.executionengine.mapReduceLayer.PigGenericMapBase.runPipeline(PigGenericMapBase.java:267)
[junit] at
org.apache.pig.backend.hadoop.executionengine.mapReduceLayer.PigGenericMapBase.map(PigGenericMapBase.java:262)
[junit] at
org.apache.pig.backend.hadoop.executionengine.mapReduceLayer.PigGenericMapBase.map(PigGenericMapBase.java:64)
[junit] at org.apache.hadoop.mapreduce.Mapper.run(Mapper.java:144)
[junit] at
org.apache.hadoop.mapred.MapTask.runNewMapper(MapTask.java:621)
[junit] at org.apache.hadoop.mapred.MapTask.run(MapTask.java:305)
[junit] at org.apache.hadoop.mapred.Child.main(Child.java:170)
[junit] Caused by: java.io.IOException: FailUDFException
[junit] at
org.apache.pig.test.TestStore$FailUDF.exec(TestStore.java:690)
[junit] at
org.apache.pig.test.TestStore$FailUDF.exec(TestStore.java:686)
[junit] at
org.apache.pig.backend.hadoop.executionengine.physicalLayer.expressionOperators.POUserFunc.getNext(POUserFunc.java:216)
[junit] ... 11 more
[junit]
[junit] 11/07/22 01:37:22 ERROR pigstats.SimplePigStats: ERROR 2078: Caught
error from UDF: org.apache.pig.test.TestStore$FailUDF [FailUDFException]
[junit] 11/07/22 01:37:22 ERROR pigstats.PigStatsUtil: 1 map reduce job(s)
failed!
[junit] 11/07/22 01:37:22 INFO pigstats.SimplePigStats: Script Statistics:
[junit]
[junit] HadoopVersion PigVersion UserId StartedAt
FinishedAt Features
[junit] 0.20.2 2011-07-21_22-41-03 hudson 2011-07-22 01:35:46
2011-07-22 01:37:22 UNKNOWN
[junit]
[junit] Failed!
[junit]
[junit] Failed Jobs:
[junit] JobId Alias Feature Message Outputs
[junit] job_20110722005425547_0013 a,b MAP_ONLY Message: Job
failed! /tmp/TestStore-output--1293221001145632294.txt_1,
[junit]
[junit] Input(s):
[junit] Failed to read data from "/tmp/TestStore-1673645843841222728.txt"
[junit]
[junit] Output(s):
[junit] Failed to produce result in
"/tmp/TestStore-output--1293221001145632294.txt_1"
[junit]
[junit] Counters:
[junit] Total records written : 0
[junit] Total bytes written : 0
[junit] Spillable Memory Manager spill count : 0
[junit] Total bags proactively spilled: 0
[junit] Total records proactively spilled: 0
[junit]
[junit] Job DAG:
[junit] job_20110722005425547_0013
[junit]
[junit]
[junit] 11/07/22 01:37:22 INFO mapReduceLayer.MapReduceLauncher: Failed!
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7167232418050835033 is added to invalidSet of
127.0.0.1:51016
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7167232418050835033 is added to invalidSet of
127.0.0.1:41558
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_7167232418050835033 is added to invalidSet of
127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-1117289598324678566 is added to invalidSet of
127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-1117289598324678566 is added to invalidSet of
127.0.0.1:41558
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-1117289598324678566 is added to invalidSet of
127.0.0.1:51016
[junit] 11/07/22 01:37:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/TestStore-output--1293221001145632294.txt_1 dst=null
perm=null
[junit] 11/07/22 01:37:22 INFO executionengine.HExecutionEngine: Connecting
to hadoop file system at: hdfs://localhost:43661
[junit] 11/07/22 01:37:22 INFO executionengine.HExecutionEngine: Connecting
to map-reduce job tracker at: localhost:59099
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-4519427391306123443 is added to invalidSet of
127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-4519427391306123443 is added to invalidSet of
127.0.0.1:41558
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-4519427391306123443 is added to invalidSet of
127.0.0.1:51016
[junit] 11/07/22 01:37:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete src=/tmp/TestStore-1673645843841222728.txt
dst=null perm=null
[junit] 11/07/22 01:37:22 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create src=/tmp/TestStore-1673645843841222728.txt
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock: /tmp/TestStore-1673645843841222728.txt.
blk_-9077278668398096392_1113
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: Receiving block
blk_-9077278668398096392_1113 src: /127.0.0.1:59413 dest: /127.0.0.1:44560
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: Receiving block
blk_-9077278668398096392_1113 src: /127.0.0.1:47409 dest: /127.0.0.1:41558
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: Receiving block
blk_-9077278668398096392_1113 src: /127.0.0.1:47212 dest: /127.0.0.1:42511
[junit] 11/07/22 01:37:22 INFO DataNode.clienttrace: src: /127.0.0.1:47212,
dest: /127.0.0.1:42511, bytes: 31, op: HDFS_WRITE, cliID:
DFSClient_-1650800025, srvID: DS-687389278-127.0.1.1-42511-1311296055429,
blockid: blk_-9077278668398096392_1113
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: PacketResponder 0 for
block blk_-9077278668398096392_1113 terminating
[junit] 11/07/22 01:37:22 INFO DataNode.clienttrace: src: /127.0.0.1:47409,
dest: /127.0.0.1:41558, bytes: 31, op: HDFS_WRITE, cliID:
DFSClient_-1650800025, srvID: DS-448864010-127.0.1.1-41558-1311296045100,
blockid: blk_-9077278668398096392_1113
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42511 is added to
blk_-9077278668398096392_1113 size 31
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: PacketResponder 1 for
block blk_-9077278668398096392_1113 terminating
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41558 is added to
blk_-9077278668398096392_1113 size 31
[junit] 11/07/22 01:37:22 INFO DataNode.clienttrace: src: /127.0.0.1:59413,
dest: /127.0.0.1:44560, bytes: 31, op: HDFS_WRITE, cliID:
DFSClient_-1650800025, srvID: DS-1632131043-127.0.1.1-44560-1311296065481,
blockid: blk_-9077278668398096392_1113
[junit] 11/07/22 01:37:22 INFO datanode.DataNode: PacketResponder 2 for
block blk_-9077278668398096392_1113 terminating
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:44560 is added to
blk_-9077278668398096392_1113 size 31
[junit] 11/07/22 01:37:22 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file /tmp/TestStore-1673645843841222728.txt is closed
by DFSClient_-1650800025
[junit] 11/07/22 01:37:23 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:44560
to delete blk_1247146579982373405_1107 blk_133115624376423742_1109
blk_6858688704163705742_1108
[junit] 11/07/22 01:37:23 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:51016
to delete blk_-4519427391306123443_1106 blk_-1117289598324678566_1112
blk_133115624376423742_1109 blk_7167232418050835033_1111
[junit] 11/07/22 01:37:25 INFO datanode.DataNode: Deleting block
blk_133115624376423742_1109 file
build/test/data/dfs/data/data7/current/blk_133115624376423742
[junit] 11/07/22 01:37:25 INFO datanode.DataNode: Deleting block
blk_1247146579982373405_1107 file
build/test/data/dfs/data/data7/current/blk_1247146579982373405
[junit] 11/07/22 01:37:25 INFO datanode.DataNode: Deleting block
blk_6858688704163705742_1108 file
build/test/data/dfs/data/data8/current/blk_6858688704163705742
[junit] 11/07/22 01:37:26 INFO datanode.DataNode: Deleting block
blk_-4519427391306123443_1106 file
build/test/data/dfs/data/data3/current/blk_-4519427391306123443
[junit] 11/07/22 01:37:26 INFO datanode.DataNode: Deleting block
blk_-1117289598324678566_1112 file
build/test/data/dfs/data/data4/current/blk_-1117289598324678566
[junit] 11/07/22 01:37:26 INFO datanode.DataNode: Deleting block
blk_133115624376423742_1109 file
build/test/data/dfs/data/data4/current/blk_133115624376423742
[junit] 11/07/22 01:37:26 INFO datanode.DataNode: Deleting block
blk_7167232418050835033_1111 file
build/test/data/dfs/data/data3/current/blk_7167232418050835033
[junit] 11/07/22 01:37:26 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41558
to delete blk_-4519427391306123443_1106 blk_1247146579982373405_1107
blk_-1117289598324678566_1112 blk_7167232418050835033_1111
blk_6858688704163705742_1108
[junit] 11/07/22 01:37:26 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:42511
to delete blk_-4519427391306123443_1106 blk_1247146579982373405_1107
blk_-1117289598324678566_1112 blk_133115624376423742_1109
blk_7167232418050835033_1111 blk_6858688704163705742_1108
[junit] 11/07/22 01:37:27 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:27 INFO datanode.DataNode: Deleting block
blk_-4519427391306123443_1106 file
build/test/data/dfs/data/data1/current/blk_-4519427391306123443
[junit] 11/07/22 01:37:27 INFO datanode.DataNode: Deleting block
blk_-1117289598324678566_1112 file
build/test/data/dfs/data/data1/current/blk_-1117289598324678566
[junit] 11/07/22 01:37:27 INFO datanode.DataNode: Deleting block
blk_1247146579982373405_1107 file
build/test/data/dfs/data/data2/current/blk_1247146579982373405
[junit] 11/07/22 01:37:27 INFO datanode.DataNode: Deleting block
blk_6858688704163705742_1108 file
build/test/data/dfs/data/data1/current/blk_6858688704163705742
[junit] 11/07/22 01:37:27 INFO datanode.DataNode: Deleting block
blk_7167232418050835033_1111 file
build/test/data/dfs/data/data2/current/blk_7167232418050835033
[junit] 11/07/22 01:37:27 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:28 INFO datanode.DataNode: Deleting block
blk_-4519427391306123443_1106 file
build/test/data/dfs/data/data6/current/blk_-4519427391306123443
[junit] 11/07/22 01:37:28 INFO datanode.DataNode: Deleting block
blk_-1117289598324678566_1112 file
build/test/data/dfs/data/data5/current/blk_-1117289598324678566
[junit] 11/07/22 01:37:28 INFO datanode.DataNode: Deleting block
blk_133115624376423742_1109 file
build/test/data/dfs/data/data5/current/blk_133115624376423742
[junit] 11/07/22 01:37:28 WARN datanode.DataNode: Unexpected error trying
to delete block blk_1247146579982373405_1107. BlockInfo not found in volumeMap.
[junit] 11/07/22 01:37:28 INFO datanode.DataNode: Deleting block
blk_6858688704163705742_1108 file
build/test/data/dfs/data/data6/current/blk_6858688704163705742
[junit] 11/07/22 01:37:28 INFO datanode.DataNode: Deleting block
blk_7167232418050835033_1111 file
build/test/data/dfs/data/data6/current/blk_7167232418050835033
[junit] 11/07/22 01:37:28 WARN datanode.DataNode: Error processing datanode
Command
[junit] java.io.IOException: Error in deleting blocks.
[junit] at
org.apache.hadoop.hdfs.server.datanode.FSDataset.invalidate(FSDataset.java:1361)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.processCommand(DataNode.java:868)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.processCommand(DataNode.java:830)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.offerService(DataNode.java:710)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1186)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 11/07/22 01:37:32 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:32 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:32 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:37 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:37 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:42 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:42 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:42 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:47 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:47 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:47 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:52 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:52 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:52 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:57 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:57 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:37:57 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:02 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:02 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:02 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:02 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:07 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:07 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:12 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN pig.PigServer: bytearray is implicitly cast
to chararray under EqualExpression Operator
[junit] 11/07/22 01:38:12 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:12 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:17 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:17 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:17 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:22 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:22 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:22 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:27 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:27 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:27 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:32 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:32 INFO pigstats.ScriptState: Pig features used in
the script: FILTER
[junit] 11/07/22 01:38:32 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:32 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:37 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:37 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:37 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:42 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:42 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:42 WARN builtin.JsonMetadata: Could not find schema
file for /tmp/TestStore-1673645843841222728.txt
[junit] 11/07/22 01:38:45 WARN hdfs.StateChange: DIR*
NameSystem.completeFile: failed to complete
/tmp/TestStore-output--167234160945274
[junit] Running org.apache.pig.test.TestStore
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.pig.test.TestStore FAILED (timeout)
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 11/07/22 01:38:48 WARN builtin.SUBSTRING:
java.lang.NullPointerException
[junit] 11/07/22 01:38:48 WARN builtin.SUBSTRING:
java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/22 01:38:48 WARN builtin.SUBSTRING:
java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 11/07/22 01:38:48 WARN builtin.SUBSTRING:
java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 11/07/22 01:38:48 WARN builtin.SUBSTRING:
java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/22 01:38:48 WARN builtin.INDEXOF: Failed to process input;
error - null
[junit] 11/07/22 01:38:48 WARN builtin.LAST_INDEX_OF: Failed to process
input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.119 sec
[delete] Deleting directory /tmp/pig_junit_tmp873928744
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:664: The following
error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:719: Tests failed!
Total time: 164 minutes 20 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
