[jira] [Commented] (ZOOKEEPER-2152) Intermittent failure in TestReconfig.cc
[ https://issues.apache.org/jira/browse/ZOOKEEPER-2152?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel=15376306#comment-15376306 ] Michael Han commented on ZOOKEEPER-2152: Thanks for pointing this out - I'll make sure benchmark new tests before retiring old ones. I suspect replacing the fake servers with real servers will not slow down the tests, but I'll get some data first. > Intermittent failure in TestReconfig.cc > --- > > Key: ZOOKEEPER-2152 > URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2152 > Project: ZooKeeper > Issue Type: Sub-task > Components: c client >Reporter: Michi Mutsuzaki >Assignee: Michael Han > Labels: reconfiguration > Fix For: 3.5.3, 3.6.0 > > Attachments: ZOOKEEPER-2152.patch > > > I'm seeing this failure in the c client test once in a while: > {noformat} > [exec] > /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/src/c/tests/TestReconfig.cc:474: > Assertion: assertion failed [Expression: found != string::npos, > 10.10.10.4:2004 not in newComing list] > {noformat} > https://builds.apache.org/job/ZooKeeper-trunk/2640/console -- This message was sent by Atlassian JIRA (v6.3.4#6332)
[jira] [Commented] (ZOOKEEPER-2466) Client skips servers when trying to connect
[ https://issues.apache.org/jira/browse/ZOOKEEPER-2466?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel=15376300#comment-15376300 ] Michael Han commented on ZOOKEEPER-2466: bq. but that actually could be a bug. Good point. bq. have a second parameter for zoo_cycle_next_server so that we update according to the second parameter rather than always updating zh->addr_cur. I like this solution which parameterizes zoo_cycle_next_server such that what state it's changing is explicit in the interface, and use zoo_cycle_next_server instead of the low level addrvec function because we could also be in reconfig mode when this 'else' branch is executed. > Client skips servers when trying to connect > --- > > Key: ZOOKEEPER-2466 > URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2466 > Project: ZooKeeper > Issue Type: Bug > Components: c client >Reporter: Flavio Junqueira >Assignee: Flavio Junqueira >Priority: Critical > Fix For: 3.5.3, 3.6.0 > > > I've been looking at {{Zookeeper_simpleSystem::testFirstServerDown}} and I > observed the following behavior. The list of servers to connect contains two > servers, let's call them S1 and S2. The client never connects, but the odd > bit is the sequence of servers that the client tries to connect to: > {noformat} > S1 > S2 > S1 > S1 > S1 > > {noformat} > It intrigued me that S2 is only tried once and never again. Checking the > code, here is what happens. Initially, {{zh->reconfig}} is 1, so in > {{zoo_cycle_next_server}} we return an address from > {{get_next_server_in_reconfig}}, which is taken from {{zh->addrs_new}} in > this test case. The attempt to connect fails, and {{handle_error}} is invoked > in the error handling path. {{handle_error}} actually invokes > {{addrvec_next}} which changes the address pointer to the next server on the > list. > After two attempts, it decides that it has tried all servers in > {{zoo_cycle_next_server}} and sets {{zh->reconfig}} to zero. Once > {{zh->reconfig == 0}}, we have that each call to {{zoo_cycle_next_server}} > moves the address pointer to the next server in {{zh->addrs}}. But, given > that {{handle_error}} also moves the pointer to the next server, we end up > moving the pointer ahead twice upon every failed attempt to connect, which is > wrong. -- This message was sent by Atlassian JIRA (v6.3.4#6332)
[jira] [Commented] (ZOOKEEPER-2452) Back-port ZOOKEEPER-1460 to 3.4 for IPv6 literal address support.
[ https://issues.apache.org/jira/browse/ZOOKEEPER-2452?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel=15376286#comment-15376286 ] Hadoop QA commented on ZOOKEEPER-2452: -- -1 overall. Here are the results of testing the latest attachment http://issues.apache.org/jira/secure/attachment/12817863/ZOOKEEPER-2452.patch against trunk revision 1750739. +1 @author. The patch does not contain any @author tags. +1 tests included. The patch appears to include 12 new or modified tests. -1 patch. The patch command could not apply the patch. Console output: https://builds.apache.org/job/PreCommit-ZOOKEEPER-Build/3274//console This message is automatically generated. > Back-port ZOOKEEPER-1460 to 3.4 for IPv6 literal address support. > - > > Key: ZOOKEEPER-2452 > URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2452 > Project: ZooKeeper > Issue Type: Bug > Components: quorum >Reporter: Chris Nauroth >Assignee: Abraham Fine >Priority: Critical > Fix For: 3.4.9 > > Attachments: ZOOKEEPER-2452.patch > > > Via code inspection, I see that the "server.nnn" configuration key does not > support literal IPv6 addresses because the property value is split on ":". In > v3.4.3, the problem is in QuorumPeerConfig: > {noformat} > String parts[] = value.split(":"); > InetSocketAddress addr = new InetSocketAddress(parts[0], > Integer.parseInt(parts[1])); > {noformat} > In the current trunk > (http://svn.apache.org/viewvc/zookeeper/trunk/src/java/main/org/apache/zookeeper/server/quorum/QuorumPeer.java?view=markup) > this code has been refactored into QuorumPeer.QuorumServer, but the bug > remains: > {noformat} > String serverClientParts[] = addressStr.split(";"); > String serverParts[] = serverClientParts[0].split(":"); > addr = new InetSocketAddress(serverParts[0], > Integer.parseInt(serverParts[1])); > {noformat} > This bug probably affects very few users because most will naturally use a > hostname rather than a literal IP address. But given that IPv6 addresses are > supported for clients via ZOOKEEPER-667 it seems that server support should > be fixed too. -- This message was sent by Atlassian JIRA (v6.3.4#6332)
Failed: ZOOKEEPER-2452 PreCommit Build #3274
Jira: https://issues.apache.org/jira/browse/ZOOKEEPER-2452 Build: https://builds.apache.org/job/PreCommit-ZOOKEEPER-Build/3274/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 103 lines...] [exec] Hunk #1 succeeded at 94 (offset 1 line). [exec] Hunk #2 succeeded at 381 (offset 63 lines). [exec] PATCH APPLICATION FAILED [exec] [exec] [exec] [exec] [exec] -1 overall. Here are the results of testing the latest attachment [exec] http://issues.apache.org/jira/secure/attachment/12817863/ZOOKEEPER-2452.patch [exec] against trunk revision 1750739. [exec] [exec] +1 @author. The patch does not contain any @author tags. [exec] [exec] +1 tests included. The patch appears to include 12 new or modified tests. [exec] [exec] -1 patch. The patch command could not apply the patch. [exec] [exec] Console output: https://builds.apache.org/job/PreCommit-ZOOKEEPER-Build/3274//console [exec] [exec] This message is automatically generated. [exec] [exec] [exec] == [exec] == [exec] Adding comment to Jira. [exec] == [exec] == [exec] [exec] [exec] Comment added. [exec] d795815666c6b09837c8eac2bf7146ccb192b10c logged out [exec] [exec] [exec] == [exec] == [exec] Finished build. [exec] == [exec] == [exec] [exec] BUILD FAILED /home/jenkins/jenkins-slave/workspace/PreCommit-ZOOKEEPER-Build/trunk/build.xml:1605: exec returned: 1 Total time: 49 seconds Build step 'Execute shell' marked build as failure Archiving artifacts Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 Recording test results Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error? Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 [description-setter] Description set: ZOOKEEPER-2452 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 Setting LATEST1_7_HOME=/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7 ### ## FAILED TESTS (if any) ## No tests ran.
[jira] [Updated] (ZOOKEEPER-2452) Back-port ZOOKEEPER-1460 to 3.4 for IPv6 literal address support.
[ https://issues.apache.org/jira/browse/ZOOKEEPER-2452?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ] Abraham Fine updated ZOOKEEPER-2452: Attachment: ZOOKEEPER-2452.patch > Back-port ZOOKEEPER-1460 to 3.4 for IPv6 literal address support. > - > > Key: ZOOKEEPER-2452 > URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2452 > Project: ZooKeeper > Issue Type: Bug > Components: quorum >Reporter: Chris Nauroth >Assignee: Abraham Fine >Priority: Critical > Fix For: 3.4.9 > > Attachments: ZOOKEEPER-2452.patch > > > Via code inspection, I see that the "server.nnn" configuration key does not > support literal IPv6 addresses because the property value is split on ":". In > v3.4.3, the problem is in QuorumPeerConfig: > {noformat} > String parts[] = value.split(":"); > InetSocketAddress addr = new InetSocketAddress(parts[0], > Integer.parseInt(parts[1])); > {noformat} > In the current trunk > (http://svn.apache.org/viewvc/zookeeper/trunk/src/java/main/org/apache/zookeeper/server/quorum/QuorumPeer.java?view=markup) > this code has been refactored into QuorumPeer.QuorumServer, but the bug > remains: > {noformat} > String serverClientParts[] = addressStr.split(";"); > String serverParts[] = serverClientParts[0].split(":"); > addr = new InetSocketAddress(serverParts[0], > Integer.parseInt(serverParts[1])); > {noformat} > This bug probably affects very few users because most will naturally use a > hostname rather than a literal IP address. But given that IPv6 addresses are > supported for clients via ZOOKEEPER-667 it seems that server support should > be fixed too. -- This message was sent by Atlassian JIRA (v6.3.4#6332)
ZooKeeper_branch34 - Build # 1580 - Still Failing
See https://builds.apache.org/job/ZooKeeper_branch34/1580/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 199188 lines...] [junit] 2016-07-14 00:43:45,993 [myid:] - INFO [main:JMXEnv@250] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11221 [junit] 2016-07-14 00:43:45,994 [myid:] - INFO [main:ClientBase@490] - STOPPING server [junit] 2016-07-14 00:43:45,994 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@219] - NIOServerCnxn factory exited run method [junit] 2016-07-14 00:43:45,994 [myid:] - INFO [main:ZooKeeperServer@469] - shutting down [junit] 2016-07-14 00:43:45,994 [myid:] - INFO [main:SessionTrackerImpl@225] - Shutting down [junit] 2016-07-14 00:43:45,994 [myid:] - INFO [main:PrepRequestProcessor@765] - Shutting down [junit] 2016-07-14 00:43:45,995 [myid:] - INFO [main:SyncRequestProcessor@209] - Shutting down [junit] 2016-07-14 00:43:45,995 [myid:] - INFO [ProcessThread(sid:0 cport:11221)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop! [junit] 2016-07-14 00:43:45,995 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@187] - SyncRequestProcessor exited! [junit] 2016-07-14 00:43:45,996 [myid:] - INFO [main:FinalRequestProcessor@402] - shutdown of request processor complete [junit] 2016-07-14 00:43:45,996 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221 [junit] 2016-07-14 00:43:45,997 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[] [junit] 2016-07-14 00:43:45,998 [myid:] - INFO [main:ClientBase@443] - STARTING server [junit] 2016-07-14 00:43:45,998 [myid:] - INFO [main:ClientBase@364] - CREATING server instance 127.0.0.1:11221 [junit] 2016-07-14 00:43:45,999 [myid:] - INFO [main:NIOServerCnxnFactory@89] - binding to port 0.0.0.0/0.0.0.0:11221 [junit] 2016-07-14 00:43:45,999 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221 [junit] 2016-07-14 00:43:46,000 [myid:] - INFO [main:ZooKeeperServer@170] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 6 datadir /home/jenkins/jenkins-slave/workspace/ZooKeeper_branch34/branch-3.4/build/test/tmp/test4136946526253376475.junit.dir/version-2 snapdir /home/jenkins/jenkins-slave/workspace/ZooKeeper_branch34/branch-3.4/build/test/tmp/test4136946526253376475.junit.dir/version-2 [junit] 2016-07-14 00:43:46,004 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221 [junit] 2016-07-14 00:43:46,004 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@192] - Accepted socket connection from /127.0.0.1:40910 [junit] 2016-07-14 00:43:46,005 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@827] - Processing stat command from /127.0.0.1:40910 [junit] 2016-07-14 00:43:46,005 [myid:] - INFO [Thread-5:NIOServerCnxn$StatCommand@663] - Stat command output [junit] 2016-07-14 00:43:46,005 [myid:] - INFO [Thread-5:NIOServerCnxn@1008] - Closed socket connection for client /127.0.0.1:40910 (no session established for client) [junit] 2016-07-14 00:43:46,006 [myid:] - INFO [main:JMXEnv@229] - ensureParent:[InMemoryDataTree, StandaloneServer_port] [junit] 2016-07-14 00:43:46,007 [myid:] - INFO [main:JMXEnv@246] - expect:InMemoryDataTree [junit] 2016-07-14 00:43:46,007 [myid:] - INFO [main:JMXEnv@250] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11221,name1=InMemoryDataTree [junit] 2016-07-14 00:43:46,008 [myid:] - INFO [main:JMXEnv@246] - expect:StandaloneServer_port [junit] 2016-07-14 00:43:46,008 [myid:] - INFO [main:JMXEnv@250] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11221 [junit] 2016-07-14 00:43:46,008 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@58] - Memory used 31174 [junit] 2016-07-14 00:43:46,008 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@63] - Number of threads 20 [junit] 2016-07-14 00:43:46,008 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@78] - FINISHED TEST METHOD testQuota [junit] 2016-07-14 00:43:46,009 [myid:] - INFO [main:ClientBase@520] - tearDown starting [junit] 2016-07-14 00:43:46,079 [myid:] - INFO [main:ZooKeeper@684] - Session: 0x155e6dc0131 closed [junit] 2016-07-14 00:43:46,079 [myid:] - INFO [main:ClientBase@490] - STOPPING server [junit] 2016-07-14 00:43:46,079 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@519] - EventThread shut down for session: 0x155e6dc0131 [junit] 2016-07-14 00:43:46,079 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@219] - NIOServerCnxn factory exited run method [junit]
[jira] [Commented] (ZOOKEEPER-2466) Client skips servers when trying to connect
[ https://issues.apache.org/jira/browse/ZOOKEEPER-2466?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel=15375293#comment-15375293 ] Flavio Junqueira commented on ZOOKEEPER-2466: - {quote} zoo_cycle_next_server does more than addrvec_next - and in some cases the addrvec_next might not get called if zoo_cycle_next_server returns earlier within the reconfig logic and in this case the current server addr is reset to NULL. So it seems to me that addrvec_next and zoo_cycle_next_server is not semantically equivalent. {quote} True, they aren't semantically equivalent, but that actually could be a bug. What if the client is in RO mode and reconfig is enabled? Shouldn't it try follow the same process in zoo_cycle_next_server to find an RW server? {quote} Yeah, I think the addrvec_next(>addrs, NULL) just change the state of the zh-addrs vector without changing the state of the current server in zh handle - zoo_cycle_next_server will change the state of the current server, so both are not semantically equivalent. {quote} We could either leave as is or have a separate function to advance that only takes one parameter. The latter sounds a bit unnecessary. Another option is to have a second parameter for zoo_cycle_next_server so that we update according to the second parameter rather than always updating zh->addr_cur. Does it work? > Client skips servers when trying to connect > --- > > Key: ZOOKEEPER-2466 > URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2466 > Project: ZooKeeper > Issue Type: Bug > Components: c client >Reporter: Flavio Junqueira >Assignee: Flavio Junqueira >Priority: Critical > Fix For: 3.5.3, 3.6.0 > > > I've been looking at {{Zookeeper_simpleSystem::testFirstServerDown}} and I > observed the following behavior. The list of servers to connect contains two > servers, let's call them S1 and S2. The client never connects, but the odd > bit is the sequence of servers that the client tries to connect to: > {noformat} > S1 > S2 > S1 > S1 > S1 > > {noformat} > It intrigued me that S2 is only tried once and never again. Checking the > code, here is what happens. Initially, {{zh->reconfig}} is 1, so in > {{zoo_cycle_next_server}} we return an address from > {{get_next_server_in_reconfig}}, which is taken from {{zh->addrs_new}} in > this test case. The attempt to connect fails, and {{handle_error}} is invoked > in the error handling path. {{handle_error}} actually invokes > {{addrvec_next}} which changes the address pointer to the next server on the > list. > After two attempts, it decides that it has tried all servers in > {{zoo_cycle_next_server}} and sets {{zh->reconfig}} to zero. Once > {{zh->reconfig == 0}}, we have that each call to {{zoo_cycle_next_server}} > moves the address pointer to the next server in {{zh->addrs}}. But, given > that {{handle_error}} also moves the pointer to the next server, we end up > moving the pointer ahead twice upon every failed attempt to connect, which is > wrong. -- This message was sent by Atlassian JIRA (v6.3.4#6332)
ZooKeeper_branch34_solaris - Build # 1216 - Still Failing
See https://builds.apache.org/job/ZooKeeper_branch34_solaris/1216/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 179930 lines...] [junit] 2016-07-13 14:04:00,188 [myid:] - INFO [main:JMXEnv@246] - expect:StandaloneServer_port [junit] 2016-07-13 14:04:00,188 [myid:] - INFO [main:JMXEnv@250] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11221 [junit] 2016-07-13 14:04:00,188 [myid:] - INFO [main:ClientBase@490] - STOPPING server [junit] 2016-07-13 14:04:00,189 [myid:] - INFO [main:ZooKeeperServer@469] - shutting down [junit] 2016-07-13 14:04:00,189 [myid:] - INFO [main:SessionTrackerImpl@225] - Shutting down [junit] 2016-07-13 14:04:00,189 [myid:] - INFO [main:PrepRequestProcessor@765] - Shutting down [junit] 2016-07-13 14:04:00,189 [myid:] - INFO [main:SyncRequestProcessor@209] - Shutting down [junit] 2016-07-13 14:04:00,189 [myid:] - INFO [ProcessThread(sid:0 cport:11221)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop! [junit] 2016-07-13 14:04:00,189 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@187] - SyncRequestProcessor exited! [junit] 2016-07-13 14:04:00,190 [myid:] - INFO [main:FinalRequestProcessor@402] - shutdown of request processor complete [junit] 2016-07-13 14:04:00,190 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221 [junit] 2016-07-13 14:04:00,191 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[] [junit] 2016-07-13 14:04:00,192 [myid:] - INFO [main:ClientBase@443] - STARTING server [junit] 2016-07-13 14:04:00,192 [myid:] - INFO [main:ClientBase@364] - CREATING server instance 127.0.0.1:11221 [junit] 2016-07-13 14:04:00,192 [myid:] - INFO [main:NIOServerCnxnFactory@89] - binding to port 0.0.0.0/0.0.0.0:11221 [junit] 2016-07-13 14:04:00,192 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221 [junit] 2016-07-13 14:04:00,193 [myid:] - INFO [main:ZooKeeperServer@170] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 6 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test4194644435613534491.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test4194644435613534491.junit.dir/version-2 [junit] 2016-07-13 14:04:00,195 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221 [junit] 2016-07-13 14:04:00,196 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@192] - Accepted socket connection from /127.0.0.1:47606 [junit] 2016-07-13 14:04:00,196 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@827] - Processing stat command from /127.0.0.1:47606 [junit] 2016-07-13 14:04:00,196 [myid:] - INFO [Thread-5:NIOServerCnxn$StatCommand@663] - Stat command output [junit] 2016-07-13 14:04:00,197 [myid:] - INFO [Thread-5:NIOServerCnxn@1008] - Closed socket connection for client /127.0.0.1:47606 (no session established for client) [junit] 2016-07-13 14:04:00,197 [myid:] - INFO [main:JMXEnv@229] - ensureParent:[InMemoryDataTree, StandaloneServer_port] [junit] 2016-07-13 14:04:00,198 [myid:] - INFO [main:JMXEnv@246] - expect:InMemoryDataTree [junit] 2016-07-13 14:04:00,198 [myid:] - INFO [main:JMXEnv@250] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11221,name1=InMemoryDataTree [junit] 2016-07-13 14:04:00,198 [myid:] - INFO [main:JMXEnv@246] - expect:StandaloneServer_port [junit] 2016-07-13 14:04:00,199 [myid:] - INFO [main:JMXEnv@250] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11221 [junit] 2016-07-13 14:04:00,199 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@58] - Memory used 9197 [junit] 2016-07-13 14:04:00,199 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@63] - Number of threads 20 [junit] 2016-07-13 14:04:00,199 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@78] - FINISHED TEST METHOD testQuota [junit] 2016-07-13 14:04:00,199 [myid:] - INFO [main:ClientBase@520] - tearDown starting [junit] 2016-07-13 14:04:00,282 [myid:] - INFO [main:ZooKeeper@684] - Session: 0x155e492488c closed [junit] 2016-07-13 14:04:00,282 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@519] - EventThread shut down for session: 0x155e492488c [junit] 2016-07-13 14:04:00,282 [myid:] - INFO [main:ClientBase@490] - STOPPING server [junit] 2016-07-13 14:04:00,283 [myid:] - INFO [main:ZooKeeperServer@469] - shutting down [junit] 2016-07-13 14:04:00,283 [myid:] - INFO [main:SessionTrackerImpl@225] -
ZooKeeper_branch35_jdk8 - Build # 151 - Failure
See https://builds.apache.org/job/ZooKeeper_branch35_jdk8/151/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 437583 lines...] [junit] 2016-07-13 12:23:28,793 [myid:] - INFO [main:ZooKeeperServer@498] - shutting down [junit] 2016-07-13 12:23:28,793 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down [junit] 2016-07-13 12:23:28,793 [myid:] - INFO [main:PrepRequestProcessor@965] - Shutting down [junit] 2016-07-13 12:23:28,793 [myid:] - INFO [ProcessThread(sid:0 cport:19545)::PrepRequestProcessor@154] - PrepRequestProcessor exited loop! [junit] 2016-07-13 12:23:28,793 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down [junit] 2016-07-13 12:23:28,794 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited! [junit] 2016-07-13 12:23:28,794 [myid:] - INFO [main:FinalRequestProcessor@479] - shutdown of request processor complete [junit] 2016-07-13 12:23:28,794 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port19545,name1=InMemoryDataTree] [junit] 2016-07-13 12:23:28,794 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port19545] [junit] 2016-07-13 12:23:28,794 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 19545 [junit] 2016-07-13 12:23:28,795 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[] [junit] 2016-07-13 12:23:28,801 [myid:] - INFO [main:ClientBase@568] - fdcount after test is: 5109 at start it was 5109 [junit] 2016-07-13 12:23:28,801 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testWatcherAutoResetWithLocal [junit] 2016-07-13 12:23:28,801 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testWatcherAutoResetWithLocal [junit] Tests run: 101, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 522.257 sec, Thread: 4, Class: org.apache.zookeeper.test.NettyNettySuiteTest [junit] 2016-07-13 12:23:29,026 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop! [junit] 2016-07-13 12:23:29,027 [myid:] - INFO [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop! [junit] 2016-07-13 12:23:29,096 [myid:127.0.0.1:19428] - INFO [main-SendThread(127.0.0.1:19428):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:19428. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 12:23:29,097 [myid:] - INFO [New I/O boss #9471:ClientCnxnSocketNetty$1@127] - future isn't success, cause: {} [junit] java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:19428 [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.connect(NioClientBoss.java:152) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.processSelectedKeys(NioClientBoss.java:105) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.process(NioClientBoss.java:79) [junit] at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:337) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.run(NioClientBoss.java:42) [junit] at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:108) [junit] at org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42) [junit] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit] at java.lang.Thread.run(Thread.java:745) [junit] 2016-07-13 12:23:29,097 [myid:] - WARN [New I/O boss #9471:ClientCnxnSocketNetty$ZKClientHandler@439] - Exception caught: [id: 0xadda6586] EXCEPTION: java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:19428 [junit] java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:19428 [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.connect(NioClientBoss.java:152) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.processSelectedKeys(NioClientBoss.java:105) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.process(NioClientBoss.java:79) [junit] at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:337) [junit] at
ZooKeeper-trunk-jdk8 - Build # 667 - Still Failing
See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/667/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 417248 lines...] [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:712) [junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214) [junit] 2016-07-13 12:09:18,569 [myid:127.0.0.1:11222] - INFO [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11222. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 12:09:18,570 [myid:127.0.0.1:11222] - WARN [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1235] - Session 0x1013d51264a for server 127.0.0.1/127.0.0.1:11222, unexpected error, closing socket connection and attempting reconnect [junit] java.net.ConnectException: Connection refused [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:712) [junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214) [junit] 2016-07-13 12:09:19,763 [myid:127.0.0.1:11222] - INFO [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11222. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 12:09:19,764 [myid:127.0.0.1:11222] - WARN [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1235] - Session 0x1013d51264a for server 127.0.0.1/127.0.0.1:11222, unexpected error, closing socket connection and attempting reconnect [junit] java.net.ConnectException: Connection refused [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:712) [junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214) [junit] 2016-07-13 12:09:21,646 [myid:127.0.0.1:11222] - INFO [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11222. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 12:09:21,646 [myid:127.0.0.1:11222] - WARN [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1235] - Session 0x1013d51264a for server 127.0.0.1/127.0.0.1:11222, unexpected error, closing socket connection and attempting reconnect [junit] java.net.ConnectException: Connection refused [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:712) [junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214) [junit] 2016-07-13 12:09:23,694 [myid:127.0.0.1:11222] - INFO [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11222. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 12:09:23,694 [myid:127.0.0.1:11222] - WARN [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1235] - Session 0x1013d51264a for server 127.0.0.1/127.0.0.1:11222, unexpected error, closing socket connection and attempting reconnect [junit] java.net.ConnectException: Connection refused [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:712) [junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214) [junit] 2016-07-13 12:09:25,069 [myid:127.0.0.1:11222] - INFO [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:11222. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 12:09:25,070 [myid:127.0.0.1:11222] - WARN [main-SendThread(127.0.0.1:11222):ClientCnxn$SendThread@1235] - Session 0x1013d51264a for server 127.0.0.1/127.0.0.1:11222, unexpected error, closing socket connection and attempting reconnect [junit] java.net.ConnectException: Connection refused [junit] at
ZooKeeper_branch35_openjdk7 - Build # 150 - Failure
See https://builds.apache.org/job/ZooKeeper_branch35_openjdk7/150/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 618977 lines...] [junit] 2016-07-13 10:10:04,911 [myid:] - WARN [New I/O boss #8629:ClientCnxnSocketNetty$ZKClientHandler@439] - Exception caught: [id: 0x42f02cd0] EXCEPTION: java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:24808 [junit] java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:24808 [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.connect(NioClientBoss.java:152) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.processSelectedKeys(NioClientBoss.java:105) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.process(NioClientBoss.java:79) [junit] at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:337) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.run(NioClientBoss.java:42) [junit] at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:108) [junit] at org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42) [junit] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [junit] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [junit] at java.lang.Thread.run(Thread.java:745) [junit] 2016-07-13 10:10:04,912 [myid:] - INFO [New I/O boss #8629:ClientCnxnSocketNetty@208] - channel is told closing [junit] 2016-07-13 10:10:04,912 [myid:127.0.0.1:24808] - INFO [main-SendThread(127.0.0.1:24808):ClientCnxn$SendThread@1231] - channel for sessionid 0x1013cedd037 is lost, closing socket connection and attempting reconnect [junit] 2016-07-13 10:10:05,100 [myid:127.0.0.1:24835] - INFO [main-SendThread(127.0.0.1:24835):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:24835. Will not attempt to authenticate using SASL (unknown error) [junit] 2016-07-13 10:10:05,101 [myid:] - INFO [New I/O boss #9454:ClientCnxnSocketNetty$1@127] - future isn't success, cause: {} [junit] java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:24835 [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.connect(NioClientBoss.java:152) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.processSelectedKeys(NioClientBoss.java:105) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.process(NioClientBoss.java:79) [junit] at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:337) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.run(NioClientBoss.java:42) [junit] at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:108) [junit] at org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42) [junit] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [junit] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [junit] at java.lang.Thread.run(Thread.java:745) [junit] 2016-07-13 10:10:05,102 [myid:] - WARN [New I/O boss #9454:ClientCnxnSocketNetty$ZKClientHandler@439] - Exception caught: [id: 0x76ee657f] EXCEPTION: java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:24835 [junit] java.net.ConnectException: Connection refused: 127.0.0.1/127.0.0.1:24835 [junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) [junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.connect(NioClientBoss.java:152) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.processSelectedKeys(NioClientBoss.java:105) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.process(NioClientBoss.java:79) [junit] at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:337) [junit] at org.jboss.netty.channel.socket.nio.NioClientBoss.run(NioClientBoss.java:42) [junit] at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:108) [junit] at org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42) [junit] at
ZooKeeper-trunk-solaris - Build # 1228 - Still Failing
See https://builds.apache.org/job/ZooKeeper-trunk-solaris/1228/ ### ## LAST 60 LINES OF THE CONSOLE ### [...truncated 423204 lines...] [junit] 2016-07-13 08:32:44,252 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[] [junit] 2016-07-13 08:32:44,253 [myid:] - INFO [main:ClientBase@466] - STARTING server [junit] 2016-07-13 08:32:44,253 [myid:] - INFO [main:ClientBase@386] - CREATING server instance 127.0.0.1:11222 [junit] 2016-07-13 08:32:44,253 [myid:] - INFO [main:NIOServerCnxnFactory@673] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers. [junit] 2016-07-13 08:32:44,254 [myid:] - INFO [main:NIOServerCnxnFactory@686] - binding to port 0.0.0.0/0.0.0.0:11222 [junit] 2016-07-13 08:32:44,255 [myid:] - INFO [main:ClientBase@361] - STARTING server instance 127.0.0.1:11222 [junit] 2016-07-13 08:32:44,255 [myid:] - INFO [main:ZooKeeperServer@858] - minSessionTimeout set to 6000 [junit] 2016-07-13 08:32:44,255 [myid:] - INFO [main:ZooKeeperServer@867] - maxSessionTimeout set to 6 [junit] 2016-07-13 08:32:44,255 [myid:] - INFO [main:ZooKeeperServer@156] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 6 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper-trunk-solaris/trunk/build/test/tmp/test6532986952183711454.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper-trunk-solaris/trunk/build/test/tmp/test6532986952183711454.junit.dir/version-2 [junit] 2016-07-13 08:32:44,257 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper-trunk-solaris/trunk/build/test/tmp/test6532986952183711454.junit.dir/version-2/snapshot.b [junit] 2016-07-13 08:32:44,259 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper-trunk-solaris/trunk/build/test/tmp/test6532986952183711454.junit.dir/version-2/snapshot.b [junit] 2016-07-13 08:32:44,260 [myid:] - INFO [main:FourLetterWordMain@85] - connecting to 127.0.0.1 11222 [junit] 2016-07-13 08:32:44,261 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40346 [junit] 2016-07-13 08:32:44,262 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@485] - Processing stat command from /127.0.0.1:40346 [junit] 2016-07-13 08:32:44,262 [myid:] - INFO [NIOWorkerThread-1:StatCommand@49] - Stat command output [junit] 2016-07-13 08:32:44,263 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@607] - Closed socket connection for client /127.0.0.1:40346 (no session established for client) [junit] 2016-07-13 08:32:44,263 [myid:] - INFO [main:JMXEnv@228] - ensureParent:[InMemoryDataTree, StandaloneServer_port] [junit] 2016-07-13 08:32:44,265 [myid:] - INFO [main:JMXEnv@245] - expect:InMemoryDataTree [junit] 2016-07-13 08:32:44,265 [myid:] - INFO [main:JMXEnv@249] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11222,name1=InMemoryDataTree [junit] 2016-07-13 08:32:44,265 [myid:] - INFO [main:JMXEnv@245] - expect:StandaloneServer_port [junit] 2016-07-13 08:32:44,265 [myid:] - INFO [main:JMXEnv@249] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11222 [junit] 2016-07-13 08:32:44,265 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 17710 [junit] 2016-07-13 08:32:44,266 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 24 [junit] 2016-07-13 08:32:44,266 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testQuota [junit] 2016-07-13 08:32:44,266 [myid:] - INFO [main:ClientBase@543] - tearDown starting [junit] 2016-07-13 08:32:44,610 [myid:] - INFO [main:ZooKeeper@1313] - Session: 0x122605f367c closed [junit] 2016-07-13 08:32:44,611 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x122605f367c [junit] 2016-07-13 08:32:44,611 [myid:] - INFO [main:ClientBase@513] - STOPPING server [junit] 2016-07-13 08:32:44,612 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted [junit] 2016-07-13 08:32:44,613 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11222:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method [junit] 2016-07-13 08:32:44,613 [myid:] - INFO