See <https://builds.apache.org/job/gora-trunk/277/changes>
Changes: [lewismc] trivial commit to address GORA-122 and update to CHANGES.txt [lewismc] commit to address GORA-63 & 133 respectively and update to CHANGES.txt ------------------------------------------ [...truncated 3904 lines...] 12/05/16 20:07:04 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x137573d768d0000 type:delete cxid:0x25d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/WebPage Error:KeeperErrorCode = NoNode for /hbase/table/WebPage 12/05/16 20:07:04 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 20:07:04 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 20:07:04 INFO wal.HLog: New hlog /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.logs/hlog.1337198824813 12/05/16 20:07:04 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 20:07:04 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.tmp/.regioninfo. blk_3596771762782519237_1055 12/05/16 20:07:04 INFO datanode.DataNode: Receiving block blk_3596771762782519237_1055 src: /127.0.0.1:41798 dest: /127.0.0.1:41366 12/05/16 20:07:04 INFO DataNode.clienttrace: src: /127.0.0.1:41798, dest: /127.0.0.1:41366, bytes: 1892, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_3596771762782519237_1055, duration: 854143 12/05/16 20:07:04 INFO datanode.DataNode: PacketResponder 0 for block blk_3596771762782519237_1055 terminating 12/05/16 20:07:04 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_3596771762782519237_1055 size 1892 12/05/16 20:07:05 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.tmp/.regioninfo from client DFSClient_1367231064 12/05/16 20:07:05 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.tmp/.regioninfo is closed by DFSClient_1367231064 12/05/16 20:07:05 INFO regionserver.HRegion: Onlined WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d.; next sequenceid=1 12/05/16 20:07:05 INFO catalog.MetaEditor: Added region WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. to META 12/05/16 20:07:05 INFO regionserver.HRegion: Closed WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. 12/05/16 20:07:05 INFO wal.HLog: IPC Server handler 2 on 41371.logSyncer exiting 12/05/16 20:07:05 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.logs/hlog.1337198824813. blk_6393163021453802459_1055 12/05/16 20:07:05 INFO datanode.DataNode: Receiving block blk_6393163021453802459_1055 src: /127.0.0.1:41799 dest: /127.0.0.1:41366 12/05/16 20:07:05 INFO DataNode.clienttrace: src: /127.0.0.1:41799, dest: /127.0.0.1:41366, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_6393163021453802459_1055, duration: 801823 12/05/16 20:07:05 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_6393163021453802459_1055 size 124 12/05/16 20:07:05 INFO datanode.DataNode: PacketResponder 0 for block blk_6393163021453802459_1055 terminating 12/05/16 20:07:05 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.logs/hlog.1337198824813 from client DFSClient_1367231064 12/05/16 20:07:05 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/4f2dcd645688d8df52816ec5cee52a3d/.logs/hlog.1337198824813 is closed by DFSClient_1367231064 12/05/16 20:07:05 INFO regionserver.HRegionServer: Received request to open region: WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. 12/05/16 20:07:05 INFO regionserver.HRegion: Onlined WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d.; next sequenceid=1 12/05/16 20:07:05 INFO catalog.MetaEditor: Updated row WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. in region .META.,,1 with server=hudson_solaris:41375, startcode=1337198475952 12/05/16 20:07:10 INFO gora.GoraTestDriver: setting up test 12/05/16 20:07:10 INFO handler.DisableTableHandler: Attemping to disable table Employee 12/05/16 20:07:10 INFO client.HBaseAdmin: Started disable of Employee 12/05/16 20:07:10 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 20:07:10 INFO regionserver.HRegionServer: Received close region: Employee,,1337198817970.7a888298ca0c18c1fd6116c0e5267e19. 12/05/16 20:07:10 INFO regionserver.HRegion: Closed Employee,,1337198817970.7a888298ca0c18c1fd6116c0e5267e19. 12/05/16 20:07:11 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 20:07:15 INFO client.HBaseAdmin: Disabled Employee 12/05/16 20:07:15 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table Employee 12/05/16 20:07:15 INFO catalog.MetaEditor: Deleted region Employee,,1337198817970.7a888298ca0c18c1fd6116c0e5267e19. from META 12/05/16 20:07:15 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-861851324206095695 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:15 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_4238736742214674344 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:15 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41366 to delete blk_-861851324206095695_1053 blk_4238736742214674344_1053 12/05/16 20:07:18 INFO datanode.DataNode: Scheduling block blk_-861851324206095695_1053 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_-861851324206095695> for deletion 12/05/16 20:07:18 INFO datanode.DataNode: Scheduling block blk_4238736742214674344_1053 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_4238736742214674344> for deletion 12/05/16 20:07:18 INFO datanode.DataNode: Deleted block blk_-861851324206095695_1053 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_-861851324206095695> 12/05/16 20:07:18 INFO datanode.DataNode: Deleted block blk_4238736742214674344_1053 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_4238736742214674344> 12/05/16 20:07:20 INFO client.HBaseAdmin: Deleted Employee 12/05/16 20:07:20 WARN zookeeper.ZKTable: Moving table Employee state to enabled but was already enabled 12/05/16 20:07:20 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x137573d768d0000 type:delete cxid:0x278 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/Employee Error:KeeperErrorCode = NoNode for /hbase/table/Employee 12/05/16 20:07:20 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 20:07:20 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 20:07:20 INFO wal.HLog: New hlog /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.logs/hlog.1337198840673 12/05/16 20:07:20 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 20:07:20 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.tmp/.regioninfo. blk_-4797935280678884998_1057 12/05/16 20:07:20 INFO datanode.DataNode: Receiving block blk_-4797935280678884998_1057 src: /127.0.0.1:41807 dest: /127.0.0.1:41366 12/05/16 20:07:20 INFO DataNode.clienttrace: src: /127.0.0.1:41807, dest: /127.0.0.1:41366, bytes: 720, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_-4797935280678884998_1057, duration: 710378 12/05/16 20:07:20 INFO datanode.DataNode: PacketResponder 0 for block blk_-4797935280678884998_1057 terminating 12/05/16 20:07:20 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_-4797935280678884998_1057 size 720 12/05/16 20:07:21 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.tmp/.regioninfo from client DFSClient_1367231064 12/05/16 20:07:21 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.tmp/.regioninfo is closed by DFSClient_1367231064 12/05/16 20:07:21 INFO regionserver.HRegion: Onlined Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b.; next sequenceid=1 12/05/16 20:07:21 INFO catalog.MetaEditor: Added region Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. to META 12/05/16 20:07:21 INFO regionserver.HRegion: Closed Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. 12/05/16 20:07:21 INFO wal.HLog: IPC Server handler 1 on 41371.logSyncer exiting 12/05/16 20:07:21 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.logs/hlog.1337198840673. blk_-5719707454110523288_1057 12/05/16 20:07:21 INFO datanode.DataNode: Receiving block blk_-5719707454110523288_1057 src: /127.0.0.1:41808 dest: /127.0.0.1:41366 12/05/16 20:07:21 INFO DataNode.clienttrace: src: /127.0.0.1:41808, dest: /127.0.0.1:41366, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_-5719707454110523288_1057, duration: 839153 12/05/16 20:07:21 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_-5719707454110523288_1057 size 124 12/05/16 20:07:21 INFO datanode.DataNode: PacketResponder 0 for block blk_-5719707454110523288_1057 terminating 12/05/16 20:07:21 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.logs/hlog.1337198840673 from client DFSClient_1367231064 12/05/16 20:07:21 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/a2a57c8822b8cf4d5bfc63f41626e04b/.logs/hlog.1337198840673 is closed by DFSClient_1367231064 12/05/16 20:07:21 INFO regionserver.HRegionServer: Received request to open region: Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. 12/05/16 20:07:21 INFO regionserver.HRegion: Onlined Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b.; next sequenceid=1 12/05/16 20:07:21 INFO catalog.MetaEditor: Updated row Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. in region .META.,,1 with server=hudson_solaris:41375, startcode=1337198475952 12/05/16 20:07:26 INFO handler.DisableTableHandler: Attemping to disable table WebPage 12/05/16 20:07:26 INFO client.HBaseAdmin: Started disable of WebPage 12/05/16 20:07:26 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 20:07:26 INFO regionserver.HRegionServer: Received close region: WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. 12/05/16 20:07:26 INFO regionserver.HRegion: Closed WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. 12/05/16 20:07:27 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 20:07:31 INFO client.HBaseAdmin: Disabled WebPage 12/05/16 20:07:31 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table WebPage 12/05/16 20:07:31 INFO catalog.MetaEditor: Deleted region WebPage,,1337198824688.4f2dcd645688d8df52816ec5cee52a3d. from META 12/05/16 20:07:31 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_6393163021453802459 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:31 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3596771762782519237 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:31 INFO namenode.FSNamesystem: Number of transactions: 510 Total time for transactions(ms): 12Number of transactions batched in Syncs: 45 Number of syncs: 400 SyncTimes(ms): 4857 3113 12/05/16 20:07:34 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41366 to delete blk_6393163021453802459_1055 blk_3596771762782519237_1055 12/05/16 20:07:36 INFO client.HBaseAdmin: Deleted WebPage 12/05/16 20:07:36 WARN zookeeper.ZKTable: Moving table WebPage state to enabled but was already enabled 12/05/16 20:07:36 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x137573d768d0000 type:delete cxid:0x293 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/WebPage Error:KeeperErrorCode = NoNode for /hbase/table/WebPage 12/05/16 20:07:36 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 20:07:36 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 20:07:36 INFO wal.HLog: New hlog /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.logs/hlog.1337198856423 12/05/16 20:07:36 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 20:07:36 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.tmp/.regioninfo. blk_-2319186353182343349_1059 12/05/16 20:07:36 INFO datanode.DataNode: Receiving block blk_-2319186353182343349_1059 src: /127.0.0.1:41840 dest: /127.0.0.1:41366 12/05/16 20:07:36 INFO DataNode.clienttrace: src: /127.0.0.1:41840, dest: /127.0.0.1:41366, bytes: 1892, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_-2319186353182343349_1059, duration: 1053489 12/05/16 20:07:36 INFO datanode.DataNode: PacketResponder 0 for block blk_-2319186353182343349_1059 terminating 12/05/16 20:07:36 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_-2319186353182343349_1059 size 1892 12/05/16 20:07:36 INFO datanode.DataNode: Scheduling block blk_3596771762782519237_1055 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_3596771762782519237> for deletion 12/05/16 20:07:36 INFO datanode.DataNode: Scheduling block blk_6393163021453802459_1055 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_6393163021453802459> for deletion 12/05/16 20:07:36 INFO datanode.DataNode: Deleted block blk_3596771762782519237_1055 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_3596771762782519237> 12/05/16 20:07:36 INFO datanode.DataNode: Deleted block blk_6393163021453802459_1055 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_6393163021453802459> 12/05/16 20:07:36 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.tmp/.regioninfo from client DFSClient_1367231064 12/05/16 20:07:36 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.tmp/.regioninfo is closed by DFSClient_1367231064 12/05/16 20:07:36 INFO regionserver.HRegion: Onlined WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05.; next sequenceid=1 12/05/16 20:07:36 INFO catalog.MetaEditor: Added region WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. to META 12/05/16 20:07:36 INFO regionserver.HRegion: Closed WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. 12/05/16 20:07:36 INFO wal.HLog: IPC Server handler 3 on 41371.logSyncer exiting 12/05/16 20:07:36 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.logs/hlog.1337198856423. blk_7500721857842742896_1059 12/05/16 20:07:36 INFO datanode.DataNode: Receiving block blk_7500721857842742896_1059 src: /127.0.0.1:41841 dest: /127.0.0.1:41366 12/05/16 20:07:36 INFO DataNode.clienttrace: src: /127.0.0.1:41841, dest: /127.0.0.1:41366, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_7500721857842742896_1059, duration: 811663 12/05/16 20:07:36 INFO datanode.DataNode: PacketResponder 0 for block blk_7500721857842742896_1059 terminating 12/05/16 20:07:36 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_7500721857842742896_1059 size 124 12/05/16 20:07:37 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.logs/hlog.1337198856423 from client DFSClient_1367231064 12/05/16 20:07:37 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/e670254f3e40ecfa8368489f7029ef05/.logs/hlog.1337198856423 is closed by DFSClient_1367231064 12/05/16 20:07:37 INFO regionserver.HRegionServer: Received request to open region: WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. 12/05/16 20:07:37 INFO regionserver.HRegion: Onlined WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05.; next sequenceid=1 12/05/16 20:07:37 INFO catalog.MetaEditor: Updated row WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. in region .META.,,1 with server=hudson_solaris:41375, startcode=1337198475952 12/05/16 20:07:42 INFO store.DataStoreTestBase: test method: testCreateSchema 12/05/16 20:07:42 INFO zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:21818 sessionTimeout=180000 watcher=hconnection 12/05/16 20:07:42 INFO zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:21818 12/05/16 20:07:42 INFO zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:21818, initiating session 12/05/16 20:07:42 INFO server.NIOServerCnxn: Accepted socket connection from /127.0.0.1:41856 12/05/16 20:07:42 INFO server.NIOServerCnxn: Client attempting to establish new session at /127.0.0.1:41856 12/05/16 20:07:42 INFO server.NIOServerCnxn: Established session 0x137573d768d0012 with negotiated timeout 40000 for client /127.0.0.1:41856 12/05/16 20:07:42 INFO zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:21818, sessionid = 0x137573d768d0012, negotiated timeout = 40000 12/05/16 20:07:42 INFO store.DataStoreTestBase: tearing down test 12/05/16 20:07:42 INFO gora.GoraTestDriver: tearing down test 12/05/16 20:07:42 INFO handler.DisableTableHandler: Attemping to disable table Employee 12/05/16 20:07:42 INFO client.HBaseAdmin: Started disable of Employee 12/05/16 20:07:42 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 20:07:42 INFO regionserver.HRegionServer: Received close region: Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. 12/05/16 20:07:42 INFO regionserver.HRegion: Closed Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. 12/05/16 20:07:43 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 20:07:47 INFO client.HBaseAdmin: Disabled Employee 12/05/16 20:07:47 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table Employee 12/05/16 20:07:47 INFO catalog.MetaEditor: Deleted region Employee,,1337198840600.a2a57c8822b8cf4d5bfc63f41626e04b. from META 12/05/16 20:07:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5719707454110523288 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4797935280678884998 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:49 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41366 to delete blk_-4797935280678884998_1057 blk_-5719707454110523288_1057 12/05/16 20:07:51 INFO datanode.DataNode: Scheduling block blk_-5719707454110523288_1057 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_-5719707454110523288> for deletion 12/05/16 20:07:51 INFO datanode.DataNode: Scheduling block blk_-4797935280678884998_1057 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_-4797935280678884998> for deletion 12/05/16 20:07:51 INFO datanode.DataNode: Deleted block blk_-5719707454110523288_1057 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_-5719707454110523288> 12/05/16 20:07:51 INFO datanode.DataNode: Deleted block blk_-4797935280678884998_1057 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_-4797935280678884998> 12/05/16 20:07:52 INFO client.HBaseAdmin: Deleted Employee 12/05/16 20:07:52 INFO handler.DisableTableHandler: Attemping to disable table WebPage 12/05/16 20:07:52 INFO client.HBaseAdmin: Started disable of WebPage 12/05/16 20:07:52 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 20:07:52 INFO regionserver.HRegionServer: Received close region: WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. 12/05/16 20:07:52 INFO regionserver.HRegion: Closed WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. 12/05/16 20:07:53 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 20:07:57 INFO client.HBaseAdmin: Disabled WebPage 12/05/16 20:07:57 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table WebPage 12/05/16 20:07:57 INFO catalog.MetaEditor: Deleted region WebPage,,1337198856410.e670254f3e40ecfa8368489f7029ef05. from META 12/05/16 20:07:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_7500721857842742896 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:57 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-2319186353182343349 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:07:58 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41366 to delete blk_-2319186353182343349_1059 blk_7500721857842742896_1059 12/05/16 20:08:00 INFO datanode.DataNode: Scheduling block blk_-2319186353182343349_1059 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_-2319186353182343349> for deletion 12/05/16 20:08:00 INFO datanode.DataNode: Scheduling block blk_7500721857842742896_1059 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_7500721857842742896> for deletion 12/05/16 20:08:00 INFO datanode.DataNode: Deleted block blk_-2319186353182343349_1059 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_-2319186353182343349> 12/05/16 20:08:00 INFO datanode.DataNode: Deleted block blk_7500721857842742896_1059 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_7500721857842742896> 12/05/16 20:08:02 INFO client.HBaseAdmin: Deleted WebPage 12/05/16 20:08:02 INFO store.DataStoreTestBase: setting up test 12/05/16 20:08:02 INFO zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:21818 sessionTimeout=180000 watcher=hconnection 12/05/16 20:08:02 INFO zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:21818 12/05/16 20:08:02 INFO zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:21818, initiating session 12/05/16 20:08:02 INFO server.NIOServerCnxn: Accepted socket connection from /127.0.0.1:41902 12/05/16 20:08:02 INFO server.NIOServerCnxn: Client attempting to establish new session at /127.0.0.1:41902 12/05/16 20:08:02 INFO server.NIOServerCnxn: Established session 0x137573d768d0013 with negotiated timeout 40000 for client /127.0.0.1:41902 12/05/16 20:08:02 INFO zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:21818, sessionid = 0x137573d768d0013, negotiated timeout = 40000 12/05/16 20:08:02 WARN zookeeper.ZKTable: Moving table Employee state to enabled but was already enabled 12/05/16 20:08:02 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x137573d768d0000 type:delete cxid:0x2bc zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/Employee Error:KeeperErrorCode = NoNode for /hbase/table/Employee 12/05/16 20:08:02 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 20:08:02 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 20:08:02 INFO wal.HLog: New hlog /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.logs/hlog.1337198882602 12/05/16 20:08:02 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 20:08:02 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.tmp/.regioninfo. blk_-8322148437295658214_1061 12/05/16 20:08:02 INFO datanode.DataNode: Receiving block blk_-8322148437295658214_1061 src: /127.0.0.1:41903 dest: /127.0.0.1:41366 12/05/16 20:08:02 INFO DataNode.clienttrace: src: /127.0.0.1:41903, dest: /127.0.0.1:41366, bytes: 720, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_-8322148437295658214_1061, duration: 623409 12/05/16 20:08:02 INFO datanode.DataNode: PacketResponder 0 for block blk_-8322148437295658214_1061 terminating 12/05/16 20:08:02 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_-8322148437295658214_1061 size 720 12/05/16 20:08:02 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.tmp/.regioninfo from client DFSClient_1367231064 12/05/16 20:08:02 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.tmp/.regioninfo is closed by DFSClient_1367231064 12/05/16 20:08:02 INFO regionserver.HRegion: Onlined Employee,,1337198882589.45a04dad9d1f07d60bffcfd7e0f8917d.; next sequenceid=1 12/05/16 20:08:02 INFO catalog.MetaEditor: Added region Employee,,1337198882589.45a04dad9d1f07d60bffcfd7e0f8917d. to META 12/05/16 20:08:02 INFO regionserver.HRegion: Closed Employee,,1337198882589.45a04dad9d1f07d60bffcfd7e0f8917d. 12/05/16 20:08:02 INFO wal.HLog: IPC Server handler 2 on 41371.logSyncer exiting 12/05/16 20:08:02 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.logs/hlog.1337198882602. blk_2852448915243603202_1061 12/05/16 20:08:02 INFO datanode.DataNode: Receiving block blk_2852448915243603202_1061 src: /127.0.0.1:41904 dest: /127.0.0.1:41366 12/05/16 20:08:02 INFO DataNode.clienttrace: src: /127.0.0.1:41904, dest: /127.0.0.1:41366, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_2852448915243603202_1061, duration: 408799 12/05/16 20:08:02 INFO datanode.DataNode: PacketResponder 0 for block blk_2852448915243603202_1061 terminating 12/05/16 20:08:02 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_2852448915243603202_1061 size 124 12/05/16 20:08:03 INFO hdfs.StateChange: Removing lease on file /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.logs/hlog.1337198882602 from client DFSClient_1367231064 12/05/16 20:08:03 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/Employee/45a04dad9d1f07d60bffcfd7e0f8917d/.logs/hlog.1337198882602 is closed by DFSClient_1367231064 12/05/16 20:08:03 INFO regionserver.HRegionServer: Received request to open region: Employee,,1337198882589.45a04dad9d1f07d60bffcfd7e0f8917d. 12/05/16 20:08:03 INFO regionserver.HRegion: Onlined Employee,,1337198882589.45a04dad9d1f07d60bffcfd7e0f8917d.; next sequenceid=1 12/05/16 20:08:03 INFO catalog.MetaEditor: Updated row Employee,,1337198882589.45a04dad9d1f07d60bffcfd7e0f8917d. in region .META.,,1 with server=hudson_solaris:41375, startcode=1337198475952 12/05/16 20:08:08 INFO zookeeper.ZooKeeper: Initiating client connection, connectString=localhost:21818 sessionTimeout=180000 watcher=hconnection 12/05/16 20:08:08 INFO zookeeper.ClientCnxn: Opening socket connection to server localhost/127.0.0.1:21818 12/05/16 20:08:08 INFO zookeeper.ClientCnxn: Socket connection established to localhost/127.0.0.1:21818, initiating session 12/05/16 20:08:08 INFO server.NIOServerCnxn: Accepted socket connection from /127.0.0.1:41910 12/05/16 20:08:08 INFO server.NIOServerCnxn: Client attempting to establish new session at /127.0.0.1:41910 12/05/16 20:08:08 INFO server.NIOServerCnxn: Established session 0x137573d768d0014 with negotiated timeout 40000 for client /127.0.0.1:41910 12/05/16 20:08:08 INFO zookeeper.ClientCnxn: Session establishment complete on server localhost/127.0.0.1:21818, sessionid = 0x137573d768d0014, negotiated timeout = 40000 12/05/16 20:08:08 WARN zookeeper.ZKTable: Moving table WebPage state to enabled but was already enabled 12/05/16 20:08:08 INFO server.PrepRequestProcessor: Got user-level KeeperException when processing sessionid:0x137573d768d0000 type:delete cxid:0x2c9 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/hbase/table/WebPage Error:KeeperErrorCode = NoNode for /hbase/table/WebPage 12/05/16 20:08:08 INFO wal.HLog: HLog configuration: blocksize=64 MB, rollsize=60.8 MB, enabled=true, optionallogflushinternal=1000ms 12/05/16 20:08:08 INFO wal.SequenceFileLogWriter: syncFs -- HDFS-200 -- not available, dfs.support.append=false 12/05/16 20:08:08 INFO wal.HLog: New hlog /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.logs/hlog.1337198888121 12/05/16 20:08:08 INFO wal.HLog: Using getNumCurrentReplicas--HDFS-826 12/05/16 20:08:08 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.tmp/.regioninfo. blk_1144396838013924334_1063 12/05/16 20:08:08 INFO datanode.DataNode: Receiving block blk_1144396838013924334_1063 src: /127.0.0.1:41911 dest: /127.0.0.1:41366 12/05/16 20:08:08 INFO DataNode.clienttrace: src: /127.0.0.1:41911, dest: /127.0.0.1:41366, bytes: 1892, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_1144396838013924334_1063, duration: 97392722 12/05/16 20:08:08 INFO datanode.DataNode: PacketResponder 0 for block blk_1144396838013924334_1063 terminating 12/05/16 20:08:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_1144396838013924334_1063 size 1892 12/05/16 20:08:08 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.tmp/.regioninfo from client DFSClient_1367231064 12/05/16 20:08:08 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.tmp/.regioninfo is closed by DFSClient_1367231064 12/05/16 20:08:08 INFO regionserver.HRegion: Onlined WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526.; next sequenceid=1 12/05/16 20:08:08 INFO catalog.MetaEditor: Added region WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. to META 12/05/16 20:08:08 INFO regionserver.HRegion: Closed WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. 12/05/16 20:08:08 INFO wal.HLog: IPC Server handler 0 on 41371.logSyncer exiting 12/05/16 20:08:08 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.logs/hlog.1337198888121. blk_7354505193699840396_1063 12/05/16 20:08:08 INFO datanode.DataNode: Receiving block blk_7354505193699840396_1063 src: /127.0.0.1:41912 dest: /127.0.0.1:41366 12/05/16 20:08:08 INFO DataNode.clienttrace: src: /127.0.0.1:41912, dest: /127.0.0.1:41366, bytes: 124, op: HDFS_WRITE, cliID: DFSClient_1367231064, offset: 0, srvID: DS-1458692246-192.87.106.251-41366-1337198474655, blockid: blk_7354505193699840396_1063, duration: 1471873 12/05/16 20:08:08 INFO datanode.DataNode: PacketResponder 0 for block blk_7354505193699840396_1063 terminating 12/05/16 20:08:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41366 is added to blk_7354505193699840396_1063 size 124 12/05/16 20:08:08 INFO hdfs.StateChange: Removing lease on file /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.logs/hlog.1337198888121 from client DFSClient_1367231064 12/05/16 20:08:08 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /user/hudson/WebPage/d6801337be25575a7cf85d21f5e12526/.logs/hlog.1337198888121 is closed by DFSClient_1367231064 12/05/16 20:08:08 INFO regionserver.HRegionServer: Received request to open region: WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. 12/05/16 20:08:08 INFO regionserver.HRegion: Onlined WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526.; next sequenceid=1 12/05/16 20:08:08 INFO catalog.MetaEditor: Updated row WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. in region .META.,,1 with server=hudson_solaris:41375, startcode=1337198475952 12/05/16 20:08:13 INFO gora.GoraTestDriver: setting up test 12/05/16 20:08:13 INFO handler.DisableTableHandler: Attemping to disable table WebPage 12/05/16 20:08:13 INFO client.HBaseAdmin: Started disable of WebPage 12/05/16 20:08:13 INFO handler.DisableTableHandler: Offlining 1 regions. 12/05/16 20:08:13 INFO regionserver.HRegionServer: Received close region: WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. 12/05/16 20:08:13 INFO regionserver.HRegion: Closed WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. 12/05/16 20:08:14 INFO handler.DisableTableHandler: Disabled table is done=true 12/05/16 20:08:18 INFO client.HBaseAdmin: Disabled WebPage 12/05/16 20:08:18 INFO handler.TableEventHandler: Handling table operation C_M_DELETE_TABLE on table WebPage 12/05/16 20:08:18 INFO catalog.MetaEditor: Deleted region WebPage,,1337198888109.d6801337be25575a7cf85d21f5e12526. from META 12/05/16 20:08:18 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_7354505193699840396 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:08:18 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_1144396838013924334 is added to invalidSet of 127.0.0.1:41366 12/05/16 20:08:19 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41366 to delete blk_1144396838013924334_1063 blk_7354505193699840396_1063 12/05/16 20:08:21 INFO datanode.DataNode: Scheduling block blk_1144396838013924334_1063 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_1144396838013924334> for deletion 12/05/16 20:08:21 INFO datanode.DataNode: Scheduling block blk_7354505193699840396_1063 file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_7354505193699840396> for deletion channel stopped 12/05/16 20:08:22 INFO datanode.DataNode: Deleted block blk_1144396838013924334_1063 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data1/current/blk_1144396838013924334> 12/05/16 20:08:22 INFO datanode.DataNode: Deleted block blk_7354505193699840396_1063 at file <https://builds.apache.org/job/gora-trunk/ws/trunk./gora-hbase/target/test-data/a65ed2d3-fbe8-4be9-9829-f5a308ccadf3/dfs/data/data2/current/blk_7354505193699840396> Updating GORA-63 Updating GORA-122
