I have just installed Solr 8.2.0 on CentOS 7.7.1908. Java version is
as follows:
openjdk version "11.0.4" 2019-07-16 LTS
OpenJDK Runtime Environment 18.9 (build 11.0.4+11-LTS)
OpenJDK 64-Bit Server VM 18.9 (build 11.0.4+11-LTS, mixed mode, sharing)
I am using the following commad to create a collection "test" on Solr Cloud:
solr create_collection -c test
The output from the command follows:
WARNING: Using _default configset with data driven schema functionality.
NOT RECOMMENDED for production use.
To turn off: bin/solr config -c test -p 8983 -action
set-user-property -property update.autoCreateFields -value false
ERROR: Failed to create collection 'test' due to: Underlying core
creation failed while creating collection: test
The problem seems to be caused by the following error:
Caused by: java.time.format.DateTimeParseException: Text
'2019-10-11T04:46:03.971Z' could not be parsed: null
at
java.base/java.time.format.DateTimeFormatter.createError(DateTimeFormatter.java:2017)
at
java.base/java.time.format.DateTimeFormatter.parse(DateTimeFormatter.java:1920)
at
org.apache.solr.update.processor.ParseDateFieldUpdateProcessorFactory.parseInstant(ParseDateFieldUpdateProcessorFactory.java:230)
at
org.apache.solr.update.processor.ParseDateFieldUpdateProcessorFactory.validateFormatter(ParseDateFieldUpdateProcessorFactory.java:214)
Note that I have tested this and it is working on Windows 10 with Solr
8.2.0 using the following Java version:
openjdk version "11.0.2" 2019-01-15
OpenJDK Runtime Environment 18.9 (build 11.0.2+9)
OpenJDK 64-Bit Server VM 18.9 (build 11.0.2+9, mixed mode)
The full detail from the solr.log file follows:
2019-10-11 04:45:58.361 INFO (qtp195801026-19) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with
params
replicationFactor=1&maxShardsPerNode=-1&collection.configName=test&name=test&action=CREATE&numShards=1&wt=json
and sendToOCPQueue=true
2019-10-11 04:45:58.445 INFO
(OverseerThreadFactory-9-thread-1-processing-n:192.168.1.33:8983_solr)
[ ] o.a.s.c.a.c.CreateCollectionCmd Create collection test
2019-10-11 04:45:58.735 INFO
(OverseerStateUpdate-72057977101680640-192.168.1.33:8983_solr-n_0000000000)
[ ] o.a.s.c.o.SliceMutator createReplica() {
"operation":"ADDREPLICA",
"collection":"test",
"shard":"shard1",
"core":"test_shard1_replica_n1",
"state":"down",
"base_url":"http://192.168.1.33:8983/solr";,
"type":"NRT",
"waitForFinalState":"false"}
2019-10-11 04:45:59.114 INFO (qtp195801026-21) [
x:test_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=test&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&
replicaType=NRT
2019-10-11 04:45:59.119 INFO (qtp195801026-21) [
x:test_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 2147483647 transient cores
2019-10-11 04:46:00.389 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.RequestParams conf
resource params.json loaded . version : 0
2019-10-11 04:46:00.390 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.RequestParams request
params refreshed to version 0
2019-10-11 04:46:00.424 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrResourceLoader
[test_shard1_replica_n1] Added 61 libs to classloader, from paths:
[/opt/solr-8.2.0/contrib/clustering/lib,
/opt/solr-8.2.0/contrib/extraction/lib,
/opt/solr-8.2.0/contrib/langid/lib, /o
pt/solr-8.2.0/contrib/velocity/lib, /opt/solr-8.2.0/dist]
2019-10-11 04:46:00.814 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.2.0
2019-10-11 04:46:01.323 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema
[test_shard1_replica_n1] Schema name=default-config
2019-10-11 04:46:03.017 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
default-config/1.6 with uniqueid field id
2019-10-11 04:46:03.205 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'test_shard1_replica_n1' using configuration from collection
test, trusted=true
2019-10-11 04:46:03.212 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.test.shard1.replica_n1' (registry
'solr.core.test.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@606d8acf
2019-10-11 04:46:03.258 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore
[[test_shard1_replica_n1] ] Opening new SolrCore at
[/opt/solr-8.2.0/server/solr/test_shard1_replica_n1],
dataDir=[/opt/solr-8.2.0/server/solr/test_shard1_replica_n1/data/]
2019-10-11 04:46:03.496 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.r.XSLTResponseWriter
xsltCacheLifetimeSeconds=5
2019-10-11 04:46:04.024 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.c.SolrCore
[test_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@29f80a78
2019-10-11 04:46:04.024 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for registry=solr.core.test.shard1.replica_n1, tag=29f80a78
2019-10-11 04:46:04.025 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70be46b4:
rootName = null, domain = solr.core.test.shard1.replica_n1, service url
= null, agent id = null] for registry
solr.core.test.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@78bbdfd4
2019-10-11 04:46:04.046 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for registry=solr.collection.test.shard1.leader,
tag=29f80a78
2019-10-11 04:46:04.061 ERROR (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.h.RequestHandlerBase
org.apache.solr.common.SolrException: Error CREATEing SolrCore
'test_shard1_replica_n1': Unable to create core [test_shard1_replica_n1]
Caused by: null
at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:1195)
at
org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:92)
at
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:360)
at
org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:396)
at
org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:180)
at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:820)
at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:786)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:546)
at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:423)
at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:350)
at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1602)
at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146)
at
org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257)
at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1711)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1347)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1678)
at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1249)
at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
at
org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:220)
at
org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:152)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at
org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:335)
at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at org.eclipse.jetty.server.Server.handle(Server.java:505)
at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:370)
at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:267)
at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:781)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:917)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.apache.solr.common.SolrException: Unable to create core
[test_shard1_replica_n1]
at
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1266)
at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:1156)
... 44 more
Caused by: org.apache.solr.common.SolrException: Bad or unsupported
pattern: java.time.format.DateTimeFormatter$ClassicFormat@446eb3a2
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1055)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:882)
at
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1245)
... 45 more
Caused by: org.apache.solr.common.SolrException: Bad or unsupported
pattern: java.time.format.DateTimeFormatter$ClassicFormat@446eb3a2
at
org.apache.solr.update.processor.ParseDateFieldUpdateProcessorFactory.validateFormatter(ParseDateFieldUpdateProcessorFactory.java:217)
at
org.apache.solr.update.processor.ParseDateFieldUpdateProcessorFactory.init(ParseDateFieldUpdateProcessorFactory.java:189)
at org.apache.solr.core.PluginBag.initInstance(PluginBag.java:104)
at org.apache.solr.core.PluginBag.createPlugin(PluginBag.java:142)
at org.apache.solr.core.PluginBag.init(PluginBag.java:277)
at org.apache.solr.core.PluginBag.init(PluginBag.java:266)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:978)
... 47 more
Caused by: java.time.format.DateTimeParseException: Text
'2019-10-11T04:46:03.971Z' could not be parsed: null
at
java.base/java.time.format.DateTimeFormatter.createError(DateTimeFormatter.java:2017)
at
java.base/java.time.format.DateTimeFormatter.parse(DateTimeFormatter.java:1920)
at
org.apache.solr.update.processor.ParseDateFieldUpdateProcessorFactory.parseInstant(ParseDateFieldUpdateProcessorFactory.java:230)
at
org.apache.solr.update.processor.ParseDateFieldUpdateProcessorFactory.validateFormatter(ParseDateFieldUpdateProcessorFactory.java:214)
... 53 more
Caused by: java.lang.NullPointerException
at
java.base/java.time.format.DateTimeFormatterBuilder$PrefixTree.prefixLength(DateTimeFormatterBuilder.java:4527)
at
java.base/java.time.format.DateTimeFormatterBuilder$PrefixTree.add0(DateTimeFormatterBuilder.java:4396)
at
java.base/java.time.format.DateTimeFormatterBuilder$PrefixTree.add(DateTimeFormatterBuilder.java:4391)
at
java.base/java.time.format.DateTimeFormatterBuilder$ZoneTextPrinterParser.getTree(DateTimeFormatterBuilder.java:4138)
at
java.base/java.time.format.DateTimeFormatterBuilder$ZoneIdPrinterParser.parse(DateTimeFormatterBuilder.java:4249)
at
java.base/java.time.format.DateTimeFormatterBuilder$CompositePrinterParser.parse(DateTimeFormatterBuilder.java:2360)
at
java.base/java.time.format.DateTimeFormatterBuilder$CompositePrinterParser.parse(DateTimeFormatterBuilder.java:2360)
at
java.base/java.time.format.DateTimeFormatterBuilder$CompositePrinterParser.parse(DateTimeFormatterBuilder.java:2360)
at
java.base/java.time.format.DateTimeFormatterBuilder$CompositePrinterParser.parse(DateTimeFormatterBuilder.java:2370)
at
java.base/java.time.format.DateTimeFormatter.parseUnresolved0(DateTimeFormatter.java:2107)
at
java.base/java.time.format.DateTimeFormatter.parseResolved0(DateTimeFormatter.java:2036)
at
java.base/java.time.format.DateTimeFormatter.parse(DateTimeFormatter.java:1916)
... 55 more
2019-10-11 04:46:04.062 INFO (qtp195801026-21) [c:test s:shard1
r:core_node2 x:test_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=test&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=1&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=400 QTime=4950
2019-10-11 04:46:04.081 ERROR
(OverseerThreadFactory-9-thread-1-processing-n:192.168.1.33:8983_solr)
[ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard:
http://192.168.1.33:8983/solr =>
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException:
Error from server at http://192.168.1.33:8983/solr: Error CREATEing
SolrCore 'test_shard1_replica_n1': Unable to create core
[test_shard1_replica_n1] Caused by: null
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:656)
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException:
Error from server at http://192.168.1.33:8983/solr: Error CREATEing
SolrCore 'test_shard1_replica_n1': Unable to create core
[test_shard1_replica_n1] Caused by: null
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:656)
~[?:?]
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
~[?:?]
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:245)
~[?:?]
at
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1274) ~[?:?]
at
org.apache.solr.handler.component.HttpShardHandlerFactory$1.request(HttpShardHandlerFactory.java:176)
~[?:?]
at
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:199)
~[?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
~[?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:181)
~[metrics-core-4.0.5.jar:4.0.5]
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
~[?:?]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
~[?:?]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
~[?:?]
at java.lang.Thread.run(Thread.java:834) [?:?]
2019-10-11 04:46:04.081 ERROR
(OverseerThreadFactory-9-thread-1-processing-n:192.168.1.33:8983_solr)
[ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Cleaning up
collection [test].
2019-10-11 04:46:04.105 INFO
(OverseerThreadFactory-9-thread-1-processing-n:192.168.1.33:8983_solr)
[ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
2019-10-11 04:46:04.119 INFO (qtp195801026-22) [
x:test_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={deleteInstanceDir=true&deleteMetricsHistory=true&core=test_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=2
2019-10-11 04:46:04.173 INFO (zkCallback-6-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDeleted path:/collections/test/state.json]
for collection [test] has occurred - updating... (live nodes size: [1])
2019-10-11 04:46:04.245 INFO
(OverseerThreadFactory-9-thread-1-processing-n:192.168.1.33:8983_solr)
[ ] o.a.s.c.a.c.CreateCollectionCmd Cleaned up artifacts for failed
create collection for [test]
2019-10-11 04:46:04.246 ERROR
(OverseerThreadFactory-9-thread-1-processing-n:192.168.1.33:8983_solr)
[ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: test
operation: create failed:org.apache.solr.common.SolrException:
Underlying core creation failed while creating collection: test
at
org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:302)
at
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:264)
at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:505)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-10-11 04:46:04.363 INFO (qtp195801026-19) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&maxShardsPerNode=-1&collection.configName=test&name=test&action=CREATE&numShards=1&wt=json}
status=400 QTime=6001
2019-10-11 04:46:04.494 INFO
(OverseerCollectionConfigSetProcessor-72057977101680640-192.168.1.33:8983_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor
may have disconnected from ZooKeeper
2019-10-11 04:46:28.539 INFO (qtp195801026-22) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.jvm:os.processCpuLoad&key=solr.node:CONTAINER.fs.coreRoot.usableSpace&key=solr.jvm:os.systemLoadAverage&key=solr.jvm:memory.heap.used}
status=0 QTime=15
Any thoughts/suggestions?
Kind Regards,
Peter
