[ https://issues.apache.org/jira/browse/SOLR-6265?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14069559#comment-14069559 ]
Hoss Man commented on SOLR-6265: -------------------------------- Example of how to reproduce: 1) apply this patch to either trunk or 4x... {noformat} Index: solr/example/solr/collection1/conf/schema.xml =================================================================== --- solr/example/solr/collection1/conf/schema.xml (revision 1612418) +++ solr/example/solr/collection1/conf/schema.xml (working copy) @@ -696,7 +696,7 @@ The subFields are an implementation detail of the fieldType, and end users normally should not need to know about them. --> - <fieldType name="point" class="solr.PointType" dimension="2" subFieldSuffix="_d"/> + <fieldType name="point" class="solr.PointType" dimension="-2" subFieldSuffix="_d"/> <!-- A specialized field for geospatial search. If indexed, this fieldType must not be multivalued. --> <fieldType name="location" class="solr.LatLonType" subFieldSuffix="_coordinate"/> {noformat} 2) start the example, and watch the logs... {noformat} hossman@frisbee:~/lucene/dev/solr/example$ java -jar start.jar 0 [main] INFO org.eclipse.jetty.server.Server – jetty-8.1.10.v20130312 20 [main] INFO org.eclipse.jetty.deploy.providers.ScanningAppProvider – Deployment monitor /home/hossman/lucene/dev/solr/example/contexts at interval 0 26 [main] INFO org.eclipse.jetty.deploy.DeploymentManager – Deployable added: /home/hossman/lucene/dev/solr/example/contexts/solr-jetty-context.xml 952 [main] INFO org.eclipse.jetty.webapp.StandardDescriptorProcessor – NO JSP Support for /solr, did not find org.apache.jasper.servlet.JspServlet 999 [main] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() 1012 [main] INFO org.apache.solr.core.SolrResourceLoader – JNDI not configured for solr (NoInitialContextEx) 1012 [main] INFO org.apache.solr.core.SolrResourceLoader – solr home defaulted to 'solr/' (could not find system property or JNDI) 1013 [main] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: 'solr/' 1109 [main] INFO org.apache.solr.core.ConfigSolr – Loading container configuration from /home/hossman/lucene/dev/solr/example/solr/solr.xml 1209 [main] INFO org.apache.solr.core.CoresLocator – Config-defined core root directory: /home/hossman/lucene/dev/solr/example/solr 1216 [main] INFO org.apache.solr.core.CoreContainer – New CoreContainer 1906063587 1217 [main] INFO org.apache.solr.core.CoreContainer – Loading cores into CoreContainer [instanceDir=solr/] 1231 [main] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting socketTimeout to: 0 1231 [main] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting urlScheme to: null ...CoreContainer obviously init'ing fine... ...more expected stuff... 1439 [main] INFO org.apache.solr.core.CoresLocator – Found core collection1 in /home/hossman/lucene/dev/solr/example/solr/collection1/ 1439 [main] INFO org.apache.solr.core.CoresLocator – Found 1 core definitions 1441 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: '/home/hossman/lucene/dev/solr/example/solr/collection1/' 1514 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.core.SolrConfig – Adding specified lib dirs to ClassLoader 1516 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.core.SolrResourceLoader – Adding 'file:/home/hossman/lucene/dev/solr/contrib/extraction/lib/xz-1.4.jar' to classloader ...solr found collection1 and is clearly parsing solrconfig.xml to load libs... ...more expected stuff... 1534 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.core.SolrResourceLoader – Adding 'file:/home/hossman/lucene/dev/solr/dist/solr-velocity-5.0-SNAPSHOT.jar' to classloader 1679 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.update.SolrIndexConfig – IndexWriter infoStream solr logging is enabled 1688 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.core.SolrConfig – Using Lucene MatchVersion: LUCENE_5_0 1845 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.core.Config – Loaded SolrConfig: solrconfig.xml 1852 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.schema.IndexSchema – Reading Solr Schema from schema.xml 1929 [coreLoadExecutor-5-thread-1] INFO org.apache.solr.schema.IndexSchema – [collection1] Schema name=example 2411 [main] INFO org.apache.solr.servlet.SolrDispatchFilter – user.dir=/home/hossman/lucene/dev/solr/example 2411 [main] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() done 2434 [main] INFO org.eclipse.jetty.server.AbstractConnector – Started SocketConnector@0.0.0.0:8983 {noformat} NOTE: that's the whole log -- nothing about anything found in schema.xml 3) load this URL: http://localhost:8983/solr/admin/cores You'll get a response like this... {noformat} $ curl http://localhost:8983/solr/admin/cores <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><str name="defaultCoreName">collection1</str><lst name="initFailures"><str name="collection1">org.apache.solr.common.SolrException:org.apache.solr.common.SolrException: Could not load core configuration for core collection1</str></lst><lst name="status"/> </response> {noformat} And meanwhile the terminal with the solr log in it will grow by one line... {noformat} 210453 [qtp546236873-14] INFO org.apache.solr.servlet.SolrDispatchFilter – [admin] webapp=null path=/admin/cores params={} status=0 QTime=1 {noformat} 4) attempt to quey the core: http://localhost:8983/solr/collection1/select?q=*:* your browser will get all the details about hwy this core couldn't be init'ed, and *now* the error shows up in the log... {noformat} 255212 [qtp546236873-11] ERROR org.apache.solr.servlet.SolrDispatchFilter – null:org.apache.solr.common.SolrException: SolrCore 'collection1' is not available due to init failure: Could not load core configuration for core collection1 at org.apache.solr.core.CoreContainer.getCore(CoreContainer.java:740) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:300) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1075) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:255) at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:154) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53) at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72) at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:744) Caused by: org.apache.solr.common.SolrException: Could not load core configuration for core collection1 at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:66) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:485) at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:251) at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:248) at java.util.concurrent.FutureTask.run(FutureTask.java:262) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) ... 1 more Caused by: org.apache.solr.common.SolrException: Plugin Initializing failure for [schema.xml] fieldType. Schema file is /home/hossman/lucene/dev/solr/example/solr/collection1/schema.xml at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:600) at org.apache.solr.schema.IndexSchema.<init>(IndexSchema.java:171) at org.apache.solr.schema.IndexSchemaFactory.create(IndexSchemaFactory.java:55) at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:69) at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:89) at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:62) ... 7 more Caused by: org.apache.solr.common.SolrException: Plugin Initializing failure for [schema.xml] fieldType at org.apache.solr.util.plugin.AbstractPluginLoader.load(AbstractPluginLoader.java:193) at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:491) ... 12 more Caused by: org.apache.solr.common.SolrException: The dimension must be > 0: -2 at org.apache.solr.schema.PointType.init(PointType.java:52) at org.apache.solr.schema.FieldType.setArgs(FieldType.java:166) at org.apache.solr.schema.FieldTypePluginLoader.init(FieldTypePluginLoader.java:141) at org.apache.solr.schema.FieldTypePluginLoader.init(FieldTypePluginLoader.java:43) at org.apache.solr.util.plugin.AbstractPluginLoader.load(AbstractPluginLoader.java:190) ... 13 more {noformat} [~romseygeek]: is this a side effect of some of the CoreContainer init changes you made? (It seems like really, REALLY, bad behavior that this stuff is somehow now getting "lazy logged") > core errors on startup are not showing up in the log until attempts to use > the core? > ------------------------------------------------------------------------------------ > > Key: SOLR-6265 > URL: https://issues.apache.org/jira/browse/SOLR-6265 > Project: Solr > Issue Type: Bug > Reporter: Hoss Man > Priority: Blocker > Fix For: 4.10 > > > As of r1612418, both the 4x and trunk svn trees seem to have a bug where any > core specific init errors that occur on startup don't show up in the log > until/unless someone attempts to access that core via HTTP. > i'm not sure when exactly this bug was introduced, but it definitely isn't in > 4.9. > The impact on users, particularly new users, is that starting up solr with a > mistake in your configs appears to work fine until you actually try to use > solr and then you get ugly errors. -- This message was sent by Atlassian JIRA (v6.2#6252) --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org