java.lang.IllegalArgumentException in logs after upgrade from 12.10.10 LTS to 13.10.2 LTS

GOhrner · January 2, 2022, 2:27pm

After the upgrade from 12.10.10 LTS to 13.10.2 LTS I’m getting lots of exceptions in my Catalina log.

I installed the official Xwiki LTS Debian packages on Debian 10 Buster.

There were no error messages / warnings in the web-based upgrade wizard.

[2022-01-02 15:05:27] [info] 2022-01-02 15:05:27,692 [XWiki Solr index thread] ERROR c.x.x.d.XWikiDocument          - Failed to get the default edit mode for [xwiki:Sandbox.Formattest MediaWiki 2.WebHome]
[2022-01-02 15:05:27] [info] java.lang.IllegalArgumentException: org.xwiki.contrib.mediawiki.syntax.internal.input.MediaWikiContentFilter referenced from a method is not visible from class loader
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.ensureVisible(Proxy.java:858)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.validateProxyInterfaces(Proxy.java:681)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.<init>(Proxy.java:627)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.<init>(Proxy.java:635)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy.lambda$getProxyConstructor$0(Proxy.java:415)
[2022-01-02 15:05:27] [info] #011at java.base/jdk.internal.loader.AbstractClassLoaderValue$Memoizer.get(AbstractClassLoaderValue.java:329)
[2022-01-02 15:05:27] [info] #011at java.base/jdk.internal.loader.AbstractClassLoaderValue.computeIfAbsent(AbstractClassLoaderValue.java:205)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy.getProxyConstructor(Proxy.java:413)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy.newProxyInstance(Proxy.java:1006)
[2022-01-02 15:05:27] [info] #011at org.xwiki.filter.internal.DefaultFilterDescriptorManager.createFilterProxy(DefaultFilterDescriptorManager.java:348)
[2022-01-02 15:05:27] [info] #011at org.xwiki.filter.internal.DefaultFilterDescriptorManager.createFilterProxy(DefaultFilterDescriptorManager.java:340)
[2022-01-02 15:05:27] [info] #011at org.xwiki.filter.input.AbstractBeanInputFilterStream.read(AbstractBeanInputFilterStream.java:77)
[2022-01-02 15:05:27] [info] #011at org.xwiki.contrib.mediawiki.syntax.internal.parser.MediaWikiStreamParser.parse(MediaWikiStreamParser.java:89)
[2022-01-02 15:05:27] [info] #011at org.xwiki.contrib.mediawiki.syntax.internal.parser.MediaWikiParser.parse(MediaWikiParser.java:63)
[2022-01-02 15:05:27] [info] #011at org.xwiki.rendering.internal.parser.DefaultContentParser.parse(DefaultContentParser.java:62)
[2022-01-02 15:05:27] [info] #011at org.xwiki.rendering.internal.parser.DefaultContentParser.parse(DefaultContentParser.java:69)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.parseContent(XWikiDocument.java:8822)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.parseContent(XWikiDocument.java:8811)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.getXDOM(XWikiDocument.java:8653)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.getDefaultEditModeInternal(XWikiDocument.java:7199)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.getDefaultEditMode(XWikiDocument.java:7161)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.internal.sheet.DefaultModelBridge.getDefaultEditMode(DefaultModelBridge.java:71)
[2022-01-02 15:05:27] [info] #011at org.xwiki.sheet.internal.SheetDocumentDisplayer.isNewSheetSystem(SheetDocumentDisplayer.java:157)
[2022-01-02 15:05:27] [info] #011at org.xwiki.sheet.internal.SheetDocumentDisplayer.isSheetExpected(SheetDocumentDisplayer.java:152)
[2022-01-02 15:05:27] [info] #011at org.xwiki.sheet.internal.SheetDocumentDisplayer.display(SheetDocumentDisplayer.java:98)
[2022-01-02 15:05:27] [info] #011at org.xwiki.sheet.internal.SheetDocumentDisplayer.display(SheetDocumentDisplayer.java:52)
[2022-01-02 15:05:27] [info] #011at org.xwiki.display.internal.ConfiguredDocumentDisplayer.display(ConfiguredDocumentDisplayer.java:68)
[2022-01-02 15:05:27] [info] #011at org.xwiki.display.internal.ConfiguredDocumentDisplayer.display(ConfiguredDocumentDisplayer.java:42)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.getRenderedTitle(XWikiDocument.java:1778)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.metadata.DocumentSolrMetadataExtractor.setFieldsInternal(DocumentSolrMetadataExtractor.java:103)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.metadata.AbstractSolrMetadataExtractor.getSolrDocument(AbstractSolrMetadataExtractor.java:151)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.DefaultSolrIndexer.getSolrDocument(DefaultSolrIndexer.java:499)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.DefaultSolrIndexer.processBatch(DefaultSolrIndexer.java:408)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.DefaultSolrIndexer.run(DefaultSolrIndexer.java:376)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.Thread.run(Thread.java:829)
[2022-01-02 15:05:27] [info] 2022-01-02 15:05:27,699 [XWiki Solr index thread] ERROR o.x.s.s.i.DefaultSolrIndexer   - Failed to process entry [INDEX xwiki:Sandbox.Formattest MediaWiki 2.WebHome]
[2022-01-02 15:05:27] [info] org.xwiki.search.solr.internal.api.SolrIndexerException: Failed to get input Solr document for entity 'xwiki:Sandbox.Formattest MediaWiki 2.WebHome'
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.metadata.AbstractSolrMetadataExtractor.getSolrDocument(AbstractSolrMetadataExtractor.java:158)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.DefaultSolrIndexer.getSolrDocument(DefaultSolrIndexer.java:499)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.DefaultSolrIndexer.processBatch(DefaultSolrIndexer.java:408)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.DefaultSolrIndexer.run(DefaultSolrIndexer.java:376)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.Thread.run(Thread.java:829)
[2022-01-02 15:05:27] [info] Caused by: java.lang.IllegalArgumentException: org.xwiki.contrib.mediawiki.syntax.internal.input.MediaWikiContentFilter referenced from a method is not visible from class loader
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.ensureVisible(Proxy.java:858)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.validateProxyInterfaces(Proxy.java:681)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.<init>(Proxy.java:627)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy$ProxyBuilder.<init>(Proxy.java:635)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy.lambda$getProxyConstructor$0(Proxy.java:415)
[2022-01-02 15:05:27] [info] #011at java.base/jdk.internal.loader.AbstractClassLoaderValue$Memoizer.get(AbstractClassLoaderValue.java:329)
[2022-01-02 15:05:27] [info] #011at java.base/jdk.internal.loader.AbstractClassLoaderValue.computeIfAbsent(AbstractClassLoaderValue.java:205)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy.getProxyConstructor(Proxy.java:413)
[2022-01-02 15:05:27] [info] #011at java.base/java.lang.reflect.Proxy.newProxyInstance(Proxy.java:1006)
[2022-01-02 15:05:27] [info] #011at org.xwiki.filter.internal.DefaultFilterDescriptorManager.createFilterProxy(DefaultFilterDescriptorManager.java:348)
[2022-01-02 15:05:27] [info] #011at org.xwiki.filter.internal.DefaultFilterDescriptorManager.createFilterProxy(DefaultFilterDescriptorManager.java:340)
[2022-01-02 15:05:27] [info] #011at org.xwiki.filter.input.AbstractBeanInputFilterStream.read(AbstractBeanInputFilterStream.java:77)
[2022-01-02 15:05:27] [info] #011at org.xwiki.contrib.mediawiki.syntax.internal.parser.MediaWikiStreamParser.parse(MediaWikiStreamParser.java:89)
[2022-01-02 15:05:27] [info] #011at org.xwiki.contrib.mediawiki.syntax.internal.parser.MediaWikiParser.parse(MediaWikiParser.java:63)
[2022-01-02 15:05:27] [info] #011at org.xwiki.rendering.internal.parser.DefaultContentParser.parse(DefaultContentParser.java:62)
[2022-01-02 15:05:27] [info] #011at org.xwiki.rendering.internal.parser.DefaultContentParser.parse(DefaultContentParser.java:69)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.parseContent(XWikiDocument.java:8822)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.parseContent(XWikiDocument.java:8811)
[2022-01-02 15:05:27] [info] #011at com.xpn.xwiki.doc.XWikiDocument.getXDOM(XWikiDocument.java:8653)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.metadata.DocumentSolrMetadataExtractor.setFieldsInternal(DocumentSolrMetadataExtractor.java:112)
[2022-01-02 15:05:27] [info] #011at org.xwiki.search.solr.internal.metadata.AbstractSolrMetadataExtractor.getSolrDocument(AbstractSolrMetadataExtractor.java:151)
[2022-01-02 15:05:27] [info] #011... 4 common frames omitted

