[ 
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

Reply via email to