4:20 a.m.
[
https://issues.redhat.com/browse/WFLY-11999?page=com.atlassian.jira.plugi...
]
Stefan Frings edited comment on WFLY-11999 at 4/28/20 5:19 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 situation has not changed.
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: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-SNAP
SHOT.war/SoapServiceService.wsdl
2020-04-28 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"
2020-04-28 11:04:42,119 INFO [io.smallrye.metrics] (MSC service thread 1-4) MicroProfile:
Metrics activated (SmallRye Metrics version: 2.4.0)
2020-04-28 11:04:42,987 DEBUG [com.mvneco.test.SoapService] (ServerService Thread Pool --
78) Init SoapService 1
2020-04-28 11:04:42,991 DEBUG [com.mvneco.test.SoapService] (ServerService Thread Pool --
78) Init SoapService 2
2020-04-28 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'
2020-04-28 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")
2020-04-28 11:04:43,276 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0212:
Resuming server
2020-04-28 11:04:43,280 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http
management interface listening on http://127.0.0.1:9990/management
2020-04-28 11:04:43,281 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin
console listening on http://127.0.0.1:9990
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 first startup methods has now been executed 3 times!
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 situation has not changed.
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, but now both
have been executed by the same thread, which wasd different before.
The other method that would output "Init SoapService 2" has not been executed
anmore. Ist this a new additional bug?
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, standalone.xml
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)
2200
days inactive
2200
days old
0 comments
1 participants
participants (1)
-
Stefan Frings (Jira)