After another server restart this didn’t occur - possibly SOLR did not try to re-index this page?

Other exceptions I get - not necessarily new with 13.10 now, but I just noticed these while having a closer look at the logs:

[2022-01-02 15:17:33] [info] 2022-01-02 15:17:33,532 [main] WARN  .e.r.i.l.LocalExtensionStorage - Failed to load extension from file [/mnt/cstore/var_lib_xwiki/data/extension/repository/org%2Exwiki%2Econtrib%3Amacro-jwplayer/2%2E1%2E8/org%2Exwiki%2Econtrib%3Amacro-jwplayer-2%2E1%2E8.xed] in local repository
[2022-01-02 15:17:33] [info] org.xwiki.extension.InvalidExtensionException: Failed to load local extension [/mnt/cstore/var_lib_xwiki/data/extension/repository/org%2Exwiki%2Econtrib%3Amacro-jwplayer/2%2E1%2E8/org%2Exwiki%2Econtrib%3Amacro-jwplayer-2%2E1%2E8.xed]: [org.xwiki.extension.repository.internal.local.DefaultLocalExtensionFile@3f33c5e4] file does not exists
[2022-01-02 15:17:33] [info] #011at org.xwiki.extension.repository.internal.local.LocalExtensionStorage.loadDescriptor(LocalExtensionStorage.java:181)
[2022-01-02 15:17:33] [info] #011at org.xwiki.extension.repository.internal.local.LocalExtensionStorage.loadExtensions(LocalExtensionStorage.java:143)
[2022-01-02 15:17:33] [info] #011at org.xwiki.extension.repository.internal.local.LocalExtensionStorage.loadExtensions(LocalExtensionStorage.java:140)
[2022-01-02 15:17:33] [info] #011at org.xwiki.extension.repository.internal.local.LocalExtensionStorage.loadExtensions(LocalExtensionStorage.java:140)
[2022-01-02 15:17:33] [info] #011at org.xwiki.extension.repository.internal.local.LocalExtensionStorage.loadExtensions(LocalExtensionStorage.java:112)
[2022-01-02 15:17:33] [info] #011at org.xwiki.extension.repository.internal.local.DefaultLocalExtensionRepository.initialize(DefaultLocalExtensionRepository.java:108)

(The JWPlayer extension was upgraded as a part of the Xwiki upgrade process. A more recent version was available in the extension store.)

It the following something to worry about, or as expected: “Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.”?

2022-01-02 15:17:43] [info] 2022-01-02 15:17:43,458 [main] INFO  o.x.s.s.i.EmbeddedSolr         - Starting embedded Solr server...
[2022-01-02 15:17:43] [info] 2022-01-02 15:17:43,459 [main] INFO  o.x.s.s.i.EmbeddedSolr         - Using Solr home directory: [/mnt/cstore/var_lib_xwiki/data/store/solr]
[2022-01-02 15:17:45] [info] 2022-01-02 15:17:45,534 [main] WARN  o.e.j.u.s.S.config             - Trusting all certificates configured for Client@393ca457[provider=null,keyStore=null,trustStore=null]
[2022-01-02 15:17:45] [info] 2022-01-02 15:17:45,535 [main] WARN  o.e.j.u.s.S.config             - No Client EndPointIdentificationAlgorithm configured for Client@393ca457[provider=null,keyStore=null,trustStore=null]
[2022-01-02 15:17:45] [info] 2022-01-02 15:17:45,923 [main] WARN  o.e.j.u.s.S.config             - Trusting all certificates configured for Client@9be2619[provider=null,keyStore=null,trustStore=null]
[2022-01-02 15:17:45] [info] 2022-01-02 15:17:45,923 [main] WARN  o.e.j.u.s.S.config             - No Client EndPointIdentificationAlgorithm configured for Client@9be2619[provider=null,keyStore=null,trustStore=null]
[2022-01-02 15:17:46] [info] 2022-01-02 15:17:46,138 [main] WARN  o.a.s.c.CoreContainer          - Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
[2022-01-02 15:17:49] [info] 2022-01-02 15:17:49,303 [main] INFO  o.x.s.s.i.EmbeddedSolr         - Started embedded Solr server.

Another exception during server startup:

[2022-01-02 15:17:51] [info] WARNING: An illegal reflective access operation has occurred
[2022-01-02 15:17:51] [info] WARNING: Illegal reflective access by com.thoughtworks.xstream.converters.reflection.FieldDictionary (file:/usr
/lib/xwiki/WEB-INF/lib/xstream-1.4.17.jar) to field java.util.Collections$UnmodifiableMap.serialVersionUID
[2022-01-02 15:17:51] [info] WARNING: Please consider reporting this to the maintainers of com.thoughtworks.xstream.converters.reflection.Fi
eldDictionary
[2022-01-02 15:17:51] [info] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
[2022-01-02 15:17:51] [info] WARNING: All illegal access operations will be denied in a future release

surli · January 3, 2022, 8:46am

Hi,

this sounds like an issue with the MediaWiki extension, I guess you should open a ticket for it with the full stack.

This one is currently expected, you can follow Loading... about it.

tmortagne · January 3, 2022, 9:23am

For this one see Loading....

GOhrner · January 3, 2022, 11:40am

@surli , @tmortagne : Thanks, I subscribed to both issues. So these were no now warnings, and I can ignore them for now.

Concerning the Mediawiki Markup exception, I think it occured when SOLR tried to index the page - not sure how to reproduce it, as it does not seem to occur any more. (Maybe Xwiki thinks the page was indexed now.)

Shall I still report it with will stacktraces?

tmortagne · January 3, 2022, 1:19pm

Probably. Now, the error suggests the problem is that the thread classloader of the Solr indexer does not see some classes used by the mediawiki syntax parser. This feels more like a XWikiDocument#parseContent bug or a solr indexer bug than a mediawiki syntax parser one: both should make sure the right wiki (the parsed document’s wiki) is in the context and the current Thread classloader is the context classloader.

You can probably workaround it by making sure mediawiki syntax extension is installed “On Farm” if that’s not already the case.

But I don’t think this have anything to do with the upgrade.

GOhrner · January 3, 2022, 2:10pm

@tmortagne : Loading...