See <https://builds.apache.org/job/Tajo-0.11.0-build/85/>
------------------------------------------
[...truncated 3631 lines...]
2015-09-30 00:42:46,159 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
size 0
2015-09-30 00:42:46,161 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/customer/customer.tbl
is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,227 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.customer" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,229 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/lineitem dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,230 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/lineitem/lineitem.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,233 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/lineitem/lineitem.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,236 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/lineitem/lineitem.tbl
2015-09-30 00:42:46,238 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741827_1003 src:
/127.0.0.1:37464 dest: /127.0.0.1:53226
2015-09-30 00:42:46,243 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37464, dest: /127.0.0.1:53226, bytes:
604, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741827_1003, duration: 2939722
2015-09-30 00:42:46,243 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741827_1003,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,245 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|FINALIZED]]}
size 0
2015-09-30 00:42:46,246 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/lineitem/lineitem.tbl
is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,260 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.lineitem" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,261 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/nation dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,263 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/nation/nation.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,265 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/nation/nation.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,268 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/nation/nation.tbl
2015-09-30 00:42:46,270 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741828_1004 src:
/127.0.0.1:37465 dest: /127.0.0.1:53226
2015-09-30 00:42:46,274 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37465, dest: /127.0.0.1:53226, bytes:
2224, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741828_1004, duration: 2500162
2015-09-30 00:42:46,274 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741828_1004,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,275 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
size 0
2015-09-30 00:42:46,276 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/nation/nation.tbl is
closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,284 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.nation" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,286 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/orders dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,288 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/orders/orders.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,291 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/orders/orders.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,293 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/orders/orders.tbl
2015-09-30 00:42:46,295 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741829_1005 src:
/127.0.0.1:37466 dest: /127.0.0.1:53226
2015-09-30 00:42:46,299 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37466, dest: /127.0.0.1:53226, bytes:
324, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741829_1005, duration: 2242432
2015-09-30 00:42:46,299 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741829_1005,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,300 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|FINALIZED]]}
size 0
2015-09-30 00:42:46,301 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/orders/orders.tbl is
closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,311 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.orders" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,313 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/part dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,315 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/part/part.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,318 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/part/part.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,321 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/part/part.tbl
2015-09-30 00:42:46,322 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741830_1006 src:
/127.0.0.1:37467 dest: /127.0.0.1:53226
2015-09-30 00:42:46,326 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37467, dest: /127.0.0.1:53226, bytes:
480, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741830_1006, duration: 2276753
2015-09-30 00:42:46,326 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741830_1006,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,328 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
size 0
2015-09-30 00:42:46,329 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/part/part.tbl is
closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,337 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.part" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,339 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/partsupp dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,341 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/partsupp/partsupp.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,344 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/partsupp/partsupp.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,346 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/partsupp/partsupp.tbl
2015-09-30 00:42:46,349 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741831_1007 src:
/127.0.0.1:37468 dest: /127.0.0.1:53226
2015-09-30 00:42:46,353 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37468, dest: /127.0.0.1:53226, bytes:
469, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741831_1007, duration: 3077804
2015-09-30 00:42:46,353 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741831_1007,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,355 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|FINALIZED]]}
size 0
2015-09-30 00:42:46,356 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/partsupp/partsupp.tbl
is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,364 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.partsupp" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,366 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/region dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,368 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/region/region.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,370 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/region/region.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,373 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/region/region.tbl
2015-09-30 00:42:46,375 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741832_1008 src:
/127.0.0.1:37469 dest: /127.0.0.1:53226
2015-09-30 00:42:46,379 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37469, dest: /127.0.0.1:53226, bytes:
389, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741832_1008, duration: 2320305
2015-09-30 00:42:46,379 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741832_1008,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,381 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]}
size 0
2015-09-30 00:42:46,382 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/region/region.tbl is
closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,389 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.region" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,391 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/supplier dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,393 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/supplier/supplier.tbl
dst=null perm=null proto=rpc
2015-09-30 00:42:46,396 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create src=/tajo/warehouse/supplier/supplier.tbl
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,399 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|RBW]]}
for /tajo/warehouse/supplier/supplier.tbl
2015-09-30 00:42:46,401 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741833_1009 src:
/127.0.0.1:37470 dest: /127.0.0.1:53226
2015-09-30 00:42:46,405 INFO:
org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace
(finalizeBlock(1375)) - src: /127.0.0.1:37470, dest: /127.0.0.1:53226, bytes:
366, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0,
srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid:
BP-678977945-67.195.81.144-1443573757221:blk_1073741833_1009, duration: 2759025
2015-09-30 00:42:46,406 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(run(1348)) - PacketResponder:
BP-678977945-67.195.81.144-1443573757221:blk_1073741833_1009,
type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,407 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to
blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|RBW]]}
size 0
2015-09-30 00:42:46,408 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile: /tajo/warehouse/supplier/supplier.tbl
is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,418 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.supplier" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,421 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/empty_orders
dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,423 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo
src=/tajo/warehouse/empty_orders/empty_orders.tbl dst=null
perm=null proto=rpc
2015-09-30 00:42:46,426 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create
src=/tajo/warehouse/empty_orders/empty_orders.tbl dst=null
perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,428 INFO: org.apache.hadoop.hdfs.StateChange
(completeFile(3495)) - DIR* completeFile:
/tajo/warehouse/empty_orders/empty_orders.tbl is closed by
DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,436 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(695)) - relation "default.empty_orders" is added to the catalog
(127.0.0.1:16756)
2015-09-30 00:42:46,437 INFO: org.apache.tajo.LocalTajoTestingUtility
(setup(131)) - ===================================================
2015-09-30 00:42:46,437 INFO: org.apache.tajo.LocalTajoTestingUtility
(setup(132)) - Test Cluster ready and test table created.
2015-09-30 00:42:46,437 INFO: org.apache.tajo.LocalTajoTestingUtility
(setup(133)) - ===================================================
2015-09-30 00:42:46,451 INFO: org.apache.tajo.session.SessionManager
(createSession(79)) - Session 32b7ca59-4454-413e-b81e-0752f68cbf3e is created.
2015-09-30 00:42:46,468 INFO: org.apache.tajo.master.GlobalEngine
(updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestSQLState"
2015-09-30 00:42:46,672 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestSQLState
2015-09-30 00:42:46,774 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-09-30 00:42:46,774 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestSQLState
2015-09-30 00:42:46,774 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-09-30 00:42:46,779 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/TestSQLState
dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,783 INFO: org.apache.tajo.catalog.CatalogServer
(createDatabase(421)) - database "TestSQLState" is created
2015-09-30 00:42:46,783 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(257)) - database "TestSQLState" created.
Run: testSyntaxError Used memory: 193 MBytes, Active Threads:103
2015-09-30 00:42:46,824 INFO: org.apache.tajo.session.SessionManager
(createSession(79)) - Session 8456e524-025b-424f-aed1-6a3d06c66dec is created.
2015-09-30 00:42:46,836 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(176)) - Query: SELECT 1;
2015-09-30 00:42:47,090 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:42:47,152 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-09-30 00:42:47,152 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:42:47,152 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-09-30 00:42:47,172 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(124)) - =========================================================
2015-09-30 00:42:47,173 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(125)) - Stop query:q_1443573761614_0001
2015-09-30 00:42:47,185 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(176)) - Query: selec x,y,x from lineitem
2015-09-30 00:42:47,192 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 8456e524-025b-424f-aed1-6a3d06c66dec is removed.
2015-09-30 00:42:47,251 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 32b7ca59-4454-413e-b81e-0752f68cbf3e is removed.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 13.223 sec - in
org.apache.tajo.jdbc.TestSQLState
Running org.apache.tajo.jdbc.TestTajoJdbcNegative
2015-09-30 00:42:47,267 INFO: org.apache.tajo.session.SessionManager
(createSession(79)) - Session 0db02158-2caa-4579-9b2c-e6358a250c82 is created.
2015-09-30 00:42:47,271 INFO: org.apache.tajo.master.GlobalEngine
(updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestTajoJdbcNegative"
2015-09-30 00:42:47,273 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestTajoJdbcNegative
2015-09-30 00:42:47,274 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-09-30 00:42:47,274 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestTajoJdbcNegative
2015-09-30 00:42:47,274 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-09-30 00:42:47,276 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/TestTajoJdbcNegative
dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:47,278 INFO: org.apache.tajo.catalog.CatalogServer
(createDatabase(421)) - database "TestTajoJdbcNegative" is created
2015-09-30 00:42:47,278 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(257)) - database "TestTajoJdbcNegative" created.
Run: testConnectTimeout Used memory: 220 MBytes, Active Threads:111
2015-09-30 00:42:47,850 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=getfileinfo
src=/tmp/tajo-jenkins/staging/history/20150930/query-list dst=null
perm=null proto=rpc
2015-09-30 00:42:47,852 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=mkdirs
src=/tmp/tajo-jenkins/staging/history/20150930/query-list dst=null
perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:47,853 INFO: org.apache.tajo.util.history.HistoryWriter
(rollingQuerySummaryWriter(432)) - Create query history file:
hdfs://localhost:58551/tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist
2015-09-30 00:42:47,855 INFO:
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit
(logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE)
ip=/127.0.0.1 cmd=create
src=/tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist
dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:47,873 INFO: org.apache.hadoop.hdfs.StateChange
(saveAllocatedBlock(3575)) - BLOCK* allocate
blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|RBW]]}
for
/tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist
2015-09-30 00:42:47,876 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode
(writeBlock(655)) - Receiving
BP-678977945-67.195.81.144-1443573757221:blk_1073741834_1010 src:
/127.0.0.1:37475 dest: /127.0.0.1:53226
2015-09-30 00:42:47,884 INFO: org.apache.hadoop.hdfs.StateChange (fsync(3977))
- BLOCK* fsync:
/tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist
for DFSClient_NONMAPREDUCE_2107134690_1
Killed
Results :
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.077 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.458 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.212 s]
[INFO] Tajo Common ....................................... SUCCESS [ 43.569 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.125 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 6.826 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 8.339 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.424 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 52.062 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.352 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 12.549 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.238 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.460 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.239 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.503 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.068 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.696 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.799 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.138 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.345 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.270 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.920 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.359 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.062 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.015 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.057 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.335 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.942 s]
[INFO] Tajo JDBC Driver .................................. FAILURE [ 16.167 s]
[INFO] Tajo JDBC storage common .......................... SKIPPED
[INFO] Tajo PostgreSQL JDBC storage ...................... SKIPPED
[INFO] Tajo Storage ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] Tajo Core Tests ................................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:16 min
[INFO] Finished at: 2015-09-30T00:42:48+00:00
[INFO] Final Memory: 121M/507M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on
project tajo-jdbc: Execution default-test of goal
org.apache.maven.plugins:maven-surefire-plugin:2.17:test failed: The forked VM
terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd
<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc> &&
/home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -Xms512m -Xmx1024m
-XX:MaxPermSize=152m -Dfile.encoding=UTF-8 -Dderby.storage.pageSize=1024
-Dderby.stream.error.file=/dev/null -jar
<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc/target/surefire/surefirebooter5434800226325486627.jar>
<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc/target/surefire/surefire334072516956304884tmp>
<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc/target/surefire/surefire_137815203712329215974tmp>
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e
switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please
read the following articles:
[ERROR] [Help 1]
http://cwiki.apache.org/confluence/display/MAVEN/PluginExecutionException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-jdbc
Build step 'Execute shell' marked build as failure
Archiving artifacts
