You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/12/18 08:49:19 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-14-ea+27) - Build
# 25172 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25172/
Java: 64bit/jdk-14-ea+27 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
All tests passed
Build Log:
[...truncated 569 lines...]
[junit4] JVM J2: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J2-20191218_083121_51113809133018400636834.sysout
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # SIGSEGV (0xb) at pc=0x00007f95321bed20, pid=16620, tid=17797
[junit4] #
[junit4] # JRE version: OpenJDK Runtime Environment (14.0+27) (build 14-ea+27-1339)
[junit4] # Java VM: OpenJDK 64-Bit Server VM (14-ea+27-1339, mixed mode, tiered, z gc, linux-amd64)
[junit4] # Problematic frame:
[junit4] # V [libjvm.so+0xaf2d20] Node::uncast(bool) const+0x0
[junit4] #
[junit4] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J2/hs_err_pid16620.log
[junit4] #
[junit4] # Compiler replay data is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J2/replay_pid16620.log
[junit4] #
[junit4] # If you would like to submit a bug report, please visit:
[junit4] # https://bugreport.java.com/bugreport/crash.jsp
[junit4] #
[junit4] <<< JVM J2: EOF ----
[...truncated 65 lines...]
[junit4] JVM J0: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20191218_083121_5105031942026616959431.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # SIGSEGV (0xb) at pc=0x00007fdc14fbf5a8, pid=16622, tid=17903
[junit4] #
[junit4] # JRE version: OpenJDK Runtime Environment (14.0+27) (build 14-ea+27-1339)
[junit4] # Java VM: OpenJDK 64-Bit Server VM (14-ea+27-1339, mixed mode, tiered, z gc, linux-amd64)
[junit4] # Problematic frame:
[junit4] # V [libjvm.so+0x9d85a8] IdealLoopTree::est_loop_flow_merge_sz() const+0x158
[junit4] #
[junit4] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J0/hs_err_pid16622.log
[junit4] [thread 17908 also had an error]
[junit4] #
[junit4] # Compiler replay data is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J0/replay_pid16622.log
[junit4] #
[junit4] # If you would like to submit a bug report, please visit:
[junit4] # https://bugreport.java.com/bugreport/crash.jsp
[junit4] #
[junit4] <<< JVM J0: EOF ----
[...truncated 1075 lines...]
[junit4] ERROR: JVM J0 ended with an exception, command line: /home/jenkins/tools/java/64bit/jdk-14-ea+27/bin/java -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps -ea -esa --illegal-access=deny -Dtests.prefix=tests -Dtests.seed=C5C4986257252DBC -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=9.0.0 -Dtests.cleanthreads=perMethod -Djava.util.logging.config.file=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=3 -DtempDir=./temp -Djava.io.tmpdir=./temp -Dcommon.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene -Dclover.db.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/clover/db -Djava.security.policy=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=9.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Dtests.src.home=/home/jenkins/workspace/Lucene-Solr-master-Linux -Djava.security.egd=file:/dev/./urandom -Djunit4.childvm.cwd=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J0 -Djunit4.tempDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp -Djunit4.childvm.id=0 -Djunit4.childvm.count=3 -Dfile.encoding=ISO-8859-1 -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dtests.filterstacks=true -Dtests.leaveTemporary=false -Dtests.badapples=false -classpath /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/hamcrest-core-1.3.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/junit-4.12.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.7.5.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/classes/test:/home/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.7.5.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20191218_083121_5106072793301447686528.events @/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20191218_083121_51016805212527729941035.suites -stdin
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20191218_083121_5105031942026616959431.sysout
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1542)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:123)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:997)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:994)
[junit4] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
[junit4] at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] ERROR: JVM J2 ended with an exception, command line: /home/jenkins/tools/java/64bit/jdk-14-ea+27/bin/java -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps -ea -esa --illegal-access=deny -Dtests.prefix=tests -Dtests.seed=C5C4986257252DBC -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=9.0.0 -Dtests.cleanthreads=perMethod -Djava.util.logging.config.file=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=3 -DtempDir=./temp -Djava.io.tmpdir=./temp -Dcommon.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene -Dclover.db.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/clover/db -Djava.security.policy=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=9.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Dtests.src.home=/home/jenkins/workspace/Lucene-Solr-master-Linux -Djava.security.egd=file:/dev/./urandom -Djunit4.childvm.cwd=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/J2 -Djunit4.tempDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp -Djunit4.childvm.id=2 -Djunit4.childvm.count=3 -Dfile.encoding=ISO-8859-1 -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dtests.filterstacks=true -Dtests.leaveTemporary=false -Dtests.badapples=false -classpath /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/hamcrest-core-1.3.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/junit-4.12.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.7.5.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/classes/test:/home/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.7.5.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J2-20191218_083121_5113943506974856045078.events @/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J2-20191218_083121_51116044798482813149024.suites -stdin
[junit4] ERROR: JVM J2 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J2-20191218_083121_51113809133018400636834.sysout
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1542)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:123)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:997)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:994)
[junit4] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
[junit4] at java.base/java.lang.Thread.run(Thread.java:832)
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:634: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:578: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build.xml:50: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1510: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1037: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20191218_083121_5105031942026616959431.sysout
Total time: 18 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-11.0.4) - Build #
25173 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25173/
Java: 64bit/jdk-11.0.4 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
1 tests failed.
FAILED: org.apache.solr.update.processor.TestNamedUpdateProcessors.test
Error Message:
Captured an uncaught exception in thread: Thread[id=54132, name=Thread-9457, state=RUNNABLE, group=TGRP-TestNamedUpdateProcessors]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=54132, name=Thread-9457, state=RUNNABLE, group=TGRP-TestNamedUpdateProcessors]
Caused by: java.lang.OutOfMemoryError: Java heap space
at __randomizedtesting.SeedInfo.seed([B8C2D88ECCED248B]:0)
at java.base/java.io.BufferedWriter.<init>(BufferedWriter.java:99)
at java.base/java.io.BufferedWriter.<init>(BufferedWriter.java:82)
at java.base/java.io.PrintWriter.<init>(PrintWriter.java:164)
at java.base/java.io.PrintWriter.<init>(PrintWriter.java:145)
at java.xml/com.sun.org.apache.xml.internal.utils.DefaultErrorHandler.<init>(DefaultErrorHandler.java:83)
at java.xml/com.sun.org.apache.xml.internal.utils.DefaultErrorHandler.<init>(DefaultErrorHandler.java:75)
at java.xml/com.sun.org.apache.xpath.internal.XPath.<init>(XPath.java:170)
at java.xml/com.sun.org.apache.xpath.internal.XPath.<init>(XPath.java:266)
at java.xml/com.sun.org.apache.xpath.internal.jaxp.XPathImpl.eval(XPathImpl.java:116)
at java.xml/com.sun.org.apache.xpath.internal.jaxp.XPathImpl.evaluate(XPathImpl.java:131)
at org.apache.solr.schema.FieldTypePluginLoader.readAnalyzer(FieldTypePluginLoader.java:207)
at org.apache.solr.schema.FieldTypePluginLoader.create(FieldTypePluginLoader.java:100)
at org.apache.solr.schema.FieldTypePluginLoader.create(FieldTypePluginLoader.java:49)
at org.apache.solr.util.plugin.AbstractPluginLoader.load(AbstractPluginLoader.java:157)
at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:489)
at org.apache.solr.schema.IndexSchema.<init>(IndexSchema.java:177)
at org.apache.solr.schema.IndexSchemaFactory.create(IndexSchemaFactory.java:56)
at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:70)
at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:119)
at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:91)
at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1533)
at org.apache.solr.core.SolrCore.lambda$getConfListener$21(SolrCore.java:3080)
at org.apache.solr.core.SolrCore$$Lambda$553/0x00007f7258ed2168.run(Unknown Source)
at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$14(ZkController.java:2509)
at org.apache.solr.cloud.ZkController$$Lambda$926/0x00007f72508774b0.run(Unknown Source)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15942 lines...]
[junit4] Suite: org.apache.solr.update.processor.TestNamedUpdateProcessors
[junit4] 2> 3307022 INFO (SUITE-TestNamedUpdateProcessors-seed#[B8C2D88ECCED248B]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/data-dir-279-001
[junit4] 2> 3307022 WARN (SUITE-TestNamedUpdateProcessors-seed#[B8C2D88ECCED248B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=142 numCloses=142
[junit4] 2> 3307023 INFO (SUITE-TestNamedUpdateProcessors-seed#[B8C2D88ECCED248B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 3307023 INFO (SUITE-TestNamedUpdateProcessors-seed#[B8C2D88ECCED248B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 3307024 INFO (SUITE-TestNamedUpdateProcessors-seed#[B8C2D88ECCED248B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3307024 INFO (SUITE-TestNamedUpdateProcessors-seed#[B8C2D88ECCED248B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /dfpz/
[junit4] 2> 3307026 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3307031 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3307031 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3307131 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer start zk server on port:46815
[junit4] 2> 3307131 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46815
[junit4] 2> 3307131 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46815
[junit4] 2> 3307131 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46815
[junit4] 2> 3307145 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3307167 INFO (zkConnectionManagerCallback-18899-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3307167 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3307186 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3307193 INFO (zkConnectionManagerCallback-18901-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3307193 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3307211 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 3307223 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 3307224 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3307225 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 3307225 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 3307226 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 3307226 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 3307227 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 3307228 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3307228 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 3307229 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 3307234 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3307254 INFO (zkConnectionManagerCallback-18905-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3307254 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3307355 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 3307556 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3307556 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3307557 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3307557 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3307557 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+11
[junit4] 2> 3307565 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3307565 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3307565 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 3307566 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c1d6468{/dfpz,null,AVAILABLE}
[junit4] 2> 3307567 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c0dbfa4{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:46331}
[junit4] 2> 3307567 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.s.Server Started @3307684ms
[junit4] 2> 3307567 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dfpz, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/tempDir-001/control/data, hostPort=46331, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/control-001/cores, replicaType=NRT}
[junit4] 2> 3307568 ERROR (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3307568 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3307568 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 3307568 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3307568 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3307568 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-18T13:06:40.971132Z
[junit4] 2> 3307571 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3307575 INFO (zkConnectionManagerCallback-18907-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3307575 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3307680 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3307680 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/control-001/solr.xml
[junit4] 2> 3307683 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 3307683 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 3307886 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 3307941 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 3307942 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@12fcc612[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3307942 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@12fcc612[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3307943 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@18708fa2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3307943 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@18708fa2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3307945 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46815/solr
[junit4] 2> 3307946 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3307946 INFO (zkConnectionManagerCallback-18914-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3307946 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3308048 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3308049 INFO (zkConnectionManagerCallback-18916-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3308049 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3308086 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46331_dfpz
[junit4] 2> 3308087 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.c.Overseer Overseer (id=72205050182828037-127.0.0.1:46331_dfpz-n_0000000000) starting
[junit4] 2> 3308089 INFO (OverseerStateUpdate-72205050182828037-127.0.0.1:46331_dfpz-n_0000000000) [n:127.0.0.1:46331_dfpz ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46331_dfpz
[junit4] 2> 3308089 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46331_dfpz
[junit4] 2> 3308090 INFO (zkCallback-18915-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3308091 WARN (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3308101 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3308120 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3308127 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3308127 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3308129 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3308129 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [n:127.0.0.1:46331_dfpz ] 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.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/control-001/cores
[junit4] 2> 3308163 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3308170 INFO (zkConnectionManagerCallback-18925-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3308170 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3308171 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3308172 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46815/solr ready
[junit4] 2> 3308424 INFO (qtp718085499-53726) [n:127.0.0.1:46331_dfpz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46331_dfpz&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3308426 INFO (OverseerThreadFactory-13510-thread-1-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 3308536 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3308536 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 3309555 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3309572 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 3310779 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 3310789 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 3310789 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3310789 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 3310790 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=62.185546875, floorSegmentMB=0.7275390625, forceMergeDeletesPctAllowed=26.01899863228569, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17694889399983202, deletesPctAllowed=24.67339629297046
[junit4] 2> 3310791 WARN (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 3310857 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3310857 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3311266 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3311266 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3311266 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5092638205181852]
[junit4] 2> 3311267 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19bc9fa5[control_collection_shard1_replica_n1] main]
[junit4] 2> 3311268 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3311268 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3311268 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3311269 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1653262940553347072
[junit4] 2> 3311271 INFO (searcherExecutor-13515-thread-1-processing-n:127.0.0.1:46331_dfpz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@19bc9fa5[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3311275 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 3311275 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 3311277 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3311277 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3311277 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46331/dfpz/control_collection_shard1_replica_n1/
[junit4] 2> 3311277 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3311277 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:46331/dfpz/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 3311277 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72205050182828037-core_node2-n_0000000000
[junit4] 2> 3311278 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46331/dfpz/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 3311279 INFO (zkCallback-18915-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 3311280 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3311283 INFO (qtp718085499-53728) [n:127.0.0.1:46331_dfpz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2747
[junit4] 2> 3311289 INFO (qtp718085499-53726) [n:127.0.0.1:46331_dfpz ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 3311381 INFO (zkCallback-18915-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 3311381 INFO (zkCallback-18915-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 3311382 INFO (qtp718085499-53726) [n:127.0.0.1:46331_dfpz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46331_dfpz&wt=javabin&version=2} status=0 QTime=2957
[junit4] 2> 3311382 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 3311495 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3311496 INFO (zkConnectionManagerCallback-18931-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3311496 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3311496 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3311497 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46815/solr ready
[junit4] 2> 3311497 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 3311508 INFO (qtp718085499-53726) [n:127.0.0.1:46331_dfpz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3311511 INFO (OverseerThreadFactory-13510-thread-2-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 3311511 INFO (OverseerCollectionConfigSetProcessor-72205050182828037-127.0.0.1:46331_dfpz-n_0000000000) [n:127.0.0.1:46331_dfpz ] 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> 3311713 WARN (OverseerThreadFactory-13510-thread-2-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 3311716 INFO (qtp718085499-53726) [n:127.0.0.1:46331_dfpz ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 3311716 INFO (qtp718085499-53726) [n:127.0.0.1:46331_dfpz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=208
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 3311717 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 3311850 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-1-001 of type NRT
[junit4] 2> 3311856 WARN (closeThreadPool-18932-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3311870 WARN (closeThreadPool-18932-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3311870 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3311870 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3311870 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+11
[junit4] 2> 3311974 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3311975 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3311975 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 3311991 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d674257{/dfpz,null,AVAILABLE}
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1485da14{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:37463}
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.e.j.s.Server Started @3312380ms
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dfpz, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/tempDir-001/jetty1, hostPort=37463, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-1-001/cores}
[junit4] 2> 3312264 ERROR (closeThreadPool-18932-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3312264 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-18T13:06:45.667556Z
[junit4] 2> 3312265 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3312265 INFO (zkConnectionManagerCallback-18934-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3312265 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3312366 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3312366 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-1-001/solr.xml
[junit4] 2> 3312773 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 3312773 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 3312795 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 3312800 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-2-001 of type NRT
[junit4] 2> 3312804 WARN (closeThreadPool-18932-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3312804 WARN (closeThreadPool-18932-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3312804 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3312804 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3312804 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+11
[junit4] 2> 3312842 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3312842 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3312843 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3312843 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4693646d{/dfpz,null,AVAILABLE}
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@75ca6dd4{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:41357}
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.e.j.s.Server Started @3312960ms
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dfpz, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/tempDir-001/jetty2, hostPort=41357, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-2-001/cores}
[junit4] 2> 3312844 ERROR (closeThreadPool-18932-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3312844 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-18T13:06:46.247616Z
[junit4] 2> 3312846 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3312851 INFO (zkConnectionManagerCallback-18937-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3312851 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3313007 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3313007 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-2-001/solr.xml
[junit4] 2> 3313023 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 3313023 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 3313024 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 3313077 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-3-001 of type NRT
[junit4] 2> 3313088 WARN (closeThreadPool-18932-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3313088 WARN (closeThreadPool-18932-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3313089 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3313089 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3313089 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+11
[junit4] 2> 3313093 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 3313094 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3313094 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3313094 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3313094 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27364f80{/dfpz,null,AVAILABLE}
[junit4] 2> 3313095 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@226ca84a{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:45057}
[junit4] 2> 3313095 INFO (closeThreadPool-18932-thread-3) [ ] o.e.j.s.Server Started @3313212ms
[junit4] 2> 3313095 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dfpz, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/tempDir-001/jetty3, hostPort=45057, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-3-001/cores}
[junit4] 2> 3313096 ERROR (closeThreadPool-18932-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3313096 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3313096 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 3313096 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3313096 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3313096 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-18T13:06:46.499111Z
[junit4] 2> 3313097 WARN (closeThreadPool-18932-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@76152920[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3313097 WARN (closeThreadPool-18932-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@76152920[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3313098 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3313118 WARN (closeThreadPool-18932-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@edc77af[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3313118 WARN (closeThreadPool-18932-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@edc77af[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3313120 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46815/solr
[junit4] 2> 3313130 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3313131 INFO (zkConnectionManagerCallback-18941-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3313131 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3313135 INFO (zkConnectionManagerCallback-18946-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3313135 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3313233 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3313233 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-3-001/solr.xml
[junit4] 2> 3313334 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3313545 INFO (zkConnectionManagerCallback-18948-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3313545 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3313546 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 3313546 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 3313561 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 3313562 INFO (OverseerCollectionConfigSetProcessor-72205050182828037-127.0.0.1:46331_dfpz-n_0000000000) [n:127.0.0.1:46331_dfpz ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 3313567 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3313585 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.ZkController Publish node=127.0.0.1:37463_dfpz as DOWN
[junit4] 2> 3313586 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 3313586 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37463_dfpz
[junit4] 2> 3313587 INFO (zkCallback-18915-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3313587 INFO (zkCallback-18930-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3313587 WARN (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3313661 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3313671 INFO (zkCallback-18947-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3313678 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314019 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314019 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314020 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3314021 INFO (closeThreadPool-18932-thread-1) [n:127.0.0.1:37463_dfpz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-1-001/cores
[junit4] 2> 3314100 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 3314102 INFO (closeThreadPool-18932-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:37463_dfpz
[junit4] 2> 3314108 INFO (TEST-TestNamedUpdateProcessors.test-seed#[B8C2D88ECCED248B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-4-001 of type NRT
[junit4] 2> 3314113 WARN (closeThreadPool-18932-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@18b3728b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314113 WARN (closeThreadPool-18932-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@18b3728b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314118 WARN (closeThreadPool-18932-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3314119 WARN (closeThreadPool-18932-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3314119 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3314119 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3314119 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+11
[junit4] 2> 3314127 WARN (closeThreadPool-18932-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ce93f25[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314127 WARN (closeThreadPool-18932-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ce93f25[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314128 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46815/solr
[junit4] 2> 3314134 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3314134 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3314134 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3314137 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3314143 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2afc3113{/dfpz,null,AVAILABLE}
[junit4] 2> 3314143 INFO (zkConnectionManagerCallback-18959-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3314143 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3314143 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d8c64ee{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:41035}
[junit4] 2> 3314143 INFO (closeThreadPool-18932-thread-4) [ ] o.e.j.s.Server Started @3314260ms
[junit4] 2> 3314143 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dfpz, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/tempDir-001/jetty4, hostPort=41035, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-4-001/cores}
[junit4] 2> 3314144 ERROR (closeThreadPool-18932-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3314144 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3314144 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 3314144 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3314144 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3314144 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-18T13:06:47.547220Z
[junit4] 2> 3314150 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3314151 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 3314154 INFO (zkConnectionManagerCallback-18961-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3314154 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3314155 WARN (closeThreadPool-18932-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@186238b4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314155 WARN (closeThreadPool-18932-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@186238b4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314160 WARN (closeThreadPool-18932-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3e442f45[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314160 WARN (closeThreadPool-18932-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3e442f45[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3314161 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46815/solr
[junit4] 2> 3314163 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3314166 INFO (zkConnectionManagerCallback-18967-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3314167 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3314638 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3314639 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3314641 INFO (zkConnectionManagerCallback-18969-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3314641 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3314644 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3314646 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.ZkController Publish node=127.0.0.1:45057_dfpz as DOWN
[junit4] 2> 3314646 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 3314646 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45057_dfpz
[junit4] 2> 3314647 INFO (zkCallback-18930-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3314647 INFO (zkCallback-18947-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3314647 INFO (zkCallback-18915-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3314648 WARN (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3314651 INFO (zkConnectionManagerCallback-18971-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3314651 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3314653 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 3314655 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.ZkController Publish node=127.0.0.1:41357_dfpz as DOWN
[junit4] 2> 3314655 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 3314655 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41357_dfpz
[junit4] 2> 3314656 INFO (zkCallback-18947-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 3314656 INFO (zkCallback-18930-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 3314656 INFO (zkCallback-18915-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 3314656 WARN (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3314659 INFO (zkCallback-18968-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 3314659 INFO (zkCallback-18970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 3314666 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3314668 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3314686 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314688 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314695 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314695 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314697 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3314697 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314697 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3314697 INFO (closeThreadPool-18932-thread-2) [n:127.0.0.1:41357_dfpz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-2-001/cores
[junit4] 2> 3314698 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3314698 INFO (closeThreadPool-18932-thread-3) [n:127.0.0.1:45057_dfpz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-3-001/cores
[junit4] 2> 3314704 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 3314704 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-4-001/solr.xml
[junit4] 2> 3314706 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 3314706 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 3314721 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 3314760 INFO (closeThreadPool-18932-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:41357_dfpz
[junit4] 2> 3315121 INFO (closeThreadPool-18932-thread-3) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:45057_dfpz
[junit4] 2> 3315128 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 3315163 WARN (closeThreadPool-18932-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@69a8bbd2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3315163 WARN (closeThreadPool-18932-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@69a8bbd2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3315183 WARN (closeThreadPool-18932-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@768a5ac[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3315183 WARN (closeThreadPool-18932-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@768a5ac[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3315185 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46815/solr
[junit4] 2> 3315214 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3315222 INFO (zkConnectionManagerCallback-18986-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3315223 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3315324 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3315325 INFO (zkConnectionManagerCallback-18988-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3315325 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3315328 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 3315329 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.ZkController Publish node=127.0.0.1:41035_dfpz as DOWN
[junit4] 2> 3315330 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 3315330 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41035_dfpz
[junit4] 2> 3315331 INFO (zkCallback-18947-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3315331 INFO (zkCallback-18970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3315331 INFO (zkCallback-18930-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3315331 INFO (zkCallback-18968-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3315331 INFO (zkCallback-18915-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3315331 WARN (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3315339 INFO (zkCallback-18987-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3315342 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3315355 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3315361 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3315361 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3315362 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3315362 INFO (closeThreadPool-18932-thread-4) [n:127.0.0.1:41035_dfpz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-4-001/cores
[junit4] 2> 3315763 INFO (closeThreadPool-18932-thread-4) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:41035_dfpz
[junit4] 2> 3315881 INFO (qtp1438175279-53781) [n:127.0.0.1:37463_dfpz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:41357_dfpz&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3315887 INFO (qtp1438175279-53902) [n:127.0.0.1:37463_dfpz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:37463_dfpz&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3315895 INFO (qtp1438175279-53905) [n:127.0.0.1:37463_dfpz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:45057_dfpz&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3315904 INFO (qtp1438175279-53779) [n:127.0.0.1:37463_dfpz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:41035_dfpz&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3315919 INFO (OverseerThreadFactory-13510-thread-3-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:41357_dfpz for creating new replica of shard shard1 for collection collection1
[junit4] 2> 3315920 INFO (OverseerThreadFactory-13510-thread-3-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 3315931 INFO (OverseerThreadFactory-13510-thread-4-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:37463_dfpz for creating new replica of shard shard2 for collection collection1
[junit4] 2> 3315932 INFO (OverseerThreadFactory-13510-thread-4-processing-n:127.0.0.1:46331_dfpz) [n:127.0.0.1:46331_dfpz c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 3315999 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3316015 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3317010 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3317025 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3317043 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 3317327 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 3318244 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 3318408 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 3318415 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 3318416 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3318416 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-1-001/cores/collection1_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-1-001/cores/collection1_shard2_replica_n2/data/]
[junit4] 2> 3318418 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=62.185546875, floorSegmentMB=0.7275390625, forceMergeDeletesPctAllowed=26.01899863228569, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17694889399983202, deletesPctAllowed=24.67339629297046
[junit4] 2> 3318418 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 3318418 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56744bd3
[junit4] 2> 3318418 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-2-001/cores/collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.TestNamedUpdateProcessors_B8C2D88ECCED248B-001/shard-2-001/cores/collection1_shard1_replica_n1/data/]
[junit4] 2> 3318419 WARN (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 3318420 INFO (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=62.185546875, floorSegmentMB=0.7275390625, forceMergeDeletesPctAllowed=26.01899863228569, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17694889399983202, deletesPctAllowed=24.67339629297046
[junit4] 2> 3318421 WARN (qtp888629231-53798) [n:127.0.0.1:41357_dfpz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 3318443 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3318443 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3318444 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3318444 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3318445 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5092638205181852]
[junit4] 2> 3318445 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5de80244[collection1_shard2_replica_n2] main]
[junit4] 2> 3318446 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3318446 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3318446 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3318446 INFO (qtp1438175279-53903) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1653262948078977024
[junit4] 2> 3318448 INFO (searcherExecutor-13556-thread-1-processing-n:127.0.0.1:37463_dfpz x:collection1_shard2_replica_n2 c:collection1 s:shard2) [n:127.0.0.1:37463_dfpz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@5de80244[collection1_shard2_replica_n2] main{ExitableDirectoryReader(Uninverting
[...truncated too long message...]
: {blobName=FST50, range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), processors_s=Lucene84, multiDefault=FST50, _root_=BlockTreeOrds(blocksize=128), intDefault=BlockTreeOrds(blocksize=128), type=BlockTreeOrds(blocksize=128), mul_s=FST50, range_facet_l=BlockTreeOrds(blocksize=128), test_s=BlockTreeOrds(blocksize=128), id_i1=FST50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_i_dv=BlockTreeOrds(blocksize=128), md5=BlockTreeOrds(blocksize=128), timestamp=BlockTreeOrds(blocksize=128)}, docValues:{size=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Lucene80), version=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1758, maxMBSortInHeap=5.065054057488258, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5fa6727d), locale=dua, timezone=Pacific/Ponape
[junit4] 2> NOTE: Linux 5.0.0-36-generic amd64/AdoptOpenJDK 11.0.4 (64-bit)/cpus=8,threads=1,free=148897792,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DeleteLastCustomShardedReplicaTest, DistribDocExpirationUpdateProcessorTest, TestSolrJ, TestCopyFieldCollectionResource, TestInPlaceUpdatesStandalone, TestPullReplica, TestCloudDeleteByQuery, TestLRUStatsCache, TestCollectionAPI, EmbeddedSolrNoSerializeTest, TestSolrCachePerf, TestFastOutputStream, TestRTimerTree, TestLocalFSCloudBackupRestore, MetricsHistoryWithAuthIntegrationTest, NodeLostTriggerTest, SolrCLIZkUtilsTest, AnalyticsQueryTest, TestClusterStateMutator, RegexBytesRefFilterTest, TestMissingGroups, TestImpersonationWithHadoopAuth, RecoveryZkTest, GraphQueryTest, ShardSplitTest, QueryResultKeyTest, BigEndianAscendingWordSerializerTest, TestOverriddenPrefixQueryForCustomFieldType, TestUniqueKeyFieldResource, TestScoreJoinQPNoScore, IndexSchemaTest, SimplePostToolTest, MoveReplicaHDFSFailoverTest, CollectionPropsTest, FieldMutatingUpdateProcessorTest, PeerSyncWithIndexFingerprintCachingTest, TestFiltering, SolrLogAuditLoggerPluginTest, SplitShardTest, TestSolrConfigHandlerCloud, TestDynamicLoadingUrl, TestDeprecatedFilters, BinaryUpdateRequestHandlerTest, TriggerIntegrationTest, TestFieldCacheVsDocValues, TestInPlaceUpdateWithRouteField, ShardsWhitelistTest, TestCryptoKeys, CurrencyFieldTypeTest, SynonymTokenizerTest, BasicFunctionalityTest, TestRuleBasedAuthorizationPlugin, TestUpdate, TestAddFieldRealTimeGet, TestSystemCollAutoCreate, TestManagedSynonymGraphFilterFactory, TestSolrDeletionPolicy1, WordBreakSolrSpellCheckerTest, TestWordDelimiterFilterFactory, TestCloudRecovery2, UnloadDistributedZkTest, TestCollationFieldDocValues, TestTrackingShardHandlerFactory, SuggesterTest, TestAtomicUpdateErrorCases, PathHierarchyTokenizerFactoryTest, AsyncCallRequestStatusResponseTest, TestCloudManagedSchema, DistributedTermsComponentTest, TestJettySolrRunner, ConjunctionSolrSpellCheckerTest, MoreLikeThisComponentTest, TestDistributedMap, XmlUpdateRequestHandlerTest, ResponseBuilderTest, SolrInfoBeanTest, ForceLeaderWithTlogReplicasTest, SolrJmxReporterTest, TestSlowCompositeReaderWrapper, FastVectorHighlighterTest, NodeAddedTriggerIntegrationTest, StatelessScriptUpdateProcessorFactoryTest, NoCacheHeaderTest, MinimalSchemaTest, TestStressCloudBlindAtomicUpdates, ZkShardTermsTest, TestAuthenticationFramework, ClassificationUpdateProcessorTest, BasicDistributedZk2Test, TestRandomFlRTGCloud, LeaderVoteWaitTimeoutTest, EnumFieldTest, TestCursorMarkWithoutUniqueKey, TestPolicyCloud, DistributedFacetExistsSmallTest, TestDocTermOrds, TestCodecSupport, TestDFISimilarityFactory, DimensionalRoutedAliasUpdateProcessorTest, TestRTGBase, TestSolrCloudWithDelegationTokens, CreateCollectionCleanupTest, ScriptEngineTest, PackageManagerCLITest, DistributedQueryElevationComponentTest, TestFieldTypeResource, TestSolrCloudSnapshots, DistributedMLTComponentTest, DataDrivenBlockJoinTest, IndexBasedSpellCheckerTest, RulesTest, AutoscalingHistoryHandlerTest, TestExtendedDismaxParser, TermVectorComponentTest, SimpleMLTQParserTest, TestNonDefinedSimilarityFactory, ConcurrentDeleteAndCreateCollectionTest, MaxSizeAutoCommitTest, SchemaVersionSpecificBehaviorTest, HighlighterTest, CollectionsAPIAsyncDistributedZkTest, PreAnalyzedUpdateProcessorTest, OverseerStatusTest, CoreAdminOperationTest, RootFieldTest, TestRandomFaceting, HdfsChaosMonkeyNothingIsSafeTest, TestCustomSort, LeaderElectionContextKeyTest, TestFunctionQuery, TestNumericTerms32, TestSweetSpotSimilarityFactory, BufferStoreTest, NumberUtilsTest, TestNumericRangeQuery32, HttpTriggerListenerTest, CacheHeaderTest, SpellPossibilityIteratorTest, CheckHdfsIndexTest, ChaosMonkeySafeLeaderWithPullReplicasTest, TestQuerySenderListener, LeaderFailoverAfterPartitionTest, ZkControllerTest, ConfigureRecoveryStrategyTest, ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, DeleteReplicaTest, DistributedVersionInfoTest, HttpPartitionTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, RemoteQueryErrorTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, RestartWhileUpdatingTest, RollingRestartTest, RoutingToNodesWithPropertiesTest, SSLMigrationTest, SaslZkACLProviderTest, ShardRoutingCustomTest, SharedFSAutoReplicaFailoverTest, TestCloudPhrasesIdentificationComponent, TestCloudPseudoReturnFields, TestClusterProperties, TestConfigSetsAPIExclusivity, TestDynamicFieldNamesIndexCorrectly, TestLeaderElectionWithEmptyReplica, TestRebalanceLeaders, TestShortCircuitedRequests, TestSizeLimitedDistributedMap, TestStressLiveNodes, IndexSizeTriggerTest, MetricTriggerIntegrationTest, RestoreTriggerStateTest, ScheduledTriggerTest, SearchRateTriggerTest, TestSimTriggerIntegration, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, HdfsBasicDistributedZk2Test, HdfsNNFailoverTest, ZkStateWriterTest, SolrCoreCheckLockOnStartupTest, TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSetImmutable, TestConfigSetProperties, TestConfigSets, TestCoreContainer, TestCorePropertiesReload, TestCustomStream, TestShardHandlerFactory, TestSimpleTextCodec, TestSolrIndexConfig, TestSolrXml, TestXIncludeConfig, FieldAnalysisRequestHandlerTest, MoreLikeThisHandlerTest, RequestLoggingTest, SearchHandlerTest, TestCSVLoader, TestConfigReload, TestReplicationHandlerBackup, TestReplicationHandlerDiskOverFlow, TestReqParamsAPI, TestRestoreCore, CoreMergeIndexesAdminHandlerTest, DaemonStreamApiTest, IndexSizeEstimatorTest, InfoHandlerTest, LoggingHandlerTest, LukeRequestHandlerTest, MBeansHandlerTest, StatsReloadRaceTest, CustomTermsComponentTest, SuggestComponentContextFilterQueryTest, TestDistributedStatsComponentCardinality, TestExplainDocTransformer, TestSubQueryTransformerDistrib, TestFieldResource, TestSchemaVersionResource, BooleanFieldTest, ChangedSchemaMergeTest, DateRangeFieldTest, DocValuesMissingTest, DocValuesMultiTest, OpenExchangeRatesOrgProviderTest, PreAnalyzedFieldManagedSchemaCloudTest, ResolveAnalyzerByNameTest, TestManagedSchemaAPI, TestManagedSchemaThreadSafety, TestOmitPositions, TestPointFields, TestLegacyNumericRangeQueryBuilder, TestPayloadScoreQParserPlugin, TestQueryTypes, TestReRankQParserPlugin, TestRecovery, TestReload, TestSolr4Spatial, TestStandardQParsers, TestStressLucene, TestStressUserVersions, TestSurroundQueryParser, TestTrieFacet, TestXmlQParser, RangeFacetCloudTest, TestCloudJSONFacetSKG, AuditLoggerPluginTest, BasicAuthIntegrationTest, JWTAuthPluginTest, JWTVerificationkeyResolverTest, MultiDestinationAuditLoggerTest, TestAnalyzeInfixSuggestions, TestAnalyzedSuggestions, TestFileDictionaryLookup, TestHighFrequencyDictionaryFactory, BlockCacheTest, TestFieldCacheReopen, TestFieldCacheSort, AddBlockUpdateTest, SolrIndexConfigTest, SolrIndexMetricsTest, UpdateLogTest, UpdateParamsTest, VersionInfoTest, AtomicUpdateProcessorFactoryTest, AtomicUpdateRemovalJavabinTest, AtomicUpdatesTest, NestedAtomicUpdateTest, TestNamedUpdateProcessors]
[junit4] Completed [890/891 (1!)] on J1 in 44.11s, 1 test, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] JVM J1: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J1-20191218_121133_2511758713951127556670.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to /home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps/java_pid27657.hprof ...
[junit4] Heap dump file created [391683987 bytes in 2.878 secs]
[junit4] <<< JVM J1: EOF ----
[...truncated 9520 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:634: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:586: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid27657.hprof
Total time: 106 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2