Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Linux/500/
Java: 32bit/jdk1.8.0-ea-b51 -server -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ZkSolrClientTest.testWatchChildren
Error Message:
expected:<2> but was:<1>
Stack Trace:
java.lang.AssertionError: expected:<2> but was:<1>
at
__randomizedtesting.SeedInfo.seed([2034617253769CA8:684A036E14B22613]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.ZkSolrClientTest.testWatchChildren(ZkSolrClientTest.java:218)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:474)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:769)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:429)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
at java.lang.Thread.run(Thread.java:722)
Build Log:
[...truncated 7286 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.ZkSolrClientTest
[junit4:junit4] 2> 166 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_START
testMakeRootNode
[junit4:junit4] 2> 166 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_END
testMakeRootNode
[junit4:junit4] 2> 167 T920 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 167 T921 oazs.ZooKeeperServer.setTickTime tickTime set to
3000
[junit4:junit4] 2> 168 T921 oazs.NIOServerCnxn$Factory.<init> binding to port
0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 168 T921 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4] 2> 267 T920 oasc.ZkTestServer.run start zk server on
port:42208
[junit4:junit4] 2> 267 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:42208 sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@142d6b1
[junit4:junit4] 2> 268 T926 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:42208
[junit4:junit4] 2> 268 T926 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:42208, initiating
session
[junit4:junit4] 2> 268 T922 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:60045
[junit4:junit4] 2> 269 T922 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:60045
[junit4:junit4] 2> 269 T924 oazsp.FileTxnLog.append Creating new log file:
log.1
[junit4:junit4] 2> 718 T924 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9c69d0000 with negotiated timeout 10000 for client
/127.0.0.1:60045
[junit4:junit4] 2> 718 T926 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:42208,
sessionid = 0x13935b9c69d0000, negotiated timeout = 10000
[junit4:junit4] 2> 719 T927 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@142d6b1 name:ZooKeeperConnection
Watcher:127.0.0.1:42208 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4:junit4] 2> 719 T920 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 721 T925 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9c69d0000
[junit4:junit4] 2> 722 T927 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 722 T922 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:60045 which had sessionid 0x13935b9c69d0000
[junit4:junit4] 2> 722 T920 oaz.ZooKeeper.close Session: 0x13935b9c69d0000
closed
[junit4:junit4] 2> 722 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:42208 sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@c574e0
[junit4:junit4] 2> 723 T928 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:42208
[junit4:junit4] 2> 723 T928 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:42208, initiating
session
[junit4:junit4] 2> 723 T922 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:60046
[junit4:junit4] 2> 723 T922 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:60046
[junit4:junit4] 2> 724 T924 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9c69d0001 with negotiated timeout 10000 for client
/127.0.0.1:60046
[junit4:junit4] 2> 724 T928 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:42208,
sessionid = 0x13935b9c69d0001, negotiated timeout = 10000
[junit4:junit4] 2> 724 T929 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@c574e0 name:ZooKeeperConnection
Watcher:127.0.0.1:42208 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4:junit4] 2> 724 T925 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9c69d0001
[junit4:junit4] 2> 725 T922 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:60046 which had sessionid 0x13935b9c69d0001
[junit4:junit4] 2> 725 T929 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 725 T920 oaz.ZooKeeper.close Session: 0x13935b9c69d0001
closed
[junit4:junit4] 2> 725 T925 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 725 T924 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 725 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 730 T922 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 731 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 731 T920 oasu.AbstractSolrTestCase.tearDown
####TEARDOWN_START testMakeRootNode
[junit4:junit4] 2> 734 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_START
testReconnect
[junit4:junit4] 2> 734 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_END
testReconnect
[junit4:junit4] 2> 734 T920 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 735 T930 oazs.ZooKeeperServer.setTickTime tickTime set to
3000
[junit4:junit4] 2> 735 T930 oazs.NIOServerCnxn$Factory.<init> binding to port
0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 736 T930 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4] 2> 835 T920 oasc.ZkTestServer.run start zk server on
port:60923
[junit4:junit4] 2> 835 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:60923 sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@61d05b
[junit4:junit4] 2> 836 T935 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:60923
[junit4:junit4] 2> 836 T935 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:60923, initiating
session
[junit4:junit4] 2> 836 T931 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:41517
[junit4:junit4] 2> 837 T931 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:41517
[junit4:junit4] 2> 837 T933 oazsp.FileTxnLog.append Creating new log file:
log.1
[junit4:junit4] 2> 868 T933 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9c8d20000 with negotiated timeout 10000 for client
/127.0.0.1:41517
[junit4:junit4] 2> 868 T935 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:60923,
sessionid = 0x13935b9c8d20000, negotiated timeout = 10000
[junit4:junit4] 2> 869 T936 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@61d05b name:ZooKeeperConnection
Watcher:127.0.0.1:60923 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4:junit4] 2> 869 T920 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 871 T934 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9c8d20000
[junit4:junit4] 2> 872 T931 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:41517 which had sessionid 0x13935b9c8d20000
[junit4:junit4] 2> 872 T920 oaz.ZooKeeper.close Session: 0x13935b9c8d20000
closed
[junit4:junit4] 2> 872 T936 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 872 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:60923/solr sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@1612fbf
[junit4:junit4] 2> 873 T937 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:60923
[junit4:junit4] 2> 873 T937 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:60923, initiating
session
[junit4:junit4] 2> 873 T931 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:41518
[junit4:junit4] 2> 873 T931 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:41518
[junit4:junit4] 2> 874 T933 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9c8d20001 with negotiated timeout 10000 for client
/127.0.0.1:41518
[junit4:junit4] 2> 874 T937 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:60923,
sessionid = 0x13935b9c8d20001, negotiated timeout = 10000
[junit4:junit4] 2> 874 T938 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1612fbf name:ZooKeeperConnection
Watcher:127.0.0.1:60923/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4:junit4] 2> 874 T920 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4:junit4] 2> 876 T920 oascc.SolrZkClient.makePath makePath:
collections/collection1
[junit4:junit4] 2> 877 T933 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 877 T934 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 877 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 878 T920 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:41518 which had sessionid 0x13935b9c8d20001
[junit4:junit4] 2> 878 T931 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 879 T937 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x13935b9c8d20001, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 879 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 959 T920 oascc.SolrZkClient.makePath makePath:
collections/collection2
[junit4:junit4] 2> 979 T938 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1612fbf name:ZooKeeperConnection
Watcher:127.0.0.1:60923/solr got event WatchedEvent state:Disconnected
type:None path:null path:null type:None
[junit4:junit4] 2> 979 T920 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 980 T939 oazs.ZooKeeperServer.setTickTime tickTime set to
3000
[junit4:junit4] 2> 980 T939 oazs.NIOServerCnxn$Factory.<init> binding to port
0.0.0.0/0.0.0.0:60923
[junit4:junit4] 2> 980 T939 oazsp.FileSnap.deserialize Reading snapshot
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ZkSolrClientTest-1345226131664/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4] 2> 981 T939 oazsp.FileTxnSnapLog.save Snapshotting: 8
[junit4:junit4] 2> 1080 T920 oasc.ZkTestServer.run start zk server on
port:60923
[junit4:junit4] 2> 1680 T920 oascc.SolrZkClient.makePath makePath:
collections/collection3
[junit4:junit4] 2> 2070 T923 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> 2070 T932 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> 2222 T937 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost.localdomain/127.0.0.1:60923
[junit4:junit4] 2> 2223 T940 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:41519
[junit4:junit4] 2> 2223 T937 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:60923, initiating
session
[junit4:junit4] 2> 2223 T940 oazs.NIOServerCnxn.readConnectRequest Client
attempting to renew session 0x13935b9c8d20001 at /127.0.0.1:41519
[junit4:junit4] 2> 2223 T940 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9c8d20001 with negotiated timeout 10000 for client
/127.0.0.1:41519
[junit4:junit4] 2> 2223 T937 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:60923,
sessionid = 0x13935b9c8d20001, negotiated timeout = 10000
[junit4:junit4] 2> 2223 T938 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1612fbf name:ZooKeeperConnection
Watcher:127.0.0.1:60923/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4:junit4] 2> 2224 T942 oazsp.FileTxnLog.append Creating new log file:
log.9
[junit4:junit4] 2> 2227 T920 oazs.ZooKeeperServer.expire Expiring session
0x13935b9c8d20001, timeout of 4000ms exceeded
[junit4:junit4] 2> 2227 T943 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9c8d20001
[junit4:junit4] 2> 2228 T942 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:41519 which had sessionid 0x13935b9c8d20001
[junit4:junit4] 2> 2228 T937 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x13935b9c8d20001, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 2328 T938 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1612fbf name:ZooKeeperConnection
Watcher:127.0.0.1:60923/solr got event WatchedEvent state:Disconnected
type:None path:null path:null type:None
[junit4:junit4] 2> 3227 T920 oascc.SolrZkClient.makePath makePath:
collections/collection4
[junit4:junit4] 2> 3360 T937 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost.localdomain/127.0.0.1:60923
[junit4:junit4] 2> 3361 T937 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:60923, initiating
session
[junit4:junit4] 2> 3361 T940 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:41520
[junit4:junit4] 2> 3361 T940 oazs.NIOServerCnxn.readConnectRequest Client
attempting to renew session 0x13935b9c8d20001 at /127.0.0.1:41520
[junit4:junit4] 2> 3361 T940 oazs.NIOServerCnxn.finishSessionInit Invalid
session 0x13935b9c8d20001 for client /127.0.0.1:41520, probably expired
[junit4:junit4] 2> 3361 T937 oaz.ClientCnxn$SendThread.run Unable to
reconnect to ZooKeeper service, session 0x13935b9c8d20001 has expired, closing
socket connection
[junit4:junit4] 2> 3361 T938 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1612fbf name:ZooKeeperConnection
Watcher:127.0.0.1:60923/solr got event WatchedEvent state:Expired type:None
path:null path:null type:None
[junit4:junit4] 2> 3361 T938 oascc.ConnectionManager.process Attempting to
reconnect to recover relationship with ZooKeeper...
[junit4:junit4] 2> 3362 T940 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:41520 which had sessionid 0x13935b9c8d20001
[junit4:junit4] 2> 3362 T938 oascc.DefaultConnectionStrategy.reconnect
Connection expired - starting a new one...
[junit4:junit4] 2> 3362 T938 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:60923/solr sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@1612fbf
[junit4:junit4] 2> 3362 T944 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:60923
[junit4:junit4] 2> 3363 T944 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:60923, initiating
session
[junit4:junit4] 2> 3363 T940 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:41521
[junit4:junit4] 2> 3363 T940 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:41521
[junit4:junit4] 2> 3364 T942 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9c9c70000 with negotiated timeout 10000 for client
/127.0.0.1:41521
[junit4:junit4] 2> 3364 T944 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:60923,
sessionid = 0x13935b9c9c70000, negotiated timeout = 10000
[junit4:junit4] 2> 3364 T945 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1612fbf name:ZooKeeperConnection
Watcher:127.0.0.1:60923/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4:junit4] 2> 3364 T938 oascc.DefaultConnectionStrategy.reconnect
Reconnected to ZooKeeper
[junit4:junit4] 2> 3364 T938 oascc.ConnectionManager.process Connected:true
[junit4:junit4] 2> 3364 T938 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 3462 T920 oascc.SolrZkClient.makePath makePath:
collections/collection4
[junit4:junit4] 2> 3463 T943 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9c9c70000
[junit4:junit4] 2> 3464 T940 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:41521 which had sessionid 0x13935b9c9c70000
[junit4:junit4] 2> 3464 T920 oaz.ZooKeeper.close Session: 0x13935b9c9c70000
closed
[junit4:junit4] 2> 3464 T943 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 3464 T945 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 3464 T942 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 3465 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 3466 T940 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 3466 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 3466 T920 oasu.AbstractSolrTestCase.tearDown
####TEARDOWN_START testReconnect
[junit4:junit4] 2> 3468 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_START
testConnect
[junit4:junit4] 2> 3468 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_END
testConnect
[junit4:junit4] 2> 3468 T920 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 3469 T946 oazs.ZooKeeperServer.setTickTime tickTime set to
3000
[junit4:junit4] 2> 3469 T946 oazs.NIOServerCnxn$Factory.<init> binding to
port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 3470 T946 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4] 2> 3569 T920 oasc.ZkTestServer.run start zk server on
port:46139
[junit4:junit4] 2> 3569 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:46139/solr sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@d33c6e
[junit4:junit4] 2> 3570 T951 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:46139
[junit4:junit4] 2> 3570 T951 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:46139, initiating
session
[junit4:junit4] 2> 3570 T947 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:40409
[junit4:junit4] 2> 3570 T947 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:40409
[junit4:junit4] 2> 3570 T949 oazsp.FileTxnLog.append Creating new log file:
log.1
[junit4:junit4] 2> 3638 T949 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9d3800000 with negotiated timeout 10000 for client
/127.0.0.1:40409
[junit4:junit4] 2> 3638 T951 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:46139,
sessionid = 0x13935b9d3800000, negotiated timeout = 10000
[junit4:junit4] 2> 3638 T952 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@d33c6e name:ZooKeeperConnection
Watcher:127.0.0.1:46139/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4:junit4] 2> 3639 T950 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9d3800000
[junit4:junit4] 2> 3640 T952 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 3640 T947 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:40409 which had sessionid 0x13935b9d3800000
[junit4:junit4] 2> 3640 T920 oaz.ZooKeeper.close Session: 0x13935b9d3800000
closed
[junit4:junit4] 2> 3640 T950 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 3640 T949 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 3641 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 3645 T947 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 3645 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 3646 T920 oasu.AbstractSolrTestCase.tearDown
####TEARDOWN_START testConnect
[junit4:junit4] 2> 3648 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_START
testWatchChildren
[junit4:junit4] 2> 3648 T920 oasu.AbstractSolrTestCase.setUp ####SETUP_END
testWatchChildren
[junit4:junit4] 2> 3648 T920 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 3649 T953 oazs.ZooKeeperServer.setTickTime tickTime set to
3000
[junit4:junit4] 2> 3649 T953 oazs.NIOServerCnxn$Factory.<init> binding to
port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 3649 T953 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4] 2> 3748 T920 oasc.ZkTestServer.run start zk server on
port:54269
[junit4:junit4] 2> 4149 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:54269 sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@1289f6b
[junit4:junit4] 2> 4149 T958 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:54269
[junit4:junit4] 2> 4150 T958 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:54269, initiating
session
[junit4:junit4] 2> 4150 T954 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:49071
[junit4:junit4] 2> 4150 T954 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:49071
[junit4:junit4] 2> 4150 T956 oazsp.FileTxnLog.append Creating new log file:
log.1
[junit4:junit4] 2> 4188 T956 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9d4330000 with negotiated timeout 10000 for client
/127.0.0.1:49071
[junit4:junit4] 2> 4188 T958 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:54269,
sessionid = 0x13935b9d4330000, negotiated timeout = 10000
[junit4:junit4] 2> 4189 T959 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1289f6b name:ZooKeeperConnection
Watcher:127.0.0.1:54269 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4:junit4] 2> 4189 T920 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 4191 T957 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9d4330000
[junit4:junit4] 2> 4191 T959 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 4192 T954 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:49071 which had sessionid 0x13935b9d4330000
[junit4:junit4] 2> 4191 T920 oaz.ZooKeeper.close Session: 0x13935b9d4330000
closed
[junit4:junit4] 2> 4192 T920 oaz.ZooKeeper.<init> Initiating client
connection, connectString=127.0.0.1:54269/solr sessionTimeout=10000
watcher=org.apache.solr.common.cloud.ConnectionManager@86bdad
[junit4:junit4] 2> 4192 T960 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server /127.0.0.1:54269
[junit4:junit4] 2> 4193 T954 oazs.NIOServerCnxn$Factory.run Accepted socket
connection from /127.0.0.1:49072
[junit4:junit4] 2> 4193 T960 oaz.ClientCnxn$SendThread.primeConnection Socket
connection established to localhost.localdomain/127.0.0.1:54269, initiating
session
[junit4:junit4] 2> 4193 T954 oazs.NIOServerCnxn.readConnectRequest Client
attempting to establish new session at /127.0.0.1:49072
[junit4:junit4] 2> 4193 T956 oazs.NIOServerCnxn.finishSessionInit Established
session 0x13935b9d4330001 with negotiated timeout 10000 for client
/127.0.0.1:49072
[junit4:junit4] 2> 4193 T960 oaz.ClientCnxn$SendThread.readConnectResult
Session establishment complete on server localhost.localdomain/127.0.0.1:54269,
sessionid = 0x13935b9d4330001, negotiated timeout = 10000
[junit4:junit4] 2> 4193 T961 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@86bdad name:ZooKeeperConnection
Watcher:127.0.0.1:54269/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4:junit4] 2> 4194 T920 oascc.SolrZkClient.makePath makePath:
/collections
[junit4:junit4] 2> 4195 T920 oascc.SolrZkClient.makePath makePath:
/collections/collection99/shards
[junit4:junit4] 2> 4198 T920 oascc.SolrZkClient.makePath makePath:
collections/collection99/config=collection1
[junit4:junit4] 2> 4200 T920 oascc.SolrZkClient.makePath makePath:
collections/collection99/config=collection3
[junit4:junit4] 2> 4201 T920 oascc.SolrZkClient.makePath makePath:
/collections/collection97/shards
[junit4:junit4] 2> 5070 T948 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> 5070 T941 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> 12905 T957 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x13935b9d4330001
[junit4:junit4] 2> 12905 T961 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 12905 T954 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /127.0.0.1:49072 which had sessionid 0x13935b9d4330001
[junit4:junit4] 2> 12905 T920 oaz.ZooKeeper.close Session: 0x13935b9d4330001
closed
[junit4:junit4] 2> 12905 T957 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 12905 T956 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 12906 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 12907 T954 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 12908 T920 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 12908 T920 oasu.AbstractSolrTestCase.tearDown
####TEARDOWN_START testWatchChildren
[junit4:junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ZkSolrClientTest -Dtests.method=testWatchChildren
-Dtests.seed=2034617253769CA8 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=hu_HU -Dtests.timezone=Pacific/Apia
-Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE 9.29s J1 | ZkSolrClientTest.testWatchChildren <<<
[junit4:junit4] > Throwable #1: java.lang.AssertionError: expected:<2> but
was:<1>
[junit4:junit4] > at
__randomizedtesting.SeedInfo.seed([2034617253769CA8:684A036E14B22613]:0)
[junit4:junit4] > at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4] > at org.junit.Assert.failNotEquals(Assert.java:647)
[junit4:junit4] > at org.junit.Assert.assertEquals(Assert.java:128)
[junit4:junit4] > at org.junit.Assert.assertEquals(Assert.java:472)
[junit4:junit4] > at org.junit.Assert.assertEquals(Assert.java:456)
[junit4:junit4] > at
org.apache.solr.cloud.ZkSolrClientTest.testWatchChildren(ZkSolrClientTest.java:218)
[junit4:junit4] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
[junit4:junit4] > at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4] > at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4] > at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4] > at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:769)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:429)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4] > at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4] > at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4] > at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:345)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2> 14070 T955 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> NOTE: test params are: codec=Lucene40: {},
sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=hu_HU,
timezone=Pacific/Apia
[junit4:junit4] 2> NOTE: Linux 3.2.0-29-generic i386/Oracle Corporation
1.8.0-ea (32-bit)/cpus=8,threads=1,free=27458768,total=86507520
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestQuerySenderListener,
TestJmxIntegration, FieldMutatingUpdateProcessorTest, TestSolrCoreProperties,
TestQueryUtils, BasicFunctionalityTest, PeerSyncTest, TestRecovery,
TestFoldingMultitermQuery, TestDocSet, LukeRequestHandlerTest, TestWriterPerf,
SolrCoreTest, TestStressRecovery, TestPhraseSuggestions,
CoreContainerCoreInitFailuresTest, TestBinaryField, TestLRUCache,
TestSurroundQueryParser, SolrIndexConfigTest, ClusterStateUpdateTest,
TestRemoteStreaming, SuggesterTSTTest, LeaderElectionTest,
TestValueSourceCache, TestCSVLoader, FullSolrCloudDistribCmdsTest,
TestRealTimeGet, TestOmitPositions, QueryEqualityTest, MultiTermTest,
QueryParsingTest, TestReversedWildcardFilterFactory,
DistributedQueryElevationComponentTest, BasicDistributedZk2Test,
TimeZoneUtilsTest, TestBM25SimilarityFactory, ZkSolrClientTest]
[junit4:junit4] Completed on J1 in 14.78s, 4 tests, 1 failure <<< FAILURES!
[...truncated 550 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:29: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:144: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/module-build.xml:62:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1105:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:797:
There were test failures: 225 suites, 906 tests, 1 failure, 7 ignored (2
assumptions)
Total time: 32 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b51 -server -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
