[
https://issues.redhat.com/browse/WFLY-11999?page=com.atlassian.jira.plugi...
]
Stefan Frings edited comment on WFLY-11999 at 4/28/20 5:11 AM:
---------------------------------------------------------------
Since we have a stable workaround we continue to use Wildfly 16 for the next years.
Anyway I found time to test it with Wildfly 19. The result is different than before but
still bad.
The console output from startup looks fine:
{code}
=========================================================================
JBoss Bootstrap Environment
JBOSS_HOME: /home/sfrings/Downloads/wildfly-19.0.0.Final
JAVA: /opt/jdk11/bin/java
JAVA_OPTS: -server -Xms64m -Xmx512m -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m
-Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman
-Djava.awt.headless=true --add-exports=java.base/sun.nio.ch=ALL-UNNAMED
--add-exports=jdk.unsupported/sun.misc=ALL-UNNAMED
--add-exports=jdk.unsupported/sun.reflect=ALL-UNNAMED
=========================================================================
11:04:32,415 INFO [org.jboss.modules] (main) JBoss Modules version 1.10.0.Final
11:04:33,282 INFO [org.jboss.msc] (main) JBoss MSC version 1.4.11.Final
11:04:33,294 INFO [org.jboss.threads] (main) JBoss Threads version 2.3.3.Final
11:04:33,520 INFO [org.jboss.as] (MSC service thread 1-3) WFLYSRV0049: WildFly Full
19.0.0.Final (WildFly Core 11.0.0.Final) starting
11:04:34,962 INFO [org.wildfly.security] (ServerService Thread Pool -- 26) ELY00001:
WildFly Elytron version 1.11.2.Final
11:04:35,845 INFO [org.jboss.as.controller.management-deprecated] (Controller Boot
Thread) WFLYCTL0028: Attribute 'security-realm' in the resource at address
'/core-service=management/management-interface=http-interface' is deprecated, and
may be removed in a future version. See the attribute description in the output of the
read-resource-description operation to learn more about the deprecation.
11:04:35,924 INFO [org.jboss.as.controller.management-deprecated] (ServerService Thread
Pool -- 17) WFLYCTL0028: Attribute 'security-realm' in the resource at address
'/subsystem=undertow/server=default-server/https-listener=https' is deprecated,
and may be removed in a future version. See the attribute description in the output of the
read-resource-description operation to learn more about the deprecation.
11:04:36,157 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating
http management service using socket-binding (management-http)
11:04:36,193 INFO [org.xnio] (MSC service thread 1-1) XNIO version 3.7.7.Final
11:04:36,208 INFO [org.xnio.nio] (MSC service thread 1-1) XNIO NIO Implementation Version
3.7.7.Final
11:04:36,322 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 52)
WFLYCLINF0001: Activating Infinispan subsystem.
11:04:36,324 INFO [org.jboss.remoting] (MSC service thread 1-4) JBoss Remoting version
5.0.17.Final
11:04:36,410 INFO [org.wildfly.extension.microprofile.config.smallrye._private]
(ServerService Thread Pool -- 61) WFLYCONF0001: Activating WildFly MicroProfile Config
Subsystem
11:04:36,438 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 66) WFLYNAM0001:
Activating Naming Subsystem
11:04:36,332 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread
Pool -- 44) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version
1.4)
11:04:36,523 INFO [org.jboss.as.security] (ServerService Thread Pool -- 72) WFLYSEC0002:
Activating Security Subsystem
11:04:36,367 INFO [org.jboss.as.jaxrs] (ServerService Thread Pool -- 54) WFLYRS0016:
RESTEasy version 3.11.0.Final
11:04:36,380 INFO [org.jboss.as.connector] (MSC service thread 1-6) WFLYJCA0009: Starting
JCA Subsystem (WildFly/IronJacamar 1.4.20.Final)
11:04:36,412 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 59) WFLYJSF0007:
Activated the following JSF Implementations: [main]
11:04:36,532 INFO [org.wildfly.extension.microprofile.jwt.smallrye._private]
(ServerService Thread Pool -- 63) WFLYJWT0001: Activating WildFly MicroProfile JWT
Subsystem
11:04:36,463 INFO [org.jboss.as.naming] (MSC service thread 1-8) WFLYNAM0003: Starting
Naming Service
11:04:36,487 INFO [org.jboss.as.mail.extension] (MSC service thread 1-1) WFLYMAIL0001:
Bound mail session [java:jboss/mail/Default]
11:04:36,489 INFO [org.wildfly.extension.io] (ServerService Thread Pool -- 53) WFLYIO001:
Worker 'default' has auto-configured to 8 IO threads with 64 max task threads
based on your 4 available processors
11:04:36,491 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-5)
WFLYJCA0018: Started Driver service with driver-name = h2
11:04:36,534 INFO [org.jboss.as.security] (MSC service thread 1-6) WFLYSEC0001: Current
PicketBox version=5.0.3.Final
11:04:36,544 INFO [org.wildfly.extension.microprofile.opentracing] (ServerService Thread
Pool -- 65) WFLYTRACEXT0001: Activating MicroProfile OpenTracing Subsystem
11:04:36,549 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 76)
WFLYWS0002: Activating WebServices Extension
11:04:36,559 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 74) WFLYTX0013: The
node-identifier attribute on the /subsystem=transactions is set to the default value. This
is a danger for environments running multiple servers. Please make sure the attribute
value is unique.
11:04:36,575 INFO [org.wildfly.extension.microprofile.metrics.smallrye] (ServerService
Thread Pool -- 64) WFLYMETRICS0001: Activating Eclipse MicroProfile Metrics Subsystem
11:04:36,575 INFO [org.wildfly.extension.microprofile.health.smallrye] (ServerService
Thread Pool -- 62) WFLYHEALTH0001: Activating Eclipse MicroProfile Health Subsystem
11:04:36,616 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0003:
Undertow 2.0.30.Final starting
11:04:36,960 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 75)
WFLYUT0014: Creating file handler for path
'/home/sfrings/Downloads/wildfly-19.0.0.Final/welcome-content' with options
[directory-listing: 'false', follow-symlink: 'false', case-sensitive:
'true', safe-symlink-paths: '[]']
11:04:36,977 INFO [org.wildfly.extension.undertow] (MSC service thread 1-3) WFLYUT0012:
Started server default-server.
11:04:36,984 INFO [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0018:
Host default-host starting
11:04:36,985 INFO [org.jboss.as.ejb3] (MSC service thread 1-2) WFLYEJB0481: Strict pool
slsb-strict-max-pool is using a max instance size of 64 (per class), which is derived from
thread worker pool sizing.
11:04:36,985 INFO [org.jboss.as.ejb3] (MSC service thread 1-5) WFLYEJB0482: Strict pool
mdb-strict-max-pool is using a max instance size of 16 (per class), which is derived from
the number of CPUs on this host.
11:04:37,147 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0006:
Undertow HTTP listener default listening on 127.0.0.1:8080
11:04:37,441 INFO [org.jboss.as.ejb3] (MSC service thread 1-3) WFLYEJB0493: EJB subsystem
suspension complete
11:04:37,571 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread
1-5) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS]
11:04:37,801 INFO [org.jboss.as.patching] (MSC service thread 1-5) WFLYPAT0050: WildFly
Full cumulative patch ID is: base, one-off patches include: none
11:04:37,838 WARN [org.jboss.as.domain.management.security] (MSC service thread 1-2)
WFLYDM0111: Keystore
/home/sfrings/Downloads/wildfly-19.0.0.Final/standalone/configuration/application.keystore
not found, it will be auto generated on first use with a self signed certificate for host
localhost
11:04:37,879 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-1)
WFLYDS0013: Started FileSystemDeploymentService for directory
/home/sfrings/Downloads/wildfly-19.0.0.Final/standalone/deployments
11:04:37,889 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027:
Starting deployment of "Test-1.0-SNAPSHOT.war" (runtime-name:
"Test-1.0-SNAPSHOT.war")
11:04:38,044 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0006:
Undertow HTTPS listener https listening on 127.0.0.1:8443
11:04:38,181 INFO [org.jboss.ws.common.management] (MSC service thread 1-6) JBWS022052:
Starting JBossWS 5.4.1.Final (Apache CXF 3.3.5)
11:04:38,905 INFO [org.jboss.weld.deployer] (MSC service thread 1-3) WFLYWELD0003:
Processing weld deployment Test-1.0-SNAPSHOT.war
11:04:39,086 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread
1-3) HV000001: Hibernate Validator 6.0.18.Final
11:04:39,624 INFO [io.jaegertracing.internal.JaegerTracer] (MSC service thread 1-3) No
shutdown hook registered: Please call close() manually on application shutdown.
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.jboss.invocation.proxy.AbstractProxyFactory$1
(jar:file:/home/sfrings/Downloads/wildfly-19.0.0.Final/modules/system/layers/base/org/jboss/invocation/main/jboss-invocation-1.5.2.Final.jar!/)
to method java.lang.Object.clone()
WARNING: Please consider reporting this to the maintainers of
org.jboss.invocation.proxy.AbstractProxyFactory$1
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access
operations
WARNING: All illegal access operations will be denied in a future release
11:04:39,788 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread
1-1) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2'
9.4.18.Final
11:04:39,861 INFO [org.jboss.weld.Version] (MSC service thread 1-5) WELD-000900: 3.1.3
(Final)
11:04:40,006 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-5) JBWS024061: Adding
service endpoint metadata: id=com.mvneco.test.SoapService
address=http://localhost:8080/Test-1.0-SNAPSHOT/SoapService
implementor=com.mvneco.test.SoapService
serviceName={http://test.mvneco.com/}SoapServiceService
portName={http://test.mvneco.com/}SoapServicePort
annotationWsdlLocation=null
wsdlLocationOverride=null
mtomEnabled=false
11:04:40,618 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 78)
WFLYCLINF0002: Started client-mappings cache from ejb container
11:04:40,957 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC
service thread 1-5) Creating Service {http://test.mvneco.com/}SoapServiceService from
class com.mvneco.test.SoapService
11:04:41,506 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-5) Setting
the server's publish address to be
http://localhost:8080/Test-1.0-SNAPSHOT/SoapService
11:04:41,609 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-5) JBWS024074: WSDL
published to:
file:/home/sfrings/Downloads/wildfly-19.0.0.Final/standalone/data/wsdl/Test-1.0-SNAPSHOT.war/SoapServiceService.wsdl
11:04:41,708 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003:
Starting service
jboss.ws.endpoint."Test-1.0-SNAPSHOT.war"."com.mvneco.test.SoapService"
11:04:42,119 INFO [io.smallrye.metrics] (MSC service thread 1-4) MicroProfile: Metrics
activated (SmallRye Metrics version: 2.4.0)
11:04:43,018 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 78)
WFLYUT0021: Registered web context: '/Test-1.0-SNAPSHOT' for server
'default-server'
11:04:43,173 INFO [org.jboss.as.server] (ServerService Thread Pool -- 45) WFLYSRV0010:
Deployed "Test-1.0-SNAPSHOT.war" (runtime-name :
"Test-1.0-SNAPSHOT.war")
11:04:43,276 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0212: Resuming
server
11:04:43,280 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management
interface listening on
http://127.0.0.1:9990/management
11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console
listening on
http://127.0.0.1:9990
11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full
19.0.0.Final (WildFly Core 11.0.0.Final) started in 11438ms - Started 448 of 675 services
(379 services are lazy, passive or on-demand)
{code}
The I called the SOAP function with SOAP-UI and got the following bad output in
server.log:
{code}
2020-04-28 11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly
Full 19.0.0.Final (WildFly Core 11.0.0.Final) started in 11438ms - Started 448 of 675
services (379 services are lazy, passive or on-demand)
2020-04-28 11:05:02,489 DEBUG [com.mvneco.test.SoapService] (default task-1) Init
SoapService 1
2020-04-28 11:05:02,496 DEBUG [com.mvneco.test.SoapService] (default task-1) Init
SoapService 1
2020-04-28 11:05:02,497 DEBUG [com.mvneco.test.SoapService] (default task-1) Start test().
payload=?
{code}
You see that one of the two startup methods has still been executed twice and the other
was not executed anymore. The message "Init SoapService 2" is missing.
I attached the compiled *.war file so you can reproduce the issue more easily.
was (Author: s.frings74):
Since we have a stable workaround we continue to use Wildfly 16 for the next years.
Anyway I found time to test it with Wildfly 19. The result is differnet than before but
still bad.
The console output from startup looks fine:
{code}
=========================================================================
JBoss Bootstrap Environment
JBOSS_HOME: /home/sfrings/Downloads/wildfly-19.0.0.Final
JAVA: /opt/jdk11/bin/java
JAVA_OPTS: -server -Xms64m -Xmx512m -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m
-Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman
-Djava.awt.headless=true --add-exports=java.base/sun.nio.ch=ALL-UNNAMED
--add-exports=jdk.unsupported/sun.misc=ALL-UNNAMED
--add-exports=jdk.unsupported/sun.reflect=ALL-UNNAMED
=========================================================================
11:04:32,415 INFO [org.jboss.modules] (main) JBoss Modules version 1.10.0.Final
11:04:33,282 INFO [org.jboss.msc] (main) JBoss MSC version 1.4.11.Final
11:04:33,294 INFO [org.jboss.threads] (main) JBoss Threads version 2.3.3.Final
11:04:33,520 INFO [org.jboss.as] (MSC service thread 1-3) WFLYSRV0049: WildFly Full
19.0.0.Final (WildFly Core 11.0.0.Final) starting
11:04:34,962 INFO [org.wildfly.security] (ServerService Thread Pool -- 26) ELY00001:
WildFly Elytron version 1.11.2.Final
11:04:35,845 INFO [org.jboss.as.controller.management-deprecated] (Controller Boot
Thread) WFLYCTL0028: Attribute 'security-realm' in the resource at address
'/core-service=management/management-interface=http-interface' is deprecated, and
may be removed in a future version. See the attribute description in the output of the
read-resource-description operation to learn more about the deprecation.
11:04:35,924 INFO [org.jboss.as.controller.management-deprecated] (ServerService Thread
Pool -- 17) WFLYCTL0028: Attribute 'security-realm' in the resource at address
'/subsystem=undertow/server=default-server/https-listener=https' is deprecated,
and may be removed in a future version. See the attribute description in the output of the
read-resource-description operation to learn more about the deprecation.
11:04:36,157 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating
http management service using socket-binding (management-http)
11:04:36,193 INFO [org.xnio] (MSC service thread 1-1) XNIO version 3.7.7.Final
11:04:36,208 INFO [org.xnio.nio] (MSC service thread 1-1) XNIO NIO Implementation Version
3.7.7.Final
11:04:36,322 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 52)
WFLYCLINF0001: Activating Infinispan subsystem.
11:04:36,324 INFO [org.jboss.remoting] (MSC service thread 1-4) JBoss Remoting version
5.0.17.Final
11:04:36,410 INFO [org.wildfly.extension.microprofile.config.smallrye._private]
(ServerService Thread Pool -- 61) WFLYCONF0001: Activating WildFly MicroProfile Config
Subsystem
11:04:36,438 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 66) WFLYNAM0001:
Activating Naming Subsystem
11:04:36,332 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread
Pool -- 44) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version
1.4)
11:04:36,523 INFO [org.jboss.as.security] (ServerService Thread Pool -- 72) WFLYSEC0002:
Activating Security Subsystem
11:04:36,367 INFO [org.jboss.as.jaxrs] (ServerService Thread Pool -- 54) WFLYRS0016:
RESTEasy version 3.11.0.Final
11:04:36,380 INFO [org.jboss.as.connector] (MSC service thread 1-6) WFLYJCA0009: Starting
JCA Subsystem (WildFly/IronJacamar 1.4.20.Final)
11:04:36,412 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 59) WFLYJSF0007:
Activated the following JSF Implementations: [main]
11:04:36,532 INFO [org.wildfly.extension.microprofile.jwt.smallrye._private]
(ServerService Thread Pool -- 63) WFLYJWT0001: Activating WildFly MicroProfile JWT
Subsystem
11:04:36,463 INFO [org.jboss.as.naming] (MSC service thread 1-8) WFLYNAM0003: Starting
Naming Service
11:04:36,487 INFO [org.jboss.as.mail.extension] (MSC service thread 1-1) WFLYMAIL0001:
Bound mail session [java:jboss/mail/Default]
11:04:36,489 INFO [org.wildfly.extension.io] (ServerService Thread Pool -- 53) WFLYIO001:
Worker 'default' has auto-configured to 8 IO threads with 64 max task threads
based on your 4 available processors
11:04:36,491 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-5)
WFLYJCA0018: Started Driver service with driver-name = h2
11:04:36,534 INFO [org.jboss.as.security] (MSC service thread 1-6) WFLYSEC0001: Current
PicketBox version=5.0.3.Final
11:04:36,544 INFO [org.wildfly.extension.microprofile.opentracing] (ServerService Thread
Pool -- 65) WFLYTRACEXT0001: Activating MicroProfile OpenTracing Subsystem
11:04:36,549 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 76)
WFLYWS0002: Activating WebServices Extension
11:04:36,559 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 74) WFLYTX0013: The
node-identifier attribute on the /subsystem=transactions is set to the default value. This
is a danger for environments running multiple servers. Please make sure the attribute
value is unique.
11:04:36,575 INFO [org.wildfly.extension.microprofile.metrics.smallrye] (ServerService
Thread Pool -- 64) WFLYMETRICS0001: Activating Eclipse MicroProfile Metrics Subsystem
11:04:36,575 INFO [org.wildfly.extension.microprofile.health.smallrye] (ServerService
Thread Pool -- 62) WFLYHEALTH0001: Activating Eclipse MicroProfile Health Subsystem
11:04:36,616 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0003:
Undertow 2.0.30.Final starting
11:04:36,960 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 75)
WFLYUT0014: Creating file handler for path
'/home/sfrings/Downloads/wildfly-19.0.0.Final/welcome-content' with options
[directory-listing: 'false', follow-symlink: 'false', case-sensitive:
'true', safe-symlink-paths: '[]']
11:04:36,977 INFO [org.wildfly.extension.undertow] (MSC service thread 1-3) WFLYUT0012:
Started server default-server.
11:04:36,984 INFO [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0018:
Host default-host starting
11:04:36,985 INFO [org.jboss.as.ejb3] (MSC service thread 1-2) WFLYEJB0481: Strict pool
slsb-strict-max-pool is using a max instance size of 64 (per class), which is derived from
thread worker pool sizing.
11:04:36,985 INFO [org.jboss.as.ejb3] (MSC service thread 1-5) WFLYEJB0482: Strict pool
mdb-strict-max-pool is using a max instance size of 16 (per class), which is derived from
the number of CPUs on this host.
11:04:37,147 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0006:
Undertow HTTP listener default listening on 127.0.0.1:8080
11:04:37,441 INFO [org.jboss.as.ejb3] (MSC service thread 1-3) WFLYEJB0493: EJB subsystem
suspension complete
11:04:37,571 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread
1-5) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS]
11:04:37,801 INFO [org.jboss.as.patching] (MSC service thread 1-5) WFLYPAT0050: WildFly
Full cumulative patch ID is: base, one-off patches include: none
11:04:37,838 WARN [org.jboss.as.domain.management.security] (MSC service thread 1-2)
WFLYDM0111: Keystore
/home/sfrings/Downloads/wildfly-19.0.0.Final/standalone/configuration/application.keystore
not found, it will be auto generated on first use with a self signed certificate for host
localhost
11:04:37,879 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-1)
WFLYDS0013: Started FileSystemDeploymentService for directory
/home/sfrings/Downloads/wildfly-19.0.0.Final/standalone/deployments
11:04:37,889 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027:
Starting deployment of "Test-1.0-SNAPSHOT.war" (runtime-name:
"Test-1.0-SNAPSHOT.war")
11:04:38,044 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0006:
Undertow HTTPS listener https listening on 127.0.0.1:8443
11:04:38,181 INFO [org.jboss.ws.common.management] (MSC service thread 1-6) JBWS022052:
Starting JBossWS 5.4.1.Final (Apache CXF 3.3.5)
11:04:38,905 INFO [org.jboss.weld.deployer] (MSC service thread 1-3) WFLYWELD0003:
Processing weld deployment Test-1.0-SNAPSHOT.war
11:04:39,086 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread
1-3) HV000001: Hibernate Validator 6.0.18.Final
11:04:39,624 INFO [io.jaegertracing.internal.JaegerTracer] (MSC service thread 1-3) No
shutdown hook registered: Please call close() manually on application shutdown.
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.jboss.invocation.proxy.AbstractProxyFactory$1
(jar:file:/home/sfrings/Downloads/wildfly-19.0.0.Final/modules/system/layers/base/org/jboss/invocation/main/jboss-invocation-1.5.2.Final.jar!/)
to method java.lang.Object.clone()
WARNING: Please consider reporting this to the maintainers of
org.jboss.invocation.proxy.AbstractProxyFactory$1
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access
operations
WARNING: All illegal access operations will be denied in a future release
11:04:39,788 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread
1-1) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2'
9.4.18.Final
11:04:39,861 INFO [org.jboss.weld.Version] (MSC service thread 1-5) WELD-000900: 3.1.3
(Final)
11:04:40,006 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-5) JBWS024061: Adding
service endpoint metadata: id=com.mvneco.test.SoapService
address=http://localhost:8080/Test-1.0-SNAPSHOT/SoapService
implementor=com.mvneco.test.SoapService
serviceName={http://test.mvneco.com/}SoapServiceService
portName={http://test.mvneco.com/}SoapServicePort
annotationWsdlLocation=null
wsdlLocationOverride=null
mtomEnabled=false
11:04:40,618 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 78)
WFLYCLINF0002: Started client-mappings cache from ejb container
11:04:40,957 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC
service thread 1-5) Creating Service {http://test.mvneco.com/}SoapServiceService from
class com.mvneco.test.SoapService
11:04:41,506 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-5) Setting
the server's publish address to be
http://localhost:8080/Test-1.0-SNAPSHOT/SoapService
11:04:41,609 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-5) JBWS024074: WSDL
published to:
file:/home/sfrings/Downloads/wildfly-19.0.0.Final/standalone/data/wsdl/Test-1.0-SNAPSHOT.war/SoapServiceService.wsdl
11:04:41,708 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003:
Starting service
jboss.ws.endpoint."Test-1.0-SNAPSHOT.war"."com.mvneco.test.SoapService"
11:04:42,119 INFO [io.smallrye.metrics] (MSC service thread 1-4) MicroProfile: Metrics
activated (SmallRye Metrics version: 2.4.0)
11:04:43,018 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 78)
WFLYUT0021: Registered web context: '/Test-1.0-SNAPSHOT' for server
'default-server'
11:04:43,173 INFO [org.jboss.as.server] (ServerService Thread Pool -- 45) WFLYSRV0010:
Deployed "Test-1.0-SNAPSHOT.war" (runtime-name :
"Test-1.0-SNAPSHOT.war")
11:04:43,276 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0212: Resuming
server
11:04:43,280 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management
interface listening on
http://127.0.0.1:9990/management
11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console
listening on
http://127.0.0.1:9990
11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full
19.0.0.Final (WildFly Core 11.0.0.Final) started in 11438ms - Started 448 of 675 services
(379 services are lazy, passive or on-demand)
{code}
The I called the SOAP function with SOAP-UI and got the following bad output in
server.log:
{code}
2020-04-28 11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly
Full 19.0.0.Final (WildFly Core 11.0.0.Final) started in 11438ms - Started 448 of 675
services (379 services are lazy, passive or on-demand)
2020-04-28 11:05:02,489 DEBUG [com.mvneco.test.SoapService] (default task-1) Init
SoapService 1
2020-04-28 11:05:02,496 DEBUG [com.mvneco.test.SoapService] (default task-1) Init
SoapService 1
2020-04-28 11:05:02,497 DEBUG [com.mvneco.test.SoapService] (default task-1) Start test().
payload=?
{code}
You see that one of the two startup methods has still been executed twice and the other
was not executed anymore. The message "Init SoapService 2" is missing.
I attached the compiled *.war file so you can reproduce the issue more easily.
Wildfly creates to many instances of all web service classes
------------------------------------------------------------
Key: WFLY-11999
URL:
https://issues.redhat.com/browse/WFLY-11999
Project: WildFly
Issue Type: Bug
Components: Web Services
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: Jim Ma
Priority: Minor
Attachments: Test-1.0-SNAPSHOT.war, 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.13.8#713008)