You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@tajo.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/10/27 03:34:23 UTC
Build failed in Jenkins: Tajo-block_iteration-branch-build #19
See <https://builds.apache.org/job/Tajo-block_iteration-branch-build/19/changes>
Changes:
[jhkim] TAJO-1045: Implement nextFetch(RowBlock) of RCFile scanner. (jinho)
------------------------------------------
[...truncated 1260 lines...]
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: written 51B for [name] BINARY: 10,000 values, 12B raw, 12B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 1 entries, 10B raw, 1B comp}
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: written 55B for [age] INT64: 10,000 values, 12B raw, 12B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 1 entries, 8B raw, 1B comp}
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: RecordReader initialized will read a total of 10000 records.
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: at row 0. reading next block
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: block read in memory in 0 ms. row count = 10000
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: RecordReader initialized will read a total of 10000 records.
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: at row 0. reading next block
Oct 27, 2014 2:34:08 AM parquet.Log info
INFO: block read in memory in 0 ms. row count = 10000
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.334 sec - in org.apache.tajo.storage.TestMergeScanner
Running org.apache.tajo.storage.TestStorageManager
Formatting using clusterid: testClusterID
2014-10-27 02:34:09,409 WARN: org.apache.hadoop.metrics2.impl.MetricsConfig (loadFirst(124)) - Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
2014-10-27 02:34:09,533 INFO: org.mortbay.log (info(67)) - Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
2014-10-27 02:34:09,577 INFO: org.mortbay.log (info(67)) - jetty-6.1.26
2014-10-27 02:34:09,602 INFO: org.mortbay.log (info(67)) - Extract jar:file:/home/jenkins/.m2/repository/org/apache/hadoop/hadoop-hdfs/2.5.1/hadoop-hdfs-2.5.1-tests.jar!/webapps/hdfs to /tmp/Jetty_localhost_47608_hdfs____.2sk1rv/webapp
2014-10-27 02:34:09,746 INFO: org.mortbay.log (info(67)) - NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
2014-10-27 02:34:09,757 WARN: org.apache.hadoop.security.authentication.server.AuthenticationFilter (init(166)) - 'signature.secret' configuration not set, using a random value as secret
2014-10-27 02:34:09,778 INFO: org.mortbay.log (info(67)) - Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47608
2014-10-27 02:34:10,240 INFO: org.mortbay.log (info(67)) - jetty-6.1.26
2014-10-27 02:34:10,243 INFO: org.mortbay.log (info(67)) - Extract jar:file:/home/jenkins/.m2/repository/org/apache/hadoop/hadoop-hdfs/2.5.1/hadoop-hdfs-2.5.1-tests.jar!/webapps/datanode to /tmp/Jetty_localhost_56015_datanode____n9ox2y/webapp
2014-10-27 02:34:10,347 INFO: org.mortbay.log (info(67)) - NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
2014-10-27 02:34:10,351 INFO: org.mortbay.log (info(67)) - Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:56015
2014-10-27 02:34:10,877 INFO: BlockStateChange (processReport(1753)) - BLOCK* processReport: from storage DS-9eb8b9f5-f0fc-43c8-8c14-1460f07c3d05 node DatanodeRegistration(127.0.0.1, datanodeUuid=c187ebb8-a189-4583-8ab9-b242e3255411, infoPort=56015, ipcPort=37705, storageInfo=lv=-55;cid=testClusterID;nsid=1032904498;c=0), blocks: 0, hasStaleStorages: true, processing time: 2 msecs
2014-10-27 02:34:10,878 INFO: BlockStateChange (processReport(1753)) - BLOCK* processReport: from storage DS-5f5a4bf1-942e-411c-afe7-9aba98fc708b node DatanodeRegistration(127.0.0.1, datanodeUuid=c187ebb8-a189-4583-8ab9-b242e3255411, infoPort=56015, ipcPort=37705, storageInfo=lv=-55;cid=testClusterID;nsid=1032904498;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
2014-10-27 02:34:11,096 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5f5a4bf1-942e-411c-afe7-9aba98fc708b:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,124 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741826_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9eb8b9f5-f0fc-43c8-8c14-1460f07c3d05:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,139 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741827_1003{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5f5a4bf1-942e-411c-afe7-9aba98fc708b:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,153 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741828_1004{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9eb8b9f5-f0fc-43c8-8c14-1460f07c3d05:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,166 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741829_1005{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5f5a4bf1-942e-411c-afe7-9aba98fc708b:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,180 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741830_1006{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9eb8b9f5-f0fc-43c8-8c14-1460f07c3d05:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,194 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741831_1007{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5f5a4bf1-942e-411c-afe7-9aba98fc708b:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,208 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741832_1008{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9eb8b9f5-f0fc-43c8-8c14-1460f07c3d05:NORMAL|RBW]]} size 10
2014-10-27 02:34:11,628 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741833_1009{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5f5a4bf1-942e-411c-afe7-9aba98fc708b:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,643 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46381 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9eb8b9f5-f0fc-43c8-8c14-1460f07c3d05:NORMAL|RBW]]} size 0
2014-10-27 02:34:11,650 WARN: org.apache.tajo.storage.AbstractStorageManager (<init>(86)) - does not support block metadata. ('dfs.datanode.hdfs-blocks-metadata.enabled')
2014-10-27 02:34:11,662 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,680 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,688 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,695 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,702 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,709 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,716 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,724 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,731 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,738 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,740 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(614)) - Total # of splits: 10
2014-10-27 02:34:11,745 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,752 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,759 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,766 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,773 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1
2014-10-27 02:34:11,776 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(614)) - Total # of splits: 5
2014-10-27 02:34:11,776 WARN: org.apache.hadoop.hdfs.server.datanode.DirectoryScanner (shutdown(375)) - DirectoryScanner: shutdown has been called
2014-10-27 02:34:11,783 INFO: org.mortbay.log (info(67)) - Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
2014-10-27 02:34:11,888 WARN: org.apache.hadoop.hdfs.server.datanode.DataNode (offerService(722)) - BPOfferService for Block pool BP-252857958-67.195.81.146-1414377249257 (Datanode Uuid c187ebb8-a189-4583-8ab9-b242e3255411) service to localhost/127.0.0.1:58648 interrupted
2014-10-27 02:34:11,889 WARN: org.apache.hadoop.hdfs.server.datanode.DataNode (run(845)) - Ending block pool service for: Block pool BP-252857958-67.195.81.146-1414377249257 (Datanode Uuid c187ebb8-a189-4583-8ab9-b242e3255411) service to localhost/127.0.0.1:58648
2014-10-27 02:34:12,003 WARN: org.apache.hadoop.hdfs.server.blockmanagement.DecommissionManager (run(78)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
2014-10-27 02:34:12,014 INFO: org.mortbay.log (info(67)) - Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
Formatting using clusterid: testClusterID
2014-10-27 02:34:12,183 WARN: org.apache.hadoop.metrics2.impl.MetricsConfig (loadFirst(124)) - Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
2014-10-27 02:34:12,195 INFO: org.mortbay.log (info(67)) - jetty-6.1.26
2014-10-27 02:34:12,197 INFO: org.mortbay.log (info(67)) - Extract jar:file:/home/jenkins/.m2/repository/org/apache/hadoop/hadoop-hdfs/2.5.1/hadoop-hdfs-2.5.1-tests.jar!/webapps/hdfs to /tmp/Jetty_localhost_35530_hdfs____.r89tpi/webapp
2014-10-27 02:34:12,303 INFO: org.mortbay.log (info(67)) - NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
2014-10-27 02:34:12,307 WARN: org.apache.hadoop.security.authentication.server.AuthenticationFilter (init(166)) - 'signature.secret' configuration not set, using a random value as secret
2014-10-27 02:34:12,308 INFO: org.mortbay.log (info(67)) - Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:35530
2014-10-27 02:34:12,376 INFO: org.mortbay.log (info(67)) - jetty-6.1.26
2014-10-27 02:34:12,378 INFO: org.mortbay.log (info(67)) - Extract jar:file:/home/jenkins/.m2/repository/org/apache/hadoop/hadoop-hdfs/2.5.1/hadoop-hdfs-2.5.1-tests.jar!/webapps/datanode to /tmp/Jetty_localhost_56423_datanode____.hpxiat/webapp
2014-10-27 02:34:12,474 INFO: org.mortbay.log (info(67)) - NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
2014-10-27 02:34:12,478 INFO: org.mortbay.log (info(67)) - Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:56423
2014-10-27 02:34:12,522 INFO: org.mortbay.log (info(67)) - jetty-6.1.26
2014-10-27 02:34:12,525 INFO: org.mortbay.log (info(67)) - Extract jar:file:/home/jenkins/.m2/repository/org/apache/hadoop/hadoop-hdfs/2.5.1/hadoop-hdfs-2.5.1-tests.jar!/webapps/datanode to /tmp/Jetty_localhost_42868_datanode____.zc42fx/webapp
2014-10-27 02:34:12,569 INFO: BlockStateChange (processReport(1753)) - BLOCK* processReport: from storage DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9 node DatanodeRegistration(127.0.0.1, datanodeUuid=939af996-3930-4923-8b98-ac6563fb1ca2, infoPort=56423, ipcPort=35826, storageInfo=lv=-55;cid=testClusterID;nsid=2070895486;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs
2014-10-27 02:34:12,569 INFO: BlockStateChange (processReport(1753)) - BLOCK* processReport: from storage DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359 node DatanodeRegistration(127.0.0.1, datanodeUuid=939af996-3930-4923-8b98-ac6563fb1ca2, infoPort=56423, ipcPort=35826, storageInfo=lv=-55;cid=testClusterID;nsid=2070895486;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
2014-10-27 02:34:12,641 INFO: org.mortbay.log (info(67)) - NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
2014-10-27 02:34:12,645 INFO: org.mortbay.log (info(67)) - Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:42868
2014-10-27 02:34:12,742 INFO: BlockStateChange (processReport(1753)) - BLOCK* processReport: from storage DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16 node DatanodeRegistration(127.0.0.1, datanodeUuid=ad106f14-db1d-49c3-bba6-135147c3eee3, infoPort=42868, ipcPort=38812, storageInfo=lv=-55;cid=testClusterID;nsid=2070895486;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs
2014-10-27 02:34:12,743 INFO: BlockStateChange (processReport(1753)) - BLOCK* processReport: from storage DS-952a5dfa-b8b6-41db-af23-9e9727f36be5 node DatanodeRegistration(127.0.0.1, datanodeUuid=ad106f14-db1d-49c3-bba6-135147c3eee3, infoPort=42868, ipcPort=38812, storageInfo=lv=-55;cid=testClusterID;nsid=2070895486;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
2014-10-27 02:34:12,796 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,798 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,819 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741826_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,821 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741826_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,844 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741827_1003{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,845 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741827_1003{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,863 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741828_1004{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,864 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741828_1004{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,881 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741829_1005{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,882 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741829_1005{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,899 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741830_1006{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,900 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741830_1006 size 10
2014-10-27 02:34:12,915 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741831_1007{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,915 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741831_1007{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,931 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741832_1008{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,932 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741832_1008{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,947 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741833_1009{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,949 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741833_1009{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-6d5417d5-1ebd-4c16-8688-3b0cd0092cf9:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-2b7f5c0c-352b-4de4-ba1d-9cb3b3b1ae16:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,966 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57902 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8f8b7687-b392-4cc2-83c0-ab9a44c2f359:NORMAL|RBW], ReplicaUnderConstruction[[DISK]DS-952a5dfa-b8b6-41db-af23-9e9727f36be5:NORMAL|RBW]]} size 0
2014-10-27 02:34:12,967 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43174 is added to blk_1073741834_1010 size 10
2014-10-27 02:34:12,976 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 10
2014-10-27 02:34:13,057 INFO: org.apache.tajo.storage.AbstractStorageManager (setVolumeMeta(650)) - # of splits with volumeId 10
2014-10-27 02:34:13,058 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(614)) - Total # of splits: 10
2014-10-27 02:34:13,058 WARN: org.apache.hadoop.hdfs.server.datanode.DirectoryScanner (shutdown(375)) - DirectoryScanner: shutdown has been called
2014-10-27 02:34:13,062 INFO: org.mortbay.log (info(67)) - Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
2014-10-27 02:34:13,167 WARN: org.apache.hadoop.hdfs.server.datanode.DataNode (offerService(722)) - BPOfferService for Block pool BP-1133983793-67.195.81.146-1414377252170 (Datanode Uuid ad106f14-db1d-49c3-bba6-135147c3eee3) service to localhost/127.0.0.1:51017 interrupted
2014-10-27 02:34:13,168 WARN: org.apache.hadoop.hdfs.server.datanode.DataNode (run(845)) - Ending block pool service for: Block pool BP-1133983793-67.195.81.146-1414377252170 (Datanode Uuid ad106f14-db1d-49c3-bba6-135147c3eee3) service to localhost/127.0.0.1:51017
2014-10-27 02:34:13,169 WARN: org.apache.hadoop.hdfs.server.datanode.DirectoryScanner (shutdown(375)) - DirectoryScanner: shutdown has been called
2014-10-27 02:34:13,173 INFO: org.mortbay.log (info(67)) - Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
2014-10-27 02:34:13,276 WARN: org.apache.hadoop.hdfs.server.datanode.DataNode (offerService(722)) - BPOfferService for Block pool BP-1133983793-67.195.81.146-1414377252170 (Datanode Uuid 939af996-3930-4923-8b98-ac6563fb1ca2) service to localhost/127.0.0.1:51017 interrupted
2014-10-27 02:34:13,279 WARN: org.apache.hadoop.hdfs.server.datanode.DataNode (run(845)) - Ending block pool service for: Block pool BP-1133983793-67.195.81.146-1414377252170 (Datanode Uuid 939af996-3930-4923-8b98-ac6563fb1ca2) service to localhost/127.0.0.1:51017
2014-10-27 02:34:13,387 WARN: org.apache.hadoop.hdfs.server.blockmanagement.DecommissionManager (run(78)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
2014-10-27 02:34:13,394 INFO: org.mortbay.log (info(67)) - Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.695 sec - in org.apache.tajo.storage.TestStorageManager
Running org.apache.tajo.storage.TestLazyTuple
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.tajo.storage.TestLazyTuple
Running org.apache.tajo.tuple.TestBaseTupleBuilder
2014-10-27 02:34:13,544 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(323)) - 8.4 MB bytes allocated 3 msec
2014-10-27 02:34:13,689 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(331)) - writing takes 144 msec
2014-10-27 02:34:14,054 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlockWithNull(340)) - 8.4 MB bytes allocated 3 msec
2014-10-27 02:34:14,160 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlockWithNull(348)) - writing and validating take 106 msec
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.853 sec - in org.apache.tajo.tuple.TestBaseTupleBuilder
Running org.apache.tajo.tuple.offheap.TestHeapTuple
2014-10-27 02:34:14,398 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(323)) - 8.4 MB bytes allocated 1 msec
2014-10-27 02:34:14,404 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(331)) - writing takes 6 msec
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.019 sec - in org.apache.tajo.tuple.offheap.TestHeapTuple
Running org.apache.tajo.tuple.offheap.TestOffHeapRowBlock
2014-10-27 02:34:14,417 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (explainRowBlockAllocation(72)) - 1.0 kB bytes allocated 0 msec
2014-10-27 02:34:14,418 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 2.0 KiB
2014-10-27 02:34:14,419 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 4.0 KiB
2014-10-27 02:34:14,423 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 8.0 KiB
2014-10-27 02:34:14,430 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 16.0 KiB
2014-10-27 02:34:14,460 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 32.0 KiB
2014-10-27 02:34:14,567 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 64.0 KiB
2014-10-27 02:34:15,017 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 128.0 KiB
2014-10-27 02:34:16,662 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 256.0 KiB
2014-10-27 02:34:17,933 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testPutAndReadValidation(101)) - writing and validating take 3516 msec
2014-10-27 02:34:17,941 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testPutAndReadValidation(112)) - reading takes 7 msec
2014-10-27 02:34:17,944 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(323)) - 8.4 MB bytes allocated 3 msec
2014-10-27 02:34:17,963 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(331)) - writing takes 19 msec
2014-10-27 02:34:17,964 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testSortBenchmark(210)) - reading takes 0 msec
2014-10-27 02:34:17,965 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testSortBenchmark(218)) - sorting took 1 msec
2014-10-27 02:34:17,965 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (explainRowBlockAllocation(72)) - 1.0 kB bytes allocated 0 msec
2014-10-27 02:34:17,967 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 2.0 KiB
2014-10-27 02:34:17,968 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 4.0 KiB
2014-10-27 02:34:17,970 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 8.0 KiB
2014-10-27 02:34:17,979 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 16.0 KiB
2014-10-27 02:34:18,011 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 32.0 KiB
2014-10-27 02:34:18,135 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 64.0 KiB
2014-10-27 02:34:18,628 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 128.0 KiB
2014-10-27 02:34:20,585 INFO: org.apache.tajo.tuple.offheap.OffHeapRowBlock (ensureSize(115)) - Increase DirectRowBlock to 256.0 KiB
2014-10-27 02:34:21,145 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testNullityValidation(141)) - writing and nullity validating take 3179 msec
2014-10-27 02:34:21,152 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testNullityValidation(153)) - reading takes 6 msec
2014-10-27 02:34:21,213 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testVTuplePutAndGetBenchmarkViaDirectRowEncoder(272)) - Writing takes 30 msec
2014-10-27 02:34:21,220 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (validateResults(497)) - Reading takes 7 msec
2014-10-27 02:34:21,223 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(323)) - 8.4 MB bytes allocated 2 msec
2014-10-27 02:34:21,236 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(331)) - writing takes 13 msec
2014-10-27 02:34:21,237 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testSerDerOfZeroCopyTuple(314)) - reading takes 0 msec
2014-10-27 02:34:21,247 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testVTuplePutAndGetBenchmark(235)) - Writing takes 10 msec
2014-10-27 02:34:21,261 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testVTuplePutAndGetBenchmark(244)) - reading takes 13 msec
2014-10-27 02:34:21,265 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testVTuplePutAndGetBenchmark(255)) - The number of INT4 values is 1000.
2014-10-27 02:34:21,268 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(323)) - 8.4 MB bytes allocated 1 msec
2014-10-27 02:34:21,281 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (createRowBlock(331)) - writing takes 13 msec
2014-10-27 02:34:21,281 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (validateResults(497)) - Reading takes 0 msec
2014-10-27 02:34:21,285 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (explainRowBlockAllocation(72)) - 10.5 MB bytes allocated 3 msec
2014-10-27 02:34:21,285 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testEmptyRow(174)) - writing tooks 0 msec
2014-10-27 02:34:21,285 INFO: org.apache.tajo.tuple.offheap.TestOffHeapRowBlock (testEmptyRow(186)) - reading takes 0 msec
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.87 sec - in org.apache.tajo.tuple.offheap.TestOffHeapRowBlock
Running org.apache.tajo.tuple.offheap.TestResizableSpec
2014-10-27 02:34:21,287 INFO: org.apache.tajo.tuple.offheap.ResizableLimitSpec (increasedSize(124)) - Increasing reaches size limit (100.0 MiB)
2014-10-27 02:34:21,287 INFO: org.apache.tajo.tuple.offheap.ResizableLimitSpec (increasedSize(124)) - Increasing reaches size limit (1.1 GiB)
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.tajo.tuple.offheap.TestResizableSpec
Oct 27, 2014 2:33:22 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:24 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:24 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records.
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 10000 records.
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 2 ms. row count = 10000
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 12 records.
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Oct 27, 2014 2:33:31 AM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 12
Oct 27, 2014 2:33:33 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:33 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:33:33 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:34:08 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Oct 27, 2014 2:34:08 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Results :
Tests in error:
TestCompressionStorages.testBzip2CodecCompressionData:106->storageCompressionTest:214 » NullPointer
TestCompressionStorages.testDeflateCodecCompressionData:79->storageCompressionTest:214 » NullPointer
Tests run: 231, Failures: 0, Errors: 2, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.723 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.131 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.005 s]
[INFO] Tajo Common ....................................... SUCCESS [ 38.745 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.315 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.586 s]
[INFO] Tajo Rpc .......................................... SUCCESS [ 25.888 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 0.994 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 6.069 s]
[INFO] Tajo Storage ...................................... FAILURE [01:02 min]
[INFO] Tajo Core PullServer .............................. SKIPPED
[INFO] Tajo Client ....................................... SKIPPED
[INFO] Tajo JDBC Driver .................................. SKIPPED
[INFO] ASM (thirdparty) .................................. SKIPPED
[INFO] Tajo Core ......................................... SKIPPED
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 02:27 min
[INFO] Finished at: 2014-10-27T02:34:21+00:00
[INFO] Final Memory: 61M/872M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-storage: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Tajo-block_iteration-branch-build/ws/tajo-storage/target/surefire-reports> for the individual test results.
[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/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-storage
Build step 'Execute shell' marked build as failure
Updating TAJO-1045
Jenkins build is back to normal : Tajo-block_iteration-branch-build
#20
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-block_iteration-branch-build/20/changes>