See <https://builds.apache.org/job/gora-trunk/276/>
------------------------------------------ [...truncated 5388 lines...] 12/05/16 07:02:04 INFO datanode.DataNode: Receiving block blk_-1655563110250074632_1115 src: /127.0.0.1:62838 dest: /127.0.0.1:61632 12/05/16 07:02:04 INFO DataNode.clienttrace: src: /127.0.0.1:62838, dest: /127.0.0.1:61632, bytes: 720, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_-1655563110250074632_1115, duration: 1070275 12/05/16 07:02:04 INFO datanode.DataNode: PacketResponder 0 for block blk_-1655563110250074632_1115 terminating 12/05/16 07:02:04 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_-1655563110250074632_1115 size 720 12/05/16 07:02:04 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/9e4a7a8b1a607d68160099c12b2f2939/.tmp/.regioninfo from client DFSClient_-1833973968 12/05/16 07:02:04 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/9e4a7a8b1a607d68160099c12b2f2939/.tmp/.regioninfo is closed by DFSClient_-1833973968 12/05/16 07:02:04 INFO regionserver.HRegion: Onlined Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939.; next sequenceid=1 12/05/16 07:02:04 INFO catalog.MetaEditor: Added region Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. to META 12/05/16 07:02:04 INFO regionserver.HRegion: Closed Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. 12/05/16 07:02:04 INFO wal.HLog: IPC Server handler 2 on 61639.logSyncer exiting 12/05/16 07:02:04 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/9e4a7a8b1a607d68160099c12b2f2939/.logs/hlog.1337151724382. blk_8262852088659939544_1115 12/05/16 07:02:04 INFO datanode.DataNode: Receiving block blk_8262852088659939544_1115 src: /127.0.0.1:62839 dest: /127.0.0.1:61632 12/05/16 07:02:04 INFO DataNode.clienttrace: src: /127.0.0.1:62839, dest: /127.0.0.1:61632, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_8262852088659939544_1115, duration: 1210995 12/05/16 07:02:04 INFO datanode.DataNode: PacketResponder 0 for block blk_8262852088659939544_1115 terminating 12/05/16 07:02:04 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_8262852088659939544_1115 size 124 12/05/16 07:02:05 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/9e4a7a8b1a607d68160099c12b2f2939/.logs/hlog.1337151724382 from client DFSClient_-1833973968 12/05/16 07:02:05 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/9e4a7a8b1a607d68160099c12b2f2939/.logs/hlog.1337151724382 is closed by DFSClient_-1833973968 12/05/16 07:02:05 INFO regionserver.HRegionServer: Received request to open region: Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. 12/05/16 07:02:05 INFO regionserver.HRegion: Onlined Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939.; next sequenceid=1 12/05/16 07:02:05 INFO catalog.MetaEditor: Updated row Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. in region .META.,,1 with server=hudson_solaris:61642, startcode=1337150942230 12/05/16 07:02:10 INFO handler.DisableTableHandler: Attemping to disable table WebPage 12/05/16 07:02:10 INFO client.HBaseAdmin: Started disable of WebPage 12/05/16 07:02:10 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 07:02:10 INFO regionserver.HRegionServer: Received close region: WebPage,,1337151708445.ca9cfeb08ca63be57671e2aadffa799d. 12/05/16 07:02:10 INFO regionserver.HRegion: Closed WebPage,,1337151708445.ca9cfeb08ca63be57671e2aadffa799d. 12/05/16 07:02:11 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 07:02:15 INFO client.HBaseAdmin: Disabled WebPage 12/05/16 07:02:15 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table WebPage 12/05/16 07:02:15 INFO catalog.MetaEditor: Deleted region WebPage,,1337151708445.ca9cfeb08ca63be57671e2aadffa799d. from META 12/05/16 07:02:15 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2352674074150339987 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:15 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_6000551225842426011 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:17 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:61632 to delete blk_6000551225842426011_1113 blk_2352674074150339987_1113 12/05/16 07:02:18 INFO datanode.DataNode: Scheduling block blk_2352674074150339987_1113 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_2352674074150339987> for deletion 12/05/16 07:02:18 INFO datanode.DataNode: Scheduling block blk_6000551225842426011_1113 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_6000551225842426011> for deletion 12/05/16 07:02:18 INFO datanode.DataNode: Deleted block blk_2352674074150339987_1113 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_2352674074150339987> 12/05/16 07:02:18 INFO datanode.DataNode: Deleted block blk_6000551225842426011_1113 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_6000551225842426011> 12/05/16 07:02:20 INFO client.HBaseAdmin: Deleted WebPage 12/05/16 07:02:20 WARN zookeeper.ZKTable: Moving table WebPage state to enabled but was already enabled 12/05/16 07:02:20 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x13754682a550000 type:delete cxid:0x536 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/WebPage Error:KeeperErrorCode = NoNode for /hbase/table/WebPage 12/05/16 07:02:20 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 07:02:20 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 07:02:20 INFO wal.HLog: New hlog /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.logs/hlog.1337151740303 12/05/16 07:02:20 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 07:02:20 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/.regioninfo. blk_5114012497398779396_1117 12/05/16 07:02:20 INFO datanode.DataNode: Receiving block blk_5114012497398779396_1117 src: /127.0.0.1:62846 dest: /127.0.0.1:61632 12/05/16 07:02:20 INFO DataNode.clienttrace: src: /127.0.0.1:62846, dest: /127.0.0.1:61632, bytes: 1892, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_5114012497398779396_1117, duration: 769955 12/05/16 07:02:20 INFO datanode.DataNode: PacketResponder 0 for block blk_5114012497398779396_1117 terminating 12/05/16 07:02:20 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_5114012497398779396_1117 size 1892 12/05/16 07:02:20 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/.regioninfo from client DFSClient_-1833973968 12/05/16 07:02:20 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/.regioninfo is closed by DFSClient_-1833973968 12/05/16 07:02:20 INFO regionserver.HRegion: Onlined WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd.; next sequenceid=1 12/05/16 07:02:20 INFO catalog.MetaEditor: Added region WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. to META 12/05/16 07:02:20 INFO regionserver.HRegion: Closed WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. 12/05/16 07:02:20 INFO wal.HLog: IPC Server handler 1 on 61639.logSyncer exiting 12/05/16 07:02:20 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.logs/hlog.1337151740303. blk_102938401512191742_1117 12/05/16 07:02:20 INFO datanode.DataNode: Receiving block blk_102938401512191742_1117 src: /127.0.0.1:62847 dest: /127.0.0.1:61632 12/05/16 07:02:20 INFO DataNode.clienttrace: src: /127.0.0.1:62847, dest: /127.0.0.1:61632, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_102938401512191742_1117, duration: 1299258 12/05/16 07:02:20 INFO datanode.DataNode: PacketResponder 0 for block blk_102938401512191742_1117 terminating 12/05/16 07:02:20 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_102938401512191742_1117 size 124 12/05/16 07:02:21 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.logs/hlog.1337151740303 from client DFSClient_-1833973968 12/05/16 07:02:21 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.logs/hlog.1337151740303 is closed by DFSClient_-1833973968 12/05/16 07:02:21 INFO regionserver.HRegionServer: Received request to open region: WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. 12/05/16 07:02:21 INFO regionserver.HRegion: Onlined WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd.; next sequenceid=1 12/05/16 07:02:21 INFO catalog.MetaEditor: Updated row WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. in region .META.,,1 with server=hudson_solaris:61642, startcode=1337150942230 12/05/16 07:02:26 INFO store.DataStoreTestBase: test method: testPutNested 12/05/16 07:02:26 INFO zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:21818 sessionTimeout=180000 watcher=hconnection 12/05/16 07:02:26 INFO zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:21818 12/05/16 07:02:26 INFO zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:21818, initiating session 12/05/16 07:02:26 INFO server.NIOServerCnxn: Accepted socket connection from /127.0.0.1:62849 12/05/16 07:02:26 INFO server.NIOServerCnxn: Client attempting to establish new session at /127.0.0.1:62849 12/05/16 07:02:26 INFO server.NIOServerCnxn: Established session 0x13754682a550024 with negotiated timeout 40000 for client /127.0.0.1:62849 12/05/16 07:02:26 INFO zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:21818, sessionid = 0x13754682a550024, negotiated timeout = 40000 12/05/16 07:02:26 INFO store.DataStoreTestBase: tearing down test 12/05/16 07:02:26 INFO gora.GoraTestDriver: tearing down test 12/05/16 07:02:26 INFO handler.DisableTableHandler: Attemping to disable table Employee 12/05/16 07:02:26 INFO client.HBaseAdmin: Started disable of Employee 12/05/16 07:02:26 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 07:02:26 INFO regionserver.HRegionServer: Received close region: Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. 12/05/16 07:02:26 INFO regionserver.HRegion: Closed Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. 12/05/16 07:02:27 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 07:02:31 INFO client.HBaseAdmin: Disabled Employee 12/05/16 07:02:31 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table Employee 12/05/16 07:02:31 INFO catalog.MetaEditor: Deleted region Employee,,1337151724368.9e4a7a8b1a607d68160099c12b2f2939. from META 12/05/16 07:02:31 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_8262852088659939544 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:31 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-1655563110250074632 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:32 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:61632 to delete blk_-1655563110250074632_1115 blk_8262852088659939544_1115 12/05/16 07:02:33 INFO datanode.DataNode: Scheduling block blk_-1655563110250074632_1115 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_-1655563110250074632> for deletion 12/05/16 07:02:33 INFO datanode.DataNode: Scheduling block blk_8262852088659939544_1115 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_8262852088659939544> for deletion 12/05/16 07:02:33 INFO datanode.DataNode: Deleted block blk_-1655563110250074632_1115 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_-1655563110250074632> 12/05/16 07:02:33 INFO datanode.DataNode: Deleted block blk_8262852088659939544_1115 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_8262852088659939544> 12/05/16 07:02:36 INFO client.HBaseAdmin: Deleted Employee 12/05/16 07:02:36 INFO handler.DisableTableHandler: Attemping to disable table WebPage 12/05/16 07:02:36 INFO client.HBaseAdmin: Started disable of WebPage 12/05/16 07:02:36 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 07:02:36 INFO regionserver.HRegionServer: Received close region: WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. 12/05/16 07:02:36 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/3905417121604740781. blk_-2012436973786246145_1118 12/05/16 07:02:36 INFO datanode.DataNode: Receiving block blk_-2012436973786246145_1118 src: /127.0.0.1:62853 dest: /127.0.0.1:61632 12/05/16 07:02:36 INFO DataNode.clienttrace: src: /127.0.0.1:62853, dest: /127.0.0.1:61632, bytes: 501, op: HDFS_WRITE, cliID: DFSClient_hb_rs_hudson_solaris,61642,1337150942230_1337150944850, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_-2012436973786246145_1118, duration: 991028 12/05/16 07:02:36 INFO datanode.DataNode: PacketResponder 0 for block blk_-2012436973786246145_1118 terminating 12/05/16 07:02:36 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_-2012436973786246145_1118 size 501 12/05/16 07:02:36 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/3905417121604740781 from client DFSClient_hb_rs_hudson_solaris,61642,1337150942230_1337150944850 12/05/16 07:02:36 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/3905417121604740781 is closed by DFSClient_hb_rs_hudson_solaris,61642,1337150942230_1337150944850 12/05/16 07:02:36 INFO regionserver.Store: Renaming flushed file at hdfs://localhost:61626/user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/.tmp/3905417121604740781 to hdfs://localhost:61626/user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/common/4135712305608693014 12/05/16 07:02:36 INFO DataNode.clienttrace: src: /127.0.0.1:61632, dest: /127.0.0.1:62854, bytes: 505, op: HDFS_READ, cliID: DFSClient_hb_rs_hudson_solaris,61642,1337150942230_1337150944850, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_-2012436973786246145_1118, duration: 704698 12/05/16 07:02:36 INFO DataNode.clienttrace: src: /127.0.0.1:61632, dest: /127.0.0.1:62855, bytes: 505, op: HDFS_READ, cliID: DFSClient_hb_rs_hudson_solaris,61642,1337150942230_1337150944850, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_-2012436973786246145_1118, duration: 537552 12/05/16 07:02:36 INFO regionserver.Store: Added hdfs://localhost:61626/user/hudson/WebPage/800bd2f672ee8ec6638af9586a5ab1dd/common/4135712305608693014, entries=2, sequenceid=158, memsize=360.0, filesize=501.0 12/05/16 07:02:36 INFO regionserver.HRegion: Closed WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. 12/05/16 07:02:37 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 07:02:41 INFO client.HBaseAdmin: Disabled WebPage 12/05/16 07:02:41 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table WebPage 12/05/16 07:02:41 INFO catalog.MetaEditor: Deleted region WebPage,,1337151740289.800bd2f672ee8ec6638af9586a5ab1dd. from META 12/05/16 07:02:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_102938401512191742 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_5114012497398779396 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:41 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2012436973786246145 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:02:41 INFO namenode.FSNamesystem: Number of transactions: 1060 Total time for transactions(ms): 30Number of transactions batched in Syncs: 85 Number of syncs: 840 SyncTimes(ms): 7712 6068 12/05/16 07:02:41 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:61632 to delete blk_102938401512191742_1117 blk_-2012436973786246145_1118 blk_5114012497398779396_1117 12/05/16 07:02:42 INFO datanode.DataNode: Scheduling block blk_-2012436973786246145_1118 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_-2012436973786246145> for deletion 12/05/16 07:02:42 INFO datanode.DataNode: Scheduling block blk_102938401512191742_1117 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_102938401512191742> for deletion 12/05/16 07:02:42 INFO datanode.DataNode: Deleted block blk_-2012436973786246145_1118 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_-2012436973786246145> 12/05/16 07:02:42 INFO datanode.DataNode: Scheduling block blk_5114012497398779396_1117 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_5114012497398779396> for deletion 12/05/16 07:02:42 INFO datanode.DataNode: Deleted block blk_102938401512191742_1117 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_102938401512191742> 12/05/16 07:02:42 INFO datanode.DataNode: Deleted block blk_5114012497398779396_1117 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_5114012497398779396> 12/05/16 07:02:46 INFO client.HBaseAdmin: Deleted WebPage 12/05/16 07:02:46 INFO store.DataStoreTestBase: setting up test 12/05/16 07:02:46 INFO zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:21818 sessionTimeout=180000 watcher=hconnection 12/05/16 07:02:46 INFO zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:21818 12/05/16 07:02:46 INFO zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:21818, initiating session 12/05/16 07:02:46 INFO server.NIOServerCnxn: Accepted socket connection from /127.0.0.1:62861 12/05/16 07:02:46 INFO server.NIOServerCnxn: Client attempting to establish new session at /127.0.0.1:62861 12/05/16 07:02:46 INFO server.NIOServerCnxn: Established session 0x13754682a550025 with negotiated timeout 40000 for client /127.0.0.1:62861 12/05/16 07:02:46 INFO zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:21818, sessionid = 0x13754682a550025, negotiated timeout = 40000 12/05/16 07:02:46 WARN zookeeper.ZKTable: Moving table Employee state to enabled but was already enabled 12/05/16 07:02:46 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x13754682a550000 type:delete cxid:0x55f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/Employee Error:KeeperErrorCode = NoNode for /hbase/table/Employee 12/05/16 07:02:46 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 07:02:46 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 07:02:46 INFO wal.HLog: New hlog /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.logs/hlog.1337151766439 12/05/16 07:02:46 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 07:02:46 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.tmp/.regioninfo. blk_1159033546477871779_1120 12/05/16 07:02:46 INFO datanode.DataNode: Receiving block blk_1159033546477871779_1120 src: /127.0.0.1:62862 dest: /127.0.0.1:61632 12/05/16 07:02:46 INFO DataNode.clienttrace: src: /127.0.0.1:62862, dest: /127.0.0.1:61632, bytes: 720, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_1159033546477871779_1120, duration: 550812 12/05/16 07:02:46 INFO datanode.DataNode: PacketResponder 0 for block blk_1159033546477871779_1120 terminating 12/05/16 07:02:46 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_1159033546477871779_1120 size 720 12/05/16 07:02:46 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.tmp/.regioninfo from client DFSClient_-1833973968 12/05/16 07:02:46 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.tmp/.regioninfo is closed by DFSClient_-1833973968 12/05/16 07:02:46 INFO regionserver.HRegion: Onlined Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d.; next sequenceid=1 12/05/16 07:02:46 INFO catalog.MetaEditor: Added region Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. to META 12/05/16 07:02:46 INFO regionserver.HRegion: Closed Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. 12/05/16 07:02:46 INFO wal.HLog: IPC Server handler 2 on 61639.logSyncer exiting 12/05/16 07:02:46 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.logs/hlog.1337151766439. blk_3400982650094266416_1120 12/05/16 07:02:46 INFO datanode.DataNode: Receiving block blk_3400982650094266416_1120 src: /127.0.0.1:62863 dest: /127.0.0.1:61632 12/05/16 07:02:46 INFO DataNode.clienttrace: src: /127.0.0.1:62863, dest: /127.0.0.1:61632, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_3400982650094266416_1120, duration: 920203 12/05/16 07:02:46 INFO datanode.DataNode: PacketResponder 0 for block blk_3400982650094266416_1120 terminating 12/05/16 07:02:46 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_3400982650094266416_1120 size 124 12/05/16 07:02:47 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.logs/hlog.1337151766439 from client DFSClient_-1833973968 12/05/16 07:02:47 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/34b3a1d210331490c9880aa41a03fa2d/.logs/hlog.1337151766439 is closed by DFSClient_-1833973968 12/05/16 07:02:47 INFO regionserver.HRegionServer: Received request to open region: Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. 12/05/16 07:02:47 INFO regionserver.HRegion: Onlined Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d.; next sequenceid=1 12/05/16 07:02:47 INFO catalog.MetaEditor: Updated row Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. in region .META.,,1 with server=hudson_solaris:61642, startcode=1337150942230 12/05/16 07:02:52 INFO zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:21818 sessionTimeout=180000 watcher=hconnection 12/05/16 07:02:52 INFO zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:21818 12/05/16 07:02:52 INFO zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:21818, initiating session 12/05/16 07:02:52 INFO server.NIOServerCnxn: Accepted socket connection from /127.0.0.1:62865 12/05/16 07:02:52 INFO server.NIOServerCnxn: Client attempting to establish new session at /127.0.0.1:62865 12/05/16 07:02:52 INFO server.NIOServerCnxn: Established session 0x13754682a550026 with negotiated timeout 40000 for client /127.0.0.1:62865 12/05/16 07:02:52 INFO zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:21818, sessionid = 0x13754682a550026, negotiated timeout = 40000 12/05/16 07:02:52 WARN zookeeper.ZKTable: Moving table WebPage state to enabled but was already enabled 12/05/16 07:02:52 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x13754682a550000 type:delete cxid:0x56c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/WebPage Error:KeeperErrorCode = NoNode for /hbase/table/WebPage 12/05/16 07:02:52 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 07:02:52 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 07:02:52 INFO wal.HLog: New hlog /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.logs/hlog.1337151772383 12/05/16 07:02:52 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 07:02:52 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.tmp/.regioninfo. blk_-3468919169326839901_1122 12/05/16 07:02:52 INFO datanode.DataNode: Receiving block blk_-3468919169326839901_1122 src: /127.0.0.1:62866 dest: /127.0.0.1:61632 12/05/16 07:02:52 INFO DataNode.clienttrace: src: /127.0.0.1:62866, dest: /127.0.0.1:61632, bytes: 1892, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_-3468919169326839901_1122, duration: 837872 12/05/16 07:02:52 INFO datanode.DataNode: PacketResponder 0 for block blk_-3468919169326839901_1122 terminating 12/05/16 07:02:52 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_-3468919169326839901_1122 size 1892 12/05/16 07:02:52 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.tmp/.regioninfo from client DFSClient_-1833973968 12/05/16 07:02:52 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.tmp/.regioninfo is closed by DFSClient_-1833973968 12/05/16 07:02:52 INFO regionserver.HRegion: Onlined WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b.; next sequenceid=1 12/05/16 07:02:52 INFO catalog.MetaEditor: Added region WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. to META 12/05/16 07:02:52 INFO regionserver.HRegion: Closed WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. 12/05/16 07:02:52 INFO wal.HLog: IPC Server handler 3 on 61639.logSyncer exiting 12/05/16 07:02:52 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.logs/hlog.1337151772383. blk_3276689058572987362_1122 12/05/16 07:02:52 INFO datanode.DataNode: Receiving block blk_3276689058572987362_1122 src: /127.0.0.1:62867 dest: /127.0.0.1:61632 12/05/16 07:02:52 INFO DataNode.clienttrace: src: /127.0.0.1:62867, dest: /127.0.0.1:61632, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_3276689058572987362_1122, duration: 812298 12/05/16 07:02:52 INFO datanode.DataNode: PacketResponder 0 for block blk_3276689058572987362_1122 terminating 12/05/16 07:02:52 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_3276689058572987362_1122 size 124 12/05/16 07:02:53 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.logs/hlog.1337151772383 from client DFSClient_-1833973968 12/05/16 07:02:53 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/fcf3decf5f5c012d435fc655997b426b/.logs/hlog.1337151772383 is closed by DFSClient_-1833973968 12/05/16 07:02:53 INFO regionserver.HRegionServer: Received request to open region: WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. 12/05/16 07:02:53 INFO regionserver.HRegion: Onlined WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b.; next sequenceid=1 12/05/16 07:02:53 INFO catalog.MetaEditor: Updated row WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. in region .META.,,1 with server=hudson_solaris:61642, startcode=1337150942230 12/05/16 07:02:58 INFO gora.GoraTestDriver: setting up test 12/05/16 07:02:58 INFO handler.DisableTableHandler: Attemping to disable table Employee 12/05/16 07:02:58 INFO client.HBaseAdmin: Started disable of Employee 12/05/16 07:02:58 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 07:02:58 INFO regionserver.HRegionServer: Received close region: Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. 12/05/16 07:02:58 INFO regionserver.HRegion: Closed Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. 12/05/16 07:02:59 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 07:03:03 INFO client.HBaseAdmin: Disabled Employee 12/05/16 07:03:03 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table Employee 12/05/16 07:03:03 INFO catalog.MetaEditor: Deleted region Employee,,1337151766426.34b3a1d210331490c9880aa41a03fa2d. from META 12/05/16 07:03:03 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3400982650094266416 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:03:03 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_1159033546477871779 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:03:05 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:61632 to delete blk_1159033546477871779_1120 blk_3400982650094266416_1120 12/05/16 07:03:06 INFO datanode.DataNode: Scheduling block blk_1159033546477871779_1120 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_1159033546477871779> for deletion 12/05/16 07:03:06 INFO datanode.DataNode: Scheduling block blk_3400982650094266416_1120 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_3400982650094266416> for deletion 12/05/16 07:03:06 INFO datanode.DataNode: Deleted block blk_1159033546477871779_1120 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data1/current/blk_1159033546477871779> 12/05/16 07:03:06 INFO datanode.DataNode: Deleted block blk_3400982650094266416_1120 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/7ad28056-9b5a-4856-bec4-0e77908b5a94/dfs/data/data2/current/blk_3400982650094266416> 12/05/16 07:03:08 INFO client.HBaseAdmin: Deleted Employee 12/05/16 07:03:08 WARN zookeeper.ZKTable: Moving table Employee state to enabled but was already enabled 12/05/16 07:03:08 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x13754682a550000 type:delete cxid:0x587 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/Employee Error:KeeperErrorCode = NoNode for /hbase/table/Employee 12/05/16 07:03:08 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 07:03:08 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 07:03:08 INFO wal.HLog: New hlog /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.logs/hlog.1337151788462 12/05/16 07:03:08 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 07:03:08 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.tmp/.regioninfo. blk_6303040442495062205_1124 12/05/16 07:03:08 INFO datanode.DataNode: Receiving block blk_6303040442495062205_1124 src: /127.0.0.1:62897 dest: /127.0.0.1:61632 12/05/16 07:03:08 INFO DataNode.clienttrace: src: /127.0.0.1:62897, dest: /127.0.0.1:61632, bytes: 720, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_6303040442495062205_1124, duration: 691106 12/05/16 07:03:08 INFO datanode.DataNode: PacketResponder 0 for block blk_6303040442495062205_1124 terminating 12/05/16 07:03:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_6303040442495062205_1124 size 720 12/05/16 07:03:08 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.tmp/.regioninfo from client DFSClient_-1833973968 12/05/16 07:03:08 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.tmp/.regioninfo is closed by DFSClient_-1833973968 12/05/16 07:03:08 INFO regionserver.HRegion: Onlined Employee,,1337151788289.78cf05b57ea51ed8c9c0914cdf9b7273.; next sequenceid=1 12/05/16 07:03:08 INFO catalog.MetaEditor: Added region Employee,,1337151788289.78cf05b57ea51ed8c9c0914cdf9b7273. to META 12/05/16 07:03:08 INFO regionserver.HRegion: Closed Employee,,1337151788289.78cf05b57ea51ed8c9c0914cdf9b7273. 12/05/16 07:03:08 INFO wal.HLog: IPC Server handler 1 on 61639.logSyncer exiting 12/05/16 07:03:08 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.logs/hlog.1337151788462. blk_1280659110708943278_1124 12/05/16 07:03:08 INFO datanode.DataNode: Receiving block blk_1280659110708943278_1124 src: /127.0.0.1:62898 dest: /127.0.0.1:61632 12/05/16 07:03:08 INFO DataNode.clienttrace: src: /127.0.0.1:62898, dest: /127.0.0.1:61632, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_-1833973968, offset: 0, srvID: DS-2088468811-192.87.106.251-61632-1337150941580, blockid: blk_1280659110708943278_1124, duration: 1081171 12/05/16 07:03:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:61632 is added to blk_1280659110708943278_1124 size 124 12/05/16 07:03:08 INFO datanode.DataNode: PacketResponder 0 for block blk_1280659110708943278_1124 terminating 12/05/16 07:03:08 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.logs/hlog.1337151788462 from client DFSClient_-1833973968 12/05/16 07:03:08 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/78cf05b57ea51ed8c9c0914cdf9b7273/.logs/hlog.1337151788462 is closed by DFSClient_-1833973968 12/05/16 07:03:09 INFO regionserver.HRegionServer: Received request to open region: Employee,,1337151788289.78cf05b57ea51ed8c9c0914cdf9b7273. 12/05/16 07:03:09 INFO regionserver.HRegion: Onlined Employee,,1337151788289.78cf05b57ea51ed8c9c0914cdf9b7273.; next sequenceid=1 12/05/16 07:03:09 INFO catalog.MetaEditor: Updated row Employee,,1337151788289.78cf05b57ea51ed8c9c0914cdf9b7273. in region .META.,,1 with server=hudson_solaris:61642, startcode=1337150942230 12/05/16 07:03:14 INFO handler.DisableTableHandler: Attemping to disable table WebPage 12/05/16 07:03:14 INFO client.HBaseAdmin: Started disable of WebPage 12/05/16 07:03:14 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 07:03:14 INFO regionserver.HRegionServer: Received close region: WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. 12/05/16 07:03:14 INFO regionserver.HRegion: Closed WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. 12/05/16 07:03:15 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 07:03:19 INFO client.HBaseAdmin: Disabled WebPage 12/05/16 07:03:19 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table WebPage 12/05/16 07:03:19 INFO catalog.MetaEditor: Deleted region WebPage,,1337151772371.fcf3decf5f5c012d435fc655997b426b. from META 12/05/16 07:03:19 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3276689058572987362 is added to invalidSet of 127.0.0.1:61632 12/05/16 07:03:19 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-3468919169326839901 is added to invalidSet of 127.0.0.1:61632 channel stopped
