[ https://issues.apache.org/jira/browse/SOLR-7339?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15066735#comment-15066735 ]
Ishan Chattopadhyaya commented on SOLR-7339: -------------------------------------------- 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. {code} [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]$ {code} > Upgrade Jetty from 9.2 to 9.3 > ----------------------------- > > Key: SOLR-7339 > URL: https://issues.apache.org/jira/browse/SOLR-7339 > Project: Solr > Issue Type: Improvement > Reporter: Gregg Donovan > Assignee: Shalin Shekhar Mangar > Fix For: Trunk > > Attachments: SOLR-7339.patch, SOLR-7339.patch, > SolrExampleStreamingBinaryTest.testUpdateField-jetty92.pcapng, > SolrExampleStreamingBinaryTest.testUpdateField-jetty93.pcapng > > > Jetty 9.3 offers support for HTTP/2. Interest in HTTP/2 or its predecessor > SPDY was shown in [SOLR-6699|https://issues.apache.org/jira/browse/SOLR-6699] > and [on the mailing list|http://markmail.org/message/jyhcmwexn65gbdsx]. > 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|https://http2.github.io/faq/#why-is-http2-multiplexed] > * 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|https://github.com/eclipse/jetty.project/tree/master/jetty-http2], > [Square's OkHttp|http://square.github.io/okhttp/], > [etc.|https://github.com/http2/http2-spec/wiki/Implementations]) would need > to be selected and wired up. Perhaps this is worthy of a branch? -- This message was sent by Atlassian JIRA (v6.3.4#6332) --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org