You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/11/15 02:28:58 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_45) - Build #
3467 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3467/
Java: 64bit/jdk1.7.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.OpenCloseCoreStressTest
Error Message:
10 threads leaked from SUITE scope at org.apache.solr.core.OpenCloseCoreStressTest: 1) Thread[id=6106, name=commitScheduler-4598-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 2) Thread[id=6074, name=commitScheduler-4577-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 3) Thread[id=6100, name=commitScheduler-4589-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 4) Thread[id=6071, name=commitScheduler-4572-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 5) Thread[id=6104, name=commitScheduler-4595-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 6) Thread[id=6073, name=commitScheduler-4576-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 7) Thread[id=6076, name=commitScheduler-4580-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 8) Thread[id=6063, name=commitScheduler-4568-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 9) Thread[id=6102, name=commitScheduler-4592-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 10) Thread[id=6099, name=commitScheduler-4587-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 10 threads leaked from SUITE scope at org.apache.solr.core.OpenCloseCoreStressTest:
1) Thread[id=6106, name=commitScheduler-4598-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2) Thread[id=6074, name=commitScheduler-4577-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
3) Thread[id=6100, name=commitScheduler-4589-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
4) Thread[id=6071, name=commitScheduler-4572-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
5) Thread[id=6104, name=commitScheduler-4595-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
6) Thread[id=6073, name=commitScheduler-4576-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
7) Thread[id=6076, name=commitScheduler-4580-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
8) Thread[id=6063, name=commitScheduler-4568-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
9) Thread[id=6102, name=commitScheduler-4592-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
10) Thread[id=6099, name=commitScheduler-4587-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
at __randomizedtesting.SeedInfo.seed([394F48E1455B2220]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.core.OpenCloseCoreStressTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=6106, name=commitScheduler-4598-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 2) Thread[id=6074, name=commitScheduler-4577-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 3) Thread[id=6100, name=commitScheduler-4589-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 4) Thread[id=6071, name=commitScheduler-4572-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 5) Thread[id=6104, name=commitScheduler-4595-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 6) Thread[id=6073, name=commitScheduler-4576-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 7) Thread[id=6076, name=commitScheduler-4580-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 8) Thread[id=6063, name=commitScheduler-4568-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 9) Thread[id=6102, name=commitScheduler-4592-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 10) Thread[id=6099, name=commitScheduler-4587-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=6106, name=commitScheduler-4598-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2) Thread[id=6074, name=commitScheduler-4577-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
3) Thread[id=6100, name=commitScheduler-4589-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
4) Thread[id=6071, name=commitScheduler-4572-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
5) Thread[id=6104, name=commitScheduler-4595-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
6) Thread[id=6073, name=commitScheduler-4576-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
7) Thread[id=6076, name=commitScheduler-4580-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
8) Thread[id=6063, name=commitScheduler-4568-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
9) Thread[id=6102, name=commitScheduler-4592-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
10) Thread[id=6099, name=commitScheduler-4587-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
at __randomizedtesting.SeedInfo.seed([394F48E1455B2220]:0)
Build Log:
[...truncated 10356 lines...]
[junit4] Suite: org.apache.solr.core.OpenCloseCoreStressTest
[junit4] IGNOR/A 0.01s | OpenCloseCoreStressTest.test1HourNew
[junit4] > Assumption #1: 'weekly' test group is disabled (@Weekly)
[junit4] 2> 2138317 T6040 oas.SolrTestCaseJ4.setUp ###Starting test15SecondsOld
[junit4] 2> 2138373 T6040 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2138379 T6040 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54206
[junit4] 2> 2138380 T6040 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2138380 T6040 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2138380 T6040 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_
[junit4] 2> 2138380 T6040 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\'
[junit4] 2> 2138423 T6040 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\solr.xml
[junit4] 2> 2138522 T6040 oasc.CoreContainer.<init> New CoreContainer 381509090
[junit4] 2> 2138522 T6040 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\]
[junit4] 2> 2138524 T6040 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2138525 T6040 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2138525 T6040 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2138525 T6040 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2138525 T6040 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2138526 T6040 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2138526 T6040 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2138526 T6040 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2138527 T6040 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2138530 T6040 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2138531 T6040 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2138531 T6040 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2138596 T6050 oasc.CoreContainer.create Creating SolrCore '00000_core' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core
[junit4] 2> 2138597 T6050 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\'
[junit4] 2> 2138689 T6050 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2138769 T6050 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2138769 T6050 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2138779 T6050 oass.IndexSchema.readSchema [00000_core] Schema name=tiny
[junit4] 2> 2138815 T6050 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2138818 T6050 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2138818 T6050 oasc.SolrCore.<init> [00000_core] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\data\
[junit4] 2> 2138818 T6050 oasc.SolrCore.<init> JMX monitoring not detected for core: 00000_core
[junit4] 2> 2138818 T6050 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\data\
[junit4] 2> 2138818 T6050 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\data\index/
[junit4] 2> 2138818 T6050 oasc.SolrCore.initIndex WARN [00000_core] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\data\index' doesn't exist. Creating new index...
[junit4] 2> 2138819 T6050 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00000_core\data\index
[junit4] 2> 2138820 T6050 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7362737071509415]
[junit4] 2> 2138820 T6050 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@2ab607ba lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49512d79; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2138820 T6050 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2138823 T6050 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2138824 T6050 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2138824 T6050 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2138825 T6050 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2138837 T6050 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2138842 T6050 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2138842 T6050 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2138842 T6050 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=2.4912109375, floorSegmentMB=1.015625, forceMergeDeletesPctAllowed=23.767092820508992, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6546272784567513
[junit4] 2> 2138842 T6050 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@2ab607ba lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49512d79; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2138842 T6050 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2138842 T6050 oass.SolrIndexSearcher.<init> Opening Searcher@203a509 main
[junit4] 2> 2138845 T6050 oasc.CoreContainer.registerCore registering core: 00000_core
[junit4] 2> 2138845 T6051 oasc.SolrCore.registerSearcher [00000_core] Registered new searcher Searcher@203a509 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2138846 T6040 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2138847 T6040 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2138852 T6040 oasc.OpenCloseCoreStressTest.doStress Starting indexing and querying
[junit4] 2> 2138856 T6040 oasc.OpenCloseCoreStressTest.doStress
[junit4] 2>
[junit4] 2>
[junit4] 2>
[junit4] 2>
[junit4] 2> Starting a 15 second cycle, seconds left: 15. Seconds run so far: 0.
[junit4] 2> 2138858 T6053 oasc.OneIndexer.run Starting indexing thread: 6053
[junit4] 2> 2138860 T6056 oasc.OneIndexer.run Starting indexing thread: 6056
[junit4] 2> 2138861 T6057 oasc.OneIndexer.run Starting indexing thread: 6057
[junit4] 2> 2138860 T6054 oasc.OneIndexer.run Starting indexing thread: 6054
[junit4] 2> 2138861 T6055 oasc.OneIndexer.run Starting indexing thread: 6055
[junit4] 2> 2138862 T6058 oasc.OneQuery.run Starting query thread: 6058
[junit4] 2> 2138863 T6059 oasc.OneQuery.run Starting query thread: 6059
[junit4] 2> 2138864 T6060 oasc.OneQuery.run Starting query thread: 6060
[junit4] 2> 2138864 T6062 oasc.OneQuery.run Starting query thread: 6062
[junit4] 2> 2138865 T6061 oasc.OneQuery.run Starting query thread: 6061
[junit4] 2> 2138867 T6044 oasc.CoreContainer.create Creating SolrCore '00003_core' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core
[junit4] 2> 2138873 T6044 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\'
[junit4] 2> 2138870 T6046 oasc.CoreContainer.create Creating SolrCore '00004_core' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core
[junit4] 2> ASYNC NEW_CORE C4546 name=00000_core org.apache.solr.core.SolrCore@27e37fb8
[junit4] 2> 2138882 T6047 C4546 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4]} 0 2
[junit4] 2> 2138883 T6046 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\'
[junit4] 2> 2138930 T6044 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2138956 T6046 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2138976 T6045 oasc.CoreContainer.create Creating SolrCore '00001_core' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core
[junit4] 2> 2138976 T6045 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\'
[junit4] 2> 2139027 T6065 oasc.CoreContainer.create Creating SolrCore '00002_core' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core
[junit4] 2> 2139029 T6065 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\'
[junit4] 2> 2139041 T6046 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2139041 T6046 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2139047 T6046 oass.IndexSchema.readSchema [00004_core] Schema name=tiny
[junit4] 2> 2139058 T6044 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2139058 T6044 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2139083 T6046 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2139084 T6046 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2139084 T6046 oasc.SolrCore.<init> [00004_core] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\data\
[junit4] 2> 2139084 T6046 oasc.SolrCore.<init> JMX monitoring not detected for core: 00004_core
[junit4] 2> 2139085 T6046 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\data\
[junit4] 2> 2139090 T6044 oass.IndexSchema.readSchema [00003_core] Schema name=tiny
[junit4] 2> 2139106 T6045 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2139127 T6046 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\data\index/
[junit4] 2> 2139127 T6046 oasc.SolrCore.initIndex WARN [00004_core] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\data\index' doesn't exist. Creating new index...
[junit4] 2> 2139128 T6046 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00004_core\data\index
[junit4] 2> 2139128 T6046 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7362737071509415]
[junit4] 2> 2139128 T6046 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@2c1f9dc0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b144aaa; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139128 T6046 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139130 T6046 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2139131 T6046 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2139131 T6046 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2139131 T6046 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2139136 T6044 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2139138 T6044 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2139138 T6044 oasc.SolrCore.<init> [00003_core] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\data\
[junit4] 2> 2139138 T6044 oasc.SolrCore.<init> JMX monitoring not detected for core: 00003_core
[junit4] 2> 2139139 T6044 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\data\
[junit4] 2> 2139140 T6046 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2139144 T6046 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2139144 T6046 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2139145 T6044 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\data\index/
[junit4] 2> 2139145 T6044 oasc.SolrCore.initIndex WARN [00003_core] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\data\index' doesn't exist. Creating new index...
[junit4] 2> 2139146 T6046 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=2.4912109375, floorSegmentMB=1.015625, forceMergeDeletesPctAllowed=23.767092820508992, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6546272784567513
[junit4] 2> 2139147 T6044 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00003_core\data\index
[junit4] 2> 2139147 T6044 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7362737071509415]
[junit4] 2> 2139147 T6046 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@2c1f9dc0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b144aaa; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139147 T6046 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139147 T6046 oass.SolrIndexSearcher.<init> Opening Searcher@4d7d631b main
[junit4] 2> 2139148 T6044 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@233a1567 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70470969; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139178 T6044 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139180 T6044 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2139180 T6044 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2139181 T6044 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2139181 T6044 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2139182 T6065 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2139183 T6044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2139178 T6045 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2139186 T6045 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2139192 T6045 oass.IndexSchema.readSchema [00001_core] Schema name=tiny
[junit4] 2> 2139214 T6045 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2139216 T6045 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2139216 T6045 oasc.SolrCore.<init> [00001_core] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\data\
[junit4] 2> 2139217 T6045 oasc.SolrCore.<init> JMX monitoring not detected for core: 00001_core
[junit4] 2> 2139217 T6045 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\data\
[junit4] 2> 2139217 T6045 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\data\index/
[junit4] 2> 2139217 T6045 oasc.SolrCore.initIndex WARN [00001_core] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\data\index' doesn't exist. Creating new index...
[junit4] 2> 2139217 T6045 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00001_core\data\index
[junit4] 2> 2139218 T6045 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7362737071509415]
[junit4] 2> 2139218 T6045 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@67a8e47d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6405f21; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139219 T6045 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139219 T6045 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2139220 T6045 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2139221 T6045 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2139221 T6045 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2139222 T6045 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2139153 T6046 oasc.SolrCores.putTransientCore Opening transient core 00004_core
[junit4] 2> 2139153 T6068 oasc.SolrCore.registerSearcher [00004_core] Registered new searcher Searcher@4d7d631b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2139229 T6044 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2139229 T6044 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2139228 T6046 oasc.CoreContainer.registerCore registering core: 00004_core
[junit4] 2> 2139228 T6045 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2139235 T6045 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2139237 T6065 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-minimal.xml
[junit4] 2> 2139237 T6065 oass.IndexSchema.readSchema Reading Solr Schema from schema-tiny.xml
[junit4] 2> 2139239 T6045 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=2.4912109375, floorSegmentMB=1.015625, forceMergeDeletesPctAllowed=23.767092820508992, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6546272784567513
[junit4] 2> 2139240 T6045 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@67a8e47d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6405f21; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139240 T6045 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139240 T6045 oass.SolrIndexSearcher.<init> Opening Searcher@66f60419 main
[junit4] 2> ASYNC NEW_CORE C4547 name=00004_core org.apache.solr.core.SolrCore@477798bb
[junit4] 2> 2139242 T6046 C4547 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3]} 0 7
[junit4] 2> 2139243 T6044 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=2.4912109375, floorSegmentMB=1.015625, forceMergeDeletesPctAllowed=23.767092820508992, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6546272784567513
[junit4] 2> 2139244 T6044 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@233a1567 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70470969; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139244 T6044 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139244 T6044 oass.SolrIndexSearcher.<init> Opening Searcher@56c78757 main
[junit4] 2> 2139247 T6065 oass.IndexSchema.readSchema [00002_core] Schema name=tiny
[junit4] 2> 2139248 T6044 oasc.SolrCores.putTransientCore Opening transient core 00003_core
[junit4] 2> 2139248 T6044 oasc.CoreContainer.registerCore registering core: 00003_core
[junit4] 2> ASYNC NEW_CORE C4548 name=00003_core org.apache.solr.core.SolrCore@430ccc01
[junit4] 2> 2139256 T6044 C4548 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1]} 0 1
[junit4] 2> 2139270 T6045 oasc.SolrCores.putTransientCore Opening transient core 00001_core
[junit4] 2> 2139270 T6045 oasc.CoreContainer.registerCore registering core: 00001_core
[junit4] 2> ASYNC NEW_CORE C4549 name=00004_core org.apache.solr.core.SolrCore@477798bb
[junit4] 2> 2139277 T6049 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id6]} 0 38
[junit4] 2> ASYNC NEW_CORE C4550 name=00000_core org.apache.solr.core.SolrCore@27e37fb8
[junit4] 2> 2139280 T6049 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id7]} 0 2
[junit4] 2> 2139280 T6070 oasc.SolrCore.registerSearcher [00001_core] Registered new searcher Searcher@66f60419 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> ASYNC NEW_CORE C4551 name=00001_core org.apache.solr.core.SolrCore@785fa585
[junit4] 2> 2139281 T6045 C4551 oasc.SolrCore.execute [00001_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=4
[junit4] 2> 2139283 T6065 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2139283 T6049 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id8]} 0 1
[junit4] 2> 2139285 T6065 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2139285 T6065 oasc.SolrCore.<init> [00002_core] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\data\
[junit4] 2> 2139285 T6065 oasc.SolrCore.<init> JMX monitoring not detected for core: 00002_core
[junit4] 2> 2139286 T6065 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\data\
[junit4] 2> 2139286 T6065 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\data\index/
[junit4] 2> 2139286 T6065 oasc.SolrCore.initIndex WARN [00002_core] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\data\index' doesn't exist. Creating new index...
[junit4] 2> ASYNC NEW_CORE C4552 name=00003_core org.apache.solr.core.SolrCore@430ccc01
[junit4] 2> 2139286 T6072 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id9]} 0 1
[junit4] 2> 2139287 T6066 C4551 oasc.SolrCore.execute [00001_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2139287 T6069 oasc.SolrCore.registerSearcher [00003_core] Registered new searcher Searcher@56c78757 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2139289 T6047 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id5]} 0 1
[junit4] 2> 2139289 T6072 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id11]} 0 2
[junit4] 2> 2139287 T6065 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\OpenCloseCoreStressTest_\00002_core\data\index
[junit4] 2> 2139289 T6064 C4552 oasc.SolrCore.execute [00003_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=2
[junit4] 2> 2139290 T6065 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7362737071509415]
[junit4] 2> 2139295 T6064 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id13]} 0 3
[junit4] 2> 2139295 T6045 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id14]} 0 3
[junit4] 2> 2139295 T6048 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2]} 0 6
[junit4] 2> 2139295 T6066 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id12]} 0 7
[junit4] 2> 2139295 T6065 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@25499abe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3739fc53; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139296 T6065 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139298 T6047 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id16]} 0 1
[junit4] 2> 2139299 T6065 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2139299 T6064 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id18]} 0 1
[junit4] 2> 2139299 T6072 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id17]} 0 2
[junit4] 2> 2139299 T6065 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 2139300 T6065 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2139301 T6065 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2139302 T6045 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id19]} 0 1
[junit4] 2> 2139303 T6065 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2139303 T6045 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id21]} 0 0
[junit4] 2> 2139306 T6065 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2139306 T6065 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2139306 T6045 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id20]} 0 1
[junit4] 2> 2139307 T6065 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=2.4912109375, floorSegmentMB=1.015625, forceMergeDeletesPctAllowed=23.767092820508992, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6546272784567513
[junit4] 2> 2139308 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id22]} 0 2
[junit4] 2> 2139308 T6065 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@25499abe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3739fc53; maxCacheMB=0.8662109375 maxMergeSizeMB=0.9306640625)),segFN=segments_1,generation=1}
[junit4] 2> 2139308 T6065 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2139309 T6065 oass.SolrIndexSearcher.<init> Opening Searcher@130d4907 main
[junit4] 2> 2139310 T6065 oasc.SolrCores.putTransientCore Opening transient core 00002_core
[junit4] 2> 2139310 T6065 oasc.CoreContainer.registerCore registering core: 00002_core
[junit4] 2> 2139310 T6048 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id23]} 0 1
[junit4] 2> 2139311 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id24]} 0 0
[junit4] 2> 2139314 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id25]} 0 2
[junit4] 2> 2139316 T6075 oasc.SolrCore.registerSearcher [00002_core] Registered new searcher Searcher@130d4907 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2139317 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id26]} 0 2
[junit4] 2> ASYNC NEW_CORE C4553 name=00002_core org.apache.solr.core.SolrCore@3856b832
[junit4] 2> 2139318 T6065 C4553 oasc.SolrCore.execute [00002_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2139319 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id27]} 0 3
[junit4] 2> 2139319 T6047 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id28]} 0 3
[junit4] 2> 2139323 T6045 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id31]} 0 1
[junit4] 2> 2139324 T6049 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id10]} 0 4
[junit4] 2> 2139326 T6045 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id29]} 0 2
[junit4] 2> 2139327 T6067 C4553 oasc.SolrCore.execute [00002_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=1
[junit4] 2> 2139327 T6067 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id32]} 0 0
[junit4] 2> 2139329 T6067 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id33]} 0 1
[junit4] 2> 2139331 T6067 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id34]} 0 1
[junit4] 2> 2139333 T6044 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id15]} 0 2
[junit4] 2> 2139333 T6067 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id35]} 0 1
[junit4] 2> 2139323 T6064 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id30]} 0 1
[junit4] 2> 2139334 T6067 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id36]} 0 0
[junit4] 2> 2139335 T6045 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id37]} 0 1
[junit4] 2> 2139336 T6067 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id38]} 0 1
[junit4] 2> 2139339 T6045 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id39]} 0 3
[junit4] 2> 2139339 T6067 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id40]} 0 1
[junit4] 2> 2139340 T6045 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id41]} 0 1
[junit4] 2> 2139341 T6067 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id42]} 0 1
[junit4] 2> 2139342 T6045 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id43]} 0 1
[junit4] 2> 2139342 T6067 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id44]} 0 0
[junit4] 2> 2139344 T6047 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id46]} 0 1
[junit4] 2> 2139346 T6045 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id45]} 0 4
[junit4] 2> 2139348 T6067 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id47]} 0 5
[junit4] 2> 2139350 T6067 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id49]} 0 2
[junit4] 2> 2139350 T6047 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id48]} 0 5
[junit4] 2> 2139352 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id52]} 0 2
[junit4] 2> 2139353 T6048 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id50]} 0 1
[junit4] 2> 2139356 T6048 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id51]} 0 1
[junit4] 2> 2139358 T6072 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id53]} 0 1
[junit4] 2> 2139359 T6072 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id54]} 0 1
[junit4] 2> 2139361 T6072 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id55]} 0 1
[junit4] 2> 2139363 T6072 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id56]} 0 1
[junit4] 2> 2139366 T6044 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id57]} 0 2
[junit4] 2> 2139366 T6044 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id59]} 0 0
[junit4] 2> 2139367 T6044 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id58]} 0 0
[junit4] 2> 2139371 T6066 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id60]} 0 1
[junit4] 2> 2139372 T6066 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id61]} 0 1
[junit4] 2> 2139374 T6066 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id62]} 0 1
[junit4] 2> 2139376 T6064 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id63]} 0 1
[junit4] 2> 2139378 T6064 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id64]} 0 1
[junit4] 2> 2139380 T6064 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id65]} 0 1
[junit4] 2> 2139381 T6048 C4552 oasc.SolrCore.execute [00003_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2139383 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id66]} 0 1
[junit4] 2> 2139384 T6048 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id68]} 0 1
[junit4] 2> 2139387 T6048 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id67]} 0 1
[junit4] 2> 2139388 T6072 C4553 oasc.SolrCore.execute [00002_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=1
[junit4] 2> 2139389 T6065 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id69]} 0 0
[junit4] 2> 2139392 T6072 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id70]} 0 4
[junit4] 2> 2139392 T6049 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id71]} 0 1
[junit4] 2> 2139395 T6044 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id72]} 0 1
[junit4] 2> 2139397 T6044 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id74]} 0 1
[junit4] 2> 2139398 T6044 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id73]} 0 1
[junit4] 2> 2139401 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id75]} 0 1
[junit4] 2> 2139402 T6066 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id76]} 0 1
[junit4] 2> 2139404 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id77]} 0 1
[junit4] 2> 2139404 T6066 C4551 oasc.SolrCore.execute [00001_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2139406 T6066 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id78]} 0 1
[junit4] 2> 2139408 T6064 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id79]} 0 1
[junit4] 2> 2139410 T6064 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id80]} 0 1
[junit4] 2> 2139411 T6064 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id81]} 0 1
[junit4] 2> 2139413 T6065 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id84]} 0 0
[junit4] 2> 2139415 T6065 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id83]} 0 1
[junit4] 2> 2139416 T6048 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id82]} 0 1
[junit4] 2> 2139419 T6072 C4551 oasc.SolrCore.execute [00001_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2139420 T6072 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id86]} 0 1
[junit4] 2> 2139421 T6072 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id87]} 0 1
[junit4] 2> 2139422 T6072 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id85]} 0 1
[junit4] 2> 2139426 T6044 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id90]} 0 2
[junit4] 2> 2139427 T6044 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id88]} 0 1
[junit4] 2> 2139428 T6044 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id89]} 0 0
[junit4] 2> 2139430 T6066 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id91]} 0 1
[junit4] 2> 2139437 T6064 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id94]} 0 1
[junit4] 2> 2139438 T6064 C4550 oasc.SolrCore.execute [00000_core] webapp=/solr path=/select params={q=*:*&wt=javabin&qt=/select&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2139440 T6064 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id92]} 0 1
[junit4] 2> 2139442 T6064 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id95]} 0 1
[junit4] 2> 2139444 T6064 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id97]} 0 1
[junit4] 2> 2139446 T6064 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id98]} 0 2
[junit4] 2> 2139448 T6064 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id99]} 0 1
[junit4] 2> 2139450 T6064 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id100]} 0 1
[junit4] 2> 2139452 T6064 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id101]} 0 1
[junit4] 2> 2139455 T6064 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id102]} 0 1
[junit4] 2> 2139457 T6064 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id103]} 0 1
[junit4] 2> 2139459 T6064 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id104]} 0 0
[junit4] 2> 2139460 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id93]} 0 29
[junit4] 2> 2139463 T6066 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id96]} 0 2
[junit4] 2> 2139465 T6066 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id105]} 0 2
[junit4] 2> 2139467 T6066 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id106]} 0 1
[junit4] 2> 2139470 T6066 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id108]} 0 2
[junit4] 2> 2139472 T6066 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id109]} 0 1
[junit4] 2> 2139475 T6066 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id110]} 0 1
[junit4] 2> 2139477 T6066 C4551 oasup.LogUpdateProcessor.finish [00001_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id111]} 0 1
[junit4] 2> 2139480 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id112]} 0 1
[junit4] 2> 2139483 T6066 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id113]} 0 1
[junit4] 2> 2139484 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id114]} 0 1
[junit4] 2> 2139486 T6066 C4549 oasup.LogUpdateProcessor.finish [00004_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id115]} 0 1
[junit4] 2> 2139489 T6066 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id116]} 0 1
[junit4] 2> 2139491 T6066 C4550 oasup.LogUpdateProcessor.finish [00000_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id117]} 0 1
[junit4] 2> 2139493 T6066 C4552 oasup.LogUpdateProcessor.finish [00003_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id118]} 0 1
[junit4] 2> 2139495 T6066 C4553 oasup.LogUpdateProcessor.finish [00002_core] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id119]} 0 1
[junit4] 2> 213949
[...truncated too long message...]
kSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 4) Thread[id=6071, name=commitScheduler-4572-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 5) Thread[id=6104, name=commitScheduler-4595-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 6) Thread[id=6073, name=commitScheduler-4576-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 7) Thread[id=6076, name=commitScheduler-4580-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 8) Thread[id=6063, name=commitScheduler-4568-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 9) Thread[id=6102, name=commitScheduler-4592-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 10) Thread[id=6099, name=commitScheduler-4587-thread-1, state=WAITING, group=TGRP-OpenCloseCoreStressTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1079)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > at __randomizedtesting.SeedInfo.seed([394F48E1455B2220]:0)
[junit4] Completed in 54.76s, 6 tests, 2 errors, 4 skipped <<< FAILURES!
[...truncated 501 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:423: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:403: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:489: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1272: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 336 suites, 1474 tests, 2 suite-level errors, 657 ignored (6 assumptions)
Total time: 66 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_45) - Build #
3468 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3468/
Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([6CD5E04FB32AB344:D95381C80CEB01B0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:133)
at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:94)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 10005 lines...]
[junit4] Suite: org.apache.solr.core.TestNonNRTOpen
[junit4] 2> 579834 T1218 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691
[junit4] 2> 579835 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 579839 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 579839 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 579930 T1218 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 579989 T1218 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 579989 T1218 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 579997 T1218 oass.IndexSchema.readSchema [null] Schema name=minimal
[junit4] 2> 580024 T1218 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 580026 T1218 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 580026 T1218 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 580026 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 580040 T1218 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 580040 T1218 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 580040 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 580121 T1218 oasc.CoreContainer.<init> New CoreContainer 25996671
[junit4] 2> 580121 T1218 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 580124 T1218 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 580129 T1218 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 580129 T1218 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 580129 T1218 oasc.CoreContainer.load Host Name:
[junit4] 2> 580138 T1219 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 580138 T1219 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 580139 T1219 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 580139 T1219 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 580211 T1219 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 580282 T1219 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 580282 T1219 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 580290 T1219 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 580305 T1219 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 580307 T1219 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 580340 T1219 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\
[junit4] 2> 580340 T1219 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 580342 T1219 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691
[junit4] 2> 580343 T1219 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\index/
[junit4] 2> 580343 T1219 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\index' doesn't exist. Creating new index...
[junit4] 2> 580343 T1219 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index
[junit4] 2> 580350 T1219 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_1,generation=1}
[junit4] 2> 580350 T1219 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 580351 T1219 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 580351 T1219 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 580351 T1219 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 580361 T1219 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 580365 T1219 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 580366 T1219 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 580367 T1219 oass.SolrIndexSearcher.<init> Opening Searcher@ff2775 main
[junit4] 2> 580367 T1219 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 580367 T1220 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ff2775 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 580369 T1218 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C297 name=collection1 org.apache.solr.core.SolrCore@111b70a
[junit4] 2> 580380 T1218 C297 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_1,generation=1}
[junit4] 2> 580381 T1218 C297 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 580383 T1218 C297 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 6
[junit4] 2> 580394 T1218 C297 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 580420 T1218 C297 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_2,generation=2}
[junit4] 2> 580420 T1218 C297 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 580432 T1218 C297 oass.SolrIndexSearcher.<init> Opening Searcher@17800ec main
[junit4] 2> 580433 T1218 C297 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 580433 T1220 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17800ec main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4] 2> 580435 T1218 C297 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 41
[junit4] 2> 580441 T1218 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 580441 T1218 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=25996671
[junit4] 2> 580442 T1218 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@111b70a
[junit4] 2> 580442 T1218 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 580443 T1218 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 580443 T1218 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 580443 T1218 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 580444 T1218 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 580444 T1218 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 580444 T1218 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index;done=false>>]
[junit4] 2> 580444 T1218 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index
[junit4] 2> 580444 T1218 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691;done=false>>]
[junit4] 2> 580444 T1218 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691
[junit4] 2> NOTE: per solr.test.leavedatadir, dataDir will not be removed: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691
[junit4] 2> 580446 T1218 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 580446 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 580449 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 580452 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 580538 T1218 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 580597 T1218 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 580598 T1218 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 580606 T1218 oass.IndexSchema.readSchema [null] Schema name=minimal
[junit4] 2> 580625 T1218 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 580627 T1218 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 580627 T1218 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 580627 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 580642 T1218 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 580642 T1218 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 580642 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 580725 T1218 oasc.CoreContainer.<init> New CoreContainer 11914315
[junit4] 2> 580725 T1218 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 580727 T1218 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 580728 T1218 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 580730 T1218 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 580730 T1218 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 580730 T1218 oasc.CoreContainer.load Host Name:
[junit4] 2> 580738 T1222 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 580739 T1222 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 580740 T1222 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 580740 T1222 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 580815 T1222 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 580883 T1222 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 580884 T1222 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 580891 T1222 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 580905 T1222 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 580907 T1222 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 580907 T1222 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\
[junit4] 2> 580907 T1222 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 580908 T1222 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691
[junit4] 2> 580908 T1222 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\index/
[junit4] 2> 580909 T1222 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 580909 T1222 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 580916 T1222 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 580925 T1222 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 580929 T1222 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 580929 T1222 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 580930 T1222 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index
[junit4] 2> 580933 T1222 oass.SolrIndexSearcher.<init> Opening Searcher@11ecc9f main
[junit4] 2> 580933 T1222 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 580933 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11ecc9f main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4] 2> 580934 T1218 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 580940 T1218 oas.SolrTestCaseJ4.setUp ###Starting testSharedCores
[junit4] 2> ASYNC NEW_CORE C298 name=collection1 org.apache.solr.core.SolrCore@3275e2
[junit4] 2> 580948 T1218 C298 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 580952 T1218 C298 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_2,generation=2}
[junit4] 2> 580953 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 580953 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 6
[junit4] 2> 580964 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 1
[junit4] 2> 580974 T1218 C298 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 580989 T1218 C298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_2,generation=2}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_3,generation=3}
[junit4] 2> 580989 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 581004 T1218 C298 oass.SolrIndexSearcher.<init> Opening Searcher@1296e98 main
[junit4] 2> 581004 T1218 C298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581005 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1296e98 main{StandardDirectoryReader(segments_3:6 _1(5.0):C1)}
[junit4] 2> 581006 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 33
[junit4] 2> 581021 T1218 C298 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581048 T1218 C298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_3,generation=3}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_4,generation=4}
[junit4] 2> 581048 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 581069 T1218 C298 oass.SolrIndexSearcher.<init> Opening Searcher@f87d0 main
[junit4] 2> 581069 T1218 C298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581071 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f87d0 main{StandardDirectoryReader(segments_4:8 _2(5.0):C1)}
[junit4] 2> 581072 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {optimize=} 0 54
[junit4] 2> 581090 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 6
[junit4] 2> 581100 T1218 C298 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581126 T1218 C298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_4,generation=4}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_5,generation=5}
[junit4] 2> 581126 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 581135 T1218 C298 oass.SolrIndexSearcher.<init> Opening Searcher@1726e0f main
[junit4] 2> 581137 T1218 C298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581137 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1726e0f main{StandardDirectoryReader(segments_5:10 _2(5.0):C1 _3(5.0):C1)}
[junit4] 2> 581137 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 37
[junit4] 2> 581157 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 6
[junit4] 2> 581166 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 0
[junit4] 2> 581179 T1218 C298 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581199 T1218 C298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_5,generation=5}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_6,generation=6}
[junit4] 2> 581199 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 581212 T1218 C298 oass.SolrIndexSearcher.<init> Opening Searcher@1abacf4 main
[junit4] 2> 581213 T1218 C298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581214 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1abacf4 main{StandardDirectoryReader(segments_6:12 _2(5.0):C1 _3(5.0):C1 _4(5.0):C2)}
[junit4] 2> 581214 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 35
[junit4] 2> 581228 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=foo2:doc} 0 2
[junit4] 2> 581239 T1218 C298 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581253 T1218 C298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_6,generation=6}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_7,generation=7}
[junit4] 2> 581254 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 581271 T1218 C298 oass.SolrIndexSearcher.<init> Opening Searcher@1775728 main
[junit4] 2> 581272 T1218 C298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581272 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1775728 main{StandardDirectoryReader(segments_7:13 _2(5.0):C1 _3(5.0):C1 _4(5.0):C2/1:delGen=1)}
[junit4] 2> 581273 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 34
[junit4] 2> 581281 T1218 oas.SolrTestCaseJ4.tearDown ###Ending testSharedCores
[junit4] 2> 581285 T1218 oas.SolrTestCaseJ4.setUp ###Starting testReaderIsNotNRT
[junit4] 2> 581296 T1218 C298 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 581297 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 5
[junit4] 2> 581308 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 2
[junit4] 2> 581317 T1218 C298 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581330 T1218 C298 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_7,generation=7}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_8,generation=8}
[junit4] 2> 581330 T1218 C298 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 581365 T1218 C298 oass.SolrIndexSearcher.<init> Opening Searcher@191a923 main
[junit4] 2> 581368 T1218 C298 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581368 T1223 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@191a923 main{StandardDirectoryReader(segments_8:16 _5(5.0):C1)}
[junit4] 2> 581370 T1218 C298 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 54
[junit4] 2> 581375 T1218 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 581375 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 581378 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 581378 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 581469 T1218 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 581524 T1218 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 581524 T1218 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 581532 T1218 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 581553 T1218 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 581555 T1218 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\
[junit4] 2> 581555 T1218 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 581555 T1218 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\index/
[junit4] 2> 581557 T1218 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 581557 T1218 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 581558 T1218 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 581568 T1218 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 581572 T1218 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 581572 T1218 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 581575 T1218 oass.SolrIndexSearcher.<init> Opening Searcher@1552207 main
[junit4] 2> 581575 T1218 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 581577 T1218 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 581577 T1218 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 581576 T1226 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1552207 main{StandardDirectoryReader(segments_8:16 _5(5.0):C1)}
[junit4] 2> 581595 T1218 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_8,generation=8}
[junit4] 2> 581595 T1218 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 581595 T1218 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 581598 T1218 oass.SolrIndexSearcher.<init> Opening Searcher@c8294f main
[junit4] 2> 581600 T1218 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 581600 T1218 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3275e2
[junit4] 2> 581601 T1218 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=3,cumulative_errors=0}
[junit4] 2> 581600 T1226 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c8294f main{StandardDirectoryReader(segments_8:16 _5(5.0):C1)}
[junit4] 2> 581601 T1218 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> ASYNC NEW_CORE C299 name=collection1 org.apache.solr.core.SolrCore@1101c9b
[junit4] 2> 581620 T1218 C299 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 6
[junit4] 2> 581630 T1218 C299 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581646 T1218 C299 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_8,generation=8}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_9,generation=9}
[junit4] 2> 581646 T1218 C299 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 581658 T1218 C299 oass.SolrIndexSearcher.<init> Opening Searcher@167600b main
[junit4] 2> 581658 T1218 C299 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 581658 T1226 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@167600b main{StandardDirectoryReader(segments_9:18 _5(5.0):C1 _6(5.0):C1)}
[junit4] 2> 581659 T1218 C299 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 29
[junit4] 2> 581677 T1218 C299 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 6
[junit4] 2> 581678 T1218 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 581678 T1218 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 581681 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 581681 T1218 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 581784 T1218 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 581862 T1218 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 581862 T1218 oass.IndexSchema.readSchema Reading Solr Schema from schema-minimal.xml
[junit4] 2> 581872 T1218 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 581900 T1218 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 581902 T1218 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\
[junit4] 2> 581902 T1218 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 581902 T1218 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384500098691\index/
[junit4] 2> 581905 T1218 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 581906 T1218 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 581906 T1218 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 581920 T1218 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 581925 T1218 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 581925 T1218 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 581929 T1218 oass.SolrIndexSearcher.<init> Opening Searcher@78a6e9 main
[junit4] 2> 581931 T1218 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 581931 T1218 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 581931 T1218 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 581949 T1227 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78a6e9 main{StandardDirectoryReader(segments_9:18 _5(5.0):C1 _6(5.0):C1)}
[junit4] 2> 581951 T1218 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_9,generation=9}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_a,generation=10}
[junit4] 2> 581951 T1218 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 581962 T1218 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index,segFN=segments_a,generation=10}
[junit4] 2> 581962 T1218 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 581962 T1218 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 581971 T1218 oass.SolrIndexSearcher.<init> Opening Searcher@1a9d771 main
[junit4] 2> 581971 T1218 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 581971 T1218 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1101c9b
[junit4] 2> 581971 T1218 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 581974 T1218 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 581974 T1218 oas.SolrTestCaseJ4.tearDown ###Ending testReaderIsNotNRT
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNonNRTOpen -Dtests.method=testReaderIsNotNRT -Dtests.seed=6CD5E04FB32AB344 -Dtests.slow=true -Dtests.locale=en -Dtests.timezone=Europe/Budapest -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.70s | TestNonNRTOpen.testReaderIsNotNRT <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<3> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([6CD5E04FB32AB344:D95381C80CEB01B0]:0)
[junit4] > at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:133)
[junit4] > at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:94)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 581982 T1218 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 581982 T1218 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=11914315
[junit4] 2> 581983 T1218 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@17f5843
[junit4] 2> 581983 T1218 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 581983 T1218 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 581983 T1218 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 581983 T1218 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 581985 T1227 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a9d771 main{StandardDirectoryReader(segments_a:20 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1)}
[junit4] 2> 581985 T1218 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 581985 T1218 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 581985 T1218 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index;done=false>>]
[junit4] 2> 581986 T1218 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691\index
[junit4] 2> 581986 T1218 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691;done=false>>]
[junit4] 2> 581986 T1218 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384500098691
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=en, timezone=Europe/Budapest
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_45 (32-bit)/cpus=2,threads=1,free=43711280,total=265814016
[junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryElevationComponentTest, TestJmxIntegration, BJQParserTest, PreAnalyzedFieldTest, BinaryUpdateRequestHandlerTest, RecoveryZkTest, SynonymTokenizerTest, DisMaxRequestHandlerTest, XsltUpdateRequestHandlerTest, SliceStateUpdateTest, PreAnalyzedUpdateProcessorTest, TestRecovery, BadIndexSchemaTest, TestDFRSimilarityFactory, ClusterStateTest, TestRecoveryHdfs, XmlUpdateRequestHandlerTest, TestAddFieldRealTimeGet, ShardRoutingTest, TestDistributedSearch, TestFieldTypeCollectionResource, TestJoin, TestRemoteStreaming, TestDynamicFieldCollectionResource, TestSchemaNameResource, AutoCommitTest, TestExtendedDismaxParser, DistributedDebugComponentTest, TestStressVersions, TestInfoStreamLogging, TestSchemaResource, TestCodecSupport, NoCacheHeaderTest, HdfsBasicDistributedZk2Test, HdfsUnloadDistributedZkTest, TestImplicitCoreProperties, DateFieldTest, TestCoreContainer, FullSolrCloudDistribCmdsTest, OpenCloseCoreStressTest, TestShardHandlerFactory, TestSchemaVersionResource, FieldMutatingUpdateProcessorTest, TestDynamicFieldResource, TestBinaryResponseWriter, OutputWriterTest, ZkNodePropsTest, WordBreakSolrSpellCheckerTest, SpellCheckComponentTest, TestPostingsSolrHighlighter, StandardRequestHandlerTest, TestNonNRTOpen]
[junit4] Completed in 2.21s, 2 tests, 1 failure <<< FAILURES!
[...truncated 880 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:423: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:403: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:489: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1272: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 336 suites, 1474 tests, 1 failure, 40 ignored (11 assumptions)
Total time: 84 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure