[jboss-jira] [JBoss JIRA] (WFLY-11999) Wildfly creates to many instances of all web service classes

Wednesday, 17 April 2019



    [
https://issues.jboss.org/browse/WFLY-11999?page=com.atlassian.jira.plugin...
] 

Stefan Frings commented on WFLY-11999:
--------------------------------------

Interesting info: Apache Tomcat starts only 1 instance of the class with
@ApplicationScoped:
{code}
Using CATALINA_BASE:   /home/sfrings/workspace_Congstar/apache-tomcat-8.5.40
Using CATALINA_HOME:   /home/sfrings/workspace_Congstar/apache-tomcat-8.5.40
Using CATALINA_TMPDIR: /home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/temp
Using JRE_HOME:        /usr/lib/jvm/java-8-openjdk-amd64
Using CLASSPATH:      
/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/bin/bootstrap.jar:/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/bin/tomcat-juli.jar
Listening for transport dt_socket at address: 11550
17-Apr-2019 16:27:14.075 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Server version:        Apache
Tomcat/8.5.40
17-Apr-2019 16:27:14.077 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Server built:          Apr 10 2019
14:31:19 UTC
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Server number:         8.5.40.0
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log OS Name:               Linux
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log OS Version:           
4.18.0-17-generic
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Architecture:          amd64
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Java Home:            
/usr/lib/jvm/java-8-openjdk-amd64/jre
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log JVM Version:          
1.8.0_191-8u191-b12-2ubuntu0.18.10.1-b12
17-Apr-2019 16:27:14.078 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor:            Oracle
Corporation
17-Apr-2019 16:27:14.079 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE:        
/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40
17-Apr-2019 16:27:14.079 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME:        
/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40
17-Apr-2019 16:27:14.079 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Djava.util.logging.config.file=/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/conf/logging.properties
17-Apr-2019 16:27:14.079 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
17-Apr-2019 16:27:14.079 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Dhttp.nonProxyHosts=localhost|127.0.0.1|stefanpc
17-Apr-2019 16:27:14.080 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Djdk.tls.ephemeralDHKeySize=2048
17-Apr-2019 16:27:14.080 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Djava.protocol.handler.pkgs=org.apache.catalina.webresources
17-Apr-2019 16:27:14.080 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Dorg.apache.catalina.security.SecurityListener.UMASK=0027
17-Apr-2019 16:27:14.080 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-agentlib:jdwp=transport=dt_socket,address=11550,server=y,suspend=n
17-Apr-2019 16:27:14.080 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Dignore.endorsed.dirs=
17-Apr-2019 16:27:14.080 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Dcatalina.base=/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40
17-Apr-2019 16:27:14.081 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Dcatalina.home=/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40
17-Apr-2019 16:27:14.081 INFORMATION [main]
org.apache.catalina.startup.VersionLoggerListener.log Command line argument:
-Djava.io.tmpdir=/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/temp
17-Apr-2019 16:27:14.081 INFORMATION [main]
org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The APR based Apache Tomcat
Native library which allows optimal performance in production environments was not found
on the java.library.path:
[/usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib]
17-Apr-2019 16:27:14.245 INFORMATION [main] org.apache.coyote.AbstractProtocol.init
Initializing ProtocolHandler ["http-nio-8080"]
17-Apr-2019 16:27:14.260 INFORMATION [main]
org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for
servlet write/read
17-Apr-2019 16:27:14.299 INFORMATION [main] org.apache.coyote.AbstractProtocol.init
Initializing ProtocolHandler ["ajp-nio-8009"]
17-Apr-2019 16:27:14.305 INFORMATION [main]
org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for
servlet write/read
17-Apr-2019 16:27:14.308 INFORMATION [main] org.apache.catalina.startup.Catalina.load
Initialization processed in 868 ms
17-Apr-2019 16:27:14.384 INFORMATION [main]
org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
17-Apr-2019 16:27:14.387 INFORMATION [main]
org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache
Tomcat/8.5.40
17-Apr-2019 16:27:14.421 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDescriptor Deploying configuration descriptor
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/conf/Catalina/localhost/Test-1.0-SNAPSHOT.xml]
17-Apr-2019 16:27:15.702 INFORMATION [localhost-startStop-1]
org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet
contained no TLDs. Enable debug logging for this logger for a complete list of JARs that
were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can
improve startup time and JSP compilation time.
17-Apr-2019 16:27:15.865 FEIN [localhost-startStop-1]
com.mvneco.test.SoapService.<init> Init SoapService 1
17-Apr-2019 16:27:18.690 INFORMATION [localhost-startStop-1]
com.sun.xml.ws.server.MonitorBase.createRoot Metro monitoring rootname successfully set
to:
com.sun.metro:pp=/,type=WSEndpoint,name=/Test-1.0-SNAPSHOT-SoapServiceService-SoapServicePort
17-Apr-2019 16:27:18.870 INFORMATION [localhost-startStop-1]
com.sun.xml.ws.transport.http.servlet.WSServletDelegate.<init> WSSERVLET14:
Initialisierung von JAX-WS-Servlet
17-Apr-2019 16:27:18.872 INFORMATION [localhost-startStop-1]
com.sun.xml.ws.transport.http.servlet.WSServletContextListener.contextInitialized
WSSERVLET12: Initialisierung von JAX-WS-Kontext-Listener
17-Apr-2019 16:27:18.872 INFORMATION [localhost-startStop-1]
com.sun.xml.ws.transport.http.servlet.WSServletContextListener.contextInitialized
WSSERVLET12: Initialisierung von JAX-WS-Kontext-Listener
17-Apr-2019 16:27:18.905 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDescriptor Deployment of configuration
descriptor
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/conf/Catalina/localhost/Test-1.0-SNAPSHOT.xml]
has finished in [4,484] ms
17-Apr-2019 16:27:18.907 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/docs]
17-Apr-2019 16:27:19.728 INFORMATION [localhost-startStop-1]
org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet
contained no TLDs. Enable debug logging for this logger for a complete list of JARs that
were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can
improve startup time and JSP compilation time.
17-Apr-2019 16:27:19.730 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application
directory [/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/docs] has
finished in [823] ms
17-Apr-2019 16:27:19.731 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/host-manager]
17-Apr-2019 16:27:20.473 INFORMATION [localhost-startStop-1]
org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet
contained no TLDs. Enable debug logging for this logger for a complete list of JARs that
were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can
improve startup time and JSP compilation time.
17-Apr-2019 16:27:20.479 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application
directory [/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/host-manager] has
finished in [749] ms
17-Apr-2019 16:27:20.479 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/examples]
17-Apr-2019 16:27:21.633 INFORMATION [localhost-startStop-1]
org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet
contained no TLDs. Enable debug logging for this logger for a complete list of JARs that
were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can
improve startup time and JSP compilation time.
17-Apr-2019 16:27:21.700 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application
directory [/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/examples] has
finished in [1,221] ms
17-Apr-2019 16:27:21.700 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/manager]
17-Apr-2019 16:27:23.001 INFORMATION [localhost-startStop-1]
org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet
contained no TLDs. Enable debug logging for this logger for a complete list of JARs that
were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can
improve startup time and JSP compilation time.
17-Apr-2019 16:27:23.088 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application
directory [/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/manager] has
finished in [1,388] ms
17-Apr-2019 16:27:23.088 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory
[/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/ROOT]
17-Apr-2019 16:27:23.757 INFORMATION [localhost-startStop-1]
org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet
contained no TLDs. Enable debug logging for this logger for a complete list of JARs that
were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can
improve startup time and JSP compilation time.
17-Apr-2019 16:27:23.759 INFORMATION [localhost-startStop-1]
org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application
directory [/home/sfrings/workspace_Congstar/apache-tomcat-8.5.40/webapps/ROOT] has
finished in [671] ms
17-Apr-2019 16:27:23.765 INFORMATION [main] org.apache.coyote.AbstractProtocol.start
Starting ProtocolHandler ["http-nio-8080"]
17-Apr-2019 16:27:23.944 INFORMATION [main] org.apache.coyote.AbstractProtocol.start
Starting ProtocolHandler ["ajp-nio-8009"]
17-Apr-2019 16:27:23.951 INFORMATION [main] org.apache.catalina.startup.Catalina.start
Server startup in 9641 ms
17-Apr-2019 16:27:35.074 FEIN [http-nio-8080-exec-1] com.mvneco.test.SoapService.test
Start test(). payload=?
{code}

...
 Wildfly creates to many instances of all web service classes
 ------------------------------------------------------------

                 Key: WFLY-11999
                 URL: https://issues.jboss.org/browse/WFLY-11999
             Project: WildFly
          Issue Type: Bug
          Components: EE
    Affects Versions: 8.0.0.Final, 16.0.0.Final
         Environment: Ubuntu 18.10 64bit, Open JDK 8 or Open JDK 11
            Reporter: Stefan Frings
            Assignee: Brian Stansberry
            Priority: Minor
         Attachments: Test.zip


 1) My @Webservice classes are all constructed multiple times (3x - 4x) but I expected
only one instance.
 2) Only the second instance receives a call to the observer method for initialization. I
expect that this method is called in each instance.
 The issue can be reproduced with the attached minimum project on Wildfly 8 with JDK 8 as
well as Wildfly 16 with JDK 11.
 Complete source code of the whole application:
 {code}
 package com.mvneco.test;
 import javax.enterprise.context.ApplicationScoped;
 import javax.enterprise.context.Initialized;
 import javax.enterprise.event.Observes;
 import javax.jws.WebMethod;
 import javax.jws.WebService;
 import org.apache.commons.logging.Log;
 import org.apache.commons.logging.LogFactory;
 @ApplicationScoped
 @WebService
 public class SoapService
 {
     private Log log = LogFactory.getLog(SoapService.class);
     public SoapService() 
     {
         log.debug("Init SoapService 1");
     }
     @WebMethod(exclude = true)
     public void init(@Observes @Initialized(ApplicationScoped.class) Object init)
     {
         log.debug("Init SoapService 2");
     }
     @WebMethod
     public String test(String payload)
     {
         log.debug("Start test(). payload="+payload);
         return "OK";
     }
 }
 {code}
 Extract from Log messages that show the issue (the complete log is in the attached ZIP):
 {code}
 2019-04-17 13:12:21,399 DEBUG [com.mvneco.test.SoapService] (MSC service thread 1-2) Init
SoapService 1
 2019-04-17 13:12:23,068 DEBUG [com.mvneco.test.SoapService] (ServerService Thread Pool --
74) Init SoapService 1
 2019-04-17 13:12:23,070 DEBUG [com.mvneco.test.SoapService] (ServerService Thread Pool --
74) Init SoapService 2
 2019-04-17 13:12:23,475 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0025:
WildFly Full 16.0.0.Final (WildFly Core 8.0.0.Final) started in 10514ms - Started 510 of
699 services (337 services are lazy, passive or on-demand)
 2019-04-17 13:12:28,920 INFO  [org.apache.cxf.services.SoapService.REQ_IN] (default
task-1) REQ_IN
     Address: http://localhost:8080/Test-1.0-SNAPSHOT/SoapService
     ...
 2019-04-17 13:12:28,932 DEBUG [com.mvneco.test.SoapService] (default task-1) Init
SoapService 1
 2019-04-17 13:12:28,937 DEBUG [com.mvneco.test.SoapService] (default task-1) Init
SoapService 1
 2019-04-17 13:12:28,937 DEBUG [com.mvneco.test.SoapService] (default task-1) Start
test(). payload=?
 2019-04-17 13:12:28,969 INFO  [org.apache.cxf.services.SoapService.RESP_OUT] (default
task-1) RESP_OUT
     Content-Type: text/xml
     ...
 {code} 


--
This message was sent by Atlassian Jira
(v7.12.1#712002)

2026

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[jboss-jira] [JBoss JIRA] (WFLY-11999) Wildfly creates to many instances of all web service classes