Details

    • Type: Improvement
    • Status: Resolved
    • Priority: Blocker
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 6.0
    • Component/s: None
    • Labels:
      None

      Description

      Jetty 9.3 offers support for HTTP/2. Interest in HTTP/2 or its predecessor SPDY was shown in SOLR-6699 and on the mailing list.

      Among the HTTP/2 benefits over HTTP/1.1 relevant to Solr are:

      • multiplexing requests over a single TCP connection ("streams")
      • canceling a single request without closing the TCP connection
      • removing head-of-line blocking
      • header compression

      Caveats:

      • Jetty 9.3 is at M2, not released.
      • Full Solr support for HTTP/2 would require more work than just upgrading Jetty. The server configuration would need to change and a new HTTP client (Jetty's own client, Square's OkHttp, etc.) would need to be selected and wired up. Perhaps this is worthy of a branch?
      1. SOLR-7339.patch
        23 kB
        Mark Miller
      2. SOLR-7339.patch
        19 kB
        Shalin Shekhar Mangar
      3. SOLR-7339.patch
        14 kB
        Gregg Donovan
      4. SOLR-7339-jetty-9.3.8.patch
        13 kB
        Steve Rowe
      5. SOLR-7339-jetty-9.3.8.patch
        12 kB
        Steve Rowe
      6. SOLR-7339-revert.patch
        20 kB
        Shalin Shekhar Mangar
      7. SolrExampleStreamingBinaryTest.testUpdateField-jetty92.pcapng
        13 kB
        Gregg Donovan
      8. SolrExampleStreamingBinaryTest.testUpdateField-jetty93.pcapng
        37 kB
        Gregg Donovan

        Issue Links

          Activity

          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Yep, we're going to Jetty 9 soon. See SOLR-4839 where I already upgraded to Jetty 9.2 in trunk so that we could go to SPDY but then SPDY was deprecated and HTTP/2 will come out in 9.3. So my next plan is to target 9.3 as soon as it is out and then use Jetty's HTTP client throughout. In the meanwhile, I'll port Jetty 9.2 to 5.x branches after 5.1 is released.

          If you don't mind, I will close this issue and we can continue the discussion on SOLR-4839.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Yep, we're going to Jetty 9 soon. See SOLR-4839 where I already upgraded to Jetty 9.2 in trunk so that we could go to SPDY but then SPDY was deprecated and HTTP/2 will come out in 9.3. So my next plan is to target 9.3 as soon as it is out and then use Jetty's HTTP client throughout. In the meanwhile, I'll port Jetty 9.2 to 5.x branches after 5.1 is released. If you don't mind, I will close this issue and we can continue the discussion on SOLR-4839 .
          Hide
          greggny3 Gregg Donovan added a comment -

          My apologies! I didn't realize SOLR-4839 was still open. I have a trunk patch for part of Jetty 9.2->9.3. Which ticket should I add that to?

          Show
          greggny3 Gregg Donovan added a comment - My apologies! I didn't realize SOLR-4839 was still open. I have a trunk patch for part of Jetty 9.2->9.3. Which ticket should I add that to?
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Hmm, you bring up a good point. You can attach the patch here on this issue itself. I'll link this with SOLR-4839.

          I don't think we will push a jetty m2 release to trunk so we can keep SOLR-4839 for the move to 9.2 and then use this issue for the 9.3 upgrade.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Hmm, you bring up a good point. You can attach the patch here on this issue itself. I'll link this with SOLR-4839 . I don't think we will push a jetty m2 release to trunk so we can keep SOLR-4839 for the move to 9.2 and then use this issue for the 9.3 upgrade.
          Hide
          greggny3 Gregg Donovan added a comment -

          Partial patch to move from Jetty 9.2 to 9.3M2. A few tests are fixed but HttpPartitionTest.test stalled out for a few hours and SolrExampleStreamingTest.testUpdateField fails.

          Show
          greggny3 Gregg Donovan added a comment - Partial patch to move from Jetty 9.2 to 9.3M2. A few tests are fixed but HttpPartitionTest.test stalled out for a few hours and SolrExampleStreamingTest.testUpdateField fails.
          Hide
          greggny3 Gregg Donovan added a comment -

          Thanks, Shalin. I'll update the ticket title to reflect that this builds on SOLR-4839.

          Show
          greggny3 Gregg Donovan added a comment - Thanks, Shalin. I'll update the ticket title to reflect that this builds on SOLR-4839 .
          Show
          billnbell Bill Bell added a comment - http://central.maven.org/maven2/org/eclipse/jetty/jetty-distribution/9.3.0.RC0/ RC0 released
          Hide
          thetaphi Uwe Schindler added a comment - - edited

          9.3.0 was finally released on Jun 12: https://dev.eclipse.org/mhonarc/lists/jetty-announce/msg00080.html

          Please keep in mind, that we can do the upgrade only on trunk, because Jety 9.3 requires Java 8 as minimum requirement.

          Show
          thetaphi Uwe Schindler added a comment - - edited 9.3.0 was finally released on Jun 12: https://dev.eclipse.org/mhonarc/lists/jetty-announce/msg00080.html Please keep in mind, that we can do the upgrade only on trunk, because Jety 9.3 requires Java 8 as minimum requirement.
          Hide
          billnbell Bill Bell added a comment -

          Java 7 is EOL

          Java SE 7 End of Public Updates Notice
          After April 2015, Oracle will no longer post updates of Java SE 7 to its public download sites. Existing Java SE 7 downloads already posted as of April 2015 will remain accessible in the Java Archive on the Oracle Technology Network. Developers and end-users are encouraged to update to more recent Java SE versions that remain available for public download in order to continue receiving public updates and security enhancements.

          https://www.java.com/en/download/faq/java_7.xml

          Show
          billnbell Bill Bell added a comment - Java 7 is EOL Java SE 7 End of Public Updates Notice After April 2015, Oracle will no longer post updates of Java SE 7 to its public download sites. Existing Java SE 7 downloads already posted as of April 2015 will remain accessible in the Java Archive on the Oracle Technology Network. Developers and end-users are encouraged to update to more recent Java SE versions that remain available for public download in order to continue receiving public updates and security enhancements. https://www.java.com/en/download/faq/java_7.xml
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          See LUCENE-6722 for the Java 8 discussion. The consensus is to move to Java 8 in 6.x and keep 5.x releases on Java 7.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - See LUCENE-6722 for the Java 8 discussion. The consensus is to move to Java 8 in 6.x and keep 5.x releases on Java 7.
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Patch to upgrade trunk to Jetty 9.3.6.v20151106

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Patch to upgrade trunk to Jetty 9.3.6.v20151106
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 1717377 from shalin@apache.org in branch 'dev/trunk'
          [ https://svn.apache.org/r1717377 ]

          SOLR-7339: Upgrade Jetty to v9.3.6.v20151106

          Show
          jira-bot ASF subversion and git services added a comment - Commit 1717377 from shalin@apache.org in branch 'dev/trunk' [ https://svn.apache.org/r1717377 ] SOLR-7339 : Upgrade Jetty to v9.3.6.v20151106
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          This is a trunk-only feature (requires Java8) so backporting to 5x is not necessary.

          Thanks Gregg!

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - This is a trunk-only feature (requires Java8) so backporting to 5x is not necessary. Thanks Gregg!
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Oops, this broke the tests because Jetty 9.3 removed the GzipFilter functionality but didn't actually remove the filter class!

          I'll test with the fix that Greg has given in his original patch.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Oops, this broke the tests because Jetty 9.3 removed the GzipFilter functionality but didn't actually remove the filter class! I'll test with the fix that Greg has given in his original patch.
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 1717431 from shalin@apache.org in branch 'dev/trunk'
          [ https://svn.apache.org/r1717431 ]

          SOLR-7339: Use GzipHandler instead of GzipFilter

          Show
          jira-bot ASF subversion and git services added a comment - Commit 1717431 from shalin@apache.org in branch 'dev/trunk' [ https://svn.apache.org/r1717431 ] SOLR-7339 : Use GzipHandler instead of GzipFilter
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          There's still a curious failure in SolrExampleStreamingBinaryTest.testUpdateField which runs into connection reset errors with the new jetty and I'm not sure why.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - There's still a curious failure in SolrExampleStreamingBinaryTest.testUpdateField which runs into connection reset errors with the new jetty and I'm not sure why.
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 1717512 from shalin@apache.org in branch 'dev/trunk'
          [ https://svn.apache.org/r1717512 ]

          SOLR-7339: Disabling testUpdateField until we can fix the underlying issue

          Show
          jira-bot ASF subversion and git services added a comment - Commit 1717512 from shalin@apache.org in branch 'dev/trunk' [ https://svn.apache.org/r1717512 ] SOLR-7339 : Disabling testUpdateField until we can fix the underlying issue
          Hide
          greggny3 Gregg Donovan added a comment -

          Thanks, Shalin Shekhar Mangar!

          Wireshark dumps of SolrExampleStreamingBinaryTest.testUpdateField show Jetty 9.3 sends an RST after the expected "HTTP 409 Conflict" and Jetty 9.2 does not. I haven't figured out what's changed in 9.3 that leads to this, though.

          Show
          greggny3 Gregg Donovan added a comment - Thanks, Shalin Shekhar Mangar ! Wireshark dumps of SolrExampleStreamingBinaryTest.testUpdateField show Jetty 9.3 sends an RST after the expected "HTTP 409 Conflict" and Jetty 9.2 does not. I haven't figured out what's changed in 9.3 that leads to this, though.
          Hide
          greggny3 Gregg Donovan added a comment -

          Attached are the Wireshark files for 9.2 (right before the 9.3 commits) and with the 9.3 commits.

          Show
          greggny3 Gregg Donovan added a comment - Attached are the Wireshark files for 9.2 (right before the 9.3 commits) and with the 9.3 commits.
          Hide
          joakime Joakim Erdfelt added a comment - - edited

          The Jetty Project developers took a look at this and ...

          If you turn on DEBUG logging for Jetty, you'll see that the test does not send correct data to the server.
          The request that fails sends data using chunked encoding, but sends one chunk of length 0x59, but does not send the terminal chunk.
          The logs show Jetty reporting that the processing is complete but with unconsumed input, and therefore closes the connection.
          Could this be a Solr client bug in disguise?

          Look at the attached SolrExampleStreamingBinaryTest.testUpdateField-jetty93.pcapng

          Filter: tcp.stream eq 4

          POST /solr/collection1/update?wt=javabin&version=2 HTTP/1.1
          User-Agent: Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0
          Content-Type: application/javabin
          Transfer-Encoding: chunked
          Host: 127.0.0.1:52488
          Connection: Keep-Alive
          
          59
          ...&params..&delByQ..'docsMap.....?...."id&unique.)_version_....4.....'price_f
          ..#setT...
          HTTP/1.1 409 Conflict
          Date: Tue, 01 Dec 2015 19:44:38 GMT
          Content-Type: application/octet-stream
          Content-Length: 144
          Server: Jetty(9.3.6.v20151106)
          
          ....responseHeader..&statusY..%QTimeP..%error..#msg?4version conflict for unique expected=1519385657324077057 actual=1519385657324077056.$codeY.
          
          Show
          joakime Joakim Erdfelt added a comment - - edited The Jetty Project developers took a look at this and ... If you turn on DEBUG logging for Jetty, you'll see that the test does not send correct data to the server. The request that fails sends data using chunked encoding, but sends one chunk of length 0x59, but does not send the terminal chunk. The logs show Jetty reporting that the processing is complete but with unconsumed input, and therefore closes the connection. Could this be a Solr client bug in disguise? Look at the attached SolrExampleStreamingBinaryTest.testUpdateField-jetty93.pcapng Filter: tcp.stream eq 4 POST /solr/collection1/update?wt=javabin&version=2 HTTP/1.1 User-Agent: Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0 Content-Type: application/javabin Transfer-Encoding: chunked Host: 127.0.0.1:52488 Connection: Keep-Alive 59 ...&params..&delByQ..'docsMap.....?...."id&unique.)_version_....4.....'price_f ..#setT... HTTP/1.1 409 Conflict Date: Tue, 01 Dec 2015 19:44:38 GMT Content-Type: application/octet-stream Content-Length: 144 Server: Jetty(9.3.6.v20151106) ....responseHeader..&statusY..%QTimeP..%error..#msg?4version conflict for unique expected=1519385657324077057 actual=1519385657324077056.$codeY.
          Hide
          ichattopadhyaya Ishan Chattopadhyaya added a comment - - edited

          Yesterday, I ran the same test using a debugger, and I put a breakpoint at SolrCore.postDecorateResponse's first line (and subsequently I hit resume at every instance of the breakpoint), then the test passes. I suspect there is some timing issue here. I couldn't find anything obvious. Even tried upgrading to the November release of the jetty-server artifacts, same results.

          Show
          ichattopadhyaya Ishan Chattopadhyaya added a comment - - edited Yesterday, I ran the same test using a debugger, and I put a breakpoint at SolrCore.postDecorateResponse's first line (and subsequently I hit resume at every instance of the breakpoint), then the test passes. I suspect there is some timing issue here. I couldn't find anything obvious. Even tried upgrading to the November release of the jetty-server artifacts, same results.
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 1717777 from shalin@apache.org in branch 'dev/trunk'
          [ https://svn.apache.org/r1717777 ]

          SOLR-7339: Remove http2-parent as a dependency because it is only a pom and thus fails our maven builds

          Show
          jira-bot ASF subversion and git services added a comment - Commit 1717777 from shalin@apache.org in branch 'dev/trunk' [ https://svn.apache.org/r1717777 ] SOLR-7339 : Remove http2-parent as a dependency because it is only a pom and thus fails our maven builds
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Thanks Joakim Erdfelt. I'll dig into why this is happening. As Ishan pointed out, adding a breakpoint in the test makes it pass so there's a subtle race condition somewhere.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Thanks Joakim Erdfelt . I'll dig into why this is happening. As Ishan pointed out, adding a breakpoint in the test makes it pass so there's a subtle race condition somewhere.
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Comparing the debug output from Jetty 9.2.13.v20150730, I see that Solr's behavior has remained the same but Jetty now closes the connection on unconsumed input. I also found that HttpClient by default will retry 3 times when such a thing happens which is clearly not right. Adding params.set(HttpClientUtil.PROP_USE_RETRY, false); to the HttpSolrClient constructor disables those retries. I think we should add that anyway even though it doesn't help fix these failures.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Comparing the debug output from Jetty 9.2.13.v20150730, I see that Solr's behavior has remained the same but Jetty now closes the connection on unconsumed input. I also found that HttpClient by default will retry 3 times when such a thing happens which is clearly not right. Adding params.set(HttpClientUtil.PROP_USE_RETRY, false); to the HttpSolrClient constructor disables those retries. I think we should add that anyway even though it doesn't help fix these failures.
          Hide
          markrmiller@gmail.com Mark Miller added a comment - - edited

          I think we should add that anyway even though it doesn't help fix these failures.

          Let's open a separate issue for that. Internal HttpClient usage should be getting an instance setup with the Retry policy that we want. I'm a little skeptical that we just want to hard code it as off in the HttpSolrClient constructor, but perhaps there is something to dig into there.

          This upgrade is a bit scary at the moment. I see in one of the repeatable connection reset issues, hard coding the Locale to English for the test makes it pass. Other issues don't seem to relate to the Locale. Unless we can get to the bottom of this quickly, we may want to pull this back and get a handle a better handle on the problems before putting it in.

          Show
          markrmiller@gmail.com Mark Miller added a comment - - edited I think we should add that anyway even though it doesn't help fix these failures. Let's open a separate issue for that. Internal HttpClient usage should be getting an instance setup with the Retry policy that we want. I'm a little skeptical that we just want to hard code it as off in the HttpSolrClient constructor, but perhaps there is something to dig into there. This upgrade is a bit scary at the moment. I see in one of the repeatable connection reset issues, hard coding the Locale to English for the test makes it pass. Other issues don't seem to relate to the Locale. Unless we can get to the bottom of this quickly, we may want to pull this back and get a handle a better handle on the problems before putting it in.
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Let's open a separate issue for that. Internal HttpClient usage should be getting an instance setup with the Retry policy that we want. I'm a little skeptical that we just want to hard code it as off in the HttpSolrClient constructor, but perhaps there is something to dig into there.

          I opened SOLR-8450

          This upgrade is a bit scary at the moment. I see in one of the repeatable connection reset issues, hard coding the Locale to English for the test makes it pass. Other issues don't seem to relate to the Locale. Unless we can get to the bottom of this quickly, we may want to pull this back and get a handle a better handle on the problems before putting it in.

          Agreed. Let's give it a day or two. If we can't find the root cause, I will revert this upgrade.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Let's open a separate issue for that. Internal HttpClient usage should be getting an instance setup with the Retry policy that we want. I'm a little skeptical that we just want to hard code it as off in the HttpSolrClient constructor, but perhaps there is something to dig into there. I opened SOLR-8450 This upgrade is a bit scary at the moment. I see in one of the repeatable connection reset issues, hard coding the Locale to English for the test makes it pass. Other issues don't seem to relate to the Locale. Unless we can get to the bottom of this quickly, we may want to pull this back and get a handle a better handle on the problems before putting it in. Agreed. Let's give it a day or two. If we can't find the root cause, I will revert this upgrade.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          The above test does not easily replicate for me.

          For someone that it does, could you give this patch a shot?

          Index: solr/solrj/src/java/org/apache/solr/client/solrj/impl/HttpSolrClient.java
          ===================================================================
          --- solr/solrj/src/java/org/apache/solr/client/solrj/impl/HttpSolrClient.java	(revision 1720969)
          +++ solr/solrj/src/java/org/apache/solr/client/solrj/impl/HttpSolrClient.java	(working copy)
          @@ -589,14 +589,16 @@
                 throw new SolrServerException(
                     "IOException occured when talking to server at: " + getBaseURL(), e);
               } finally {
          -      if (respBody != null && shouldClose) {
          -        try {
          -          respBody.close();
          -        } catch (IOException e) {
          -          log.error("", e);
          -        } finally {
          -          if (!success) {
          -            method.abort();
          +      try {
          +        if (!success) {
          +          method.abort();
          +        }
          +      } finally {
          +        if (respBody != null && shouldClose) {
          +          try {
          +            respBody.close();
          +          } catch (IOException e) {
          +            log.error("", e);
                     }
                   }
                 }
          
          Show
          markrmiller@gmail.com Mark Miller added a comment - The above test does not easily replicate for me. For someone that it does, could you give this patch a shot? Index: solr/solrj/src/java/org/apache/solr/client/solrj/impl/HttpSolrClient.java =================================================================== --- solr/solrj/src/java/org/apache/solr/client/solrj/impl/HttpSolrClient.java (revision 1720969) +++ solr/solrj/src/java/org/apache/solr/client/solrj/impl/HttpSolrClient.java (working copy) @@ -589,14 +589,16 @@ throw new SolrServerException( "IOException occured when talking to server at: " + getBaseURL(), e); } finally { - if (respBody != null && shouldClose) { - try { - respBody.close(); - } catch (IOException e) { - log.error("", e); - } finally { - if (!success) { - method.abort(); + try { + if (!success) { + method.abort(); + } + } finally { + if (respBody != null && shouldClose) { + try { + respBody.close(); + } catch (IOException e) { + log.error("", e); } } }
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          I moved the method abort call out of 'shouldClose' - it doesn't seem like it should be tied up in that.

          I also made it first. I was just kind of guessing on that, but it looks like it should be first according to: https://hc.apache.org/httpcomponents-client-ga/httpclient/examples/org/apache/http/examples/client/ClientAbortMethod.java

          Show
          markrmiller@gmail.com Mark Miller added a comment - I moved the method abort call out of 'shouldClose' - it doesn't seem like it should be tied up in that. I also made it first. I was just kind of guessing on that, but it looks like it should be first according to: https://hc.apache.org/httpcomponents-client-ga/httpclient/examples/org/apache/http/examples/client/ClientAbortMethod.java
          Hide
          ichattopadhyaya Ishan Chattopadhyaya added a comment -

          Mark, I applied your patch and ran the test, but the test still fails with the following output. It appears to be the same as what I saw without your patch, but the exact error isn't clear to me from that output.

          [ishan@x230 solr]$ ant clean test -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField
          ...
          ivy-configure:
          [ivy:configure] :: loading settings :: file = /home/ishan/code/lucene-solr-svn/lucene/ivy-settings.xml
          
          resolve:
          
          init:
          
          compile-core:
          
          validate:
          
          resolve-groovy:
          
          -init-totals:
          
          -test:
              [mkdir] Created dir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test
              [mkdir] Created dir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/temp
             [junit4] <JUnit4> says 今日は! Master seed: EC056F9D893F1A9A
             [junit4] Executing 1 suite with 1 JVM.
             [junit4] 
             [junit4] Started J0 PID(1129@x230).
             [junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
             [junit4]   2> Creating dataDir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/init-core-data-001
             [junit4]   2> 0    INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
             [junit4]   2> 2    INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
             [junit4]   2> 6    INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-002/cores/core
             [junit4]   2> 46   INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.e.j.u.log Logging initialized @2663ms
             [junit4]   2> 277  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.e.j.s.Server jetty-9.3.6.v20151106
             [junit4]   2> 323  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6ec49310{/solr,null,AVAILABLE}
             [junit4]   2> 339  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.e.j.s.ServerConnector Started ServerConnector@3b2c45{HTTP/1.1,[http/1.1]}{127.0.0.1:33129}
             [junit4]   2> 339  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.e.j.s.Server Started @2960ms
             [junit4]   2> 340  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33129, configSetBaseDir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001, coreRootDirectory=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-002/cores}
             [junit4]   2> 349  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
             [junit4]   2> 385  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001'
             [junit4]   2> 391  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
             [junit4]   2> 393  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001
             [junit4]   2> 401  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/solr.xml
             [junit4]   2> 914  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001
             [junit4]   2> 970  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreContainer New CoreContainer 62491411
             [junit4]   2> 970  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001]
             [junit4]   2> 971  WARN  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/lib to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/lib
             [junit4]   2> 997  INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
             [junit4]   2> 1212 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
             [junit4]   2> 1217 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
             [junit4]   2> 1219 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
             [junit4]   2> 1223 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
             [junit4]   2> 1224 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreContainer No authentication plugin used.
             [junit4]   2> 1337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001
             [junit4]   2> 1347 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
             [junit4]   2> 1347 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1
             [junit4]   2> 1348 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
             [junit4]   2> 1354 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1'
             [junit4]   2> 1354 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.s.SolrDispatchFilter user.dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0
             [junit4]   2> 1354 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
             [junit4]   2> 1354 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
             [junit4]   2> 1355 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001
             [junit4]   2> 1541 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
             [junit4]   2> 1548 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
             [junit4]   2> 1551 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/extraction/lib
             [junit4]   2> 1551 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist
             [junit4]   2> 1551 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/clustering/lib
             [junit4]   2> 1552 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist
             [junit4]   2> 1552 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/langid/lib
             [junit4]   2> 1552 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist
             [junit4]   2> 1553 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/velocity/lib
             [junit4]   2> 1553 WARN  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist
             [junit4]   2> 1622 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled
             [junit4]   2> 1641 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
             [junit4]   2> 1910 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
             [junit4]   2> 1980 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=example
             [junit4]   2> 3186 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
             [junit4]   2> 3567 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
             [junit4]   2> 3569 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
             [junit4]   2> 3579 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedStopFilterFactory to existing ManagedResource /schema/analysis/stopwords/english
             [junit4]   2> 3580 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory to existing ManagedResource /schema/analysis/synonyms/english
             [junit4]   2> 3610 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
             [junit4]   2> 3619 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
             [junit4]   2> 3678 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1'
             [junit4]   2> 3679 INFO  (coreLoadExecutor-6-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1
             [junit4]   2> 3753 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
             [junit4]   2> 3765 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1], dataDir=[/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/]
             [junit4]   2> 3768 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap No JMX servers found, not exposing Solr information with JMX.
             [junit4]   2> 3782 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Added SolrEventListener for newSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
             [junit4]   2> 3782 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Added SolrEventListener for firstSearcher: [org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}]
             [junit4]   2> 3816 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data
             [junit4]   2> 3816 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index/
             [junit4]   2> 3817 WARN  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index' doesn't exist. Creating new index...
             [junit4]   2> 3834 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index
             [junit4]   2> 3884 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@6f2663c1
             [junit4]   2> 3886 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
             [junit4]   2> 3886 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 0 msec to checkpoint
             [junit4]   2> 3886 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: init: create=true
             [junit4]   2> 3887 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: 
             [junit4]   2> dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217)
             [junit4]   2> index=
             [junit4]   2> version=6.0.0
             [junit4]   2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
             [junit4]   2> ramBufferSizeMB=100.0
             [junit4]   2> maxBufferedDocs=-1
             [junit4]   2> maxBufferedDeleteTerms=-1
             [junit4]   2> mergedSegmentWarmer=null
             [junit4]   2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
             [junit4]   2> commit=null
             [junit4]   2> openMode=CREATE
             [junit4]   2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$1
             [junit4]   2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
             [junit4]   2> codec=Lucene60
             [junit4]   2> infoStream=org.apache.solr.update.LoggingInfoStream
             [junit4]   2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
             [junit4]   2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@7af6e178
             [junit4]   2> readerPooling=false
             [junit4]   2> perThreadHardLimitMB=1945
             [junit4]   2> useCompoundFile=false
             [junit4]   2> commitOnClose=true
             [junit4]   2> writer=org.apache.solr.update.SolrIndexWriter@ae71d24
             [junit4]   2> 
             [junit4]   2> 3901 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
             [junit4]   2> 3902 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now flush at close
             [junit4]   2> 3902 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]:   start flush: applyAllDeletes=true
             [junit4]   2> 3902 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]:   index before flush 
             [junit4]   2> 3902 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: startFullFlush
             [junit4]   2> 3903 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: coreLoadExecutor-6-thread-1 finishFullFlush success=true
             [junit4]   2> 3903 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: apply all deletes during flush
             [junit4]   2> 3903 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now apply all deletes for all segments maxDoc=0
             [junit4]   2> 3907 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-6-thread-1]: prune sis=segments:  minGen=9223372036854775807 packetCount=0
             [junit4]   2> 3910 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
             [junit4]   2> 3911 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
             [junit4]   2> 3911 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: now merge
             [junit4]   2> 3911 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]:   index: 
             [junit4]   2> 3911 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]:   no more merges pending; now return
             [junit4]   2> 3911 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: waitForMerges
             [junit4]   2> 3911 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: waitForMerges done
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: start
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: enter lock
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: now prepare
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: prepareCommit: flush
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]:   index before flush 
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: startFullFlush
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: apply all deletes during flush
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now apply all deletes for all segments maxDoc=0
             [junit4]   2> 3912 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-6-thread-1]: prune sis=segments:  minGen=9223372036854775807 packetCount=0
             [junit4]   2> 3913 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: coreLoadExecutor-6-thread-1 finishFullFlush success=true
             [junit4]   2> 3913 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: startCommit(): start
             [junit4]   2> 3913 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: startCommit index= changeCount=2
             [junit4]   2> 3921 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: startCommit: wrote pending segments file "pending_segments_1"
             [junit4]   2> 3921 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: done all syncs: []
             [junit4]   2> 3921 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: pendingCommit != null
             [junit4]   2> 3922 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: done writing segments file "segments_1"
             [junit4]   2> 3923 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
             [junit4]   2> 3928 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
             [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_1,generation=1}
             [junit4]   2> 3930 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
             [junit4]   2> 3930 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 7 msec to checkpoint
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: took 18.6 msec
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: done
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: rollback
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: all running merges have aborted
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: rollback: done finish merges
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: abort
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: done abort success=true
             [junit4]   2> 3931 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: rollback: infos=
             [junit4]   2> 3932 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
             [junit4]   2> 3933 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 0 msec to checkpoint
             [junit4]   2> 3935 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/
             [junit4]   2> 3935 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
             [junit4]   2> 3942 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag queryResponseWriter : 'velocity' created with startup=lazy 
             [junit4]   2> 3957 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
             [junit4]   2> 4408 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
             [junit4]   2> 4510 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
             [junit4]   2> 4517 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
             [junit4]   2> 4523 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
             [junit4]   2> 4524 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
             [junit4]   2> 4666 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/update/extract' created with startup=lazy 
             [junit4]   2> 4666 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/analysis/field' created with startup=lazy 
             [junit4]   2> 4666 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/analysis/document' created with startup=lazy 
             [junit4]   2> 4671 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/spell' created with startup=lazy 
             [junit4]   2> 4671 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/suggest' created with startup=lazy 
             [junit4]   2> 4671 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/tvrh' created with startup=lazy 
             [junit4]   2> 4671 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/terms' created with startup=lazy 
             [junit4]   2> 4672 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : '/elevate' created with startup=lazy 
             [junit4]   2> 4672 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/sql,/tvrh,/select,/admin/segments,/admin/system,/suggest,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/debug/dump,/terms,/update/extract
             [junit4]   2> 4677 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
             [junit4]   2> 4698 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
             [junit4]   2> 4698 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
             [junit4]   2> 4711 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; 
             [junit4]   2> 4711 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
             [junit4]   2> 4714 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@6f2663c1
             [junit4]   2> 4714 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: init: load commit "segments_1"
             [junit4]   2> 4714 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
             [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_1,generation=1}
             [junit4]   2> 4714 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
             [junit4]   2> 4715 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
             [junit4]   2> 4715 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 0 msec to checkpoint
             [junit4]   2> 4715 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: init: create=false
             [junit4]   2> 4715 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: 
             [junit4]   2> dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217)
             [junit4]   2> index=
             [junit4]   2> version=6.0.0
             [junit4]   2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
             [junit4]   2> ramBufferSizeMB=100.0
             [junit4]   2> maxBufferedDocs=-1
             [junit4]   2> maxBufferedDeleteTerms=-1
             [junit4]   2> mergedSegmentWarmer=null
             [junit4]   2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
             [junit4]   2> commit=null
             [junit4]   2> openMode=APPEND
             [junit4]   2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$1
             [junit4]   2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
             [junit4]   2> codec=Lucene60
             [junit4]   2> infoStream=org.apache.solr.update.LoggingInfoStream
             [junit4]   2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
             [junit4]   2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@72e55de6
             [junit4]   2> readerPooling=false
             [junit4]   2> perThreadHardLimitMB=1945
             [junit4]   2> useCompoundFile=false
             [junit4]   2> commitOnClose=true
             [junit4]   2> writer=org.apache.solr.update.SolrIndexWriter@4bc2b3b8
             [junit4]   2> 
             [junit4]   2> 4715 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true
             [junit4]   2> 4719 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: flush at getReader
             [junit4]   2> 4719 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: startFullFlush
             [junit4]   2> 4720 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: apply all deletes during flush
             [junit4]   2> 4720 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now apply all deletes for all segments maxDoc=0
             [junit4]   2> 4720 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-6-thread-1]: prune sis=segments_1:  minGen=9223372036854775807 packetCount=0
             [junit4]   2> 4724 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: incRefDeleter for NRT reader version=2 segments=
             [junit4]   2> 4724 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
             [junit4]   2> 4724 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: coreLoadExecutor-6-thread-1 finishFullFlush success=true
             [junit4]   2> 4724 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: getReader took 5 msec
             [junit4]   2> 4754 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@68f8abc5[collection1] main
             [junit4]   2> 4767 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4768 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf}
             [junit4]   2> 4770 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4774 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4775 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed
             [junit4]   2> 4775 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 2 registered ManagedResources
             [junit4]   2> 4775 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _schema_analysis_stopwords_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4777 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4777 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
             [junit4]   2> 4777 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english
             [junit4]   2> 4778 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/stopwords/english
             [junit4]   2> 4778 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _schema_analysis_synonyms_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4779 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf
             [junit4]   2> 4779 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
             [junit4]   2> 4780 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.s.a.ManagedSynonymFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english
             [junit4]   2> 4786 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/synonyms/english
             [junit4]   2> 4806 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
             [junit4]   2> 4818 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
             [junit4]   2> 4838 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent No queryConverter defined, using default converter
             [junit4]   2> 4840 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
             [junit4]   2> 4846 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.s.s.SolrSuggester init: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
             [junit4]   2> 4915 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
             [junit4]   2> 4919 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf/elevate.xml
             [junit4]   2> 5012 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
             [junit4]   2> 5045 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@68f8abc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
             [junit4]   2> 5057 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
             [junit4]   2> 5058 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
             [junit4]   2> 5058 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
             [junit4]   2> 5058 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1521188368712466432
             [junit4]   2> 5076 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.u.UpdateLog Took 17.0ms to seed version buckets with highest version 1521188368712466432
             [junit4]   2> 5084 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
             [junit4]   2> 5209 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher} hits=0 status=0 QTime=158
             [junit4]   2> 5210 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done.
             [junit4]   2> 5210 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
             [junit4]   2> 5211 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
             [junit4]   2> 5212 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68f8abc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
             [junit4]   2> 5355 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.SolrJettyTestBase Jetty Assigned Port#33129
             [junit4]   2> 5370 INFO  (TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[EC056F9D893F1A9A]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateField
             [junit4]   2> 5749 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
             [junit4]   2> 5750 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
             [junit4]   2> 5750 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
             [junit4]   2> 5750 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: flush at getReader
             [junit4]   2> 5750 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: startFullFlush
             [junit4]   2> 5750 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
             [junit4]   2> 5751 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: qtp1842250958-20: flush naked frozen global deletes
             [junit4]   2> 5761 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-20]: push deletes  1 deleted queries bytesUsed=80 segmentPrivate?=false delGen=2 packetCount=1 totBytesUsed=80
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: flush: push buffered updates:  1 deleted queries bytesUsed=80
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: apply all deletes during flush
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: now apply all deletes for all segments maxDoc=0
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-20]: prune sis=segments_1:  minGen=9223372036854775807 packetCount=1
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-20]: pruneDeletes: prune 1 packets; 0 packets remain
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: incRefDeleter for NRT reader version=2 segments=
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
             [junit4]   2> 5762 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: qtp1842250958-20 finishFullFlush success=true
             [junit4]   2> 5763 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: getReader took 13 msec
             [junit4]   2> 5763 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: decRefDeleter for NRT reader version=2 segments=
             [junit4]   2> 5781 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1521188369410818048)} 0 86
             [junit4]   2> 5867 INFO  (qtp1842250958-19) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
             [junit4]   2> 5868 INFO  (qtp1842250958-19) [    x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
             [junit4]   2> 5870 INFO  (qtp1842250958-19) [    x:collection1] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
             [junit4]   2> 5875 INFO  (qtp1842250958-19) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
             [junit4]   2> 5876 INFO  (qtp1842250958-19) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10
             [junit4]   2> 6160 INFO  (qtp1842250958-18) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[unique (1521188369615290368)]} 0 250
             [junit4]   2> 6164 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
             [junit4]   2> 6165 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: start
             [junit4]   2> 6165 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: enter lock
             [junit4]   2> 6165 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: now prepare
             [junit4]   2> 6165 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: prepareCommit: flush
             [junit4]   2> 6165 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]:   index before flush 
             [junit4]   2> 6165 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: startFullFlush
             [junit4]   2> 6166 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
             [junit4]   2> 6166 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWFC][qtp1842250958-21]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_0, aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 2 ]]
             [junit4]   2> 6170 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flush postings as segment _0 numDocs=1
             [junit4]   2> 6209 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: new segment has 0 deleted docs
             [junit4]   2> 6209 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: new segment has no vectors; norms; no docValues; prox; freqs
             [junit4]   2> 6209 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.nvm, _0.fnm]
             [junit4]   2> 6209 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flushed codec=Lucene60
             [junit4]   2> 6212 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flushed: segment=_0 ramUsed=0.103 MB newFlushedSize=0.002 MB docs/MB=660.312
             [junit4]   2> 6214 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flush time 43.661953 msec
             [junit4]   2> 6217 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: publishFlushedSegment seg-private updates=null
             [junit4]   2> 6217 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: publishFlushedSegment
             [junit4]   2> 6219 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: push deletes  1 deleted terms (unique count=1) bytesUsed=1072 segmentPrivate?=false delGen=4 packetCount=1 totBytesUsed=1072
             [junit4]   2> 6219 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: publish sets newSegment delGen=5 seg=_0(6.0.0):C1
             [junit4]   2> 6220 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = false]
             [junit4]   2> 6220 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: 0 msec to checkpoint
             [junit4]   2> 6220 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: apply all deletes during flush
             [junit4]   2> 6220 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: now apply all deletes for all segments maxDoc=1
             [junit4]   2> 6220 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: open segment readers took 0 msec
             [junit4]   2> 6220 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: infos=[_0(6.0.0):C1] packetCount=1
             [junit4]   2> 6221 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes took 0 msec for 1 segments, 0 newly deleted docs (query deletes), 0 visited terms, allDeleted=null
             [junit4]   2> 6221 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: prune sis=segments_1: _0(6.0.0):C1 minGen=5 packetCount=1
             [junit4]   2> 6221 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: pruneDeletes: prune 1 packets; 0 packets remain
             [junit4]   2> 6221 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: qtp1842250958-21 finishFullFlush success=true
             [junit4]   2> 6221 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [TMP][qtp1842250958-21]: findMerges: 1 segments
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [TMP][qtp1842250958-21]:   seg=_0(6.0.0):C1 size=0.002 MB [floored]
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [TMP][qtp1842250958-21]:   allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]: now merge
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]:   index: _0(6.0.0):C1
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]:   no more merges pending; now return
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: startCommit(): start
             [junit4]   2> 6223 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: startCommit index=_0(6.0.0):C1 changeCount=4
             [junit4]   2> 6224 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: startCommit: wrote pending segments file "pending_segments_2"
             [junit4]   2> 6225 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: done all syncs: [_0.si, _0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.nvm, _0.fnm]
             [junit4]   2> 6225 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: pendingCommit != null
             [junit4]   2> 6225 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: done writing segments file "segments_2"
             [junit4]   2> 6225 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = true]
             [junit4]   2> 6225 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
             [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_1,generation=1}
             [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_2,generation=2}
             [junit4]   2> 6225 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: deleteCommits: now decRef commit "segments_1"
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: delete "segments_1"
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: 1 msec to checkpoint
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: took 60.7 msec
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: done
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
             [junit4]   2> 6226 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: flush at getReader
             [junit4]   2> 6227 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: startFullFlush
             [junit4]   2> 6227 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: apply all deletes during flush
             [junit4]   2> 6227 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: now apply all deletes for all segments maxDoc=1
             [junit4]   2> 6227 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: open segment readers took 0 msec
             [junit4]   2> 6227 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: no segments; skipping
             [junit4]   2> 6227 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: prune sis=segments_2: _0(6.0.0):C1 minGen=5 packetCount=0
             [junit4]   2> 6255 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: incRefDeleter for NRT reader version=6 segments=_0(6.0.0):C1
             [junit4]   2> 6255 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: return reader version=6 reader=StandardDirectoryReader(segments_2:6:nrt _0(6.0.0):C1)
             [junit4]   2> 6255 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: qtp1842250958-21 finishFullFlush success=true
             [junit4]   2> 6255 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: getReader took 29 msec
             [junit4]   2> 6258 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@71c2b84c[collection1] main
             [junit4]   2> 6261 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
             [junit4]   2> 6261 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@71c2b84c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
             [junit4]   2> 6261 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done.
             [junit4]   2> 6261 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71c2b84c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
             [junit4]   2> 6261 INFO  (searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][searcherExecutor-7-thread-1-processing-x:collection1]: decRefDeleter for NRT reader version=2 segments=
             [junit4]   2> 6261 INFO  (qtp1842250958-21) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 98
             [junit4]   2> 6286 INFO  (qtp1842250958-22) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&wt=javabin&version=2} hits=1 status=0 QTime=13
             [junit4]   2> 6329 INFO  (qtp1842250958-15) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{} 0 37
             [junit4]   2> 6542 INFO  (concurrentUpdateScheduler-3-thread-1-processing-http:////127.0.0.1:33129//solr//collection1) [    ] o.a.h.i.c.SystemDefaultHttpClient I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33129: Connection reset
             [junit4]   2> 6542 INFO  (concurrentUpdateScheduler-3-thread-1-processing-http:////127.0.0.1:33129//solr//collection1) [    ] o.a.h.i.c.SystemDefaultHttpClient Retrying request to {}->http://127.0.0.1:33129
             [junit4]   2> 6545 INFO  (qtp1842250958-18) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{} 0 1
             [junit4]   2> 6793 INFO  (concurrentUpdateScheduler-3-thread-1-processing-http:////127.0.0.1:33129//solr//collection1) [    ] o.a.h.i.c.SystemDefaultHttpClient I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33129: Connection reset
             [junit4]   2> 6793 INFO  (concurrentUpdateScheduler-3-thread-1-processing-http:////127.0.0.1:33129//solr//collection1) [    ] o.a.h.i.c.SystemDefaultHttpClient Retrying request to {}->http://127.0.0.1:33129
             [junit4]   2> 6795 INFO  (qtp1842250958-19) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{} 0 0
             [junit4]   2> 7045 INFO  (concurrentUpdateScheduler-3-thread-1-processing-http:////127.0.0.1:33129//solr//collection1) [    ] o.a.h.i.c.SystemDefaultHttpClient I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33129: Connection reset
             [junit4]   2> 7046 INFO  (concurrentUpdateScheduler-3-thread-1-processing-http:////127.0.0.1:33129//solr//collection1) [    ] o.a.h.i.c.SystemDefaultHttpClient Retrying request to {}->http://127.0.0.1:33129
             [junit4]   2> 7050 INFO  (qtp1842250958-16) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{} 0 0
             [junit4]   2> 7303 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
             [junit4]   2> 7303 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
             [junit4]   2> 7303 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
             [junit4]   2> 7303 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
             [junit4]   2> 7303 INFO  (qtp1842250958-20) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
             [junit4]   2> 7305 INFO  (TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[EC056F9D893F1A9A]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateField
             [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField -Dtests.seed=EC056F9D893F1A9A -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=America/Dawson -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
             [junit4] FAILURE 1.96s | SolrExampleStreamingBinaryTest.testUpdateField <<<
             [junit4]    > Throwable #1: java.lang.AssertionError: ConcurrentUpdateSolrClient did not report an error
             [junit4]    > 	at __randomizedtesting.SeedInfo.seed([EC056F9D893F1A9A:B976C5C949170A5C]:0)
             [junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1633)
             [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
             [junit4]   2> 7325 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@3b2c45{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
             [junit4]   2> 7326 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=62491411
             [junit4]   2> 7326 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@450e9f8d
             [junit4]   2> 7327 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=3,autocommit maxTime=15000ms,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=1,cumulative_errors=0,transaction_logs_total_size=191,transaction_logs_total_number=2}
             [junit4]   2> 7328 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
             [junit4]   2> 7329 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
             [junit4]   2> 7329 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now flush at close
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]:   start flush: applyAllDeletes=true
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]:   index before flush _0(6.0.0):C1
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: startFullFlush
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker finishFullFlush success=true
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: apply all deletes during flush
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now apply all deletes for all segments maxDoc=1
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: open segment readers took 0 msec
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: no segments; skipping
             [junit4]   2> 7334 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: prune sis=segments_2: _0(6.0.0):C1 minGen=5 packetCount=0
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now merge
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]:   index: _0(6.0.0):C1
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]:   no more merges pending; now return
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: waitForMerges
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: waitForMerges done
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: start
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: enter lock
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: now prepare
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: prepareCommit: flush
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]:   index before flush _0(6.0.0):C1
             [junit4]   2> 7335 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: startFullFlush
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: apply all deletes during flush
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now apply all deletes for all segments maxDoc=1
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: open segment readers took 0 msec
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: no segments; skipping
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: prune sis=segments_2: _0(6.0.0):C1 minGen=5 packetCount=0
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker finishFullFlush success=true
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: startCommit(): start
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]:   skip startCommit(): no changes pending
             [junit4]   2> 7336 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: pendingCommit == null; skip
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: took 1.2 msec
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: done
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: rollback
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: all running merges have aborted
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: rollback: done finish merges
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: abort
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: done abort success=true
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: rollback: infos=_0(6.0.0):C1
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = false]
             [junit4]   2> 7337 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.u.LoggingInfoStream [IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: 0 msec to checkpoint
             [junit4]   2> 7338 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
             [junit4]   2> 7348 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
             [junit4]   2> 7349 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data [CachedDir<<refCount=0;path=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data;done=false>>]
             [junit4]   2> 7349 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data
             [junit4]   2> 7349 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index [CachedDir<<refCount=0;path=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index;done=false>>]
             [junit4]   2> 7350 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index
             [junit4]   2> 7352 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6ec49310{/solr,null,UNAVAILABLE}
             [junit4]   2> 7355 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
             [junit4]   2> NOTE: leaving temporary files on disk at: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001
             [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, sim=ClassicSimilarity, locale=es, timezone=America/Dawson
             [junit4]   2> NOTE: Linux 4.2.8-300.fc23.x86_64 amd64/Oracle Corporation 1.8.0_65 (64-bit)/cpus=4,threads=1,free=131111776,total=251658240
             [junit4]   2> NOTE: All tests run in this JVM: [SolrExampleStreamingBinaryTest]
             [junit4] Completed [1/1 (1!)] in 8.61s, 1 test, 1 failure <<< FAILURES!
             [junit4] 
             [junit4] 
             [junit4] Tests with failures [seed: EC056F9D893F1A9A]:
             [junit4]   - org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testUpdateField
             [junit4] 
             [junit4] 
             [junit4] JVM J0:     1.04 ..    10.55 =     9.51s
             [junit4] Execution time total: 10 seconds
             [junit4] Tests summary: 1 suite, 1 test, 1 failure
          
          BUILD FAILED
          /home/ishan/code/lucene-solr-svn/solr/build.xml:246: The following error occurred while executing this line:
          /home/ishan/code/lucene-solr-svn/solr/common-build.xml:516: The following error occurred while executing this line:
          /home/ishan/code/lucene-solr-svn/lucene/common-build.xml:1436: The following error occurred while executing this line:
          /home/ishan/code/lucene-solr-svn/lucene/common-build.xml:991: There were test failures: 1 suite, 1 test, 1 failure [seed: EC056F9D893F1A9A]
          
          Total time: 2 minutes 45 seconds
          [ishan@x230 solr]$ 
          
          Show
          ichattopadhyaya Ishan Chattopadhyaya added a comment - Mark, I applied your patch and ran the test, but the test still fails with the following output. It appears to be the same as what I saw without your patch, but the exact error isn't clear to me from that output. [ishan@x230 solr]$ ant clean test -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField ... ivy-configure: [ivy:configure] :: loading settings :: file = /home/ishan/code/lucene-solr-svn/lucene/ivy-settings.xml resolve: init: compile-core: validate: resolve-groovy: -init-totals: -test: [mkdir] Created dir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test [mkdir] Created dir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/temp [junit4] <JUnit4> says 今日は! Master seed: EC056F9D893F1A9A [junit4] Executing 1 suite with 1 JVM. [junit4] [junit4] Started J0 PID(1129@x230). [junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest [junit4] 2> Creating dataDir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/init-core-data-001 [junit4] 2> 0 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 2 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 6 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-002/cores/core [junit4] 2> 46 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.e.j.u.log Logging initialized @2663ms [junit4] 2> 277 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 323 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6ec49310{/solr, null ,AVAILABLE} [junit4] 2> 339 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.e.j.s.ServerConnector Started ServerConnector@3b2c45{HTTP/1.1,[http/1.1]}{127.0.0.1:33129} [junit4] 2> 339 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.e.j.s.Server Started @2960ms [junit4] 2> 340 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33129, configSetBaseDir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001, coreRootDirectory=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-002/cores} [junit4] 2> 349 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 385 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001' [junit4] 2> 391 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 393 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001 [junit4] 2> 401 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/solr.xml [junit4] 2> 914 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001 [junit4] 2> 970 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 62491411 [junit4] 2> 970 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001] [junit4] 2> 971 WARN (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/lib to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/lib [junit4] 2> 997 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false ,useRetries : false , [junit4] 2> 1212 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry= true [junit4] 2> 1217 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1219 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1223 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1224 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001 [junit4] 2> 1347 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup= true , schema=schema.xml, configSetProperties=configsetprops.json, transient = false , dataDir=data/} [junit4] 2> 1347 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1 [junit4] 2> 1348 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1354 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1' [junit4] 2> 1354 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0 [junit4] 2> 1354 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1354 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1355 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001 [junit4] 2> 1541 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1548 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader [junit4] 2> 1551 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/extraction/lib [junit4] 2> 1551 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist [junit4] 2> 1551 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/clustering/lib [junit4] 2> 1552 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist [junit4] 2> 1552 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/langid/lib [junit4] 2> 1552 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist [junit4] 2> 1553 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../contrib/velocity/lib [junit4] 2> 1553 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/../../../../dist [junit4] 2> 1622 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled [junit4] 2> 1641 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1910 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1980 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=example [junit4] 2> 3186 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 3567 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr. rest .schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english [junit4] 2> 3569 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr. rest .schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english [junit4] 2> 3579 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.r.RestManager Added observer of type org.apache.solr. rest .schema.analysis.ManagedStopFilterFactory to existing ManagedResource /schema/analysis/stopwords/english [junit4] 2> 3580 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.r.RestManager Added observer of type org.apache.solr. rest .schema.analysis.ManagedSynonymFilterFactory to existing ManagedResource /schema/analysis/synonyms/english [junit4] 2> 3610 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3619 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3678 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1' [junit4] 2> 3679 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1 [junit4] 2> 3753 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3765 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1], dataDir=[/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/] [junit4] 2> 3768 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap No JMX servers found, not exposing Solr information with JMX. [junit4] 2> 3782 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Added SolrEventListener for newSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}] [junit4] 2> 3782 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Added SolrEventListener for firstSearcher: [org.apache.solr.core.QuerySenderListener{queries=[{q= static firstSearcher warming in solrconfig.xml}]}] [junit4] 2> 3816 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data [junit4] 2> 3816 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old= null new =/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index/ [junit4] 2> 3817 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 3834 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index [junit4] 2> 3884 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: init: current segments file is "segments" ; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@6f2663c1 [junit4] 2> 3886 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = false ] [junit4] 2> 3886 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 0 msec to checkpoint [junit4] 2> 3886 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: init: create= true [junit4] 2> 3887 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: [junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217) [junit4] 2> index= [junit4] 2> version=6.0.0 [junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer [junit4] 2> ramBufferSizeMB=100.0 [junit4] 2> maxBufferedDocs=-1 [junit4] 2> maxBufferedDeleteTerms=-1 [junit4] 2> mergedSegmentWarmer= null [junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper [junit4] 2> commit= null [junit4] 2> openMode=CREATE [junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$1 [junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle= true [junit4] 2> codec=Lucene60 [junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream [junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1 [junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@7af6e178 [junit4] 2> readerPooling= false [junit4] 2> perThreadHardLimitMB=1945 [junit4] 2> useCompoundFile= false [junit4] 2> commitOnClose= true [junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@ae71d24 [junit4] 2> [junit4] 2> 3901 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: MMapDirectory.UNMAP_SUPPORTED= true [junit4] 2> 3902 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now flush at close [junit4] 2> 3902 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: start flush: applyAllDeletes= true [junit4] 2> 3902 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: index before flush [junit4] 2> 3902 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: startFullFlush [junit4] 2> 3903 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: coreLoadExecutor-6-thread-1 finishFullFlush success= true [junit4] 2> 3903 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: apply all deletes during flush [junit4] 2> 3903 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now apply all deletes for all segments maxDoc=0 [junit4] 2> 3907 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-6-thread-1]: prune sis=segments: minGen=9223372036854775807 packetCount=0 [junit4] 2> 3910 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: initDynamicDefaults spins= true maxThreadCount=1 maxMergeCount=6 [junit4] 2> 3911 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: updateMergeThreads ioThrottle= true targetMBPerSec=10240.0 MB/sec [junit4] 2> 3911 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: now merge [junit4] 2> 3911 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: index: [junit4] 2> 3911 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-6-thread-1]: no more merges pending; now return [junit4] 2> 3911 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: waitForMerges [junit4] 2> 3911 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: waitForMerges done [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: start [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: enter lock [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: now prepare [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: prepareCommit: flush [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: index before flush [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: startFullFlush [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: apply all deletes during flush [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now apply all deletes for all segments maxDoc=0 [junit4] 2> 3912 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-6-thread-1]: prune sis=segments: minGen=9223372036854775807 packetCount=0 [junit4] 2> 3913 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: coreLoadExecutor-6-thread-1 finishFullFlush success= true [junit4] 2> 3913 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: startCommit(): start [junit4] 2> 3913 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: startCommit index= changeCount=2 [junit4] 2> 3921 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: startCommit: wrote pending segments file "pending_segments_1" [junit4] 2> 3921 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: done all syncs: [] [junit4] 2> 3921 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: pendingCommit != null [junit4] 2> 3922 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: done writing segments file "segments_1" [junit4] 2> 3923 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = true ] [junit4] 2> 3928 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_1,generation=1} [junit4] 2> 3930 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3930 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 7 msec to checkpoint [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: took 18.6 msec [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: commit: done [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: rollback [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: all running merges have aborted [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: rollback: done finish merges [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: abort [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: done abort success= true [junit4] 2> 3931 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: rollback: infos= [junit4] 2> 3932 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = false ] [junit4] 2> 3933 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 0 msec to checkpoint [junit4] 2> 3935 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/ [junit4] 2> 3935 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 3942 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag queryResponseWriter : 'velocity' created with startup=lazy [junit4] 2> 3957 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5 [junit4] 2> 4408 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default , creating implicit default [junit4] 2> 4510 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4517 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4523 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4524 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4666 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/update/extract' created with startup=lazy [junit4] 2> 4666 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/analysis/field' created with startup=lazy [junit4] 2> 4666 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/analysis/document' created with startup=lazy [junit4] 2> 4671 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/spell' created with startup=lazy [junit4] 2> 4671 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/suggest' created with startup=lazy [junit4] 2> 4671 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/tvrh' created with startup=lazy [junit4] 2> 4671 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/terms' created with startup=lazy [junit4] 2> 4672 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : '/elevate' created with startup=lazy [junit4] 2> 4672 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/sql,/tvrh,/select,/admin/segments,/admin/system,/suggest,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/debug/dump,/terms,/update/extract [junit4] 2> 4677 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4698 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4698 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4711 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 4711 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4714 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: init: current segments file is "segments_1" ; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@6f2663c1 [junit4] 2> 4714 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: init: load commit "segments_1" [junit4] 2> 4714 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_1,generation=1} [junit4] 2> 4714 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 4715 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: now checkpoint "" [0 segments ; isCommit = false ] [junit4] 2> 4715 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-6-thread-1]: 0 msec to checkpoint [junit4] 2> 4715 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: init: create= false [junit4] 2> 4715 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: [junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217) [junit4] 2> index= [junit4] 2> version=6.0.0 [junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer [junit4] 2> ramBufferSizeMB=100.0 [junit4] 2> maxBufferedDocs=-1 [junit4] 2> maxBufferedDeleteTerms=-1 [junit4] 2> mergedSegmentWarmer= null [junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper [junit4] 2> commit= null [junit4] 2> openMode=APPEND [junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$1 [junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle= true [junit4] 2> codec=Lucene60 [junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream [junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1 [junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@72e55de6 [junit4] 2> readerPooling= false [junit4] 2> perThreadHardLimitMB=1945 [junit4] 2> useCompoundFile= false [junit4] 2> commitOnClose= true [junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@4bc2b3b8 [junit4] 2> [junit4] 2> 4715 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: MMapDirectory.UNMAP_SUPPORTED= true [junit4] 2> 4719 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: flush at getReader [junit4] 2> 4719 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: startFullFlush [junit4] 2> 4720 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: apply all deletes during flush [junit4] 2> 4720 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: now apply all deletes for all segments maxDoc=0 [junit4] 2> 4720 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-6-thread-1]: prune sis=segments_1: minGen=9223372036854775807 packetCount=0 [junit4] 2> 4724 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: incRefDeleter for NRT reader version=2 segments= [junit4] 2> 4724 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt) [junit4] 2> 4724 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-6-thread-1]: coreLoadExecutor-6-thread-1 finishFullFlush success= true [junit4] 2> 4724 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-6-thread-1]: getReader took 5 msec [junit4] 2> 4754 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@68f8abc5[collection1] main [junit4] 2> 4767 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4768 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf} [junit4] 2> 4770 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4774 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4775 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Loaded initArgs {} for / rest /managed [junit4] 2> 4775 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 2 registered ManagedResources [junit4] 2> 4775 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _schema_analysis_stopwords_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4777 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4777 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase= true } for /schema/analysis/stopwords/english [junit4] 2> 4777 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english [junit4] 2> 4778 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/stopwords/english [junit4] 2> 4778 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _schema_analysis_synonyms_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4779 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf [junit4] 2> 4779 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase= true ,format=solr} for /schema/analysis/synonyms/english [junit4] 2> 4780 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.s.a.ManagedSynonymFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english [junit4] 2> 4786 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/synonyms/english [junit4] 2> 4806 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 4818 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name= default ,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01} [junit4] 2> 4838 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent No queryConverter defined, using default converter [junit4] 2> 4840 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent [junit4] 2> 4846 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup= false } [junit4] 2> 4915 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup= false } [junit4] 2> 4919 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/conf/elevate.xml [junit4] 2> 5012 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 5045 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@68f8abc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5057 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 5058 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 5058 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 5058 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1521188368712466432 [junit4] 2> 5076 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 17.0ms to seed version buckets with highest version 1521188368712466432 [junit4] 2> 5084 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 5209 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= null path= null params={q= static +firstSearcher+warming+in+solrconfig.xml&distrib= false &event=firstSearcher} hits=0 status=0 QTime=158 [junit4] 2> 5210 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 5210 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 5211 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 5212 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68f8abc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5355 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#33129 [junit4] 2> 5370 INFO (TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[EC056F9D893F1A9A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateField [junit4] 2> 5749 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: anyChanges? numDocsInRam=0 deletes= true hasTickets: false pendingChangesInFullFlush: false [junit4] 2> 5750 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: anyChanges? numDocsInRam=0 deletes= true hasTickets: false pendingChangesInFullFlush: false [junit4] 2> 5750 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: nrtIsCurrent: infoVersion matches: true ; DW changes: true ; BD changes: false [junit4] 2> 5750 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: flush at getReader [junit4] 2> 5750 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: startFullFlush [junit4] 2> 5750 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: anyChanges? numDocsInRam=0 deletes= true hasTickets: false pendingChangesInFullFlush: false [junit4] 2> 5751 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: qtp1842250958-20: flush naked frozen global deletes [junit4] 2> 5761 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-20]: push deletes 1 deleted queries bytesUsed=80 segmentPrivate?= false delGen=2 packetCount=1 totBytesUsed=80 [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: flush: push buffered updates: 1 deleted queries bytesUsed=80 [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: apply all deletes during flush [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: now apply all deletes for all segments maxDoc=0 [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-20]: prune sis=segments_1: minGen=9223372036854775807 packetCount=1 [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-20]: pruneDeletes: prune 1 packets; 0 packets remain [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: incRefDeleter for NRT reader version=2 segments= [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt) [junit4] 2> 5762 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-20]: qtp1842250958-20 finishFullFlush success= true [junit4] 2> 5763 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: getReader took 13 msec [junit4] 2> 5763 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-20]: decRefDeleter for NRT reader version=2 segments= [junit4] 2> 5781 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1521188369410818048)} 0 86 [junit4] 2> 5867 INFO (qtp1842250958-19) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize= false ,openSearcher= true ,waitSearcher= true ,expungeDeletes= false ,softCommit= false ,prepareCommit= false } [junit4] 2> 5868 INFO (qtp1842250958-19) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 5870 INFO (qtp1842250958-19) [ x:collection1] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 5875 INFO (qtp1842250958-19) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 5876 INFO (qtp1842250958-19) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher= true &commit= true &softCommit= false &wt=javabin&version=2}{commit=} 0 10 [junit4] 2> 6160 INFO (qtp1842250958-18) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[unique (1521188369615290368)]} 0 250 [junit4] 2> 6164 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize= false ,openSearcher= true ,waitSearcher= true ,expungeDeletes= false ,softCommit= false ,prepareCommit= false } [junit4] 2> 6165 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: start [junit4] 2> 6165 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: enter lock [junit4] 2> 6165 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: now prepare [junit4] 2> 6165 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: prepareCommit: flush [junit4] 2> 6165 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: index before flush [junit4] 2> 6165 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: startFullFlush [junit4] 2> 6166 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: anyChanges? numDocsInRam=1 deletes= true hasTickets: false pendingChangesInFullFlush: false [junit4] 2> 6166 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWFC][qtp1842250958-21]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_0, aborted= false , numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 2 ]] [junit4] 2> 6170 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flush postings as segment _0 numDocs=1 [junit4] 2> 6209 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: new segment has 0 deleted docs [junit4] 2> 6209 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: new segment has no vectors; norms; no docValues; prox; freqs [junit4] 2> 6209 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.nvm, _0.fnm] [junit4] 2> 6209 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flushed codec=Lucene60 [junit4] 2> 6212 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flushed: segment=_0 ramUsed=0.103 MB newFlushedSize=0.002 MB docs/MB=660.312 [junit4] 2> 6214 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DWPT][qtp1842250958-21]: flush time 43.661953 msec [junit4] 2> 6217 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: publishFlushedSegment seg- private updates= null [junit4] 2> 6217 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: publishFlushedSegment [junit4] 2> 6219 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: push deletes 1 deleted terms (unique count=1) bytesUsed=1072 segmentPrivate?= false delGen=4 packetCount=1 totBytesUsed=1072 [junit4] 2> 6219 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: publish sets newSegment delGen=5 seg=_0(6.0.0):C1 [junit4] 2> 6220 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = false ] [junit4] 2> 6220 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: 0 msec to checkpoint [junit4] 2> 6220 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: apply all deletes during flush [junit4] 2> 6220 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: now apply all deletes for all segments maxDoc=1 [junit4] 2> 6220 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: open segment readers took 0 msec [junit4] 2> 6220 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: infos=[_0(6.0.0):C1] packetCount=1 [junit4] 2> 6221 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes took 0 msec for 1 segments, 0 newly deleted docs (query deletes), 0 visited terms, allDeleted= null [junit4] 2> 6221 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: prune sis=segments_1: _0(6.0.0):C1 minGen=5 packetCount=1 [junit4] 2> 6221 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: pruneDeletes: prune 1 packets; 0 packets remain [junit4] 2> 6221 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: qtp1842250958-21 finishFullFlush success= true [junit4] 2> 6221 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [TMP][qtp1842250958-21]: findMerges: 1 segments [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [TMP][qtp1842250958-21]: seg=_0(6.0.0):C1 size=0.002 MB [floored] [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [TMP][qtp1842250958-21]: allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0 [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]: initDynamicDefaults spins= true maxThreadCount=1 maxMergeCount=6 [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]: now merge [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]: index: _0(6.0.0):C1 [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][qtp1842250958-21]: no more merges pending; now return [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: startCommit(): start [junit4] 2> 6223 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: startCommit index=_0(6.0.0):C1 changeCount=4 [junit4] 2> 6224 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: startCommit: wrote pending segments file "pending_segments_2" [junit4] 2> 6225 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: done all syncs: [_0.si, _0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.nvm, _0.fnm] [junit4] 2> 6225 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: pendingCommit != null [junit4] 2> 6225 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: done writing segments file "segments_2" [junit4] 2> 6225 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = true ] [junit4] 2> 6225 INFO (qtp1842250958-21) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6cdff2a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3a55f217),segFN=segments_2,generation=2} [junit4] 2> 6225 INFO (qtp1842250958-21) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: deleteCommits: now decRef commit "segments_1" [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: delete "segments_1" [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][qtp1842250958-21]: 1 msec to checkpoint [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: took 60.7 msec [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: commit: done [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: nrtIsCurrent: infoVersion matches: false ; DW changes: false ; BD changes: false [junit4] 2> 6226 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: flush at getReader [junit4] 2> 6227 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: startFullFlush [junit4] 2> 6227 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: apply all deletes during flush [junit4] 2> 6227 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: now apply all deletes for all segments maxDoc=1 [junit4] 2> 6227 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: open segment readers took 0 msec [junit4] 2> 6227 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: applyDeletes: no segments; skipping [junit4] 2> 6227 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][qtp1842250958-21]: prune sis=segments_2: _0(6.0.0):C1 minGen=5 packetCount=0 [junit4] 2> 6255 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: incRefDeleter for NRT reader version=6 segments=_0(6.0.0):C1 [junit4] 2> 6255 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: return reader version=6 reader=StandardDirectoryReader(segments_2:6:nrt _0(6.0.0):C1) [junit4] 2> 6255 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][qtp1842250958-21]: qtp1842250958-21 finishFullFlush success= true [junit4] 2> 6255 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][qtp1842250958-21]: getReader took 29 msec [junit4] 2> 6258 INFO (qtp1842250958-21) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@71c2b84c[collection1] main [junit4] 2> 6261 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 6261 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@71c2b84c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))} [junit4] 2> 6261 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 6261 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71c2b84c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))} [junit4] 2> 6261 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][searcherExecutor-7-thread-1-processing-x:collection1]: decRefDeleter for NRT reader version=2 segments= [junit4] 2> 6261 INFO (qtp1842250958-21) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher= true &commit= true &softCommit= false &wt=javabin&version=2}{commit=} 0 98 [junit4] 2> 6286 INFO (qtp1842250958-22) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&wt=javabin&version=2} hits=1 status=0 QTime=13 [junit4] 2> 6329 INFO (qtp1842250958-15) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{} 0 37 [junit4] 2> 6542 INFO (concurrentUpdateScheduler-3-thread-1-processing-http: ////127.0.0.1:33129//solr//collection1) [ ] o.a.h.i.c.SystemDefaultHttpClient I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33129: Connection reset [junit4] 2> 6542 INFO (concurrentUpdateScheduler-3-thread-1-processing-http: ////127.0.0.1:33129//solr//collection1) [ ] o.a.h.i.c.SystemDefaultHttpClient Retrying request to {}->http://127.0.0.1:33129 [junit4] 2> 6545 INFO (qtp1842250958-18) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{} 0 1 [junit4] 2> 6793 INFO (concurrentUpdateScheduler-3-thread-1-processing-http: ////127.0.0.1:33129//solr//collection1) [ ] o.a.h.i.c.SystemDefaultHttpClient I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33129: Connection reset [junit4] 2> 6793 INFO (concurrentUpdateScheduler-3-thread-1-processing-http: ////127.0.0.1:33129//solr//collection1) [ ] o.a.h.i.c.SystemDefaultHttpClient Retrying request to {}->http://127.0.0.1:33129 [junit4] 2> 6795 INFO (qtp1842250958-19) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{} 0 0 [junit4] 2> 7045 INFO (concurrentUpdateScheduler-3-thread-1-processing-http: ////127.0.0.1:33129//solr//collection1) [ ] o.a.h.i.c.SystemDefaultHttpClient I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33129: Connection reset [junit4] 2> 7046 INFO (concurrentUpdateScheduler-3-thread-1-processing-http: ////127.0.0.1:33129//solr//collection1) [ ] o.a.h.i.c.SystemDefaultHttpClient Retrying request to {}->http://127.0.0.1:33129 [junit4] 2> 7050 INFO (qtp1842250958-16) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{} 0 0 [junit4] 2> 7303 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize= false ,openSearcher= true ,waitSearcher= true ,expungeDeletes= false ,softCommit= false ,prepareCommit= false } [junit4] 2> 7303 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 7303 INFO (qtp1842250958-20) [ x:collection1] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 7303 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 7303 INFO (qtp1842250958-20) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher= true &commit= true &softCommit= false &wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 7305 INFO (TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[EC056F9D893F1A9A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateField [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField -Dtests.seed=EC056F9D893F1A9A -Dtests.slow= true -Dtests.locale=es -Dtests.timezone=America/Dawson -Dtests.asserts= true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 1.96s | SolrExampleStreamingBinaryTest.testUpdateField <<< [junit4] > Throwable #1: java.lang.AssertionError: ConcurrentUpdateSolrClient did not report an error [junit4] > at __randomizedtesting.SeedInfo.seed([EC056F9D893F1A9A:B976C5C949170A5C]:0) [junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1633) [junit4] > at java.lang. Thread .run( Thread .java:745) [junit4] 2> 7325 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@3b2c45{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 7326 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=62491411 [junit4] 2> 7326 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@450e9f8d [junit4] 2> 7327 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=3,autocommit maxTime=15000ms,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=1,cumulative_errors=0,transaction_logs_total_size=191,transaction_logs_total_number=2} [junit4] 2> 7328 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 7329 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 7329 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now flush at close [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: start flush: applyAllDeletes= true [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: index before flush _0(6.0.0):C1 [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: startFullFlush [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker finishFullFlush success= true [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: apply all deletes during flush [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now apply all deletes for all segments maxDoc=1 [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: open segment readers took 0 msec [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: no segments; skipping [junit4] 2> 7334 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: prune sis=segments_2: _0(6.0.0):C1 minGen=5 packetCount=0 [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: updateMergeThreads ioThrottle= true targetMBPerSec=10240.0 MB/sec [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now merge [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: index: _0(6.0.0):C1 [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: no more merges pending; now return [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: waitForMerges [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: waitForMerges done [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: start [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: enter lock [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: now prepare [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: prepareCommit: flush [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: index before flush _0(6.0.0):C1 [junit4] 2> 7335 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: startFullFlush [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: apply all deletes during flush [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now apply all deletes for all segments maxDoc=1 [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: open segment readers took 0 msec [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: applyDeletes: no segments; skipping [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: prune sis=segments_2: _0(6.0.0):C1 minGen=5 packetCount=0 [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker finishFullFlush success= true [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: startCommit(): start [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: skip startCommit(): no changes pending [junit4] 2> 7336 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: pendingCommit == null ; skip [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: took 1.2 msec [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: commit: done [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: rollback [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: all running merges have aborted [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: rollback: done finish merges [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: abort [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: done abort success= true [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: rollback: infos=_0(6.0.0):C1 [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = false ] [junit4] 2> 7337 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker]: 0 msec to checkpoint [junit4] 2> 7338 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 7348 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 7349 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data [CachedDir<<refCount=0;path=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data;done= false >>] [junit4] 2> 7349 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data [junit4] 2> 7349 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index [CachedDir<<refCount=0;path=/home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index;done= false >>] [junit4] 2> 7350 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001/tempDir-001/collection1/data/index [junit4] 2> 7352 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6ec49310{/solr, null ,UNAVAILABLE} [junit4] 2> 7355 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[EC056F9D893F1A9A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/ishan/code/lucene-solr-svn/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest_EC056F9D893F1A9A-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, sim=ClassicSimilarity, locale=es, timezone=America/Dawson [junit4] 2> NOTE: Linux 4.2.8-300.fc23.x86_64 amd64/Oracle Corporation 1.8.0_65 (64-bit)/cpus=4,threads=1,free=131111776,total=251658240 [junit4] 2> NOTE: All tests run in this JVM: [SolrExampleStreamingBinaryTest] [junit4] Completed [1/1 (1!)] in 8.61s, 1 test, 1 failure <<< FAILURES! [junit4] [junit4] [junit4] Tests with failures [seed: EC056F9D893F1A9A]: [junit4] - org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testUpdateField [junit4] [junit4] [junit4] JVM J0: 1.04 .. 10.55 = 9.51s [junit4] Execution time total: 10 seconds [junit4] Tests summary: 1 suite, 1 test, 1 failure BUILD FAILED /home/ishan/code/lucene-solr-svn/solr/build.xml:246: The following error occurred while executing this line: /home/ishan/code/lucene-solr-svn/solr/common-build.xml:516: The following error occurred while executing this line: /home/ishan/code/lucene-solr-svn/lucene/common-build.xml:1436: The following error occurred while executing this line: /home/ishan/code/lucene-solr-svn/lucene/common-build.xml:991: There were test failures: 1 suite, 1 test, 1 failure [seed: EC056F9D893F1A9A] Total time: 2 minutes 45 seconds [ishan@x230 solr]$
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Yeah, I just hit the fail for the first time as well. (with the patch applied).

          Thanks for the confirm.

          I filed SOLR-8451 for the above change.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Yeah, I just hit the fail for the first time as well. (with the patch applied). Thanks for the confirm. I filed SOLR-8451 for the above change.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          but the exact error isn't clear to me from that output.

          It's tripping: assertTrue("ConcurrentUpdateSolrClient did not report an error", concurrentClient.lastError.getMessage().contains("Conflict"));

          Is that the same fail as without the patch? I assume so, but just to confirm.

          Show
          markrmiller@gmail.com Mark Miller added a comment - but the exact error isn't clear to me from that output. It's tripping: assertTrue("ConcurrentUpdateSolrClient did not report an error", concurrentClient.lastError.getMessage().contains("Conflict")); Is that the same fail as without the patch? I assume so, but just to confirm.
          Hide
          ichattopadhyaya Ishan Chattopadhyaya added a comment -

          Right, same as without the patch. Here's the relevant part of the log from a run without the patch.

             [junit4]   2> 6916 INFO  (TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[DD2B3DCB4B87359]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateField
             [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField -Dtests.seed=DD2B3DCB4B87359 -Dtests.slow=true -Dtests.locale=it_IT -Dtests.timezone=Europe/Skopje -Dtests.asserts=true -Dtests.file.encoding=UTF-8
             [junit4] FAILURE 2.03s | SolrExampleStreamingBinaryTest.testUpdateField <<<
             [junit4]    > Throwable #1: java.lang.AssertionError: ConcurrentUpdateSolrClient did not report an error
             [junit4]    > 	at __randomizedtesting.SeedInfo.seed([DD2B3DCB4B87359:58A119887490639F]:0)
             [junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1633)
             [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
          
          Show
          ichattopadhyaya Ishan Chattopadhyaya added a comment - Right, same as without the patch. Here's the relevant part of the log from a run without the patch. [junit4] 2> 6916 INFO (TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[DD2B3DCB4B87359]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateField [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField -Dtests.seed=DD2B3DCB4B87359 -Dtests.slow= true -Dtests.locale=it_IT -Dtests.timezone=Europe/Skopje -Dtests.asserts= true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 2.03s | SolrExampleStreamingBinaryTest.testUpdateField <<< [junit4] > Throwable #1: java.lang.AssertionError: ConcurrentUpdateSolrClient did not report an error [junit4] > at __randomizedtesting.SeedInfo.seed([DD2B3DCB4B87359:58A119887490639F]:0) [junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1633) [junit4] > at java.lang. Thread .run( Thread .java:745)
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          So that fail seems pretty odd.

          We can only hit that fail if: !(client instanceof HttpSolrClient)

          And client can only be HttpSolrClient or EmbeddedSolrServer. It should not be EmbeddedSolrServer, because we make a Jetty for this test and presumably a Jetty is involved in this connection reset business. So we really expect that client to be an HttpSolrClient don't we?

          So how do we get to this fail message below unless we are using EmbeddedSolrServer?

                client.add(doc);
                if(client instanceof HttpSolrClient) { //XXX concurrent client reports exceptions differently
                  fail("Operation should throw an exception!");
                } else {
                  client.commit(); //just to be sure the client has sent the doc
                  ErrorTrackingConcurrentUpdateSolrClient concurrentClient = (ErrorTrackingConcurrentUpdateSolrClient) client;
                  assertNotNull("ConcurrentUpdateSolrClient did not report an error", concurrentClient.lastError);
                  assertTrue("ConcurrentUpdateSolrClient did not report correct error: " + concurrentClient.lastError.getMessage(), concurrentClient.lastError.getMessage().contains("Conflict"));
                }
          
          Show
          markrmiller@gmail.com Mark Miller added a comment - So that fail seems pretty odd. We can only hit that fail if: !(client instanceof HttpSolrClient) And client can only be HttpSolrClient or EmbeddedSolrServer. It should not be EmbeddedSolrServer, because we make a Jetty for this test and presumably a Jetty is involved in this connection reset business. So we really expect that client to be an HttpSolrClient don't we? So how do we get to this fail message below unless we are using EmbeddedSolrServer? client.add(doc); if (client instanceof HttpSolrClient) { //XXX concurrent client reports exceptions differently fail( "Operation should throw an exception!" ); } else { client.commit(); //just to be sure the client has sent the doc ErrorTrackingConcurrentUpdateSolrClient concurrentClient = (ErrorTrackingConcurrentUpdateSolrClient) client; assertNotNull( "ConcurrentUpdateSolrClient did not report an error" , concurrentClient.lastError); assertTrue( "ConcurrentUpdateSolrClient did not report correct error: " + concurrentClient.lastError.getMessage(), concurrentClient.lastError.getMessage().contains( "Conflict" )); }
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Ishan Chattopadhyaya, could you try making: 'public static JettySolrRunner jetty' in SolrJettyTestBase volatile?

          Show
          markrmiller@gmail.com Mark Miller added a comment - Ishan Chattopadhyaya , could you try making: 'public static JettySolrRunner jetty' in SolrJettyTestBase volatile?
          Hide
          ichattopadhyaya Ishan Chattopadhyaya added a comment - - edited

          I changed it to "public volatile static JettySolrRunner jetty", and without your patch. Still same error:

             [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField -Dtests.seed=C198E930F63ED207 -Dtests.slow=true -Dtests.locale=es_PR -Dtests.timezone=America/Martinique -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
             [junit4] FAILURE 2.00s | SolrExampleStreamingBinaryTest.testUpdateField <<<
             [junit4]    > Throwable #1: java.lang.AssertionError: ConcurrentUpdateSolrClient did not report an error
             [junit4]    > 	at __randomizedtesting.SeedInfo.seed([C198E930F63ED207:94EB43643616C2C1]:0)
             [junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1633)
             [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
             [junit4]   2> 7502 INFO  (SUITE-SolrExampleStreamingBinaryTest-seed#[C198E930F63ED207]-worker) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@66fa0f59{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
          

          In a few hours, I can trace through and find out the exact class of the client, and compare with what is the corresponding behaviour on branch_5x for this test.

          Show
          ichattopadhyaya Ishan Chattopadhyaya added a comment - - edited I changed it to "public volatile static JettySolrRunner jetty", and without your patch. Still same error: [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateField -Dtests.seed=C198E930F63ED207 -Dtests.slow= true -Dtests.locale=es_PR -Dtests.timezone=America/Martinique -Dtests.asserts= true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 2.00s | SolrExampleStreamingBinaryTest.testUpdateField <<< [junit4] > Throwable #1: java.lang.AssertionError: ConcurrentUpdateSolrClient did not report an error [junit4] > at __randomizedtesting.SeedInfo.seed([C198E930F63ED207:94EB43643616C2C1]:0) [junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testUpdateField(SolrExampleTests.java:1633) [junit4] > at java.lang. Thread .run( Thread .java:745) [junit4] 2> 7502 INFO (SUITE-SolrExampleStreamingBinaryTest-seed#[C198E930F63ED207]-worker) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@66fa0f59{HTTP/1.1,[http/1.1]}{127.0.0.1:0} In a few hours, I can trace through and find out the exact class of the client, and compare with what is the corresponding behaviour on branch_5x for this test.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          So that fail seems pretty odd.

          Okay, sorry, I thought SolrExampleBinaryTest was hitting this, I was working from the wrong test. It's SolrExampleStreamingBinaryTest. I can't easily reproduce a fail in either one, but I have seen it happen in some test runs.

          Show
          markrmiller@gmail.com Mark Miller added a comment - So that fail seems pretty odd. Okay, sorry, I thought SolrExampleBinaryTest was hitting this, I was working from the wrong test. It's SolrExampleStreamingBinaryTest. I can't easily reproduce a fail in either one, but I have seen it happen in some test runs.
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Yeah, it is ConcurrentHttpSolrClient. The tracked exception in this case is a connection reset exception and not a "Conflict".

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Yeah, it is ConcurrentHttpSolrClient. The tracked exception in this case is a connection reset exception and not a "Conflict".
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          I don't know how to solve this for the general case. Especially for streaming, with this behavior we really need make sure the exception gets set on the response rather than cutting off the request while the client is still sending.

          For this particular version issue with this container behavior, you need something like the following instead of throwing an exception, though we probably want something more general and that plays nicer with doFinish:

          SolrException e = new SolrException(ErrorCode.CONFLICT, "version conflict for " + cmd.getPrintableId() + " expected=" + versionOnUpdate + " actual=" + foundVersion);;
          rsp.setException(e);
          return true;

          Show
          markrmiller@gmail.com Mark Miller added a comment - I don't know how to solve this for the general case. Especially for streaming, with this behavior we really need make sure the exception gets set on the response rather than cutting off the request while the client is still sending. For this particular version issue with this container behavior, you need something like the following instead of throwing an exception, though we probably want something more general and that plays nicer with doFinish: SolrException e = new SolrException(ErrorCode.CONFLICT, "version conflict for " + cmd.getPrintableId() + " expected=" + versionOnUpdate + " actual=" + foundVersion);; rsp.setException(e); return true;
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          I filed SOLR-8453 to deal with this SolrExampleStreamingBinaryTest fail issue.

          Show
          markrmiller@gmail.com Mark Miller added a comment - I filed SOLR-8453 to deal with this SolrExampleStreamingBinaryTest fail issue.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          My patch for SOLR-8453 seems to solve pretty much all the connection resets I have been seeing except for the Locale issue. SolrExampleBinaryTest, TestManagedSchemaDynamicFieldResource and a bunch of others can fail with the wrong Locale.

          Show
          markrmiller@gmail.com Mark Miller added a comment - My patch for SOLR-8453 seems to solve pretty much all the connection resets I have been seeing except for the Locale issue. SolrExampleBinaryTest, TestManagedSchemaDynamicFieldResource and a bunch of others can fail with the wrong Locale.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          My patch for SOLR-8453 seems to solve pretty much all the connection resets I have been seeing except for the Locale issue.

          So this still stands. I think I've solved the general issue with the patch in SOLR-8453. I'm still banging around with it, but it's looking pretty good on my machine.

          I still think we probably have to roll this update back because it appears to break Solr under certain default Locales. We should probably try and isolate what is causing it so we can file a bug, but we would still need to wait for a good version.

          Show
          markrmiller@gmail.com Mark Miller added a comment - My patch for SOLR-8453 seems to solve pretty much all the connection resets I have been seeing except for the Locale issue. So this still stands. I think I've solved the general issue with the patch in SOLR-8453 . I'm still banging around with it, but it's looking pretty good on my machine. I still think we probably have to roll this update back because it appears to break Solr under certain default Locales. We should probably try and isolate what is causing it so we can file a bug, but we would still need to wait for a good version.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          It's not very often, but I seem to see the following pop up after the update and don't remember it being an issue before (for a long time anyway):

          [junit4] 2> 327513 ERROR (TEST-BasicDistributedZk2Test.test-seed#[EAADE41C3F03EAEF]) [n:127.0.0.1:38892_i c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ChaosMonkey Could not get the port to start jetty again
          [junit4] 2> java.net.BindException: Address already in use

          Show
          markrmiller@gmail.com Mark Miller added a comment - It's not very often, but I seem to see the following pop up after the update and don't remember it being an issue before (for a long time anyway): [junit4] 2> 327513 ERROR (TEST-BasicDistributedZk2Test.test-seed# [EAADE41C3F03EAEF] ) [n:127.0.0.1:38892_i c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ChaosMonkey Could not get the port to start jetty again [junit4] 2> java.net.BindException: Address already in use
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Interesting exception in a CollectionsAPIDistributedZkTest fail:

             [junit4]   2> 232756 ERROR (qtp543233699-1130) [n:127.0.0.1:43044_kqam    ] o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: Error trying to proxy request for url: http://127.0.0.1:34586/kqam/awholynewcollection_0/select
             [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.remoteQuery(HttpSolrCall.java:591)
             [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:441)
             [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:226)
             [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184)
             [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
             [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:111)
             [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
             [junit4]   2> 	at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:45)
             [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
             [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
             [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
             [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
             [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
             [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:517)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
             [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
             [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
             [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
             [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
             [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
             [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
             [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
             [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
             [junit4]   2> Caused by: java.io.IOException: Response header too large
             [junit4]   2> 	at org.eclipse.jetty.http.HttpGenerator.generateResponse(HttpGenerator.java:404)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:678)
             [junit4]   2> 	at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241)
             [junit4]   2> 	at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:509)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:668)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:722)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHttpOutputInterceptor.commit(GzipHttpOutputInterceptor.java:201)
             [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHttpOutputInterceptor.write(GzipHttpOutputInterceptor.java:100)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:177)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:163)
             [junit4]   2> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:413)
             [junit4]   2> 	at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1793)
             [junit4]   2> 	at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1769)
             [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.remoteQuery(HttpSolrCall.java:582)
             [junit4]   2> 	... 28 more
             [junit4]   2> Caused by: java.nio.BufferOverflowException
             [junit4]   2> 	at java.nio.Buffer.nextPutIndex(Buffer.java:521)
             [junit4]   2> 	at java.nio.HeapByteBuffer.put(HeapByteBuffer.java:169)
             [junit4]   2> 	at org.eclipse.jetty.http.HttpGenerator.putSanitisedValue(HttpGenerator.java:982)
             [junit4]   2> 	at org.eclipse.jetty.http.HttpGenerator.putTo(HttpGenerator.java:998)
             [junit4]   2> 	at org.eclipse.jetty.http.HttpGenerator.generateHeaders(HttpGenerator.java:711)
             [junit4]   2> 	at org.eclipse.jetty.http.HttpGenerator.generateResponse(HttpGenerator.java:389)
             [junit4]   2> 	... 42 more
          
          Show
          markrmiller@gmail.com Mark Miller added a comment - Interesting exception in a CollectionsAPIDistributedZkTest fail: [junit4] 2> 232756 ERROR (qtp543233699-1130) [n:127.0.0.1:43044_kqam ] o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: Error trying to proxy request for url: http://127.0.0.1:34586/kqam/awholynewcollection_0/select [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.remoteQuery(HttpSolrCall.java:591) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:441) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:226) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:111) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668) [junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:45) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:517) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.io.IOException: Response header too large [junit4] 2> at org.eclipse.jetty.http.HttpGenerator.generateResponse(HttpGenerator.java:404) [junit4] 2> at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:678) [junit4] 2> at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) [junit4] 2> at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:224) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:509) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:668) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:722) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHttpOutputInterceptor.commit(GzipHttpOutputInterceptor.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHttpOutputInterceptor.write(GzipHttpOutputInterceptor.java:100) [junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:177) [junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:163) [junit4] 2> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:413) [junit4] 2> at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1793) [junit4] 2> at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1769) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.remoteQuery(HttpSolrCall.java:582) [junit4] 2> ... 28 more [junit4] 2> Caused by: java.nio.BufferOverflowException [junit4] 2> at java.nio.Buffer.nextPutIndex(Buffer.java:521) [junit4] 2> at java.nio.HeapByteBuffer.put(HeapByteBuffer.java:169) [junit4] 2> at org.eclipse.jetty.http.HttpGenerator.putSanitisedValue(HttpGenerator.java:982) [junit4] 2> at org.eclipse.jetty.http.HttpGenerator.putTo(HttpGenerator.java:998) [junit4] 2> at org.eclipse.jetty.http.HttpGenerator.generateHeaders(HttpGenerator.java:711) [junit4] 2> at org.eclipse.jetty.http.HttpGenerator.generateResponse(HttpGenerator.java:389) [junit4] 2> ... 42 more
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          I still think we probably have to roll this update back because it appears to break Solr under certain default Locales. We should probably try and isolate what is causing it so we can file a bug, but we would still need to wait for a good version.

          Makes sense. I'll revert.

          Interesting exception in a CollectionsAPIDistributedZkTest fail:

          I don't think that is caused by the jetty upgrade because it happens on 5x and 5.4 branches too. I had opened SOLR-8380 to track it.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - I still think we probably have to roll this update back because it appears to break Solr under certain default Locales. We should probably try and isolate what is causing it so we can file a bug, but we would still need to wait for a good version. Makes sense. I'll revert. Interesting exception in a CollectionsAPIDistributedZkTest fail: I don't think that is caused by the jetty upgrade because it happens on 5x and 5.4 branches too. I had opened SOLR-8380 to track it.
          Hide
          thetaphi Uwe Schindler added a comment -

          We should probably try and isolate what is causing it so we can file a bug, but we would still need to wait for a good version.

          ...maybe suggest to Jetty people that they use forbidden-apis with jdk-unsafe bundled signatures!

          Show
          thetaphi Uwe Schindler added a comment - We should probably try and isolate what is causing it so we can file a bug, but we would still need to wait for a good version. ...maybe suggest to Jetty people that they use forbidden-apis with jdk-unsafe bundled signatures!
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Patch to revert all Jetty9.3 related changes

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Patch to revert all Jetty9.3 related changes
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          We should probably commit that patch - trunk fails are quite noisy now.

          I think I've tracked down the other issue in SOLR-8453. I'm thinking it's a Jetty bug. So I think two bugs are currently preventing us from upgrading.

          I sent an email to the Jetty dev list to ask about the issue in SOLR-8453.

          Show
          markrmiller@gmail.com Mark Miller added a comment - We should probably commit that patch - trunk fails are quite noisy now. I think I've tracked down the other issue in SOLR-8453 . I'm thinking it's a Jetty bug. So I think two bugs are currently preventing us from upgrading. I sent an email to the Jetty dev list to ask about the issue in SOLR-8453 .
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Thanks a lot Mark for looking into this. I'll revert the jetty upgrade for now.

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Thanks a lot Mark for looking into this. I'll revert the jetty upgrade for now.
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 1723604 from shalin@apache.org in branch 'dev/trunk'
          [ https://svn.apache.org/r1723604 ]

          SOLR-7339: Revert Jetty 9.3 upgrade

          Show
          jira-bot ASF subversion and git services added a comment - Commit 1723604 from shalin@apache.org in branch 'dev/trunk' [ https://svn.apache.org/r1723604 ] SOLR-7339 : Revert Jetty 9.3 upgrade
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          The request that fails sends data using chunked encoding, but sends one chunk of length 0x59, but does not send the terminal chunk. The logs show Jetty reporting that the processing is complete but with unconsumed input, and therefore closes the connection.

          Joakim Erdfelt, it appears that the terminal chunk is still coming. Jetty is no longer waiting for it like it previously did. If we speed up how fast we send the terminal chunk, we can get the test to pass. If we slow it down too much, say even by turning on all debug logging, even speeding up how fast we send the terminal chunk is not enough. We basically now have 10-12ms to get that terminal chunk out, where previously Jetty would wait up to other connection timeouts (30 seconds) to get that terminal chunk.

          Show
          markrmiller@gmail.com Mark Miller added a comment - The request that fails sends data using chunked encoding, but sends one chunk of length 0x59, but does not send the terminal chunk. The logs show Jetty reporting that the processing is complete but with unconsumed input, and therefore closes the connection. Joakim Erdfelt , it appears that the terminal chunk is still coming. Jetty is no longer waiting for it like it previously did. If we speed up how fast we send the terminal chunk, we can get the test to pass. If we slow it down too much, say even by turning on all debug logging, even speeding up how fast we send the terminal chunk is not enough. We basically now have 10-12ms to get that terminal chunk out, where previously Jetty would wait up to other connection timeouts (30 seconds) to get that terminal chunk.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Yonik mentioned to me that this is probably legal behavior, even if undesirable as it prevents connection reuse for us in these cases. The problem is that HttpClient hits this in a way that we can not really work around to get to the right error and ?ignore? the connection reset?

          Show
          markrmiller@gmail.com Mark Miller added a comment - Yonik mentioned to me that this is probably legal behavior, even if undesirable as it prevents connection reuse for us in these cases. The problem is that HttpClient hits this in a way that we can not really work around to get to the right error and ?ignore? the connection reset?
          Hide
          gregw@webtide.com Greg Wilkins added a comment -

          Mark,

          A simple work around could be to install a filter on the server that does read until EOF - ie the filter will ensure that all the content is consumed. Something like psuedo code:
          ```java
          doFilter(FilterChain chain, ServletRequest request, ServletResponse response)
          {
          try

          { chain.doFilter(request,response); }

          finally

          { consumeAll(request.getInputStream()); }

          ```

          It may need to be more sophisticated than this is you sometimes use a Reader and/or async IO, but the principal is the same - get the filter to do the behaviour that was in jetty-9.2. From Jetty's point of view it is the application that can decide to block and wait for this content, so that is OK.

          Should I comment on 8453 also?

          Show
          gregw@webtide.com Greg Wilkins added a comment - Mark, A simple work around could be to install a filter on the server that does read until EOF - ie the filter will ensure that all the content is consumed. Something like psuedo code: ```java doFilter(FilterChain chain, ServletRequest request, ServletResponse response) { try { chain.doFilter(request,response); } finally { consumeAll(request.getInputStream()); } ``` It may need to be more sophisticated than this is you sometimes use a Reader and/or async IO, but the principal is the same - get the filter to do the behaviour that was in jetty-9.2. From Jetty's point of view it is the application that can decide to block and wait for this content, so that is OK. Should I comment on 8453 also?
          Hide
          yseeley@gmail.com Yonik Seeley added a comment -

          Yeah, I think we only call sendError in a few places... we could just as easily replace that with another call that consumes any remaining input as well. We could make it conditional on a query parameter too... this does feel more like a higher level decision (whether to consume all input or not).

          Show
          yseeley@gmail.com Yonik Seeley added a comment - Yeah, I think we only call sendError in a few places... we could just as easily replace that with another call that consumes any remaining input as well. We could make it conditional on a query parameter too... this does feel more like a higher level decision (whether to consume all input or not).
          Hide
          markrmiller@gmail.com Mark Miller added a comment - - edited

          I put up a patch in SOLR-8453. I still have to check and see if the tests that consistently failed on at least 1 locales was also due to this or not.

          Show
          markrmiller@gmail.com Mark Miller added a comment - - edited I put up a patch in SOLR-8453 . I still have to check and see if the tests that consistently failed on at least 1 locales was also due to this or not.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          SOLR-8453 is now committed, I'll check on the Locale issue soon. If that was also somehow related and is no longer a problem, we can go ahead with the update, otherwise we will have to dig in a bit I think.

          Show
          markrmiller@gmail.com Mark Miller added a comment - SOLR-8453 is now committed, I'll check on the Locale issue soon. If that was also somehow related and is no longer a problem, we can go ahead with the update, otherwise we will have to dig in a bit I think.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          I just tried this out again and had to file SOLR-8683. I think we can upgrade now though.

          Show
          markrmiller@gmail.com Mark Miller added a comment - I just tried this out again and had to file SOLR-8683 . I think we can upgrade now though.
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 0bba332549a11d5c381efc93a66087999b6de210 in lucene-solr's branch refs/heads/master from markrmiller
          [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=0bba332 ]

          SOLR-7339: Jetty 9.3 upgrade.

          Show
          jira-bot ASF subversion and git services added a comment - Commit 0bba332549a11d5c381efc93a66087999b6de210 in lucene-solr's branch refs/heads/master from markrmiller [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=0bba332 ] SOLR-7339 : Jetty 9.3 upgrade.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Okay, let's see how this works out...

          Show
          markrmiller@gmail.com Mark Miller added a comment - Okay, let's see how this works out...
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Darn, sometimes I'm still seeing connection reset problems. But much rarer.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Darn, sometimes I'm still seeing connection reset problems. But much rarer.
          Hide
          gregw@webtide.com Greg Wilkins added a comment -

          Yell if there is anything you want us to look at!

          Show
          gregw@webtide.com Greg Wilkins added a comment - Yell if there is anything you want us to look at!
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Well, at least they are repeatable fails.

          Not sure what it is yet, but fails seem to have the following that I don't see in passes:

          23813 DEBUG (qtp1185380035-63) [    ] o.e.j.i.ManagedSelector 
          java.lang.NoClassDefFoundError: Could not initialize class org.eclipse.jetty.http.HttpParser
          	at org.eclipse.jetty.server.HttpConnection.newHttpParser(HttpConnection.java:124)
          	at org.eclipse.jetty.server.HttpConnection.<init>(HttpConnection.java:102)
          	at org.eclipse.jetty.server.HttpConnectionFactory.newConnection(HttpConnectionFactory.java:58)
          	at org.eclipse.jetty.server.ServerConnector$ServerConnectorManager.newConnection(ServerConnector.java:510)
          	at org.eclipse.jetty.io.ManagedSelector.createEndPoint(ManagedSelector.java:411)
          	at org.eclipse.jetty.io.ManagedSelector.access$1600(ManagedSelector.java:56)
          	at org.eclipse.jetty.io.ManagedSelector$CreateEndPoint.run(ManagedSelector.java:587)
          	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
          	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:101)
          	at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:136)
          	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
          	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
          	at java.lang.Thread.run(Thread.java:745)
          
          Show
          markrmiller@gmail.com Mark Miller added a comment - Well, at least they are repeatable fails. Not sure what it is yet, but fails seem to have the following that I don't see in passes: 23813 DEBUG (qtp1185380035-63) [ ] o.e.j.i.ManagedSelector java.lang.NoClassDefFoundError: Could not initialize class org.eclipse.jetty.http.HttpParser at org.eclipse.jetty.server.HttpConnection.newHttpParser(HttpConnection.java:124) at org.eclipse.jetty.server.HttpConnection.<init>(HttpConnection.java:102) at org.eclipse.jetty.server.HttpConnectionFactory.newConnection(HttpConnectionFactory.java:58) at org.eclipse.jetty.server.ServerConnector$ServerConnectorManager.newConnection(ServerConnector.java:510) at org.eclipse.jetty.io.ManagedSelector.createEndPoint(ManagedSelector.java:411) at org.eclipse.jetty.io.ManagedSelector.access$1600(ManagedSelector.java:56) at org.eclipse.jetty.io.ManagedSelector$CreateEndPoint.run(ManagedSelector.java:587) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:101) at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) at java.lang.Thread.run(Thread.java:745)
          Hide
          joakime Joakim Erdfelt added a comment -

          From the gut:

          Option 1)
          You have multiple jetty-http.jars, and one of them is really old.
          Unfortunately, your system picked the old one over the new one.

          Option 2)
          You had a fundamental jvm runtime issue preventing that class from being initialized (memory, file descriptors, bad jars, etc..)
          Do you have any other log entries that could indicate this sort of issue?

          Option 3)
          You have a version mismatch between jetty-server.jar and jetty-http.jar

          Option 4)
          You have jetty-http.jar in your WEB-INF/lib and a testcase that flips the WebAppContext loaderPriority improperly

          Show
          joakime Joakim Erdfelt added a comment - From the gut: Option 1) You have multiple jetty-http.jars, and one of them is really old. Unfortunately, your system picked the old one over the new one. Option 2) You had a fundamental jvm runtime issue preventing that class from being initialized (memory, file descriptors, bad jars, etc..) Do you have any other log entries that could indicate this sort of issue? Option 3) You have a version mismatch between jetty-server.jar and jetty-http.jar Option 4) You have jetty-http.jar in your WEB-INF/lib and a testcase that flips the WebAppContext loaderPriority improperly
          Hide
          steve_rowe Steve Rowe added a comment -

          I'm seeing an uptick in master test failures on my Jenkins, e.g. this run with 21 test failures; skimming they're mostly caused by connection resets: http://jenkins.sarowe.net/job/Lucene-Solr-tests-trunk/5743/.

          Show
          steve_rowe Steve Rowe added a comment - I'm seeing an uptick in master test failures on my Jenkins, e.g. this run with 21 test failures; skimming they're mostly caused by connection resets: http://jenkins.sarowe.net/job/Lucene-Solr-tests-trunk/5743/ .
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          If it's not isolated test fails and a lot fail it's prob the above issue.

          Show
          markrmiller@gmail.com Mark Miller added a comment - If it's not isolated test fails and a lot fail it's prob the above issue.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          I'm going to work on finding what random setting triggers this.

          Show
          markrmiller@gmail.com Mark Miller added a comment - I'm going to work on finding what random setting triggers this.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Hmm, well this first one I'm looking at does look like it may be a Locale issue after all. If I hardcode ENGLISH locale it passes with my bad test seed.

          And there is an error before the last one I posted that points towards this as well.

          java.lang.ExceptionInInitializerError
          	at org.eclipse.jetty.server.HttpConnection.newHttpParser(HttpConnection.java:124)
          	at org.eclipse.jetty.server.HttpConnection.<init>(HttpConnection.java:102)
          	at org.eclipse.jetty.server.HttpConnectionFactory.newConnection(HttpConnectionFactory.java:58)
          	at org.eclipse.jetty.server.ServerConnector$ServerConnectorManager.newConnection(ServerConnector.java:510)
          	at org.eclipse.jetty.io.ManagedSelector.createEndPoint(ManagedSelector.java:411)
          	at org.eclipse.jetty.io.ManagedSelector.access$1600(ManagedSelector.java:56)
          	at org.eclipse.jetty.io.ManagedSelector$CreateEndPoint.run(ManagedSelector.java:587)
          	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
          	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:101)
          	at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:136)
          	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
          	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
          	at java.lang.Thread.run(Thread.java:745)
          Caused by: java.lang.IllegalArgumentException: non ascii character
          	at org.eclipse.jetty.util.ArrayTrie.put(ArrayTrie.java:171)
          	at org.eclipse.jetty.util.AbstractTrie.put(AbstractTrie.java:45)
          	at org.eclipse.jetty.http.HttpParser.<clinit>(HttpParser.java:204)
          	... 13 more
          
          Show
          markrmiller@gmail.com Mark Miller added a comment - Hmm, well this first one I'm looking at does look like it may be a Locale issue after all. If I hardcode ENGLISH locale it passes with my bad test seed. And there is an error before the last one I posted that points towards this as well. java.lang.ExceptionInInitializerError at org.eclipse.jetty.server.HttpConnection.newHttpParser(HttpConnection.java:124) at org.eclipse.jetty.server.HttpConnection.<init>(HttpConnection.java:102) at org.eclipse.jetty.server.HttpConnectionFactory.newConnection(HttpConnectionFactory.java:58) at org.eclipse.jetty.server.ServerConnector$ServerConnectorManager.newConnection(ServerConnector.java:510) at org.eclipse.jetty.io.ManagedSelector.createEndPoint(ManagedSelector.java:411) at org.eclipse.jetty.io.ManagedSelector.access$1600(ManagedSelector.java:56) at org.eclipse.jetty.io.ManagedSelector$CreateEndPoint.run(ManagedSelector.java:587) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:101) at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) at java.lang. Thread .run( Thread .java:745) Caused by: java.lang.IllegalArgumentException: non ascii character at org.eclipse.jetty.util.ArrayTrie.put(ArrayTrie.java:171) at org.eclipse.jetty.util.AbstractTrie.put(AbstractTrie.java:45) at org.eclipse.jetty.http.HttpParser.<clinit>(HttpParser.java:204) ... 13 more
          Hide
          markrmiller@gmail.com Mark Miller added a comment - - edited

          Greg Wilkins, Joakim Erdfelt, looks like a default locale bug:

          HttpParser

                      for (String charset : new String[]{"utf-8","iso-8859-1"})
                      {
                          CACHE.put(new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+";charset="+charset));
                          CACHE.put(new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+"; charset="+charset));
                          CACHE.put(new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+";charset="+charset.toUpperCase()));
                          CACHE.put(new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+"; charset="+charset.toUpperCase()));
                      }
          

          toUpperCase should use an ENGLISH locale or I guess our project has determined ROOT is better because ENGLISH could change over time. The turkish bug.

          FYI

          Our project uses this tool to ban default locale method calls: https://github.com/policeman-tools/forbidden-apis

          http://mattryall.net/blog/2009/02/the-infamous-turkish-locale-bug
          https://dzone.com/articles/policemans-horror-default

          Show
          markrmiller@gmail.com Mark Miller added a comment - - edited Greg Wilkins , Joakim Erdfelt , looks like a default locale bug: HttpParser for ( String charset : new String []{ "utf-8" , "iso-8859-1" }) { CACHE.put( new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+ ";charset=" +charset)); CACHE.put( new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+ "; charset=" +charset)); CACHE.put( new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+ ";charset=" +charset.toUpperCase())); CACHE.put( new PreEncodedHttpField(HttpHeader.CONTENT_TYPE,type+ "; charset=" +charset.toUpperCase())); } toUpperCase should use an ENGLISH locale or I guess our project has determined ROOT is better because ENGLISH could change over time. The turkish bug. FYI Our project uses this tool to ban default locale method calls: https://github.com/policeman-tools/forbidden-apis http://mattryall.net/blog/2009/02/the-infamous-turkish-locale-bug https://dzone.com/articles/policemans-horror-default
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Oh, good news. This is already fixed in master and perhaps in a release. I move us up to the latest release.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Oh, good news. This is already fixed in master and perhaps in a release. I move us up to the latest release.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Just looked at the code on GitHub - looks like latest release does not have it. We will need to hop on the next release.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Just looked at the code on GitHub - looks like latest release does not have it. We will need to hop on the next release.
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Looks like I may have to back this out again for the 6.0 release.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Looks like I may have to back this out again for the 6.0 release.
          Hide
          gregw@webtide.com Greg Wilkins added a comment -

          The locale bug has indeed been fixed already and will be in 9.3.8 (and RC0 of this is staged now so the full release should be less than a week away).

          Show
          gregw@webtide.com Greg Wilkins added a comment - The locale bug has indeed been fixed already and will be in 9.3.8 (and RC0 of this is staged now so the full release should be less than a week away).
          Hide
          steve_rowe Steve Rowe added a comment -

          Greg Wilkins, any updates about the 9.3.8 release? It's not final yet AFAICT.

          Show
          steve_rowe Steve Rowe added a comment - Greg Wilkins , any updates about the 9.3.8 release? It's not final yet AFAICT.
          Hide
          steve_rowe Steve Rowe added a comment -

          FYI the Jetty commit addressing the Locale problem is here (pushed to master and 9.3.8.RC0 branch): https://github.com/eclipse/jetty.project/commit/145e4bee7139125408acc7388bf58531e96ae3d7

          Show
          steve_rowe Steve Rowe added a comment - FYI the Jetty commit addressing the Locale problem is here (pushed to master and 9.3.8.RC0 branch): https://github.com/eclipse/jetty.project/commit/145e4bee7139125408acc7388bf58531e96ae3d7
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Mark Miller - are you going to revert this upgrade for 6.0?

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Mark Miller - are you going to revert this upgrade for 6.0?
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Was and then wasn't unless someone spoke up and had an issue with it. Have had my head under a rock for a bit.

          Super easy workaround if you are have a turkish locale - just set default locale to english.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Was and then wasn't unless someone spoke up and had an issue with it. Have had my head under a rock for a bit. Super easy workaround if you are have a turkish locale - just set default locale to english.
          Hide
          shalinmangar Shalin Shekhar Mangar added a comment -

          Okay I see. In that case, can you set this as resolved so it is not a blocker anymore?

          Show
          shalinmangar Shalin Shekhar Mangar added a comment - Okay I see. In that case, can you set this as resolved so it is not a blocker anymore?
          Hide
          markrmiller@gmail.com Mark Miller added a comment -

          Let's see if anyone has a problem with that.

          Show
          markrmiller@gmail.com Mark Miller added a comment - Let's see if anyone has a problem with that.
          Hide
          steve_rowe Steve Rowe added a comment -

          Jetty 9.3.8.v20160314 was announced yesterday: http://dev.eclipse.org/mhonarc/lists/jetty-announce/msg00090.html. Simple patch changing the dep version and switching out the checksums.

          I kicked off Solr tests, but won't check see the results till after I sleep.

          Maybe this can make it for 6.0?

          Show
          steve_rowe Steve Rowe added a comment - Jetty 9.3.8.v20160314 was announced yesterday: http://dev.eclipse.org/mhonarc/lists/jetty-announce/msg00090.html . Simple patch changing the dep version and switching out the checksums. I kicked off Solr tests, but won't check see the results till after I sleep. Maybe this can make it for 6.0?
          Hide
          steve_rowe Steve Rowe added a comment -

          Patch with CHANGES.txt mods.

          All Solr tests and precommit pass.

          I'm going to push to master, branch_6x and branch_6_0.

          Show
          steve_rowe Steve Rowe added a comment - Patch with CHANGES.txt mods. All Solr tests and precommit pass. I'm going to push to master, branch_6x and branch_6_0.
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 6ebf61535e90d264755ba72eea9ce51ea89703ff in lucene-solr's branch refs/heads/master from Steve Rowe
          [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=6ebf615 ]

          SOLR-7339: Upgrade to Jetty 9.3.8.v20160314

          Show
          jira-bot ASF subversion and git services added a comment - Commit 6ebf61535e90d264755ba72eea9ce51ea89703ff in lucene-solr's branch refs/heads/master from Steve Rowe [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=6ebf615 ] SOLR-7339 : Upgrade to Jetty 9.3.8.v20160314
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit b648ce5afae48662e433f204ff501ac01e45cd9a in lucene-solr's branch refs/heads/branch_6x from Steve Rowe
          [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=b648ce5 ]

          SOLR-7339: Upgrade to Jetty 9.3.8.v20160314

          Show
          jira-bot ASF subversion and git services added a comment - Commit b648ce5afae48662e433f204ff501ac01e45cd9a in lucene-solr's branch refs/heads/branch_6x from Steve Rowe [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=b648ce5 ] SOLR-7339 : Upgrade to Jetty 9.3.8.v20160314
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit fec14c022c7d4fbca07611560aa54df4aecf991b in lucene-solr's branch refs/heads/branch_6_0 from Steve Rowe
          [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=fec14c0 ]

          SOLR-7339: Upgrade to Jetty 9.3.8.v20160314

          Show
          jira-bot ASF subversion and git services added a comment - Commit fec14c022c7d4fbca07611560aa54df4aecf991b in lucene-solr's branch refs/heads/branch_6_0 from Steve Rowe [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=fec14c0 ] SOLR-7339 : Upgrade to Jetty 9.3.8.v20160314
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 6ebf61535e90d264755ba72eea9ce51ea89703ff in lucene-solr's branch refs/heads/apiv2 from Steve Rowe
          [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=6ebf615 ]

          SOLR-7339: Upgrade to Jetty 9.3.8.v20160314

          Show
          jira-bot ASF subversion and git services added a comment - Commit 6ebf61535e90d264755ba72eea9ce51ea89703ff in lucene-solr's branch refs/heads/apiv2 from Steve Rowe [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=6ebf615 ] SOLR-7339 : Upgrade to Jetty 9.3.8.v20160314
          Hide
          jira-bot ASF subversion and git services added a comment -

          Commit 6ebf61535e90d264755ba72eea9ce51ea89703ff in lucene-solr's branch refs/heads/jira/SOLR-445 from Steve Rowe
          [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=6ebf615 ]

          SOLR-7339: Upgrade to Jetty 9.3.8.v20160314

          Show
          jira-bot ASF subversion and git services added a comment - Commit 6ebf61535e90d264755ba72eea9ce51ea89703ff in lucene-solr's branch refs/heads/jira/ SOLR-445 from Steve Rowe [ https://git-wip-us.apache.org/repos/asf?p=lucene-solr.git;h=6ebf615 ] SOLR-7339 : Upgrade to Jetty 9.3.8.v20160314

            People

            • Assignee:
              markrmiller@gmail.com Mark Miller
              Reporter:
              greggny3 Gregg Donovan
            • Votes:
              0 Vote for this issue
              Watchers:
              12 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved:

                Development