Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19024/
Java: 32bit/jdk-9-ea+155 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReqParamsAPI.test
Error Message:
Could not get expected value 'A val' for path 'params/a' full output: {
"responseHeader":{ "status":0, "QTime":0}, "params":{"wt":"json"},
"context":{ "webapp":"/solr", "path":"/dump1",
"httpMethod":"GET"}}, from server:
http://127.0.0.1:35440/solr/collection1_shard1_replica1
Stack Trace:
java.lang.AssertionError: Could not get expected value 'A val' for path
'params/a' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"params":{"wt":"json"},
"context":{
"webapp":"/solr",
"path":"/dump1",
"httpMethod":"GET"}}, from server:
http://127.0.0.1:35440/solr/collection1_shard1_replica1
at
__randomizedtesting.SeedInfo.seed([69FE7569E78A71B2:E1AA4AB349761C4A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
at
org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:152)
at
org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:69)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:543)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 11164 lines...]
[junit4] Suite: org.apache.solr.handler.TestReqParamsAPI
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/init-core-data-001
[junit4] 2> 381775 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 381777 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 381777 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001
[junit4] 2> 381778 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 381778 INFO (Thread-562) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 381778 INFO (Thread-562) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 381878 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:34328
[junit4] 2> 381884 INFO (jetty-launcher-398-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 381884 INFO (jetty-launcher-398-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 381887 INFO (jetty-launcher-398-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@d88bcc{/solr,null,AVAILABLE}
[junit4] 2> 381887 INFO (jetty-launcher-398-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@74c1a8{/solr,null,AVAILABLE}
[junit4] 2> 381888 INFO (jetty-launcher-398-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@11983f6{HTTP/1.1,[http/1.1]}{127.0.0.1:34212}
[junit4] 2> 381888 INFO (jetty-launcher-398-thread-2) [ ]
o.e.j.s.Server Started @383906ms
[junit4] 2> 381888 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34212}
[junit4] 2> 381888 INFO (jetty-launcher-398-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@167696b{HTTP/1.1,[http/1.1]}{127.0.0.1:35440}
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-1) [ ]
o.e.j.s.Server Started @383906ms
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35440}
[junit4] 2> 381889 ERROR (jetty-launcher-398-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 381889 ERROR (jetty-launcher-398-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-22T09:12:36.202529Z
[junit4] 2> 381889 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 381890 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-22T09:12:36.203676Z
[junit4] 2> 381892 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 381892 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 381909 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34328/solr
[junit4] 2> 381914 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34328/solr
[junit4] 2> 381930 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 381935 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34212_solr
[junit4] 2> 381936 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=97501495695376388-127.0.0.1:34212_solr-n_0000000000) starting
[junit4] 2> 381943 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 381944 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34212_solr
[junit4] 2> 381946 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35440_solr
[junit4] 2> 381948 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 381955 INFO (zkCallback-407-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 381955 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 382130 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/.
[junit4] 2> 382181 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/.
[junit4] 2> 382247 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 382247 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34328/solr ready
[junit4] 2> 382254 INFO (qtp6657551-2448) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf1&name=collection1&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 382256 INFO (OverseerThreadFactory-889-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 382360 INFO (qtp30645254-2444) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4] 2> 382362 INFO (qtp6657551-2440) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=collection1_shard1_replica2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4] 2> 382466 INFO (zkCallback-407-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 382466 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 383370 INFO (qtp30645254-2444) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 383372 INFO (qtp6657551-2440) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 383389 INFO (qtp6657551-2440) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 383389 INFO (qtp30645254-2444) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica1] Schema name=minimal
[junit4] 2> 383391 INFO (qtp6657551-2440) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 383391 INFO (qtp30645254-2444) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 383392 INFO (qtp6657551-2440) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica2' using configuration from
collection collection1
[junit4] 2> 383392 INFO (qtp30645254-2444) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica1' using configuration from
collection collection1
[junit4] 2> 383392 INFO (qtp6657551-2440) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/collection1_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
[junit4] 2> 383392 INFO (qtp30645254-2444) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/collection1_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/./collection1_shard1_replica1/data/]
[junit4] 2> 383697 INFO (qtp30645254-2444) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 383697 INFO (qtp30645254-2444) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 383701 INFO (qtp6657551-2440) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 383701 INFO (qtp6657551-2440) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 383707 INFO (qtp30645254-2444) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 383707 INFO (qtp30645254-2444) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 383708 INFO (qtp30645254-2444) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1ce52f4[collection1_shard1_replica1] main]
[junit4] 2> 383709 INFO (qtp30645254-2444) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 383709 INFO (qtp30645254-2444) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 383711 INFO (qtp30645254-2444) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 383711 INFO (qtp30645254-2444) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 383711 INFO (qtp30645254-2444) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 383711 INFO (qtp30645254-2444) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1560023933149773824
[junit4] 2> 383712 INFO (qtp6657551-2440) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 383712 INFO (qtp6657551-2440) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 383712 INFO (qtp6657551-2440) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@62be92[collection1_shard1_replica2]
main]
[junit4] 2> 383713 INFO (qtp6657551-2440) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 383713 INFO (qtp6657551-2440) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 383713 INFO (qtp6657551-2440) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 383713 INFO (searcherExecutor-895-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@1ce52f4[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 383714 INFO (qtp6657551-2440) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 383714 INFO (qtp6657551-2440) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 383714 INFO (searcherExecutor-894-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@62be92[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 383714 INFO (qtp6657551-2440) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1560023933152919552
[junit4] 2> 383717 INFO (qtp30645254-2444) [ ]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 383818 INFO (zkCallback-407-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 383818 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 384217 INFO (qtp30645254-2444) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 384217 INFO (qtp30645254-2444) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 384217 INFO (qtp30645254-2444) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:35440/solr/collection1_shard1_replica1/
[junit4] 2> 384218 INFO (qtp30645254-2444) [ ] o.a.s.u.PeerSync
PeerSync: core=collection1_shard1_replica1 url=http://127.0.0.1:35440/solr
START replicas=[http://127.0.0.1:34212/solr/collection1_shard1_replica2/]
nUpdates=100
[junit4] 2> 384218 INFO (qtp30645254-2444) [ ] o.a.s.u.PeerSync
PeerSync: core=collection1_shard1_replica1 url=http://127.0.0.1:35440/solr
DONE. We have no versions. sync failed.
[junit4] 2> 384220 INFO (qtp6657551-2442) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 384221 INFO (qtp30645254-2444) [ ] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 384221 INFO (qtp30645254-2444) [ ]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 384221 INFO (qtp30645254-2444) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 384224 INFO (qtp30645254-2444) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ shard1
[junit4] 2> 384326 INFO (zkCallback-407-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 384326 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 384375 INFO (qtp30645254-2444) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 384380 INFO (qtp30645254-2444) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2}
status=0 QTime=2019
[junit4] 2> 384480 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 384480 INFO (zkCallback-407-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 384722 INFO (qtp6657551-2440) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=collection1_shard1_replica2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2}
status=0 QTime=2360
[junit4] 2> 384724 INFO (qtp6657551-2448) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 384823 INFO (zkCallback-407-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 384823 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 385725 INFO (qtp6657551-2448) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf1&name=collection1&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=3470
[junit4] 2> 385748 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 385763 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.c.TestSolrConfigHandler going to send config command. path
/config?wt=json , payload: {
[junit4] 2> 'create-requesthandler' : { 'name' : '/dump0', 'class':
'org.apache.solr.handler.DumpRequestHandler' }
[junit4] 2> }
[junit4] 2> 385774 INFO (Thread-583) [ ] o.a.s.c.SolrCore config
update listener called for core collection1_shard1_replica2
[junit4] 2> 385774 INFO (qtp6657551-2445) [ ]
o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted
to ZK [{"create-requesthandler":{
[junit4] 2> "name":"/dump0",
[junit4] 2> "class":"org.apache.solr.handler.DumpRequestHandler"}}]
[junit4] 2> 385774 INFO (Thread-583) [ ] o.a.s.c.SolrCore core reload
collection1_shard1_replica2
[junit4] 2> 385776 INFO (qtp6657551-2445) [ ]
o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 2 replicas to set the
property overlay to be of version 0 for collection collection1
[junit4] 2> 385780 INFO (Thread-583) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 385798 INFO (Thread-583) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 385808 INFO (Thread-583) [ ] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 385809 INFO (Thread-583) [ ] o.a.s.c.CoreContainer
Reloading SolrCore 'collection1_shard1_replica2' using configuration from
collection collection1
[junit4] 2> 385825 INFO (Thread-583) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/collection1_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
[junit4] 2> 385831 INFO (Thread-584) [ ] o.a.s.c.SolrCore config
update listener called for core collection1_shard1_replica1
[junit4] 2> 385834 INFO (Thread-584) [ ] o.a.s.c.SolrCore core reload
collection1_shard1_replica1
[junit4] 2> 385834 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 385845 INFO (Thread-584) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 385852 INFO (Thread-584) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica1] Schema name=minimal
[junit4] 2> 385870 INFO (Thread-584) [ ] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 385872 INFO (Thread-584) [ ] o.a.s.c.CoreContainer
Reloading SolrCore 'collection1_shard1_replica1' using configuration from
collection collection1
[junit4] 2> 385873 INFO (Thread-584) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/collection1_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/./collection1_shard1_replica1/data/]
[junit4] 2> 385879 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 385955 INFO (qtp30645254-2438) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 385956 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 385956 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Trying to update my configs
[junit4] 2> 385956 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore config update listener called for core
collection1_shard1_replica1
[junit4] 2> 385956 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 1 attempts
[junit4] 2> 385956 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 385963 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore core reload collection1_shard1_replica1
[junit4] 2> 385991 INFO (qtp6657551-2437) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 385991 INFO (qtp6657551-2437) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 385991 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Trying to update my configs
[junit4] 2> 385991 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore config update listener called for core
collection1_shard1_replica2
[junit4] 2> 385991 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 1 attempts
[junit4] 2> 385991 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 385999 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore core reload collection1_shard1_replica2
[junit4] 2> 386079 INFO (qtp30645254-2449) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 386079 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 386079 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 386079 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 2 attempts
[junit4] 2> 386079 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386115 INFO (qtp6657551-2440) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 386124 INFO (qtp6657551-2440) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 386125 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 2 attempts
[junit4] 2> 386125 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386125 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 386165 INFO (Thread-584) [ ] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 386166 INFO (Thread-584) [ ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 386167 INFO (Thread-584) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@199ad14[collection1_shard1_replica1] main]
[junit4] 2> 386169 INFO (Thread-584) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 386169 INFO (Thread-584) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 386169 INFO (Thread-584) [ ] o.a.s.s.ZkIndexSchemaReader
Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 386170 INFO (Thread-584) [ ] o.a.s.s.ZkIndexSchemaReader
Current schema version 0 is already the latest
[junit4] 2> 386170 INFO (Thread-584) [ ] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 386171 INFO (Thread-584) [ ] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560023935729270784
[junit4] 2> 386179 INFO (searcherExecutor-906-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@199ad14[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386198 INFO (Thread-584) [ ] o.a.s.u.DefaultSolrCoreState
New IndexWriter is ready to be used.
[junit4] 2> 386198 INFO (Thread-584) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@7baf52[collection1_shard1_replica1] main]
[junit4] 2> 386199 INFO (qtp30645254-2436) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 386199 INFO (searcherExecutor-906-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@7baf52[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386199 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 386199 INFO (qtp30645254-2436) [ ] o.a.s.c.SolrCore
[collection1_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1dc0cd7
[junit4] 2> 386200 INFO (qtp30645254-2436) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica1
[junit4] 2> 386209 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 386212 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 3 attempts
[junit4] 2> 386212 INFO (solrHandlerExecutor-904-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386212 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 386217 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema [collection1_shard1_replica1] Schema name=minimal
[junit4] 2> 386219 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 386227 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1_shard1_replica1' using
configuration from collection collection1
[junit4] 2> 386228 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [[collection1_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/collection1_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/./collection1_shard1_replica1/data/]
[junit4] 2> 386231 INFO (qtp6657551-2448) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 386238 INFO (qtp6657551-2448) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 386240 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 3 attempts
[junit4] 2> 386240 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386240 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 386278 INFO
(OverseerCollectionConfigSetProcessor-97501495695376388-127.0.0.1:34212_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 386346 INFO (Thread-583) [ ] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 386346 INFO (Thread-583) [ ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 386357 INFO (Thread-583) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@1340473[collection1_shard1_replica2] main]
[junit4] 2> 386358 INFO (qtp6657551-2442) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 386358 INFO (qtp6657551-2442) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 386359 INFO (Thread-583) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 386359 INFO (Thread-583) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 386359 INFO (Thread-583) [ ] o.a.s.s.ZkIndexSchemaReader
Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 386359 INFO (qtp30645254-2444) [ ]
o.a.s.h.SolrConfigHandler I already have the expected version 0 of config
[junit4] 2> 386359 INFO (Thread-583) [ ] o.a.s.s.ZkIndexSchemaReader
Current schema version 0 is already the latest
[junit4] 2> 386359 INFO (qtp30645254-2444) [ ]
o.a.s.h.SolrConfigHandler isStale false , resourceloader
org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 386359 INFO (qtp30645254-2444) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 386358 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 386359 INFO (Thread-583) [ ] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 386359 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 4 attempts
[junit4] 2> 386370 INFO (solrHandlerExecutor-904-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386370 INFO (Thread-583) [ ] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560023935937937408
[junit4] 2> 386371 INFO (searcherExecutor-905-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@1340473[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386373 INFO (Thread-583) [ ] o.a.s.u.DefaultSolrCoreState
New IndexWriter is ready to be used.
[junit4] 2> 386374 INFO (Thread-583) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@15200e1[collection1_shard1_replica2] main]
[junit4] 2> 386374 INFO (searcherExecutor-905-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@15200e1[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386381 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 386388 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema [collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 386390 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 386391 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1_shard1_replica2' using
configuration from collection collection1
[junit4] 2> 386392 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [[collection1_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/collection1_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
[junit4] 2> 386474 INFO (qtp6657551-2437) [ ]
o.a.s.h.SolrConfigHandler I already have the expected version 0 of config
[junit4] 2> 386475 INFO (qtp6657551-2437) [ ]
o.a.s.h.SolrConfigHandler isStale false , resourceloader
org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 386475 INFO (qtp6657551-2437) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 386476 INFO (qtp6657551-2445) [ ]
o.a.s.h.SolrConfigHandler Took 701.0ms to set the property overlay to be of
version 0 for collection collection1
[junit4] 2> 386476 INFO (qtp6657551-2445) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config params={wt=json}
status=0 QTime=711
[junit4] 2> 386476 INFO (qtp6657551-2445) [ ] o.a.s.c.SolrCore
[collection1_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@4955d7
[junit4] 2> 386479 INFO (qtp6657551-2445) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica2
[junit4] 2> 386479 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.c.TestSolrConfigHandler going to send config command. path
/config?wt=json , payload: {
[junit4] 2> 'create-requesthandler' : { 'name' : '/dump1', 'class':
'org.apache.solr.handler.DumpRequestHandler', 'useParams':'x' }
[junit4] 2> }
[junit4] 2> 386484 INFO (qtp6657551-2504) [ ] o.a.s.c.ZkController
Persisted config data to node /configs/conf1/configoverlay.json
[junit4] 2> 386484 INFO (qtp6657551-2504) [ ]
o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted
to ZK [{"create-requesthandler":{
[junit4] 2> "name":"/dump1",
[junit4] 2> "class":"org.apache.solr.handler.DumpRequestHandler",
[junit4] 2> "useParams":"x"}}]
[junit4] 2> 386485 INFO (qtp6657551-2504) [ ]
o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 2 replicas to set the
property overlay to be of version 1 for collection collection1
[junit4] 2> 386502 INFO (Thread-585) [ ] o.a.s.c.SolrCore config
update listener called for core collection1_shard1_replica2
[junit4] 2> 386503 INFO (Thread-585) [ ] o.a.s.c.SolrCore core reload
collection1_shard1_replica2
[junit4] 2> 386538 INFO (Thread-586) [ ] o.a.s.c.SolrCore config
update listener called for core collection1_shard1_replica1
[junit4] 2> 386539 INFO (Thread-586) [ ] o.a.s.c.SolrCore core reload
collection1_shard1_replica1
[junit4] 2> 386539 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386546 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 386628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 386643 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1a79c2a[collection1_shard1_replica1] main]
[junit4] 2> 386644 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 386645 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 386645 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 386645 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 386645 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 386646 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1560023936227344384
[junit4] 2> 386649 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 386650 INFO (searcherExecutor-910-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@1a79c2a[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386651 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@b8f10b[collection1_shard1_replica1]
main]
[junit4] 2> 386653 INFO (qtp6657551-2437) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 386655 INFO (searcherExecutor-910-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@b8f10b[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386655 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@f60040
[junit4] 2> 386656 INFO (qtp30645254-2439) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 386659 INFO (qtp6657551-2437) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 386666 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Trying to update my configs
[junit4] 2> 386666 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore config update listener called for core
collection1_shard1_replica2
[junit4] 2> 386671 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 386671 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 1 attempts
[junit4] 2> 386671 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386720 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore core reload collection1_shard1_replica2
[junit4] 2> 386731 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Trying to update my configs
[junit4] 2> 386731 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica1
[junit4] 2> 386731 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore config update listener called for core
collection1_shard1_replica1
[junit4] 2> 386731 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 1 attempts
[junit4] 2> 386731 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386732 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore core reload collection1_shard1_replica1
[junit4] 2> 386747 INFO (Thread-586) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 386765 INFO (Thread-586) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica1] Schema name=minimal
[junit4] 2> 386771 INFO (Thread-586) [ ] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 386772 INFO (Thread-586) [ ] o.a.s.c.CoreContainer
Reloading SolrCore 'collection1_shard1_replica1' using configuration from
collection collection1
[junit4] 2> 386776 INFO (Thread-586) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/collection1_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/./collection1_shard1_replica1/data/]
[junit4] 2> 386789 INFO (qtp30645254-2444) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 386790 INFO (qtp30645254-2444) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 386790 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 386790 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 2 attempts
[junit4] 2> 386790 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386810 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 386810 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 386811 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@82f625[collection1_shard1_replica2]
main]
[junit4] 2> 386812 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 386812 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 386812 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 386813 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 386813 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 386813 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1560023936402456576
[junit4] 2> 386816 INFO (searcherExecutor-914-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@82f625[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386823 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 386823 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1da29ce[collection1_shard1_replica2] main]
[junit4] 2> 386824 INFO (searcherExecutor-914-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@1da29ce[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386832 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 386870 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema [collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 386875 INFO (qtp6657551-2440) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 386876 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 386879 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1_shard1_replica2' using
configuration from collection collection1
[junit4] 2> 386880 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [[collection1_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/collection1_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
[junit4] 2> 386903 INFO (qtp6657551-2440) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=29
[junit4] 2> 386904 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 2 attempts
[junit4] 2> 386904 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386904 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Trying to update my configs
[junit4] 2> 386904 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore config update listener called for core
collection1_shard1_replica2
[junit4] 2> 386905 INFO (qtp30645254-2449) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 386905 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore core reload collection1_shard1_replica2
[junit4] 2> 386918 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 386919 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 3 attempts
[junit4] 2> 386919 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 386919 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 387015 INFO (qtp6657551-2448) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 387019 INFO (qtp6657551-2448) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 387019 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 3 attempts
[junit4] 2> 387019 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387020 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 387035 INFO (qtp30645254-2436) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 387035 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 387035 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 387035 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 4 attempts
[junit4] 2> 387035 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387126 INFO (qtp6657551-2445) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 387126 INFO (qtp6657551-2445) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 387126 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 387126 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 4 attempts
[junit4] 2> 387126 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387145 INFO (qtp30645254-2439) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 387150 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 387151 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:35440/solr/collection1_shard1_replica1/ for prop overlay
after 5 attempts
[junit4] 2> 387151 INFO (solrHandlerExecutor-915-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387158 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 387166 INFO (Thread-586) [ ] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 387167 INFO (Thread-586) [ ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 387178 INFO (Thread-586) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@122d25a[collection1_shard1_replica1] main]
[junit4] 2> 387179 INFO (Thread-586) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 387179 INFO (Thread-586) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 387179 INFO (Thread-586) [ ] o.a.s.s.ZkIndexSchemaReader
Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 387179 INFO (Thread-586) [ ] o.a.s.s.ZkIndexSchemaReader
Current schema version 0 is already the latest
[junit4] 2> 387180 INFO (Thread-586) [ ] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 387180 INFO (Thread-586) [ ] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560023936787283968
[junit4] 2> 387183 INFO (Thread-586) [ ] o.a.s.u.DefaultSolrCoreState
New IndexWriter is ready to be used.
[junit4] 2> 387186 INFO (searcherExecutor-919-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@122d25a[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387187 INFO (Thread-586) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@92f296[collection1_shard1_replica1] main]
[junit4] 2> 387187 INFO (Thread-586) [ ] o.a.s.c.SolrCore
[collection1_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1668d5c
[junit4] 2> 387187 INFO (searcherExecutor-919-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@92f296[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387187 INFO (Thread-586) [ ] o.a.s.m.SolrMetricManager
Closing metric reporters for: solr.core.collection1.shard1.replica1
[junit4] 2> 387194 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 387202 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema [collection1_shard1_replica1] Schema name=minimal
[junit4] 2> 387204 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 387211 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1_shard1_replica1' using
configuration from collection collection1
[junit4] 2> 387212 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [[collection1_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/collection1_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node1/./collection1_shard1_replica1/data/]
[junit4] 2> 387227 INFO (qtp6657551-2437) [ ]
o.a.s.h.SolrConfigHandler expecting overlay version 1 but my version is 0
[junit4] 2> 387235 INFO (qtp6657551-2437) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 387244 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Could not get expectedVersion 1 from
http://127.0.0.1:34212/solr/collection1_shard1_replica2/ for prop overlay
after 5 attempts
[junit4] 2> 387244 INFO (solrHandlerExecutor-915-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387247 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 387295 INFO (qtp30645254-2444) [ ]
o.a.s.h.SolrConfigHandler I already have the expected version 1 of config
[junit4] 2> 387295 INFO (qtp30645254-2444) [ ]
o.a.s.h.SolrConfigHandler isStale false , resourceloader
org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 387295 INFO (qtp30645254-2444) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 387325 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 387326 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 387337 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1a25b1c[collection1_shard1_replica2] main]
[junit4] 2> 387338 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 387338 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 387338 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 387339 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 387339 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 387339 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1560023936954007552
[junit4] 2> 387341 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 387342 INFO (searcherExecutor-923-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@1a25b1c[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387344 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1dcf3c2[collection1_shard1_replica2] main]
[junit4] 2> 387344 INFO (searcherExecutor-923-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@1dcf3c2[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387344 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@3f665b
[junit4] 2> 387345 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica2
[junit4] 2> 387352 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 387355 INFO (qtp6657551-2440) [ ]
o.a.s.h.SolrConfigHandler I already have the expected version 1 of config
[junit4] 2> 387355 INFO (qtp6657551-2440) [ ]
o.a.s.h.SolrConfigHandler isStale false , resourceloader
org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 387355 INFO (qtp6657551-2440) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={overlay=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 387356 INFO (qtp6657551-2504) [ ]
o.a.s.h.SolrConfigHandler Took 871.0ms to set the property overlay to be of
version 1 for collection collection1
[junit4] 2> 387356 INFO (qtp6657551-2504) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config params={wt=json}
status=0 QTime=876
[junit4] 2> 387356 INFO (qtp6657551-2504) [ ] o.a.s.c.SolrCore
[collection1_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@131e333
[junit4] 2> 387357 INFO (qtp6657551-2504) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica2
[junit4] 2> 387357 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):90
[junit4] 2> 387358 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 387358 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.c.TestSolrConfigHandler going to send config command. path
/config/params?wt=json , payload: {
[junit4] 2> 'set' : {'x': { 'a':'A val',
[junit4] 2> 'b': 'B val'}
[junit4] 2> }
[junit4] 2> }
[junit4] 2> 387362 INFO (qtp6657551-2442) [ ]
o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 2 replicas to set the
property params to be of version 0 for collection collection1
[junit4] 2> 387363 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema [collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 387365 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 387366 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1_shard1_replica2' using
configuration from collection collection1
[junit4] 2> 387367 INFO (Thread-587) [ ] o.a.s.c.SolrCore config
update listener called for core collection1_shard1_replica1
[junit4] 2> 387368 INFO (Thread-587) [ ] o.a.s.c.RequestParams conf
resource params.json loaded . version : 0
[junit4] 2> 387368 INFO (Thread-587) [ ] o.a.s.c.RequestParams request
params refreshed to version 0
[junit4] 2> 387370 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [[collection1_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/collection1_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
[junit4] 2> 387391 INFO (Thread-588) [ ] o.a.s.c.SolrCore config
update listener called for core collection1_shard1_replica2
[junit4] 2> 387392 INFO (Thread-588) [ ] o.a.s.c.RequestParams conf
resource params.json loaded . version : 0
[junit4] 2> 387392 INFO (Thread-588) [ ] o.a.s.c.RequestParams request
params refreshed to version 0
[junit4] 2> 387407 INFO (solrHandlerExecutor-931-thread-1) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387447 INFO (solrHandlerExecutor-931-thread-2) [ ]
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 387544 INFO (qtp30645254-2441) [ ]
o.a.s.h.SolrConfigHandler I already have the expected version 0 of params
[junit4] 2> 387544 INFO (qtp30645254-2441) [ ]
o.a.s.h.SolrConfigHandler isStale false , resourceloader
org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 387544 INFO (qtp30645254-2441) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/znodeVersion
params={params=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 387571 INFO (qtp6657551-2440) [ ]
o.a.s.h.SolrConfigHandler I already have the expected version 0 of params
[junit4] 2> 387571 INFO (qtp6657551-2440) [ ]
o.a.s.h.SolrConfigHandler isStale false , resourceloader
org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 387572 INFO (qtp6657551-2440) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/znodeVersion
params={params=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 387577 INFO (qtp6657551-2442) [ ]
o.a.s.h.SolrConfigHandler Took 214.0ms to set the property params to be of
version 0 for collection collection1
[junit4] 2> 387577 INFO (qtp6657551-2442) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/params
params={wt=json} status=0 QTime=218
[junit4] 2> 387580 INFO (qtp6657551-2504) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/params
params={wt=json} status=0 QTime=0
[junit4] 2> 387590 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/config/overlay
params={wt=json} status=0 QTime=0
[junit4] 2> 387592 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump0
params={wt=json&useParams=x} status=0 QTime=0
[junit4] 2> 387607 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 387607 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 387626 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1eb94f8[collection1_shard1_replica1] main]
[junit4] 2> 387627 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 387628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 387628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 387628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 387628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 387628 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1560023937257046016
[junit4] 2> 387631 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 387639 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump0
params={a=fomrequest&wt=json&useParams=x} status=0 QTime=0
[junit4] 2> 387639 INFO (searcherExecutor-927-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@1eb94f8[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387639 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@15d448e[collection1_shard1_replica1] main]
[junit4] 2> 387640 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1c10827
[junit4] 2> 387641 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica1
[junit4] 2> 387643 INFO (searcherExecutor-927-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher
Searcher@15d448e[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387644 INFO (qtp6657551-2504) [ ] o.a.s.c.S.Request
[collection1_shard1_replica2] webapp=/solr path=/config/overlay
params={wt=json} status=0 QTime=0
[junit4] 2> 387645 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 387752 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 387853 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 387919 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 387919 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 387931 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@13dbf8e[collection1_shard1_replica2] main]
[junit4] 2> 387932 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 387933 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 387933 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/conf1/managed-schema
[junit4] 2> 387933 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 387933 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 387933 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1560023937576861696
[junit4] 2> 387937 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 387939 INFO (searcherExecutor-932-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@13dbf8e[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387940 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1b7c633[collection1_shard1_replica2] main]
[junit4] 2> 387940 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@1fd038d
[junit4] 2> 387940 INFO (searcherExecutor-932-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@1b7c633[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 387943 INFO (SolrConfigHandler-refreshconf) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica2
[junit4] 2> 387950 INFO (Thread-585) [ ] o.a.s.c.RequestParams conf
resource params.json loaded . version : 0
[junit4] 2> 387950 INFO (Thread-585) [ ] o.a.s.c.RequestParams request
params refreshed to version 0
[junit4] 2> 387952 INFO (Thread-585) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 387954 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 387970 INFO (Thread-585) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 387983 INFO (Thread-585) [ ] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 387983 INFO (Thread-585) [ ] o.a.s.c.CoreContainer
Reloading SolrCore 'collection1_shard1_replica2' using configuration from
collection collection1
[junit4] 2> 387984 INFO (Thread-585) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/collection1_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
[junit4] 2> 388056 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388157 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388240 INFO (Thread-585) [ ] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 388240 INFO (Thread-585) [ ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 388243 INFO (Thread-585) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@1250540[collection1_shard1_replica2] main]
[junit4] 2> 388244 INFO (Thread-585) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 388245 INFO (Thread-585) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 388245 INFO (Thread-585) [ ] o.a.s.s.ZkIndexSchemaReader
Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 388245 INFO (Thread-585) [ ] o.a.s.s.ZkIndexSchemaReader
Current schema version 0 is already the latest
[junit4] 2> 388245 INFO (Thread-585) [ ] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 388245 INFO (Thread-585) [ ] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560023937904017408
[junit4] 2> 388250 INFO (Thread-585) [ ] o.a.s.u.DefaultSolrCoreState
New IndexWriter is ready to be used.
[junit4] 2> 388255 INFO (searcherExecutor-939-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@1250540[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 388255 INFO (Thread-585) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@16af9b2[collection1_shard1_replica2] main]
[junit4] 2> 388256 INFO (Thread-585) [ ] o.a.s.c.SolrCore
[collection1_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@1a4d357
[junit4] 2> 388256 INFO (Thread-585) [ ] o.a.s.m.SolrMetricManager
Closing metric reporters for: solr.core.collection1.shard1.replica2
[junit4] 2> 388256 INFO (searcherExecutor-939-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher
Searcher@16af9b2[collection1_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 388263 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388375 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388476 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388577 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388687 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388788 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388889 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 388990 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389091 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389193 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389294 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389395 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389496 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389597 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389699 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389800 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 389901 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390005 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390113 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390216 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390317 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390426 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390529 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390630 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390735 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390837 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 390939 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391040 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391143 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391245 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391347 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391448 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391549 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391650 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391751 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391852 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 391957 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392059 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392160 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392261 INFO (qtp30645254-2438) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392361 INFO (qtp30645254-2449) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392464 INFO (qtp30645254-2439) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392566 INFO (qtp30645254-2436) [ ] o.a.s.c.S.Request
[collection1_shard1_replica1] webapp=/solr path=/dump1 params={wt=json}
status=0 QTime=0
[junit4] 2> 392668 INFO
(TEST-TestReqParamsAPI.test-seed#[69FE7569E78A71B2]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReqParamsAPI
-Dtests.method=test -Dtests.seed=69FE7569E78A71B2 -Dtests.multiplier=3
-Dtests.slow=true -Dtests.locale=fr-MQ -Dtests.timezone=Asia/Baghdad
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 6.95s J1 | TestReqParamsAPI.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected
value 'A val' for path 'params/a' full output: {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "params":{"wt":"json"},
[junit4] > "context":{
[junit4] > "webapp":"/solr",
[junit4] > "path":"/dump1",
[junit4] > "httpMethod":"GET"}}, from server:
http://127.0.0.1:35440/solr/collection1_shard1_replica1
[junit4] > at
__randomizedtesting.SeedInfo.seed([69FE7569E78A71B2:E1AA4AB349761C4A]:0)
[junit4] > at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
[junit4] > at
org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:152)
[junit4] > at
org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:69)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:543)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 392672 INFO (jetty-launcher-398-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@167696b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 392672 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=18684898
[junit4] 2> 392673 INFO (jetty-launcher-398-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@11983f6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 392673 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=24004920
[junit4] 2> 392674 INFO (coreCloseExecutor-943-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@156099
[junit4] 2> 392676 INFO (coreCloseExecutor-944-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@13936e2
[junit4] 2> 392677 INFO (coreCloseExecutor-944-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica1
[junit4] 2> 392677 INFO (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 392678 WARN (zkCallback-409-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 392678 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15a6517fffe0006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 392679 INFO (jetty-launcher-398-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 392679 INFO (coreCloseExecutor-943-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1.shard1.replica2
[junit4] 2> 392679 INFO (jetty-launcher-398-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@d88bcc{/solr,null,UNAVAILABLE}
[junit4] 2> 392680 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=97501495695376388-127.0.0.1:34212_solr-n_0000000000) closing
[junit4] 2> 392680 INFO (zkCallback-407-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 392680 INFO
(OverseerStateUpdate-97501495695376388-127.0.0.1:34212_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34212_solr
[junit4] 2> 392681 INFO (zkCallback-407-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 394181 WARN (zkCallback-407-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 394181 INFO (jetty-launcher-398-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 394182 INFO (jetty-launcher-398-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@74c1a8{/solr,null,UNAVAILABLE}
[junit4] 2> 394184 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34328 34328
[junit4] 2> 394233 INFO (Thread-562) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:34328 34328
[junit4] 2> 394234 WARN (Thread-562) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/configs/conf1/managed-schema
[junit4] 2> 8 /solr/configs/conf1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/overseer/queue
[junit4] 2> 9 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 394234 INFO
(SUITE-TestReqParamsAPI-seed#[69FE7569E78A71B2]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReqParamsAPI_69FE7569E78A71B2-001
[junit4] 2> Feb 22, 2017 9:12:48 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1909, maxMBSortInHeap=5.612291769527873,
sim=RandomSimilarity(queryNorm=true): {}, locale=fr-MQ, timezone=Asia/Baghdad
[junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea
(32-bit)/cpus=12,threads=1,free=72281096,total=275775488
[junit4] 2> NOTE: All tests run in this JVM:
[SuggestComponentContextFilterQueryTest, TestObjectReleaseTracker,
TestLeaderInitiatedRecoveryThread, TestFieldSortValues,
TestFieldTypeCollectionResource, TestSolrCloudWithKerberosAlt,
TestSmileRequest, DistributedSuggestComponentTest, TestFastLRUCache,
QueryParsingTest, IndexSchemaRuntimeFieldTest, ExitableDirectoryReaderTest,
ChaosMonkeyNothingIsSafeTest, CdcrVersionReplicationTest, TestRebalanceLeaders,
ConvertedLegacyTest, ZkCLITest, TestPostingsSolrHighlighter,
TestSQLHandlerNonCloud, TestOverriddenPrefixQueryForCustomFieldType,
TestDistributedGrouping, DistribDocExpirationUpdateProcessorTest,
TestDistribIDF, TestReloadDeadlock, TestImpersonationWithHadoopAuth,
TestReqParamsAPI]
[junit4] Completed [107/693 (1!)] on J1 in 12.98s, 1 test, 1 failure <<<
FAILURES!
[...truncated 53541 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
