Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Improve information presented for jndi lookup failures #25190

Open
scottmarlow opened this issue Oct 22, 2024 · 16 comments
Open

Improve information presented for jndi lookup failures #25190

scottmarlow opened this issue Oct 22, 2024 · 16 comments

Comments

@scottmarlow
Copy link
Member

scottmarlow commented Oct 22, 2024

Is there a likely deployment descriptor problem or class not found exception that is responsible for the following? Could we include the cause exception or at least log it?

Exception call stack from EE 11 Persistence TCK testing:

acc._AS_INSTALL set to /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/..
acc.APPCPATH set to target/lib/arquillian-protocol-lib.jar:target/lib/tck-porting-lib.jar
VMARGS = null
"/usr/lib/jvm/java-17-openjdk/bin/java" -Dorg.glassfish.gmbal.no.multipleUpperBoundsException=true --add-opens=java.base/java.lang=ALL-UNNAMED -Dcom.sun.aas.installRoot="/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/.." -Djava.security.policy="/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/appclient/client.policy" -classpath /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar:. -Djava.system.class.loader=org.glassfish.appclient.client.acc.agent.ACCAgentClassLoader -Xshare:off -Djava.security.auth.login.config="/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/appclient/appclientlogin.conf"  "-Djdk.tls.client.enableSessionTicketExtension=false"  "-Djdk.tls.server.enableSessionTicketExtension=false"  "-Djava.security.policy=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/lib/appclient/client.policy"  "-Dcts.tmp=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/jakartaeetck/tmp"  "-Djava.security.auth.login.config=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/lib/appclient/appclientlogin.conf"  "-Djava.protocol.handler.pkgs=javax.net.ssl"  "-Djavax.net.ssl.keyStore=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/jakartaeetck/bin/certificates/clientcert.jks"  "-Djavax.net.ssl.keyStorePassword=changeit"  "-Djavax.net.ssl.trustStore=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/config/cacerts.jks"  "-Djavax.xml.parsers.SAXParserFactory=com.sun.org.apache.xerces.internal.jaxp.SAXParserFactoryImpl"  "-Djavax.xml.parsers.DocumentBuilderFactory=com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderFactoryImpl"  "-Djavax.xml.transform.TransformerFactory=com.sun.org.apache.xalan.internal.xsltc.trax.TransformerFactoryImpl"  "-Dorg.xml.sax.driver=com.sun.org.apache.xerces.internal.parsers.SAXParser"  "-Dorg.xml.sax.parser=org.xml.sax.helpers.XMLReaderAdapter"  "-Doracle.jdbc.J2EE13Compliant=true"  "-Doracle.jdbc.mapDateToTimestamp"  "-Dstartup.login=false"  "-Dauth.gui=false"  "-Dlog.file.location=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/logs"  "-Dri.log.file.location=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/logs"  "-DwebServerHost.2=localhost"  "-DwebServerPort.2=8080"  "-Ddeliverable.class=com.sun.ts.lib.deliverable.cts.CTSDeliverable"  -javaagent:"/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar"=argsfile="/tmp/acc6150892951202217625.dat"  -classpath  /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/lib/arquillian-protocol-lib.jar:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/lib/tck-porting-lib.jar  ee.jakarta.tck.persistence.core.EntityGraph.Client  "-p"  "/tmp/tstest.jte"  "-ap"  "/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/sql/derby/derby.dml.sql"  "-classname"  "ee.jakarta.tck.persistence.core.EntityGraph.ClientStateless3Test"  "-t"  "getEntityGraphStringTest"  "-vehicle"  "stateless3"
************************************************************
* props file set to "/tmp/smarlow-cts-props.txt"
************************************************************
10-22-2024 11:40:00:  TRACE: #######  Value of harness.socket.retry.count is "10"
10-22-2024 11:40:00:  TRACE: #######  Value of harness.log.port is "2000"
10-22-2024 11:40:00:  TRACE: #######  Actual bind value of harness.log.port is "2000"
10-22-2024 11:40:00:  TRACE: Check if called from within test process, inTestHarness= true
10-22-2024 11:40:00:  TRACE: in ServiceEETest.run(), this URL is:  file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar
10-22-2024 11:40:00:  TRACE: VehicleClient URL is:  file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar
10-22-2024 11:40:00:  TRACE: VehicleClient class check if is vehicle class =  no, is not com.sun.ts.tests.common.vehicle.VehicleClient class
10-22-2024 11:40:00:  TRACE: Using appclient vehicle so set is vehicle client to true
10-22-2024 11:40:00:  TRACE: in ServiceEETest.run() method
10-22-2024 11:40:00:  TRACE: Vehicle to be used for this test is:  stateless3
Oct 22, 2024 11:40:02 AM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getORB
INFO: ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@78d73b1b
10-22-2024 11:40:02:  ERROR: Test failed.
10-22-2024 11:40:02:  ERROR: javax.naming.NamingException: Lookup failed for java:comp/env/ejb/Stateless3VehicleBean in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/Stateless3VehicleBean]
	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
	at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
	at com.sun.ts.lib.util.TSNamingContext.lookup(TSNamingContext.java:70)
	at com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleRunner.run(Stateless3VehicleRunner.java:41)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:128)
	at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:494)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:232)
	at com.sun.ts.lib.harness.EETest.run(EETest.java:296)
	at ee.jakarta.tck.persistence.core.EntityGraph.Client.main(Client.java:48)
Caused by: javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/Stateless3VehicleBean
STATUS:Failed.Test run in stateless3 vehicle failed.
	at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:135)
	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:818)
	... 9 more
	Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/Stateless3VehicleBean,Remote 3.x interface =com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF,ejb-link=Stateless3VehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' .  Actual (possibly internal) Remote JNDI name used for lookup is '#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found]]
		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:159)
		at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$EjbReferenceProxy.create(ComponentEnvManagerImpl.java:975)
		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:621)
		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:587)
		at com.sun.enterprise.naming.impl.JavaURLContext.lambda$lookup$0(JavaURLContext.java:153)
		at com.sun.enterprise.naming.impl.JavaURLContext.lookupOrCollectException(JavaURLContext.java:550)
		at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:153)
		... 10 more
	Caused by: javax.naming.NamingException: Lookup failed for #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found]
		at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
		at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
		at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:154)
		... 16 more
	Caused by: javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found
		at com.sun.enterprise.naming.impl.TransientContext.doLookup(TransientContext.java:234)
		at com.sun.enterprise.naming.impl.TransientContext.lookup(TransientContext.java:202)
		at com.sun.enterprise.naming.impl.SerialContextProviderImpl.lookup(SerialContextProviderImpl.java:37)
		at com.sun.enterprise.naming.impl.RemoteSerialContextProviderImpl.lookup(RemoteSerialContextProviderImpl.java:85)
		at jdk.internal.reflect.GeneratedMethodAccessor59.invoke(Unknown Source)
		at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
		at java.base/java.lang.reflect.Method.invoke(Method.java:569)
		at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:122)
		at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:152)
		at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatchToServant(ServerRequestDispatcherImpl.java:501)
		at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatch(ServerRequestDispatcherImpl.java:172)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequestRequest(MessageMediatorImpl.java:1529)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:1405)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleInput(MessageMediatorImpl.java:910)
		at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:192)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:674)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.dispatch(MessageMediatorImpl.java:476)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.doWork(MessageMediatorImpl.java:2202)
		at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:476)
		at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:519)

10-22-2024 11:40:02:  TRACE: SLEPT FOR:  0
@scottmarlow
Copy link
Member Author

scottmarlow commented Oct 22, 2024

Note this line below:

Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/Stateless3VehicleBean,Remote 3.x interface =com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF,ejb-link=Stateless3VehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' . Actual (possibly internal) Remote JNDI name used for lookup is '#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found]]

App client container side output inclues:

INFO: [APPCLIENT-out] 10-22-2024 11:36:36:  TRACE: VehicleClient URL is:  file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar
Oct 22, 2024 11:36:36 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-22-2024 11:36:36:  TRACE: VehicleClient class check if is vehicle class =  no, is not com.sun.ts.tests.common.vehicle.VehicleClient class
Oct 22, 2024 11:36:36 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-22-2024 11:36:36:  TRACE: Using appclient vehicle so set is vehicle client to true
Oct 22, 2024 11:36:36 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-22-2024 11:36:36:  TRACE: in ServiceEETest.run() method
Oct 22, 2024 11:36:36 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-22-2024 11:36:36:  TRACE: Vehicle to be used for this test is:  appmanagedNoTx
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] Oct 22, 2024 11:36:38 AM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getORB
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] INFO: ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@78d73b1b
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-22-2024 11:36:38:  ERROR: Test failed.
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-22-2024 11:36:38:  ERROR: javax.naming.NamingException: Lookup failed for java:comp/env/ejb/AppManagedNoTxVehicleBean in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/AppManagedNoTxVehicleBean]
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] STATUS:Failed.Test run in application-managed resource-local vehicle failed.
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.ts.lib.util.TSNamingContext.lookup(TSNamingContext.java:70)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleRunner.run(AppManagedNoTxVehicleRunner.java:38)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:128)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:494)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:232)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.ts.lib.harness.EETest.run(EETest.java:296)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at ee.jakarta.tck.persistence.core.EntityGraph.Client.main(Client.java:48)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] Caused by: javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/AppManagedNoTxVehicleBean
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:135)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:818)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	... 9 more
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 	Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/AppManagedNoTxVehicleBean,Remote 3.x interface =com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleIF,ejb-link=AppManagedNoTxVehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' .  Actual (possibly internal) Remote JNDI name used for lookup is '#com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleIF not found]]
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:159)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$EjbReferenceProxy.create(ComponentEnvManagerImpl.java:975)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:621)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:587)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.enterprise.naming.impl.JavaURLContext.lambda$lookup$0(JavaURLContext.java:153)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.enterprise.naming.impl.JavaURLContext.lookupOrCollectException(JavaURLContext.java:550)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:153)
Oct 22, 2024 11:36:38 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 		... 10 more

@scottmarlow
Copy link
Member Author

server.log contents are below:

[2024-10-22T11:34:54.579224-04:00] [] [INFO] [NCLS-GFLAUNCHER-00005] [jakarta.enterprise.launcher] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  JVM invocation command line:
/usr/lib/jvm/java-17-openjdk/bin/java
-cp
/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/glassfish.jar
-DWALL_CLOCK_START=2024-10-22T15:34:54.159351825Z
-XX:+UnlockDiagnosticVMOptions
-XX:NewRatio=2
-Xmx512m
--add-opens=java.base/java.io=ALL-UNNAMED
--add-opens=java.base/java.lang=ALL-UNNAMED
--add-opens=java.base/java.util=ALL-UNNAMED
--add-opens=java.base/sun.nio.fs=ALL-UNNAMED
--add-opens=java.base/sun.net.www.protocol.jrt=ALL-UNNAMED
--add-opens=java.naming/javax.naming.spi=ALL-UNNAMED
--add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED
--add-opens=jdk.management/com.sun.management.internal=ALL-UNNAMED
--add-exports=java.naming/com.sun.jndi.ldap=ALL-UNNAMED
--add-exports=java.base/jdk.internal.vm.annotation=ALL-UNNAMED
--add-opens=java.base/jdk.internal.vm.annotation=ALL-UNNAMED
-javaagent:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/lib/monitor/flashlight-agent.jar
-Djava.awt.headless=true
-Djdk.corba.allowOutputStreamSubclass=true
-Djdk.tls.rejectClientInitiatedRenegotiation=true
-Djavax.xml.accessExternalSchema=all
-Djava.security.policy=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/config/server.policy
-Djava.security.auth.login.config=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/config/login.conf
-Dcom.sun.enterprise.security.httpsOutboundKeyAlias=s1as
-Djavax.net.ssl.keyStore=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/config/keystore.jks
-Djavax.net.ssl.trustStore=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/config/cacerts.jks
-Djdbc.drivers=org.apache.derby.jdbc.ClientDriver
-DANTLR_USE_DIRECT_CLASS_LOADING=true
-Dcom.sun.enterprise.config.config_environment_factory_class=com.sun.enterprise.config.serverbeans.AppserverConfigEnvironmentFactory
-Dorg.glassfish.additionalOSGiBundlesToStart=org.apache.felix.shell,org.apache.felix.gogo.runtime,org.apache.felix.gogo.shell,org.apache.felix.gogo.command,org.apache.felix.shell.remote,org.apache.felix.fileinstall
-Dosgi.shell.telnet.port=6666
-Dosgi.shell.telnet.maxconn=1
-Dosgi.shell.telnet.ip=127.0.0.1
-Dgosh.args=--nointeractive
-Dfelix.fileinstall.dir=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/autostart/
-Dfelix.fileinstall.poll=5000
-Dfelix.fileinstall.log.level=2
-Dfelix.fileinstall.bundles.new.start=true
-Dfelix.fileinstall.bundles.startTransient=true
-Dfelix.fileinstall.disableConfigSave=false
-Dorg.glassfish.gmbal.no.multipleUpperBoundsException=true
-Dcom.ctc.wstx.returnNullForDefaultNamespace=true
-Djdk.attach.allowAttachSelf=true
-Dcom.sun.aas.instanceRoot=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1
-Dcom.sun.aas.installRoot=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish
-Djava.library.path=/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/lib:/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
com.sun.enterprise.glassfish.bootstrap.ASMain
-upgrade
false
-domaindir
/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1
-read-stdin
true
-asadmin-args
--host,,,localhost,,,--port,,,4848,,,--secure=false,,,--terse=true,,,--echo=false,,,--interactive=false,,,start-domain,,,--verbose=false,,,--watchdog=false,,,--debug=false,,,--domaindir,,,/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains,,,domain1
-domainname
domain1
-instancename
server
-type
DAS
-verbose
false
-asadmin-classpath
/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/admin-cli.jar
-debug
false
-asadmin-classname
com.sun.enterprise.admin.cli.AdminMain]]

[2024-10-22T11:34:57.809886-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [com.sun.enterprise.server.logging.LogManagerService] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Using property file: /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/config/logging.properties]]

[2024-10-22T11:34:57.888712-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-LOGGING-00009] [com.sun.enterprise.server.logging.LogManagerService] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Running GlassFish Version: Eclipse GlassFish 8.0.0-JDK17-M7 (commit: 02b9dba13f056746838bc63db76436b95d2eaab8)]]

[2024-10-22T11:34:58.402025-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00087] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Grizzly Framework 4.1.0 started in: 31 ms - bound to [/0.0.0.0:8080]]]

[2024-10-22T11:34:58.434341-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00087] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Grizzly Framework 4.1.0 started in: 1 ms - bound to [/0.0.0.0:8181]]]

[2024-10-22T11:34:58.442238-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00087] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Grizzly Framework 4.1.0 started in: 1 ms - bound to [/0.0.0.0:4848]]]

[2024-10-22T11:34:58.513741-04:00] [GF 8.0.0-JDK17-M7] [INFO] [SEC-SVCS-00100] [jakarta.enterprise.security.services] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Authorization Service has successfully initialized.]]

[2024-10-22T11:34:58.564991-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01115] [jakarta.enterprise.system.core.security] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Realm [admin-realm] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.]]

[2024-10-22T11:34:58.566296-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01115] [jakarta.enterprise.system.core.security] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Realm [file] of classtype [com.sun.enterprise.security.auth.realm.file.FileRealm] successfully created.]]

[2024-10-22T11:34:58.573079-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01115] [jakarta.enterprise.system.core.security] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Realm [certificate] of classtype [com.sun.enterprise.security.auth.realm.certificate.CertificateRealm] successfully created.]]

[2024-10-22T11:34:59.181011-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.ha.store.spi.BackingStoreFactoryRegistry] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Registered org.glassfish.ha.store.adapter.cache.ShoalBackingStoreProxy for persistence-type = replicated in BackingStoreFactoryRegistry]]

[2024-10-22T11:34:59.196772-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00087] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Grizzly Framework 4.1.0 started in: 1 ms - bound to [/0.0.0.0:3700]]]

[2024-10-22T11:34:59.209870-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00017] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Eclipse GlassFish 8.0.0-JDK17-M7 (commit: 02b9dba13f056746838bc63db76436b95d2eaab8) startup time: Embedded (2,789 ms), startup services (1,498 ms), total (4,287 ms)]]

[2024-10-22T11:34:59.214337-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00018] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Total startup time including CLI: 5,054 ms]]

[2024-10-22T11:34:59.514724-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00087] [jakarta.enterprise.system.core] [tid: _ThreadID=1 _ThreadName=main] [levelValue: 800] [[
  Grizzly Framework 4.1.0 started in: 1 ms - bound to [/0.0.0.0:7676]]]

[2024-10-22T11:34:59.808405-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-JMX-00005] [jakarta.enterprise.system.jmx] [tid: _ThreadID=96 _ThreadName=Thread-10] [levelValue: 800] [[
  JMXStartupService has started JMXConnector on JMXService URL service:jmx:rmi://scottmarlow:8686/jndi/rmi://scottmarlow:8686/jmxrmi]]

[2024-10-22T11:35:01.121703-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-REST-00001] [jakarta.enterprise.admin.rest] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Listening to REST requests at context: /command/domain.]]

[2024-10-22T11:35:01.260108-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00087] [jakarta.enterprise.system.core] [tid: _ThreadID=81 _ThreadName=pool-7-thread-1] [levelValue: 800] [[
  Grizzly Framework 4.1.0 started in: 2 ms - bound to [/0.0.0.0:8080]]]

[2024-10-22T11:35:01.843963-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01117] [jakarta.enterprise.system.core.security] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Realm [file] successfully updated.]]

[2024-10-22T11:35:02.268824-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01117] [jakarta.enterprise.system.core.security] [tid: _ThreadID=71 _ThreadName=admin-listener(2)] [levelValue: 800] [[
  Realm [file] successfully updated.]]

[2024-10-22T11:35:05.770894-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01117] [jakarta.enterprise.system.core.security] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Realm [file] successfully updated.]]

[2024-10-22T11:35:07.091115-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-REST-00001] [jakarta.enterprise.admin.rest] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Listening to REST requests at context: /management/domain.]]

[2024-10-22T11:35:10.440077-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01143] [jakarta.enterprise.system.core.security] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Loading policy provider org.glassfish.exousia.modules.def.DefaultPolicy.]]

[2024-10-22T11:35:10.448161-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01002] [jakarta.enterprise.system.core.security] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Java security manager is disabled.]]

[2024-10-22T11:35:10.448688-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01010] [jakarta.enterprise.system.core.security] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Entering Security Startup Service.]]

[2024-10-22T11:35:10.456993-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-SECURITY-01011] [jakarta.enterprise.system.core.security] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Security Service(s) started successfully.]]

[2024-10-22T11:35:10.486663-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.487645-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.488234-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.488718-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.489135-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.489572-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.490023-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:35:10.533306-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.service] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  Probably the pool org.glassfish.resourcebase.resources.api.PoolInfo@cc5abc8b[jndiName=cts-derby-pool, applicationName=null, moduleName=null] is not yet initialized (lazy-loading), trying to check ...]]

[2024-10-22T11:35:11.029115-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.resource.resourceadapter.com.sun.enterprise.resource.pool] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Created connection pool and added it to PoolManager: Pool [org.glassfish.resourcebase.resources.api.PoolInfo@cc5abc8b[jndiName=cts-derby-pool, applicationName=null, moduleName=null]] PoolSize=0  FreeResources=0  QueueSize=0 matching=off validation=off]]

[2024-10-22T11:35:11.461291-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.tools.deployment.common] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Generated client JAR /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/generated/xml/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehiclesClient.jar for possible download of size 3,369 B]]

[2024-10-22T11:35:11.582129-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.eclipse.persistence.session./file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/applications/jpa_core_EntityGraph_appmanaged_vehicles/lib/jpa_core_EntityGraph.jar_CTS-EM] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  EclipseLink, version: Eclipse Persistence Services - 5.0.0-B02.v202404111748]]

[2024-10-22T11:35:13.909413-04:00] [GF 8.0.0-JDK17-M7] [INFO] [jts.startup_msg] [jakarta.enterprise.system.core.transaction.com.sun.jts.CosTransactions] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  JTS5014: Recoverable JTS instance, serverId = [3700]]]

[2024-10-22T11:35:14.113134-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.enterprise.iiop.impl.GlassFishORBManager] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@59b8db2a]]

[2024-10-22T11:35:14.273804-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.ha.store.adapter.file.FileBackingStore] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  [FileBackingStore::initialize] Successfully Created and initialized store. Working dir: /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedVehicleBean-113351806838374400; Configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='AppManagedVehicleBean-113351806838374400-BackingStore', shortUniqueName='113351806838374400', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedVehicleBean-113351806838374400', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@5ce2ea70}}]]

[2024-10-22T11:35:14.274287-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00043] [jakarta.enterprise.ejb.container] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  StatefulContainerbuilder instantiated store: org.glassfish.ha.store.adapter.file.FileBackingStore@f9f3d38, with ha-enabled [false], and backing store configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='AppManagedVehicleBean-113351806838374400-BackingStore', shortUniqueName='113351806838374400', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedVehicleBean-113351806838374400', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@5ce2ea70}}]]

[2024-10-22T11:35:14.391019-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00054] [jakarta.enterprise.ejb.container] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Portable JNDI names for EJB AppManagedVehicleBean: [java:global/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicle_ejb/AppManagedVehicleBean!com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF, java:global/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicle_ejb/AppManagedVehicleBean]]]

[2024-10-22T11:35:14.391452-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00055] [jakarta.enterprise.ejb.container] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Glassfish-specific (Non-portable) JNDI names for EJB AppManagedVehicleBean: [com_sun_ts_tests_common_vehicle_AppManagedVehicleBean#com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF, com_sun_ts_tests_common_vehicle_AppManagedVehicleBean]]]

[2024-10-22T11:35:14.416763-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Version] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  WELD-000900: 6.0.0 (Beta4)]]

[2024-10-22T11:35:14.538179-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Enabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_appmanaged_vehicles]]]

[2024-10-22T11:35:14.582948-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [ServiceLoader] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 900] [[
  Could not load service class org.glassfish.jersey.microprofile.restclient.RestClientExtension]]

[2024-10-22T11:35:14.922917-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Event] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  WELD-000411: Observer method [BackedAnnotatedMethod] public org.glassfish.jersey.ext.cdi1x.internal.ProcessAllAnnotatedTypes.processAnnotatedType(@Observes ProcessAnnotatedType<?>, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.]]

[2024-10-22T11:35:15.598832-04:00] [GF 8.0.0-JDK17-M7] [SEVERE] [] [org.glassfish.concurro.cdi.ConcurrentCDIExtension] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 1000] [[
  afterBeanDiscovery]]

[2024-10-22T11:35:15.971518-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-ACDEPL-00103] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  Java Web Start services started for the app client jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicles_client.jar (contextRoot: /jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicles_client)]]

[2024-10-22T11:35:16.207863-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.core] [tid: _ThreadID=70 _ThreadName=admin-listener(1)] [levelValue: 800] [[
  jpa_core_EntityGraph_appmanaged_vehicles was successfully deployed in 7,501 milliseconds.]]

[2024-10-22T11:36:27.071273-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS_ACDEPL-00104] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Java Web Start services stopped for the app client jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicles_client.jar]]

[2024-10-22T11:36:27.080196-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Disabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_appmanaged_vehicles]]]

[2024-10-22T11:36:32.752621-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.753281-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.753713-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.754095-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.754870-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.755266-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.755645-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:36:32.768125-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.service] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  Probably the pool org.glassfish.resourcebase.resources.api.PoolInfo@836f2b8e[jndiName=cts-derby-pool_no_tx, applicationName=null, moduleName=null] is not yet initialized (lazy-loading), trying to check ...]]

[2024-10-22T11:36:32.775237-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.resource.resourceadapter.com.sun.enterprise.resource.pool] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Created connection pool and added it to PoolManager: Pool [org.glassfish.resourcebase.resources.api.PoolInfo@836f2b8e[jndiName=cts-derby-pool_no_tx, applicationName=null, moduleName=null]] PoolSize=0  FreeResources=0  QueueSize=0 matching=off validation=off]]

[2024-10-22T11:36:32.803268-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.tools.deployment.common] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Generated client JAR /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/generated/xml/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehiclesClient.jar for possible download of size 3,361 B]]

[2024-10-22T11:36:32.833446-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.eclipse.persistence.session./file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/applications/jpa_core_EntityGraph_vehicles/lib/jpa_core_EntityGraph.jar_CTS-EM-NOTX] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  EclipseLink, version: Eclipse Persistence Services - 5.0.0-B02.v202404111748]]

[2024-10-22T11:36:33.013256-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.ha.store.adapter.file.FileBackingStore] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  [FileBackingStore::initialize] Successfully Created and initialized store. Working dir: /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedNoTxVehicleBean-113351806838439936; Configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='AppManagedNoTxVehicleBean-113351806838439936-BackingStore', shortUniqueName='113351806838439936', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedNoTxVehicleBean-113351806838439936', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@778f6152}}]]

[2024-10-22T11:36:33.013836-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00043] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  StatefulContainerbuilder instantiated store: org.glassfish.ha.store.adapter.file.FileBackingStore@48436764, with ha-enabled [false], and backing store configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='AppManagedNoTxVehicleBean-113351806838439936-BackingStore', shortUniqueName='113351806838439936', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedNoTxVehicleBean-113351806838439936', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@778f6152}}]]

[2024-10-22T11:36:33.048040-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00054] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Portable JNDI names for EJB AppManagedNoTxVehicleBean: [java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_appmanagedNoTx_vehicle_ejb/AppManagedNoTxVehicleBean, java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_appmanagedNoTx_vehicle_ejb/AppManagedNoTxVehicleBean!com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleIF]]]

[2024-10-22T11:36:33.048481-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00055] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Glassfish-specific (Non-portable) JNDI names for EJB AppManagedNoTxVehicleBean: [com_sun_ts_tests_common_vehicle_AppManagedNoTxVehicleBean, com_sun_ts_tests_common_vehicle_AppManagedNoTxVehicleBean#com.sun.ts.tests.common.vehicle.appmanagedNoTx.AppManagedNoTxVehicleIF]]]

[2024-10-22T11:36:33.085500-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Enabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_vehicles]]]

[2024-10-22T11:36:33.094863-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [ServiceLoader] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
  Could not load service class org.glassfish.jersey.microprofile.restclient.RestClientExtension]]

[2024-10-22T11:36:33.122264-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Event] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  WELD-000411: Observer method [BackedAnnotatedMethod] public org.glassfish.jersey.ext.cdi1x.internal.ProcessAllAnnotatedTypes.processAnnotatedType(@Observes ProcessAnnotatedType<?>, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.]]

[2024-10-22T11:36:33.280680-04:00] [GF 8.0.0-JDK17-M7] [SEVERE] [] [org.glassfish.concurro.cdi.ConcurrentCDIExtension] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 1000] [[
  afterBeanDiscovery]]

[2024-10-22T11:36:33.410035-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-ACDEPL-00103] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  Java Web Start services started for the app client jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client.jar (contextRoot: /jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client)]]

[2024-10-22T11:36:33.639571-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.core] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
  jpa_core_EntityGraph_vehicles was successfully deployed in 1,648 milliseconds.]]

[2024-10-22T11:37:44.451225-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS_ACDEPL-00104] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Java Web Start services stopped for the app client jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client.jar]]

[2024-10-22T11:37:44.457307-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Disabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_vehicles]]]

[2024-10-22T11:37:50.106423-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.107125-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.107455-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.107748-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.108026-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.108298-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.108586-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:37:50.146050-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.tools.deployment.common] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Generated client JAR /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/generated/xml/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehiclesClient.jar for possible download of size 3,361 B]]

[2024-10-22T11:37:50.173051-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.eclipse.persistence.session./file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/applications/jpa_core_EntityGraph_vehicles/lib/jpa_core_EntityGraph.jar_CTS-EM] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  EclipseLink, version: Eclipse Persistence Services - 5.0.0-B02.v202404111748]]

[2024-10-22T11:37:50.203722-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.ha.store.adapter.file.FileBackingStore] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  [FileBackingStore::initialize] Successfully Created and initialized store. Working dir: /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/Stateful3VehicleBean-113351806838505472; Configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='Stateful3VehicleBean-113351806838505472-BackingStore', shortUniqueName='113351806838505472', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/Stateful3VehicleBean-113351806838505472', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@671c68f4}}]]

[2024-10-22T11:37:50.204085-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00043] [jakarta.enterprise.ejb.container] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  StatefulContainerbuilder instantiated store: org.glassfish.ha.store.adapter.file.FileBackingStore@598f542e, with ha-enabled [false], and backing store configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='Stateful3VehicleBean-113351806838505472-BackingStore', shortUniqueName='113351806838505472', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/Stateful3VehicleBean-113351806838505472', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@671c68f4}}]]

[2024-10-22T11:37:50.224894-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00054] [jakarta.enterprise.ejb.container] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Portable JNDI names for EJB Stateful3VehicleBean: [java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateful3_vehicle_ejb/Stateful3VehicleBean, java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateful3_vehicle_ejb/Stateful3VehicleBean!com.sun.ts.tests.common.vehicle.stateful3.Stateful3VehicleIF]]]

[2024-10-22T11:37:50.225324-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00055] [jakarta.enterprise.ejb.container] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Glassfish-specific (Non-portable) JNDI names for EJB Stateful3VehicleBean: [com_sun_ts_tests_common_vehicle_Stateful3VehicleBean#com.sun.ts.tests.common.vehicle.stateful3.Stateful3VehicleIF, com_sun_ts_tests_common_vehicle_Stateful3VehicleBean]]]

[2024-10-22T11:37:50.264117-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Enabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_vehicles]]]

[2024-10-22T11:37:50.269346-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [ServiceLoader] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 900] [[
  Could not load service class org.glassfish.jersey.microprofile.restclient.RestClientExtension]]

[2024-10-22T11:37:50.302153-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Event] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  WELD-000411: Observer method [BackedAnnotatedMethod] public org.glassfish.jersey.ext.cdi1x.internal.ProcessAllAnnotatedTypes.processAnnotatedType(@Observes ProcessAnnotatedType<?>, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.]]

[2024-10-22T11:37:50.455684-04:00] [GF 8.0.0-JDK17-M7] [SEVERE] [] [org.glassfish.concurro.cdi.ConcurrentCDIExtension] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 1000] [[
  afterBeanDiscovery]]

[2024-10-22T11:37:50.586509-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-ACDEPL-00103] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  Java Web Start services started for the app client jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client.jar (contextRoot: /jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client)]]

[2024-10-22T11:37:50.819650-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.core] [tid: _ThreadID=73 _ThreadName=admin-listener(4)] [levelValue: 800] [[
  jpa_core_EntityGraph_vehicles was successfully deployed in 1,439 milliseconds.]]

[2024-10-22T11:39:01.922350-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS_ACDEPL-00104] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=71 _ThreadName=admin-listener(2)] [levelValue: 800] [[
  Java Web Start services stopped for the app client jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client.jar]]

[2024-10-22T11:39:01.929326-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=71 _ThreadName=admin-listener(2)] [levelValue: 800] [[
  Disabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_vehicles]]]

[2024-10-22T11:39:07.115626-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.116108-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.116406-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.116718-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.116999-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.117267-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.117621-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  enterprise.deployment.appclient.jws.nomf]]

[2024-10-22T11:39:07.141606-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.tools.deployment.common] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Generated client JAR /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/generated/xml/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehiclesClient.jar for possible download of size 3,361 B]]

[2024-10-22T11:39:07.160154-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.eclipse.persistence.session./file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/applications/jpa_core_EntityGraph_vehicles/lib/jpa_core_EntityGraph.jar_CTS-EM] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  EclipseLink, version: Eclipse Persistence Services - 5.0.0-B02.v202404111748]]

[2024-10-22T11:39:07.201212-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00054] [jakarta.enterprise.ejb.container] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Portable JNDI names for EJB Stateless3VehicleBean: [java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateless3_vehicle_ejb/Stateless3VehicleBean!com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateless3_vehicle_ejb/Stateless3VehicleBean]]]

[2024-10-22T11:39:07.201658-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00055] [jakarta.enterprise.ejb.container] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Glassfish-specific (Non-portable) JNDI names for EJB Stateless3VehicleBean: [com_sun_ts_tests_common_vehicle_Stateless3VehicleBean#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, com_sun_ts_tests_common_vehicle_Stateless3VehicleBean]]]

[2024-10-22T11:39:07.250060-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Enabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_vehicles]]]

[2024-10-22T11:39:07.254739-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [ServiceLoader] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 900] [[
  Could not load service class org.glassfish.jersey.microprofile.restclient.RestClientExtension]]

[2024-10-22T11:39:07.268115-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Event] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  WELD-000411: Observer method [BackedAnnotatedMethod] public org.glassfish.jersey.ext.cdi1x.internal.ProcessAllAnnotatedTypes.processAnnotatedType(@Observes ProcessAnnotatedType<?>, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.]]

[2024-10-22T11:39:07.391726-04:00] [GF 8.0.0-JDK17-M7] [SEVERE] [] [org.glassfish.concurro.cdi.ConcurrentCDIExtension] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 1000] [[
  afterBeanDiscovery]]

[2024-10-22T11:39:07.484207-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-ACDEPL-00103] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  Java Web Start services started for the app client jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client.jar (contextRoot: /jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client)]]

[2024-10-22T11:39:07.741234-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.core] [tid: _ThreadID=74 _ThreadName=admin-listener(5)] [levelValue: 800] [[
  jpa_core_EntityGraph_vehicles was successfully deployed in 1,051 milliseconds.]]

[2024-10-22T11:40:05.770213-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.logging.stdout] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
  FileMonitoring shutdown]]

[2024-10-22T11:40:05.776752-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-JMX-00002] [jakarta.enterprise.system.jmx] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
  JMXStartupService: Stopped JMXConnectorServer: null]]

[2024-10-22T11:40:05.777211-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-JMX-00001] [jakarta.enterprise.system.jmx] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
  JMXStartupService and JMXConnectors have been shut down.]]

[2024-10-22T11:40:05.785630-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS_ACDEPL-00104] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=393 _ThreadName=RunLevelControllerThread-1729611605780] [levelValue: 800] [[
  Java Web Start services stopped for the app client jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_vehicles_client.jar]]

[2024-10-22T11:40:05.791105-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=393 _ThreadName=RunLevelControllerThread-1729611605780] [levelValue: 800] [[
  Disabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_vehicles]]]

[2024-10-22T11:40:05.820899-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.logging.stdout] [tid: _ThreadID=395 _ThreadName=RunLevelControllerThread-1729611605781] [levelValue: 800] [[
  JdbcRuntimeExtension,  getAllSystemRAResourcesAndPools = [GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=__TimerPool,object-type=system-all,jndi-name=jdbc/__TimerPool), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=DerbyPool,object-type=system-all-req,jndi-name=jdbc/__default), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(datasource-classname=org.apache.derby.jdbc.EmbeddedXADataSource,name=__TimerPool,res-type=javax.sql.XADataSource), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(is-isolation-level-guaranteed=false,datasource-classname=org.apache.derby.jdbc.ClientDataSource,name=DerbyPool,res-type=javax.sql.DataSource), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(pool-resize-quantity=2,match-connections=false,connection-leak-reclaim=false,ping=false,connection-creation-retry-attempts=0,associate-with-thread=false,connection-validation-method=table,res-type=javax.sql.DataSource,statement-cache-size=0,max-wait-time-in-millis=60000,fail-all-connections=false,lazy-connection-association=false,allow-non-component-callers=false,max-connection-usage-count=0,datasource-classname=org.apache.derby.jdbc.ClientDataSource,wrap-jdbc-objects=true,is-connection-validation-required=false,is-isolation-level-guaranteed=true,validate-atmost-once-period-in-seconds=0,lazy-connection-enlistment=false,connection-creation-retry-interval-in-seconds=10,max-pool-size=64,connection-leak-timeout-in-seconds=0,pooling=true,non-transactional-connections=false,steady-pool-size=32,idle-timeout-in-seconds=300,name=cts-derby-pool,statement-timeout-in-seconds=-1), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(pool-resize-quantity=2,match-connections=false,connection-leak-reclaim=false,ping=false,connection-creation-retry-attempts=0,associate-with-thread=false,connection-validation-method=table,res-type=javax.sql.DataSource,statement-cache-size=0,max-wait-time-in-millis=60000,fail-all-connections=false,lazy-connection-association=false,allow-non-component-callers=false,max-connection-usage-count=0,datasource-classname=org.apache.derby.jdbc.ClientDataSource,wrap-jdbc-objects=true,is-connection-validation-required=false,is-isolation-level-guaranteed=true,validate-atmost-once-period-in-seconds=0,lazy-connection-enlistment=false,connection-creation-retry-interval-in-seconds=10,max-pool-size=64,connection-leak-timeout-in-seconds=0,pooling=true,non-transactional-connections=false,steady-pool-size=32,idle-timeout-in-seconds=300,name=cts-derby-pool_no_tx,statement-timeout-in-seconds=-1), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=cts-derby-pool,enabled=true,jndi-name=jdbc/DB1), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=cts-derby-pool_no_tx,enabled=true,jndi-name=jdbc/DB_no_tx)]]]

[2024-10-22T11:40:06.146433-04:00] [GF 8.0.0-JDK17-M7] [INFO] [ra.stop-successful] [jakarta.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.service] [tid: _ThreadID=398 _ThreadName=Thread-70] [levelValue: 800] [[
  RAR7094: __ds_jdbc_ra shutdown successful.]]

[2024-10-22T11:40:06.148355-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00013] [jakarta.enterprise.system.core] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
  Shutdown procedure finished]]

[2024-10-22T11:40:06.148907-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.logging.stdout] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
  Completed shutdown of GlassFish runtime]]


@scottmarlow
Copy link
Member Author

Paste of conversation from Jakarta EE TCK Slack channel https://eclipsefoundationhq.slack.com/archives/C0131MLD538/p1729612177243759:

Scott Marlow
Oct 22nd at 11:49 AM
Persistence TCK test (appclient) failure that is unclear to me as to the cause is included in #25190
41 replies

Scott Marlow
Oct 22nd at 11:50 AM
Error is: No object bound for java:comp/env/ejb/Stateless3VehicleBean STATUS:Failed.Test run in stateless3 vehicle failed. but not the cause unless I missed it.

Arjan Tijms
Oct 22nd at 12:45 PM
Is it an option to attach a debugger to discover what might be going wrong?

Scott Marlow
Oct 22nd at 12:50 PM
I guess not at the moment since I couldn't seem to when I tried last week.

Scott Marlow
Oct 22nd at 12:57 PM
Is it possible that it is caused by a ClassNotFoundException?

Arjan Tijms
Oct 23rd at 7:42 AM
I guess not at the moment since I couldn’t seem to when I tried last week.
You should normally be able to attach a debugger easily by supplying the -Dglassfish.suspend parameter on the commandline when starting the maven build.
E.g. mvn clean install -Dtest=…. -Dglassfish.suspend

Brian Decker
Oct 23rd at 9:47 AM
Coming from a position of not really knowing anything about Glassfish...
Where is it getting the thing it's NNFEing on? From a binding for ejb/Stateless3VehicleBean?
javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found
That seems like an incomplete name somehow compared to the locations where that bean can actually be found on the server:
Portable JNDI names for EJB Stateless3VehicleBean: [java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateless3_vehicle_ejb/Stateless3VehicleBean!com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateless3_vehicle_ejb/Stateless3VehicleBean]]]

Glassfish-specific (Non-portable) JNDI names for EJB Stateless3VehicleBean: [com_sun_ts_tests_common_vehicle_Stateless3VehicleBean#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, com_sun_ts_tests_common_vehicle_Stateless3VehicleBean]]]

Scott Marlow
Oct 23rd at 10:15 AM
https://github.com/scottmarlow/jakartaee-tck/blob/correct_jpa_sunappclientxml/jpa/[…]arta/tck/persistence/core/EntityGraph/ClientStateless3Test.java adds com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF.class to the app client jar and ejb jar but maybe some other jar is missed but we don't see a CNFE so who knows.
10:15
But yeah, maybe its missing something else.

Brian Decker
Oct 23rd at 10:17 AM
It's looking up java:comp/env/ejb/Stateless3VehicleBean , which would be a pointer to a name in actual JNDI. It's that mapping I'm asking about.

Scott Marlow
Oct 23rd at 10:20 AM
I added the GF server.log output to #25190 as a comment if that is helpful. The java:comp/env/ejb/Stateless3VehicleBean lookup must be done from an actual EE component which I think app client container is considered to be as JNDI java:comp/env lookups will fail if not done from component.

Brian Decker
Oct 23rd at 10:21 AM
Yeah, I saw the server log. The part where I pasted the actual JNDI names being bound into naming is from that.

Scott Marlow
Oct 23rd at 10:22 AM
I'm mostly looking for an easy way to identify the problem so we can quickly address it and the many other ones likely similar to it. We expect CNFEs + deployment descriptor issues but need better error information.
10:23
I'm not against using mvn clean install -Dtest=…. -Dglassfish.suspend if that helps us find the information quickly as well.

Brian Decker
Oct 23rd at 10:27 AM
I'm trying to make the argument that all of the information you need is already provided, but I can't be sure of that if we don't actually successfully determine the problem.
The missing piece is knowing what the app config is that connects the java:comp name to the name in JNDI.

Scott Marlow
Oct 23rd at 10:30 AM
The alternative is to spend time trying to use the EE 10 JPA appclient tests instead which do work with GlassFish 7 but I'm not sure if they work with GlassFish 8.
@Arjan Tijms
do you know if GlassFish 8 passes all of the EE 10 Platform TCK tests? I know that our TCK CI test that we talked about didn't complete due to the job failing since the check for glassfish7 fails when the folder is called glassfish8 but perhaps you have seen results elsewhere?

Scott Marlow
Friday at 10:24 AM
I didn't have a chance to debug yet but wanted to report that -Dglassfish.suspend did seem to lead to :
Starting container using command: [/usr/lib/jvm/java-17-openjdk/bin/java, -jar, /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/admin-cli.jar, start-domain, --suspend, -t]
Debugging is enabled and the server is suspended. Please attach to the debugging port at: 9,009

Arjan Tijms
Friday at 1:01 PM
Yes, that should work indeed. Good to hear it also works for you 😉

Scott Marlow
Friday at 2:38 PM
We do reach org.glassfish.ejb.startup.EjbDeployer.load()
2:39
So, where should I set a breakpoint on GF server side to get more info?

Scott Marlow
Friday at 2:46 PM
I seem to reach the server side InterceptorManager.intercept for POST_CONSTRUCT
2:47
For AppManagedVehicleBean; id: 90c02300a81f-ffffffffc4feef3b-0
2:47
now in callstack:
getEJB:223, EJBContextImpl (com.sun.ejb.containers)
2 hidden frames
aroundConstruct:60, CDIAroundConstructCallback (org.glassfish.weld.services)
7 hidden frames
_createCDIInjectionContext:249, CDIServiceImpl (org.glassfish.weld.services)
createCDIInjectionContext:179, CDIServiceImpl (org.glassfish.weld.services)
23 hidden frames
2:49
Now hitting post constructor for AppManagedVehicleBean; id: 90c02300a81f-ffffffffc4feef3b-2
2:49
^ sounds like it is reaching the server during jndi lookup of bean
2:51
java.lang.ClassNotFoundException: org.glassfish.jndi.cosnaming.CNCtxFactory not found by org.glassfish.main.common.glassfish-naming [108]
2:51
I'm just catching all exceptions to see what is hit
2:53
cnfe for com.sun.ejb.codegen.GenericEJBHome_Generated
2:54
And com.sun.ejb.codegen.GenericEJBHome_Generated again, problem a lot of different classes being searched for
2:55
org.omg.CORBA.BAD_PARAM: vmcid: 0x0 minor code: 0 completed: No
2:55
^ for IORImpl[type=RMI:com.sun.ejb.codegen.GenericEJBHome_Generated:0000000000000000 iorTemplates=[IORTemplate[oktemp=ObjectKeyTemplate[magic=afabcb00 scid=38 serverid=100 orbid=S1AS-ORB oaid=ObjectAdapterID[RootPOA/#RFMBase#/113369499788836864_RBusiness_com.sun.ts.tests.common.vehicle.appmanaged._AppManagedVehicleIF_Remote-EJBHome] profile templates:[IIOPProfileTemplateImpl[giopVersion=1.2 primary=192.168.0.35:3700]]]]
2:56
cnfe for org.glassfish.jndi.cosnaming.CNCtxFactory
2:57
another corba bad param:
IORImpl[type=RMI:com.sun.ejb.codegen.GenericEJBHome_Generated:0000000000000000 iorTemplates=[IORTemplate[oktemp=ObjectKeyTemplate[magic=afabcb00 scid=38 serverid=100 orbid=S1AS-ORB oaid=ObjectAdapterID[RootPOA/#RFMBase#/113369499788836864_RBusiness_com.sun.ts.tests.common.vehicle.appmanaged._AppManagedVehicleIF_Remote-EJBHome] profile templates:[IIOPProfileTemplateImpl[giopVersion=1.2 primary=192.168.0.35:3700]]]]
2:58
Now called into:
intercept:310, InterceptorManager (com.sun.ejb.containers.interceptors)
1 hidden frame
aroundConstruct:60, CDIAroundConstructCallback (org.glassfish.weld.services)
7 hidden frames
_createCDIInjectionContext:249, CDIServiceImpl (org.glassfish.weld.services)
createCDIInjectionContext:179, CDIServiceImpl (org.glassfish.weld.services)
23 hidden frames
for ##########################################################
InterceptorManager<com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleBean> has 1 interceptors
beanClassName: com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleBean
Interceptors:
org.jboss.weld.module.ejb.SessionBeanInterceptor
Callback Interceptors:
0: interface jakarta.interceptor.AroundConstruct
CallbackInterceptorChainImpl
1: interface jakarta.annotation.PostConstruct
CallbackInterceptorChainImpl
callback[0]: public java.lang.Object org.jboss.weld.module.ejb.SessionBeanInterceptor.aroundInvoke(jakarta.interceptor.InvocationContext) throws java.lang.Exception
2: interface jakarta.annotation.PreDestroy
CallbackInterceptorChainImpl
3: interface jakarta.ejb.PrePassivate
CallbackInterceptorChainImpl
4: interface jakarta.ejb.PostActivate
CallbackInterceptorChainImpl
##########################################################
3:00
Next is failure:
this = {com.sun.corba.ee.impl.transport.ConnectionImpl@16843} "SocketOrChannelConnectionImpl[ java.nio.channels.SocketChannel[connected local=/192.168.0.35:3700 remote=/192.168.0.35:39580] ESTABLISHED true true]"
this = {com.sun.corba.ee.impl.transport.ConnectionImpl@16843} "SocketOrChannelConnectionImpl[ java.nio.channels.SocketChannel[connected local=/192.168.0.35:3700 remote=/192.168.0.35:39580] ESTABLISHED true true]"
Exception = {java.io.IOException@16841}
backtrace = {java.lang.Object[6]@16848}
detailMessage = "End-of-stream"
cause = {java.io.IOException@16841} "java.io.IOException: End-of-stream"
stackTrace = {java.lang.StackTraceElement[5]@16851}
depth = 5
suppressedExceptions = {java.util.Collections$EmptyList@16684} size = 0
3:01
FINE: 00410011: IOException received when reading from connection SocketOrChannelConnectionImpl[ java.nio.channels.SocketChannel[connected local=/192.168.0.35:3700 remote=/192.168.0.35:39580] ESTABLISHED true true]
3:02
appclient side might not appreciate me stepping through code a line at a time, but I also wonder what the above tells us? Did we get further or the same?
3:03
app client side output for ^:
INFO: [APPCLIENT-err] SEVERE: Class [ ee/jakarta/tck/persistence/core/StoredProcedureQuery/EmployeeMappedSC ] not found. Error while loading [ class ee.jakarta.tck.persistence.core.StoredProcedureQuery.Client1 ]
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] ************************************************************
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] * props file set to "/tmp/smarlow-cts-props.txt"
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] ************************************************************
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: ####### Value of harness.socket.retry.count is "10"
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: ####### Value of harness.log.port is "2000"
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: ####### Actual bind value of harness.log.port is "2000"
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: Check if called from within test process, inTestHarness= true
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: in ServiceEETest.run(), this URL is: file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_StoredProcedureQuery_vehicles_client.jar
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: VehicleClient URL is: file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_StoredProcedureQuery_vehicles_client.jar
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: VehicleClient class check if is vehicle class = no, is not com.sun.ts.tests.common.vehicle.VehicleClient class
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: Using appclient vehicle so set is vehicle client to true
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: in ServiceEETest.run() method
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-25-2024 15:00:08: TRACE: Vehicle to be used for this test is: appmanaged
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] javax.naming.NoInitialContextException: Cannot instantiate class: foo [Root exception is java.lang.ClassNotFoundException: foo]
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.spi.NamingManager.getFactory(NamingManager.java:762)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.spi.NamingManager.lambda$getInitialContext$1(NamingManager.java:724)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/jdk.internal.loader.AbstractClassLoaderValue$Memoizer.get(AbstractClassLoaderValue.java:329)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/jdk.internal.loader.AbstractClassLoaderValue.computeIfAbsent(AbstractClassLoaderValue.java:205)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:724)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:305)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.InitialContext.init(InitialContext.java:236)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.InitialContext.(InitialContext.java:208)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at com.sun.ts.lib.util.TSNamingContext.lookup(TSNamingContext.java:68)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleRunner.run(AppManagedVehicleRunner.java:45)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:128)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:494)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:232)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at com.sun.ts.lib.harness.EETest.run(EETest.java:296)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at ee.jakarta.tck.persistence.core.StoredProcedureQuery.Client1.main(Client1.java:67)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] Caused by: java.lang.ClassNotFoundException: foo
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/java.net.URLClassLoader.findClass(URLClassLoader.java:445)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at org.glassfish.common.util.GlassfishUrlClassLoader.findClass(GlassfishUrlClassLoader.java:87)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at org.glassfish.appclient.client.acc.ACCClassLoader.findClassUnshadowed(ACCClassLoader.java:157)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at org.glassfish.appclient.client.acc.ACCClassLoader.findClass(ACCClassLoader.java:136)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:592)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at org.glassfish.common.util.GlassfishUrlClassLoader.loadClass(GlassfishUrlClassLoader.java:115)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:525)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/java.lang.Class.forName0(Native Method)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.base/java.lang.Class.forName(Class.java:467)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/com.sun.naming.internal.VersionHelper.loadClass(VersionHelper.java:128)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/com.sun.naming.internal.VersionHelper.loadClass(VersionHelper.java:134)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/com.sun.naming.internal.VersionHelper.loadClass(VersionHelper.java:97)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] at java.naming/javax.naming.spi.NamingManager.getFactory(NamingManager.java:759)
Oct 25, 2024 3:00:08 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] ... 14 more
Oct 25, 2024 3:00:09 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] Oct 25, 2024 3:00:09 PM org.glassfish.enterprise.iiop.impl.GlassFishORBManager getORB
Oct 25, 2024 3:00:09 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] INFO: ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@342ee097
3:04
INFO: [APPCLIENT-err] Caused by: java.lang.ClassNotFoundException: foo

Scott Marlow
Friday at 3:23 PM
No calls make it into EJBObjectInvocationHandler

Brian Decker
Friday at 3:40 PM
What's with the references to EJBHome you stepped through, I wonder? Is that just a misnomer on Glassfish's part? There shouldn't be anything like a Home interface in any of this stuff anymore.

Scott Marlow
Friday at 5:05 PM
I assume that was just legacy code to handle EJBHome that is still checked for. I basically set a breakpoint on every exception thrown just to see more info which led to the ioexception which I'm guessing is caused by a protocol error but hard to tell

@scottmarlow
Copy link
Member Author

scottmarlow commented Oct 31, 2024

Would be good to understand more about the java.lang.ClassNotFoundException: org.glassfish.jndi.cosnaming.CNCtxFactory not found by org.glassfish.main.common.glassfish-naming [108] error.

The glassfish8/glassfish/modules/glassfish-corba-orb.jar contains the org/glassfish/jndi/cosnaming/CNCtxFactory.class so either that is not on the classpath or another class that is needed isn't.

@scottmarlow
Copy link
Member Author

We are using a ts.jte from EE 10. If there are any dependency changes in GlassFish 8 we may be setting the classpath incorrectly in the ts.jte file.

@scottmarlow
Copy link
Member Author

scottmarlow commented Oct 31, 2024

Note that the ClassNotFoundException: org.glassfish.jndi.cosnaming.CNCtxFactory does not show up in the GlassFish server.log even though I saw that with the debugger.

In attached server.log, I see:

[2024-10-31T09:29:28.904810-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.tools.deployment.common] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
Generated client JAR /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/generated/xml/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehiclesClient.jar for possible download of size 3,369 B]]

[2024-10-31T09:29:28.953943-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.eclipse.persistence.session./file:/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/applications/jpa_core_EntityGraph_appmanaged_vehicles/lib/jpa_core_EntityGraph.jar_CTS-EM] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
EclipseLink, version: Eclipse Persistence Services - 5.0.0-B02.v202404111748]]

[2024-10-31T09:29:29.918392-04:00] [GF 8.0.0-JDK17-M7] [INFO] [jts.startup_msg] [jakarta.enterprise.system.core.transaction.com.sun.jts.CosTransactions] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
JTS5014: Recoverable JTS instance, serverId = [3700]]]

[2024-10-31T09:29:30.001590-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.enterprise.iiop.impl.GlassFishORBManager] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@5fccd288]]

[2024-10-31T09:29:30.046246-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.glassfish.ha.store.adapter.file.FileBackingStore] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
[FileBackingStore::initialize] Successfully Created and initialized store. Working dir: /home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedVehicleBean-113402273363460096; Configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='AppManagedVehicleBean-113402273363460096-BackingStore', shortUniqueName='113402273363460096', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedVehicleBean-113402273363460096', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@1a82658a}}]]

[2024-10-31T09:29:30.046442-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00043] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
StatefulContainerbuilder instantiated store: org.glassfish.ha.store.adapter.file.FileBackingStore@2410d222, with ha-enabled [false], and backing store configuration: BackingStoreConfiguration{clusterName='null', instanceName='null', storeName='AppManagedVehicleBean-113402273363460096-BackingStore', shortUniqueName='113402273363460096', storeType='file', maxIdleTimeInSeconds=-1, relaxVersionCheck='null', maxLoadWaitTimeInSeconds=0, baseDirectoryName='/home/smarlow/tck/platformtck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/session-store/AppManagedVehicleBean-113402273363460096', keyClazz=interface java.io.Serializable, valueClazz=class org.glassfish.ha.store.util.SimpleMetadata, synchronousSave=false, typicalPayloadSizeInKiloBytes=0, vendorSpecificSettings={value.class.is.thread.safe=true, async.replication=true, start.gms=false, local.caching=true, broadcast.remove.expired=false, key.transformer=com.sun.ejb.base.sfsb.util.SimpleKeyGenerator@1a82658a}}]]

[2024-10-31T09:29:30.078487-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00054] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
Portable JNDI names for EJB AppManagedVehicleBean: [java:global/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicle_ejb/AppManagedVehicleBean!com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF, java:global/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicle_ejb/AppManagedVehicleBean]]]

[2024-10-31T09:29:30.078672-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00055] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
Glassfish-specific (Non-portable) JNDI names for EJB AppManagedVehicleBean: [com_sun_ts_tests_common_vehicle_AppManagedVehicleBean#com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF, com_sun_ts_tests_common_vehicle_AppManagedVehicleBean]]]

[2024-10-31T09:29:30.088826-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Version] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
WELD-000900: 6.0.0 (Beta4)]]

[2024-10-31T09:29:30.136784-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
Enabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_appmanaged_vehicles]]]

[2024-10-31T09:29:30.156186-04:00] [GF 8.0.0-JDK17-M7] [WARNING] [] [ServiceLoader] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 900] [[
Could not load service class org.glassfish.jersey.microprofile.restclient.RestClientExtension]]

[2024-10-31T09:29:30.305586-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [org.jboss.weld.Event] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
WELD-000411: Observer method [BackedAnnotatedMethod] public org.glassfish.jersey.ext.cdi1x.internal.ProcessAllAnnotatedTypes.processAnnotatedType(@observes ProcessAnnotatedType<?>, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.]]

[2024-10-31T09:29:30.574410-04:00] [GF 8.0.0-JDK17-M7] [SEVERE] [] [org.glassfish.concurro.cdi.ConcurrentCDIExtension] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 1000] [[
afterBeanDiscovery]]

[2024-10-31T09:29:30.735481-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-ACDEPL-00103] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
Java Web Start services started for the app client jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicles_client.jar (contextRoot: /jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicles_client)]]

[2024-10-31T09:29:30.844754-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.system.core] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
jpa_core_EntityGraph_appmanaged_vehicles was successfully deployed in 3,019 milliseconds.]]

[2024-10-31T09:29:47.813579-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.logging.stdout] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
FileMonitoring shutdown]]

[2024-10-31T09:29:47.816019-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-JMX-00002] [jakarta.enterprise.system.jmx] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
JMXStartupService: Stopped JMXConnectorServer: null]]

[2024-10-31T09:29:47.816357-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-JMX-00001] [jakarta.enterprise.system.jmx] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
JMXStartupService and JMXConnectors have been shut down.]]

[2024-10-31T09:29:47.821947-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS_ACDEPL-00104] [jakarta.enterprise.system.container.appclient] [tid: _ThreadID=209 _ThreadName=RunLevelControllerThread-1730381387818] [levelValue: 800] [[
Java Web Start services stopped for the app client jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicles_client.jar]]

[2024-10-31T09:29:47.836304-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.inject.spi] [tid: _ThreadID=209 _ThreadName=RunLevelControllerThread-1730381387818] [levelValue: 800] [[
Disabling Weld for CompositeApplicationInfo[name=jpa_core_EntityGraph_appmanaged_vehicles]]]

[2024-10-31T09:29:47.849082-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.logging.stdout] [tid: _ThreadID=211 _ThreadName=RunLevelControllerThread-1730381387818] [levelValue: 800] [[
JdbcRuntimeExtension, getAllSystemRAResourcesAndPools = [GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=__TimerPool,object-type=system-all,jndi-name=jdbc/__TimerPool), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=DerbyPool,object-type=system-all-req,jndi-name=jdbc/__default), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(datasource-classname=org.apache.derby.jdbc.EmbeddedXADataSource,name=__TimerPool,res-type=javax.sql.XADataSource), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(is-isolation-level-guaranteed=false,datasource-classname=org.apache.derby.jdbc.ClientDataSource,name=DerbyPool,res-type=javax.sql.DataSource), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(pool-resize-quantity=2,match-connections=false,connection-leak-reclaim=false,ping=false,connection-creation-retry-attempts=0,associate-with-thread=false,connection-validation-method=table,res-type=javax.sql.DataSource,statement-cache-size=0,max-wait-time-in-millis=60000,fail-all-connections=false,lazy-connection-association=false,allow-non-component-callers=false,max-connection-usage-count=0,datasource-classname=org.apache.derby.jdbc.ClientDataSource,wrap-jdbc-objects=true,is-connection-validation-required=false,is-isolation-level-guaranteed=true,validate-atmost-once-period-in-seconds=0,lazy-connection-enlistment=false,connection-creation-retry-interval-in-seconds=10,max-pool-size=64,connection-leak-timeout-in-seconds=0,pooling=true,non-transactional-connections=false,steady-pool-size=32,idle-timeout-in-seconds=300,name=cts-derby-pool,statement-timeout-in-seconds=-1), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcConnectionPool(pool-resize-quantity=2,match-connections=false,connection-leak-reclaim=false,ping=false,connection-creation-retry-attempts=0,associate-with-thread=false,connection-validation-method=table,res-type=javax.sql.DataSource,statement-cache-size=0,max-wait-time-in-millis=60000,fail-all-connections=false,lazy-connection-association=false,allow-non-component-callers=false,max-connection-usage-count=0,datasource-classname=org.apache.derby.jdbc.ClientDataSource,wrap-jdbc-objects=true,is-connection-validation-required=false,is-isolation-level-guaranteed=true,validate-atmost-once-period-in-seconds=0,lazy-connection-enlistment=false,connection-creation-retry-interval-in-seconds=10,max-pool-size=64,connection-leak-timeout-in-seconds=0,pooling=true,non-transactional-connections=false,steady-pool-size=32,idle-timeout-in-seconds=300,name=cts-derby-pool_no_tx,statement-timeout-in-seconds=-1), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=cts-derby-pool,enabled=true,jndi-name=jdbc/DB1), GlassFishConfigBean.org.glassfish.jdbc.config.JdbcResource(pool-name=cts-derby-pool_no_tx,enabled=true,jndi-name=jdbc/DB_no_tx)]]]

[2024-10-31T09:29:48.143614-04:00] [GF 8.0.0-JDK17-M7] [INFO] [ra.stop-successful] [jakarta.enterprise.resource.resourceadapter.com.sun.enterprise.connectors.service] [tid: _ThreadID=214 _ThreadName=Thread-53] [levelValue: 800] [[
RAR7094: __ds_jdbc_ra shutdown successful.]]

[2024-10-31T09:29:48.144745-04:00] [GF 8.0.0-JDK17-M7] [INFO] [NCLS-CORE-00013] [jakarta.enterprise.system.core] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
Shutdown procedure finished]]

[2024-10-31T09:29:48.145012-04:00] [GF 8.0.0-JDK17-M7] [INFO] [] [jakarta.enterprise.logging.stdout] [tid: _ThreadID=24 _ThreadName=GlassFish Shutdown Hook] [levelValue: 800] [[
Completed shutdown of GlassFish runtime]]

@scottmarlow
Copy link
Member Author

A subset of output from GlassFish server.log for the test deployment:

java:global/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicle_ejb/AppManagedVehicleBean!com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF, java:global/jpa_core_EntityGraph_appmanaged_vehicles/jpa_core_EntityGraph_appmanaged_vehicle_ejb/AppManagedVehicleBean]]]

[2024-10-31T09:29:30.078672-04:00] [GF 8.0.0-JDK17-M7] [INFO] [AS-EJB-00055] [jakarta.enterprise.ejb.container] [tid: _ThreadID=72 _ThreadName=admin-listener(3)] [levelValue: 800] [[
Glassfish-specific (Non-portable) JNDI names for EJB AppManagedVehicleBean: [com_sun_ts_tests_common_vehicle_AppManagedVehicleBean#com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF, com_sun_ts_tests_common_vehicle_AppManagedVehicleBean]]]

A subset of console output (from maven/app client container):

INFO: [APPCLIENT-out] 1. AppManagedVehicleRunner jndi dump walking down tree branch name =
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 2. AppManagedVehicleRunner jndi dump (show name + classname pair): com_sun_ts_tests_common_vehicle_AppManagedVehicleBean__3_x_Internal_RemoteBusinessHome__: javax.naming.Reference
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 2. AppManagedVehicleRunner jndi dump (show name + classname pair): com_sun_ts_tests_common_vehicle_AppManagedVehicleBean: javax.naming.Reference
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 2. AppManagedVehicleRunner jndi dump (show name + classname pair): com_sun_ts_tests_common_vehicle_AppManagedVehicleBean#com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF: javax.naming.Reference
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-31-2024 09:29:37: TRACE: application-managed JTA runner looked up vehicle: null
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-31-2024 09:29:37: ERROR: Test failed.
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] 10-31-2024 09:29:37: ERROR: java.lang.NullPointerException: Cannot invoke "com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleIF.runTest(String[], java.util.Properties)" because "bean" is null
Oct 31, 2024 9:29:37 AM tck.arquillian.protocol.appclient.AppClientCmd outputLineReceived
INFO: [APPCLIENT-out] at com.sun.ts.tests.common.vehicle.appmanaged.AppManagedVehicleRunner.run(AppManagedVehicleRunner.java:52)

Note that the jndi dump is via custom code that we could merge:

diff --git a/common/src/main/java/com/sun/ts/tests/common/vehicle/appmanaged/AppManagedVehicleRunner.java b/common/src/main/java/com/sun/ts/tests/common/vehicle/appmanaged/AppManagedVehicleRunner.java
index de6c5ac80..14720b2a0 100644
--- a/common/src/main/java/com/sun/ts/tests/common/vehicle/appmanaged/AppManagedVehicleRunner.java
+++ b/common/src/main/java/com/sun/ts/tests/common/vehicle/appmanaged/AppManagedVehicleRunner.java
@@ -22,6 +22,11 @@ package com.sun.ts.tests.common.vehicle.appmanaged;
 
 import java.util.Properties;
 
+import javax.naming.InitialContext;
+import javax.naming.NameClassPair;
+import javax.naming.NamingEnumeration;
+import javax.naming.NamingException;
+
 import com.sun.ts.lib.harness.Status;
 import com.sun.ts.lib.util.TSNamingContext;
 import com.sun.ts.lib.util.TestUtil;
@@ -33,9 +38,15 @@ public class AppManagedVehicleRunner implements VehicleRunnable {
   public Status run(String[] args, Properties props) {
     Status sTestStatus = null;
     try {
-      TSNamingContext jc = new TSNamingContext();
-      AppManagedVehicleIF bean = (AppManagedVehicleIF) jc
-          .lookup(APPMANAGED_REF_NAME);
+      AppManagedVehicleIF bean=null;
+      TSNamingContext jc = new TSNamingContext(props);
+      try {
+         bean = (AppManagedVehicleIF) jc
+                .lookup(APPMANAGED_REF_NAME);
+      } catch (Exception e) {
+        e.printStackTrace();
+        dumpJndi("", new InitialContext());
+      }
       TestUtil.logTrace(
           "application-managed JTA runner looked up vehicle: " + bean);
       sTestStatus = (bean.runTest(args, props)).toStatus();
@@ -46,4 +57,24 @@ public class AppManagedVehicleRunner implements VehicleRunnable {
     }
     return sTestStatus;
   }
+
+  private void dumpJndi(String s,InitialContext jc ) {
+    try {
+            dumpTreeEntry(jc, jc.list(s), s);
+        } catch (Exception ignore) {
+        }
+    }
+  private void dumpTreeEntry(InitialContext jc, NamingEnumeration<NameClassPair> list, String s) throws NamingException {
+      System.out.println("\n1. AppManagedVehicleRunner jndi dump walking down tree branch name = " + s);
+      while (list.hasMore()) {
+          NameClassPair ncp = list.next();
+          System.out.println("2. AppManagedVehicleRunner jndi dump (show name + classname pair): " + ncp.toString());
+          if (s.length() == 0) {
+              dumpJndi(ncp.getName(), jc);
+          } else {
+              dumpJndi(s + "/" + ncp.getName(), jc);
+          }
+      }
+  }
+

@starksm64
Copy link
Member

The current main must be missing some updates as I had to make a few changes just to get to the point of seeing the naming exception. I has pushed the changes to an appclient-jndi branch of https://github.com/jakartaee/platform-tck.git.

This is the current exception output:

10-31-2024 13:27:16:  ERROR: NamingException looking up vehicle: com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF
10-31-2024 13:27:16:  ERROR: javax.naming.NamingException: Lookup failed for java:comp/env/ejb/Stateless3VehicleBean in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/Stateless3VehicleBean]
        at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
        at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
        at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
        at com.sun.ts.lib.util.TSNamingContext.lookup(TSNamingContext.java:70)
        at com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleRunner.run(Stateless3VehicleRunner.java:45)
        at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:128)
        at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:494)
        at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:232)
        at com.sun.ts.lib.harness.EETest.run(EETest.java:296)
        at ee.jakarta.tck.persistence.core.EntityGraph.Client.main(Client.java:48)
Caused by: javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/Stateless3VehicleBean
        at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:135)
        at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:818)
        ... 9 more
        Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/Stateless3VehicleBean,Remote 3.x interface =com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF,ejb-link=Stateless3VehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' .  Actual (possibly internal) Remote JNDI name used for lookup is '#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found]]
                at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:159)
                at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$EjbReferenceProxy.create(ComponentEnvManagerImpl.java:975)
                at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:621)
                at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:587)
                at com.sun.enterprise.naming.impl.JavaURLContext.lambda$lookup$0(JavaURLContext.java:153)
                at com.sun.enterprise.naming.impl.JavaURLContext.lookupOrCollectException(JavaURLContext.java:550)
                at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:153)
                ... 10 more
        Caused by: javax.naming.NamingException: Lookup failed for #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found]
                at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
                at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
                at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
                at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:154)
                ... 16 more
        Caused by: javax.naming.NameNotFoundException: #com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF not found
                at com.sun.enterprise.naming.impl.TransientContext.doLookup(TransientContext.java:234)
                at com.sun.enterprise.naming.impl.TransientContext.lookup(TransientContext.java:202)
                at com.sun.enterprise.naming.impl.SerialContextProviderImpl.lookup(SerialContextProviderImpl.java:37)
                at com.sun.enterprise.naming.impl.RemoteSerialContextProviderImpl.lookup(RemoteSerialContextProviderImpl.java:85)
                at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
                at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
                at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
                at java.base/java.lang.reflect.Method.invoke(Method.java:568)
                at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:122)
                at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:152)
                at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatchToServant(ServerRequestDispatcherImpl.java:501)
                at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatch(ServerRequestDispatcherImpl.java:172)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequestRequest(MessageMediatorImpl.java:1529)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:1405)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleInput(MessageMediatorImpl.java:910)
                at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:192)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:674)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.dispatch(MessageMediatorImpl.java:476)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.doWork(MessageMediatorImpl.java:2202)
                at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:476)
                at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:519)

[2024-10-31 13:27:16] F org.glassfish.naming getInitialContext(env={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming:com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl:com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}) 
10-31-2024 13:27:16:  ERROR: TSNamingContext.CS: (file:/Users/starksm/Dev/Jakarta/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/arquillian-protocol-lib.jar <no signer certificates>)

1. AppManagedVehicleRunner jndi dump walking down tree branch name = 
2. AppManagedVehicleRunner jndi dump (show name  classname pair): com_sun_ts_tests_common_vehicle_Stateless3VehicleBean#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF: javax.naming.Reference
[2024-10-31 13:27:16] F org.glassfish.naming getObjectInstance(name=com_sun_ts_tests_common_vehicle_Stateless3VehicleBean#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, obj=Reference Class Name: com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF
Type: url
Content: com_sun_ts_tests_common_vehicle_Stateless3VehicleBean__3_x_Internal_RemoteBusinessHome__
) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ejb.containers.RemoteBusinessObjectFactory, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.naming getInitialContext(env={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming:com.sun.enterprise.naming:com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl:com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl:com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ejb.codegen.GenericEJBHome_Generated, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.naming getObjectInstance(name=com_sun_ts_tests_common_vehicle_Stateless3VehicleBean__3_x_Internal_RemoteBusinessHome__, obj=IORImpl[type=RMI:com.sun.ejb.codegen.GenericEJBHome_Generated:0000000000000000 iorTemplates=[IORTemplate[oktemp=ObjectKeyTemplate[magic=afabcb00 scid=38 serverid=100 orbid=S1AS-ORB oaid=ObjectAdapterID[RootPOA/#RFMBase#/113403680031309824_RBusiness_com.sun.ts.tests.common.vehicle.stateless3._Stateless3VehicleIF_Remote-EJBHome] profile templates:[IIOPProfileTemplateImpl[giopVersion=1.2 primary=127.0.0.1:3700]]]]) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ejb.codegen.GenericEJBHome_Generated, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ejb.codegen.GenericEJBHome_Generated, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ejb.containers.GenericEJBHome, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=java.lang.Object, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=java.lang.String, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=java.rmi.Remote, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=java.rmi.RemoteException, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=javax.rmi.CORBA.Stub, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=org.omg.CORBA_2_3.portable.ObjectImpl, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=org.omg.CORBA.portable.ObjectImpl, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=java.lang.Long, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.corba.ee.impl.presentation.rmi.codegen.CodegenStubBase, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ts.tests.common.vehicle.stateless3._Stateless3VehicleIF_Wrapper, resolve=false) 
2. AppManagedVehicleRunner jndi dump (show name  classname pair): com_sun_ts_tests_common_vehicle_Stateless3VehicleBean: javax.naming.Reference
[2024-10-31 13:27:16] F org.glassfish.naming getObjectInstance(name=com_sun_ts_tests_common_vehicle_Stateless3VehicleBean, obj=Reference Class Name: com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF
Type: url
Content: com_sun_ts_tests_common_vehicle_Stateless3VehicleBean__3_x_Internal_RemoteBusinessHome__
) 
[2024-10-31 13:27:16] F org.glassfish.naming getInitialContext(env={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming:com.sun.enterprise.naming:com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl:com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl:com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}) 
[2024-10-31 13:27:16] F org.glassfish.naming getObjectInstance(name=com_sun_ts_tests_common_vehicle_Stateless3VehicleBean__3_x_Internal_RemoteBusinessHome__, obj=IORImpl[type=RMI:com.sun.ejb.codegen.GenericEJBHome_Generated:0000000000000000 iorTemplates=[IORTemplate[oktemp=ObjectKeyTemplate[magic=afabcb00 scid=38 serverid=100 orbid=S1AS-ORB oaid=ObjectAdapterID[RootPOA/#RFMBase#/113403680031309824_RBusiness_com.sun.ts.tests.common.vehicle.stateless3._Stateless3VehicleIF_Remote-EJBHome] profile templates:[IIOPProfileTemplateImpl[giopVersion=1.2 primary=127.0.0.1:3700]]]]) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ejb.codegen.GenericEJBHome_Generated, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF, resolve=false) 
[2024-10-31 13:27:16] F org.glassfish.common.util.GlassfishUrlClassLoader loadClass(name=com.sun.ts.tests.common.vehicle.stateless3._Stateless3VehicleIF_Wrapper, resolve=false) 
2. AppManagedVehicleRunner jndi dump (show name  classname pair): com_sun_ts_tests_common_vehicle_Stateless3VehicleBean__3_x_Internal_RemoteBusinessHome__: javax.naming.Reference
[2024-10-31 13:27:16] F org.glassfish.naming getObjectInstance(name=com_sun_ts_tests_common_vehicle_Stateless3VehicleBean__3_x_Internal_RemoteBusinessHome__, obj=IORImpl[type=RMI:com.sun.ejb.codegen.GenericEJBHome_Generated:0000000000000000 iorTemplates=[IORTemplate[oktemp=ObjectKeyTemplate[magic=afabcb00 scid=38 serverid=100 orbid=S1AS-ORB oaid=ObjectAdapterID[RootPOA/#RFMBase#/113403680031309824_RBusiness_com.sun.ts.tests.common.vehicle.stateless3._Stateless3VehicleIF_Remote-EJBHome] profile templates:[IIOPProfileTemplateImpl[giopVersion=1.2 primary=127.0.0.1:3700]]]]) 
10-31-2024 13:27:16:  ERROR: Test failed.

It looks like the appclient env is being linked to the Stateless3VehicleBean, but the resolution of the bound object factory for the ejb-ref somehow ends up trying to lookup "#com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF", which is not a correct server side binding.

The application-client.xml in the test artifact is:

<application-client xmlns="https://jakarta.ee/xml/ns/jakartaee" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" version="10" xsi:schemaLocation="https://jakarta.ee/xml/ns/jakartaee https://jakarta.ee/xml/ns/jakartaee/application-client_10.xsd">
  <ejb-ref>
    <ejb-ref-name>ejb/Stateless3VehicleBean</ejb-ref-name>
    <ejb-ref-type>Session</ejb-ref-type>
    <remote>com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF</remote>
    <ejb-link>Stateless3VehicleBean</ejb-link>
  </ejb-ref>
</application-client>

and the sun-ejb-jar.xml file (which the glassfish docs says it supports) is:

<sun-ejb-jar>
    <enterprise-beans>
    <ejb>
      <ejb-name>Stateless3VehicleBean</ejb-name>
      <jndi-name>com_sun_ts_tests_common_vehicle_Stateless3VehicleBean</jndi-name>
    </ejb>
    </enterprise-beans>
</sun-ejb-jar>

If I can the ejb-name in this file, the deployment fails with an exception of:

Caused by: org.omnifaces.arquillian.jersey.server.ContainerException: While Deploying Application: jpa_core_EntityGraph_vehicles --exit_code: FAILURE, message: Error occurred during deployment: Exception while deploying the app [jpa_core_EntityGraph_vehicles] : Referencing error: this bundle has no bean of name: ejb/Stateless3VehicleBean. Please see server.log for more details. [status: CLIENT_ERROR reason: Bad Request]

so the current test artifact deployment that succeeds is finding a match for the appclient ejb-link. The bound object factory is just failing to resolve the remote lookup at runtime for some reason.

I'll double check the EE 10 deployment artifact to see if there is some other descriptor missing.

@starksm64
Copy link
Member

So the ee10 dist has essentially the same application-client.xml:

<application-client xmlns="https://jakarta.ee/xml/ns/jakartaee" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" version="10" xsi:schemaLocation="https://jakarta.ee/xml/ns/jakartaee https://jakarta.ee/xml/ns/jakartaee/application-client_10.xsd">
  <display-name>jpa_core_EntityGraph_stateless3_vehicle_client</display-name>
  <ejb-ref>
    <ejb-ref-name>ejb/Stateless3VehicleBean</ejb-ref-name>
    <ejb-ref-type>Session</ejb-ref-type>
    <remote>com.sun.ts.tests.common.vehicle.stateless3.Stateless3VehicleIF</remote>
    <ejb-link>Stateless3VehicleBean</ejb-link>
  </ejb-ref>
</application-client>

and a sun-ejb-jar.xml file of:

<sun-ejb-jar>
    <enterprise-beans>
    <ejb>
      <ejb-name>Stateless3VehicleBean</ejb-name>
      <jndi-name>jpa_core_EntityGraph_stateless3_vehicle_Stateless3VehicleBean</jndi-name>
    </ejb>
    </enterprise-beans>
</sun-ejb-jar>

So the jndi-name is different, but still not one that matches the server.log jndi entries. The server side jndi name really should not matter since this is being matched via the ejb-link from the client, but there is something wrong with the resulting binding.

I'll see if I can find where the client side jndi environment is created on the server when resolving the ejb-link.

@starksm64
Copy link
Member

@arjantijms @dmatej

I have created a simple reproducer that shows the problem. The appclient has an entry in the java:comp/env/ejb/StatelessVehicleBean location, but the EjbReferenceProxy value bound there ends up attempting to lookup an invalid jndi name. To run the reproducer:

Clone the https://github.com/jakartaee/platform-tck.git
cd platform-tck
git checkout appclient-jndi
cd glassfish-runner/jpa-platform-tck
mvn -Darquillian.xml=test-arquillian.xml test

This produces a verbose appclient container log at glassfish-runner/jpa-platform-tck/target/appclient-0.log

Exception in thread "main" javax.naming.NamingException: Lookup failed for java:comp/env/ejb/StatelessVehicleBean in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/StatelessVehicleBean]
        at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
        at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
        at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
        at client.RemoteEjbClient.main(RemoteEjbClient.java:10)
Caused by: javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/StatelessVehicleBean
        at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:135)
        at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:818)
        ... 3 more
        Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/StatelessVehicleBean,Remote 3.x interface =ejb.VehicleIF,ejb-link=StatelessVehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' .  Actual (possibly internal) Remote JNDI name used for lookup is '#ejb.VehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #ejb.VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #ejb.VehicleIF not found]]
                at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:159)
                at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$EjbReferenceProxy.create(ComponentEnvManagerImpl.java:975)
                at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:621)
                at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:587)
                at com.sun.enterprise.naming.impl.JavaURLContext.lambda$lookup$0(JavaURLContext.java:153)
                at com.sun.enterprise.naming.impl.JavaURLContext.lookupOrCollectException(JavaURLContext.java:550)
                at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:153)
                ... 4 more
        Caused by: javax.naming.NamingException: Lookup failed for #ejb.VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #ejb.VehicleIF not found]
                at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
                at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
                at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
                at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:154)
                ... 10 more
        Caused by: javax.naming.NameNotFoundException: #ejb.VehicleIF not found
                at com.sun.enterprise.naming.impl.TransientContext.doLookup(TransientContext.java:234)
                at com.sun.enterprise.naming.impl.TransientContext.lookup(TransientContext.java:202)
                at com.sun.enterprise.naming.impl.SerialContextProviderImpl.lookup(SerialContextProviderImpl.java:37)
                at com.sun.enterprise.naming.impl.RemoteSerialContextProviderImpl.lookup(RemoteSerialContextProviderImpl.java:85)
                at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
                at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
                at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
                at java.base/java.lang.reflect.Method.invoke(Method.java:568)
                at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:122)
                at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:152)
                at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatchToServant(ServerRequestDispatcherImpl.java:501)
                at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatch(ServerRequestDispatcherImpl.java:172)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequestRequest(MessageMediatorImpl.java:1529)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:1405)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleInput(MessageMediatorImpl.java:910)
                at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:192)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:674)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.dispatch(MessageMediatorImpl.java:476)
                at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.doWork(MessageMediatorImpl.java:2202)
                at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:476)
                at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:519)

@dmatej
Copy link
Contributor

dmatej commented Nov 6, 2024

git checkout appclient-jndi

I don't see such branch, but I can see your branch correct_jpa_sunappclientxml, is it the right one?

Expectation: having built glassfish 8.0.0-SNAPSHOT and having the clone of the jakartaee-tck repo.
What I did:

git checkout correct_jpa_sunappclientxml 
mvn clean install
mvn clean install -f glassfish-runner/jpa-platform-tck -Darquillian.xml=test-arquillian.xml -Dglassfish.container.version=8.0.0-SNAPSHOT

... and the same result with the default glassfish version 8.0.0-JDK17-M7

....
[INFO] --- antrun:3.0.0:run (005-initdb) @ glassfish.jpa-platform-tck ---
[INFO] Executing tasks
[INFO]       [sql] Executing resource: /home/dmatej/work/repo/git/jakartaee-tck/glassfish-runner/jpa-platform-tck/sql/derby/derby.ddl.sql
[ERROR]       [sql] Failed to execute:  ALTER TABLE PHONE_TABLE DROP CONSTRAINT FK_FOR_ADDRESS 
[ERROR]       [sql] java.sql.SQLSyntaxErrorException: Schema 'CTS1' does not exist
....
[INFO] Running ee.jakarta.tck.persistence.core.EntityGraph.ClientAppmanagedTest
[ERROR] Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.069 s <<< FAILURE! -- in ee.jakarta.tck.persistence.core.EntityGraph.ClientAppmanagedTest
[ERROR] ee.jakarta.tck.persistence.core.EntityGraph.ClientAppmanagedTest -- Time elapsed: 0.069 s <<< ERROR!
java.lang.RuntimeException: Could not create new instance of class org.jboss.arquillian.test.impl.EventTestRunnerAdaptor
        at org.jboss.arquillian.test.spi.SecurityActions.newInstance(SecurityActions.java:146)
        at org.jboss.arquillian.test.spi.SecurityActions.newInstance(SecurityActions.java:89)
        at org.jboss.arquillian.test.spi.TestRunnerAdaptorBuilder.build(TestRunnerAdaptorBuilder.java:49)
        at org.jboss.arquillian.junit5.JUnitJupiterTestClassLifecycleManager.initializeAdaptor(JUnitJupiterTestClassLifecycleManager.java:38)
        at org.jboss.arquillian.junit5.JUnitJupiterTestClassLifecycleManager.getManager(JUnitJupiterTestClassLifecycleManager.java:25)
        at org.jboss.arquillian.junit5.ArquillianExtension.beforeAll(ArquillianExtension.java:35)
        at java.base/java.util.ArrayList.forEach(ArrayList.java:1596)
        Suppressed: java.lang.RuntimeException: Arquillian initialization has already been attempted, but failed. See previous exceptions for cause
                at org.jboss.arquillian.junit5.JUnitJupiterTestClassLifecycleManager.handleSuiteLevelFailure(JUnitJupiterTestClassLifecycleManager.java:62)
                at org.jboss.arquillian.junit5.JUnitJupiterTestClassLifecycleManager.getManager(JUnitJupiterTestClassLifecycleManager.java:30)
                at org.jboss.arquillian.junit5.ArquillianExtension.afterAll(ArquillianExtension.java:42)
                ... 1 more
        Caused by: [CIRCULAR REFERENCE: java.lang.RuntimeException: Could not create new instance of class org.jboss.arquillian.test.impl.EventTestRunnerAdaptor]
Caused by: java.lang.reflect.InvocationTargetException
        at java.base/java.lang.reflect.Constructor.newInstanceWithCaller(Constructor.java:502)
        at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:486)
        at org.jboss.arquillian.test.spi.SecurityActions.newInstance(SecurityActions.java:144)
        ... 6 more
Caused by: java.lang.IllegalArgumentException: Could not find configured filename as either classpath resource nor file resource: test-arquillian.xml
        at org.jboss.arquillian.config.impl.extension.FileUtils.loadResource(FileUtils.java:63)
        at org.jboss.arquillian.config.impl.extension.FileUtils.loadArquillianXml(FileUtils.java:51)
        at org.jboss.arquillian.config.impl.extension.ConfigurationRegistrar.loadConfiguration(ConfigurationRegistrar.java:75)
        at org.jboss.arquillian.config.impl.extension.ConfigurationRegistrar.loadConfiguration(ConfigurationRegistrar.java:62)
        at java.base/java.lang.reflect.Method.invoke(Method.java:580)
        at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
        at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:103)
        at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:90)
        at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:134)
        at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:106)
        at org.jboss.arquillian.core.impl.ManagerImpl.start(ManagerImpl.java:254)
        at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.<init>(EventTestRunnerAdaptor.java:61)
        ... 9 more

@starksm64
Copy link
Member

The appclient-jndi branch is in the main platform-tck repo. I just tried a clean checkout and test run:

starksm@Scotts-Mac-Studio /tmp % rm -rf platform-tck 
starksm@Scotts-Mac-Studio /tmp % git clone https://github.com/jakartaee/platform-tck.git
Cloning into 'platform-tck'...
remote: Enumerating objects: 190159, done.
remote: Counting objects: 100% (20725/20725), done.
remote: Compressing objects: 100% (3951/3951), done.
remote: Total 190159 (delta 15677), reused 19916 (delta 15194), pack-reused 169434 (from 1)
Receiving objects: 100% (190159/190159), 103.20 MiB | 2.53 MiB/s, done.
Resolving deltas: 100% (139978/139978), done.
Updating files: 100% (22544/22544), done.
starksm@Scotts-Mac-Studio /tmp % cd platform-tck 
starksm@Scotts-Mac-Studio platform-tck % git checkout appclient-jndi
Branch 'appclient-jndi' set up to track remote branch 'appclient-jndi' from 'origin'.
Switched to a new branch 'appclient-jndi'
starksm@Scotts-Mac-Studio platform-tck %  mvn -Dmaven.build.cache.enabled=false test -f glassfish-runner/jpa-platform-tck -Darquillian.xml=test-arquillian.xml -Dglassfish.container.version=8.0.0-SNAPSHOT
[INFO] Cache disabled by command line flag, project will be built fully and not cached
[INFO] Scanning for projects...
[WARNING] 
[WARNING] Some problems were encountered while building the effective model for jakarta:glassfish.jpa-platform-tck:jar:11.0.0-SNAPSHOT
[WARNING] 'build.plugins.plugin.(groupId:artifactId)' must be unique but found duplicate declaration of plugin org.apache.maven.plugins:maven-dependency-plugin @ line 366, column 21
[WARNING] 'build.plugins.plugin.(groupId:artifactId)' must be unique but found duplicate declaration of plugin org.apache.maven.plugins:maven-antrun-plugin @ line 492, column 21
[WARNING] 
[WARNING] It is highly recommended to fix these problems because they threaten the stability of your build.
[WARNING] 
[WARNING] For this reason, future Maven versions might no longer support building such malformed projects.
[WARNING] 
[INFO] 
[INFO] -----------------< jakarta:glassfish.jpa-platform-tck >-----------------
[INFO] Building glassfish.jpa-platform-tck 11.0.0-SNAPSHOT
[INFO]   from pom.xml
[INFO] --------------------------------[ jar ]---------------------------------
[INFO] 
[INFO] --- enforcer:3.0.0:enforce (enforce-maven) @ glassfish.jpa-platform-tck ---
[INFO] 
[INFO] --- spotless:2.36.0:check (check-spotless-poms) @ glassfish.jpa-platform-tck ---
[INFO] Index file does not exist. Fallback to an empty index
[INFO] Sorting file /var/folders/yh/t0fnl559607crz2vyp509bjr0000gn/T/pom16316726856461708360.xml
[INFO] Pom file is already sorted, exiting
[INFO] Sorting file /var/folders/yh/t0fnl559607crz2vyp509bjr0000gn/T/pom1542785978384178646.xml
[INFO] Pom file is already sorted, exiting
[INFO] Spotless.Pom is keeping 1 files clean - 0 needs changes to be clean, 1 were already clean, 0 were skipped because caching determined they were already clean
[INFO] 
[INFO] --- dependency:3.7.1:unpack (001-unpack) @ glassfish.jpa-platform-tck ---
[INFO] Configured Artifact: org.glassfish.main.distributions:glassfish:8.0.0-SNAPSHOT:zip
[INFO] 
[INFO] --- dependency:3.7.1:copy (003-copy-protocol-lib) @ glassfish.jpa-platform-tck ---
[INFO] Configured Artifact: jakarta.tck.arquillian:arquillian-protocol-lib:?:jar
[INFO] Copying artifact 'jakarta.tck.arquillian:arquillian-protocol-lib:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/arquillian/arquillian-protocol-lib/11.0.0-SNAPSHOT/arquillian-protocol-lib-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/protocol/protocol.jar
[INFO] 
[INFO] --- dependency:3.7.1:copy (004-copy-lib) @ glassfish.jpa-platform-tck ---
[INFO] Configured Artifact: jakarta.tck:libutil:?:jar
[INFO] Configured Artifact: jakarta.tck:common:?:jar
[INFO] Configured Artifact: jakarta.tck.arquillian:tck-porting-lib:?:jar
[INFO] Configured Artifact: jakarta.tck:runtime:?:jar
[INFO] Configured Artifact: jakarta.tck:persistence-platform-tck-tests:?:jar
[INFO] Configured Artifact: org.apache.derby:derbyclient:?:jar
[INFO] Configured Artifact: org.apache.derby:derbytools:?:jar
[INFO] Configured Artifact: jakarta.tck.arquillian:arquillian-protocol-lib:?:jar
[INFO] Copying artifact 'jakarta.tck:libutil:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/libutil/11.0.0-SNAPSHOT/libutil-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/libutil.jar
[INFO] Copying artifact 'jakarta.tck:common:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/common/11.0.0-SNAPSHOT/common-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/common.jar
[INFO] Copying artifact 'jakarta.tck.arquillian:tck-porting-lib:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/arquillian/tck-porting-lib/11.0.0-SNAPSHOT/tck-porting-lib-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/tck-porting-lib.jar
[INFO] Copying artifact 'jakarta.tck:runtime:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/runtime/11.0.0-SNAPSHOT/runtime-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/runtime.jar
[INFO] Copying artifact 'jakarta.tck:persistence-platform-tck-tests:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/persistence-platform-tck-tests/11.0.0-SNAPSHOT/persistence-platform-tck-tests-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/persistence-platform-tck-tests.jar
[INFO] Copying artifact 'org.apache.derby:derbyclient:jar:10.15.2.0' (/Users/starksm/.m2/repository/org/apache/derby/derbyclient/10.15.2.0/derbyclient-10.15.2.0.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/lib/derbyclient.jar
[INFO] Copying artifact 'org.apache.derby:derbytools:jar:10.15.2.0' (/Users/starksm/.m2/repository/org/apache/derby/derbytools/10.15.2.0/derbytools-10.15.2.0.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/lib/derbytools.jar
[INFO] Copying artifact 'jakarta.tck.arquillian:arquillian-protocol-lib:jar:11.0.0-SNAPSHOT' (/Users/starksm/.m2/repository/jakarta/tck/arquillian/arquillian-protocol-lib/11.0.0-SNAPSHOT/arquillian-protocol-lib-11.0.0-SNAPSHOT.jar) to /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/lib/arquillian-protocol-lib.jar
[INFO] 
[INFO] --- antrun:3.0.0:run (002-asadmin-permission) @ glassfish.jpa-platform-tck ---
[INFO] Executing tasks
[INFO] Executed tasks
[INFO] 
[INFO] --- resources:3.3.1:resources (default-resources) @ glassfish.jpa-platform-tck ---
[INFO] Copying 1 resource from src/main/resources to target/classes
[INFO] 
[INFO] --- exec:3.2.0:exec (004-StartDatabase) @ glassfish.jpa-platform-tck ---
Database already started on host 0.0.0.0, port 1527.
Command start-database executed successfully.
[INFO] 
[INFO] --- compiler:3.12.1:compile (default-compile) @ glassfish.jpa-platform-tck ---
[INFO] Recompiling the module because of changed source code.
[INFO] Compiling 2 source files with javac [debug release 17] to target/classes
[INFO] /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/src/main/java/org/glassfish/transactions/core/tck/GlassfishTestArchiveProcessor.java: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/src/main/java/org/glassfish/transactions/core/tck/GlassfishTestArchiveProcessor.java uses or overrides a deprecated API.
[INFO] /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/src/main/java/org/glassfish/transactions/core/tck/GlassfishTestArchiveProcessor.java: Recompile with -Xlint:deprecation for details.
[INFO] 
[INFO] --- resources:3.3.1:testResources (default-testResources) @ glassfish.jpa-platform-tck ---
[INFO] Copying 5 resources from src/test/resources to target/test-classes
[INFO] 
[INFO] --- compiler:3.12.1:testCompile (default-testCompile) @ glassfish.jpa-platform-tck ---
[INFO] Recompiling the module because of changed dependency.
[INFO] Compiling 4 source files with javac [debug release 17] to target/test-classes
[INFO] 
[INFO] --- surefire:3.0.0:test (default-test) @ glassfish.jpa-platform-tck ---
[INFO] Using auto detected provider org.apache.maven.surefire.junitplatform.JUnitPlatformProvider
[INFO] 
[INFO] -------------------------------------------------------
[INFO]  T E S T S
[INFO] -------------------------------------------------------
[INFO] Running client.AppClientTest
Created appclient directory: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient
Exported test ear to: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles.ear
Exported test ear content to: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_stateless_vehicle_ejb.jar
Exported test ear content to: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar
Starting container using command: [/Library/Java/JavaVirtualMachines/temurin-17.jdk/Contents/Home/bin/java, -jar, /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/admin-cli.jar, start-domain, -t]
Waiting finished after 1,740 ms.
Successfully started the domain : domain1
domain  Location: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1
Log File: /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/domains/domain1/logs/server.log
Admin Port: 4,848
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd run
INFO: Created process[user: Optional[starksm], cmd: /bin/sh, args: [target/glassfish8/glassfish/bin/appclient, -Djava.util.logging.config.file=jakartaeetck/logging.properties, -jar, /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar], startTime: Optional[2024-11-06T18:48:06.134Z]]
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd run
INFO: process(11659).envp: [AS_JAVA=/Library/Java/JavaVirtualMachines/temurin-17.jdk/Contents/Home, AS_DEBUG=true]
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd readClientProcess
INFO: Begin readClientProcess
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd readClientProcess
INFO: Begin readClientProcess
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd run
INFO: Started process reader threads
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] acc._AS_INSTALL set to target/glassfish8/glassfish/bin/..
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] VMARGS = null
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] "/Library/Java/JavaVirtualMachines/temurin-17.jdk/Contents/Home/bin/java" -Dorg.glassfish.gmbal.no.multipleUpperBoundsException=true --add-opens=java.base/java.lang=ALL-UNNAMED -Dcom.sun.aas.installRoot="/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/.." -Djava.security.policy="/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/appclient/client.policy" -classpath /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar:. -Djava.system.class.loader=org.glassfish.appclient.client.acc.agent.ACCAgentClassLoader -Xshare:off -Djava.security.auth.login.config="/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/appclient/appclientlogin.conf"  "-Djava.util.logging.config.file=jakartaeetck/logging.properties"  -javaagent:"/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar"=argsfile="/var/folders/yh/t0fnl559607crz2vyp509bjr0000gn/T/acc484379618692860999.dat"  -classpath  /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar:/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar  client.RemoteEjbClient 
Nov 06, 2024 12:48:06 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] [2024-11-06 12:48:06] I jakarta.enterprise.resource.jta.com.sun.enterprise.transaction DTX5019: Transaction Manager is ready. Using [com.sun.enterprise.transaction.jts.JavaEETransactionManagerJTSDelegate] as the delegate 
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] [2024-11-06 12:48:07] I org.glassfish.enterprise.iiop.impl.GlassFishORBManager ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@4f3356c0 
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] Exception in thread "main" javax.naming.NamingException: Lookup failed for java:comp/env/ejb/StatelessVehicleBean in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/StatelessVehicleBean]
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	at client.RemoteEjbClient.main(RemoteEjbClient.java:13)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] Caused by: javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/StatelessVehicleBean
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:135)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:818)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	... 3 more
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/StatelessVehicleBean,Remote 3.x interface =ejb.VehicleIF,ejb-link=StatelessVehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' .  Actual (possibly internal) Remote JNDI name used for lookup is '#ejb.VehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #ejb.VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #ejb.VehicleIF not found]]
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:159)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$EjbReferenceProxy.create(ComponentEnvManagerImpl.java:975)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:621)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:587)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.JavaURLContext.lambda$lookup$0(JavaURLContext.java:153)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.JavaURLContext.lookupOrCollectException(JavaURLContext.java:550)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:153)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		... 4 more
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	Caused by: javax.naming.NamingException: Lookup failed for #ejb.VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #ejb.VehicleIF not found]
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:154)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		... 10 more
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 	Caused by: javax.naming.NameNotFoundException: #ejb.VehicleIF not found
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.TransientContext.doLookup(TransientContext.java:235)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.TransientContext.lookup(TransientContext.java:203)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.SerialContextProviderImpl.lookup(SerialContextProviderImpl.java:38)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.enterprise.naming.impl.RemoteSerialContextProviderImpl.lookup(RemoteSerialContextProviderImpl.java:92)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at java.base/java.lang.reflect.Method.invoke(Method.java:568)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:122)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:152)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatchToServant(ServerRequestDispatcherImpl.java:501)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatch(ServerRequestDispatcherImpl.java:172)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequestRequest(MessageMediatorImpl.java:1529)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:1405)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleInput(MessageMediatorImpl.java:910)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:192)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:674)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.dispatch(MessageMediatorImpl.java:476)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.doWork(MessageMediatorImpl.java:2202)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:476)
Nov 06, 2024 12:48:07 PM tck.arquillian.protocol.appclient.AppClientCmd errorLineReceived
INFO: [APPCLIENT-err] 		at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:519)
Nov 06, 2024 12:48:08 PM tck.arquillian.protocol.appclient.AppClientCmd readClientProcess
INFO: Exiting(false), read 0 lines
Nov 06, 2024 12:48:08 PM tck.arquillian.protocol.appclient.AppClientCmd readClientProcess
INFO: Exiting(true), read 51 lines
acc._AS_INSTALL set to target/glassfish8/glassfish/bin/..
VMARGS = null
"/Library/Java/JavaVirtualMachines/temurin-17.jdk/Contents/Home/bin/java" -Dorg.glassfish.gmbal.no.multipleUpperBoundsException=true --add-opens=java.base/java.lang=ALL-UNNAMED -Dcom.sun.aas.installRoot="/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/.." -Djava.security.policy="/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/appclient/client.policy" -classpath /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar:. -Djava.system.class.loader=org.glassfish.appclient.client.acc.agent.ACCAgentClassLoader -Xshare:off -Djava.security.auth.login.config="/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/appclient/appclientlogin.conf"  "-Djava.util.logging.config.file=jakartaeetck/logging.properties"  -javaagent:"/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar"=argsfile="/var/folders/yh/t0fnl559607crz2vyp509bjr0000gn/T/acc484379618692860999.dat"  -classpath  /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/bin/../lib/gf-client.jar:/private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/appclient/jpa_core_EntityGraph_vehicles_client.jar  client.RemoteEjbClient 
[2024-11-06 12:48:06] I jakarta.enterprise.resource.jta.com.sun.enterprise.transaction DTX5019: Transaction Manager is ready. Using [com.sun.enterprise.transaction.jts.JavaEETransactionManagerJTSDelegate] as the delegate 
[2024-11-06 12:48:07] I org.glassfish.enterprise.iiop.impl.GlassFishORBManager ORB initialization succeeded: com.sun.corba.ee.impl.orb.ORBImpl@4f3356c0 
Exception in thread "main" javax.naming.NamingException: Lookup failed for java:comp/env/ejb/StatelessVehicleBean in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/StatelessVehicleBean]
	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
	at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
	at client.RemoteEjbClient.main(RemoteEjbClient.java:13)
Caused by: javax.naming.NameNotFoundException: No object bound for java:comp/env/ejb/StatelessVehicleBean
	at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:135)
	at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:818)
	... 3 more
	Suppressed: javax.naming.NamingException: Exception resolving Ejb for 'Remote ejb-ref name=ejb/StatelessVehicleBean,Remote 3.x interface =ejb.VehicleIF,ejb-link=StatelessVehicleBean,lookup=,mappedName=,jndi-name=,refType=Session' .  Actual (possibly internal) Remote JNDI name used for lookup is '#ejb.VehicleIF' [Root exception is javax.naming.NamingException: Lookup failed for #ejb.VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #ejb.VehicleIF not found]]
		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:159)
		at com.sun.enterprise.container.common.impl.ComponentEnvManagerImpl$EjbReferenceProxy.create(ComponentEnvManagerImpl.java:975)
		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:621)
		at com.sun.enterprise.naming.impl.GlassfishNamingManagerImpl.lookup(GlassfishNamingManagerImpl.java:587)
		at com.sun.enterprise.naming.impl.JavaURLContext.lambda$lookup$0(JavaURLContext.java:153)
		at com.sun.enterprise.naming.impl.JavaURLContext.lookupOrCollectException(JavaURLContext.java:550)
		at com.sun.enterprise.naming.impl.JavaURLContext.lookup(JavaURLContext.java:153)
		... 4 more
	Caused by: javax.naming.NamingException: Lookup failed for #ejb.VehicleIF in SerialContext[myEnv={java.naming.factory.initial=com.sun.enterprise.naming.impl.SerialInitContextFactory, java.naming.factory.url.pkgs=com.sun.enterprise.naming, java.naming.factory.state=com.sun.corba.ee.impl.presentation.rmi.JNDIStateFactoryImpl}] [Root exception is javax.naming.NameNotFoundException: #ejb.VehicleIF not found]
		at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:836)
		at com.sun.enterprise.naming.impl.SerialContext.lookup(SerialContext.java:257)
		at java.naming/javax.naming.InitialContext.lookup(InitialContext.java:409)
		at com.sun.ejb.EjbNamingReferenceManagerImpl.resolveEjbReference(EjbNamingReferenceManagerImpl.java:154)
		... 10 more
	Caused by: javax.naming.NameNotFoundException: #ejb.VehicleIF not found
		at com.sun.enterprise.naming.impl.TransientContext.doLookup(TransientContext.java:235)
		at com.sun.enterprise.naming.impl.TransientContext.lookup(TransientContext.java:203)
		at com.sun.enterprise.naming.impl.SerialContextProviderImpl.lookup(SerialContextProviderImpl.java:38)
		at com.sun.enterprise.naming.impl.RemoteSerialContextProviderImpl.lookup(RemoteSerialContextProviderImpl.java:92)
		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
		at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
		at java.base/java.lang.reflect.Method.invoke(Method.java:568)
		at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie.dispatchToMethod(ReflectiveTie.java:122)
		at com.sun.corba.ee.impl.presentation.rmi.ReflectiveTie._invoke(ReflectiveTie.java:152)
		at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatchToServant(ServerRequestDispatcherImpl.java:501)
		at com.sun.corba.ee.impl.protocol.ServerRequestDispatcherImpl.dispatch(ServerRequestDispatcherImpl.java:172)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequestRequest(MessageMediatorImpl.java:1529)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:1405)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleInput(MessageMediatorImpl.java:910)
		at com.sun.corba.ee.impl.protocol.giopmsgheaders.RequestMessage_1_2.callback(RequestMessage_1_2.java:192)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.handleRequest(MessageMediatorImpl.java:674)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.dispatch(MessageMediatorImpl.java:476)
		at com.sun.corba.ee.impl.protocol.MessageMediatorImpl.doWork(MessageMediatorImpl.java:2202)
		at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.performWork(ThreadPoolImpl.java:476)
		at com.sun.corba.ee.impl.threadpool.ThreadPoolImpl$WorkerThread.run(ThreadPoolImpl.java:519)
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.861 s - in client.AppClientTest
Stopping container using command: [/Library/Java/JavaVirtualMachines/temurin-17.jdk/Contents/Home/bin/java, -jar, /private/tmp/platform-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/admin-cli.jar, stop-domain, -t]
Waiting finished after 43 ms.
[INFO] 
[INFO] Results:
[INFO] 
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
[INFO] 
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  12.335 s
[INFO] Finished at: 2024-11-06T12:48:08-06:00
[INFO] ------------------------------------------------------------------------
starksm@Scotts-Mac-Studio platform-tck % 

@dmatej
Copy link
Contributor

dmatej commented Nov 6, 2024

Oh, I forgot there are two repositories, thanks! Now I reproduced it.

@edburns
Copy link

edburns commented Nov 7, 2024

Hello @dmatej , thanks so much for giving your time to this. We all greatly appreciate it.

@dmatej
Copy link
Contributor

dmatej commented Nov 7, 2024

@scottmarlow I have enabled full glassfish logging (remove all logger in GlassFish's logging.properties and set just .level=FINEST) and based on this log:

[2024-11-07T15:13:22.535024+01:00] [GF 8.0.0-SNAPSHOT] [FINE] [] [jakarta.enterprise.system.tools.deployment.dol] [tid: _ThreadID=68 _ThreadName=admin-listener(2)] [levelValue: 500] [[
  Adding ejb ref Remote ejb-ref name=ejb/StatelessVehicleBean,Remote 3.x interface =ejb.VehicleIF,ejb-link=StatelessVehicleBean,lookup=java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateless_vehicle_ejb/StatelessVehicleBean,mappedName=,jndi-name=,refType=Session]]

I added the lookup-name:

<lookup-name>java:global/jpa_core_EntityGraph_vehicles/jpa_core_EntityGraph_stateless_vehicle_ejb/StatelessVehicleBean</lookup-name>

Then the test passed:

00086 _VehicleIF_Wrapper . . . . . . . . . . . .  :  :    L2
00087 _VehicleIF_Wrapper . . . . . . . . . . . .  :  :     RETURN
     TRYCATCHBLOCK L0 L1 L3 jakarta/transaction/TransactionRolledbackException
     TRYCATCHBLOCK L0 L1 L4 jakarta/transaction/TransactionRequiredException
     TRYCATCHBLOCK L0 L1 L5 java/rmi/NoSuchObjectException
     TRYCATCHBLOCK L0 L1 L6 java/rmi/AccessException
     TRYCATCHBLOCK L0 L1 L7 com/sun/ejb/containers/InternalEJBContainerException
     TRYCATCHBLOCK L0 L1 L8 java/rmi/RemoteException
     TRYCATCHBLOCK L0 L1 L9 org/omg/CORBA/SystemException

RemoteEjbClient.main() PASSED
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 13.345 s - in client.AppClientTest
Stopping container using command: [/usr/lib/jvm/jdk21/bin/java, -jar, /home/dmatej/work/repo/git/jakartaee-tck/glassfish-runner/jpa-platform-tck/target/glassfish8/glassfish/modules/admin-cli.jar, stop-domain, -t]
Waiting finished after 117 ms.
[INFO] 
[INFO] Results:
[INFO] 
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0

I pushed it to the branch. Is it what you needed, or you need something more?

@starksm64
Copy link
Member

The problem with that is that this is a common vehicle ejb and the test artifact changes across tests. If the lookup-name is required, every test needs to have a local copy of the application-client.xml descriptor or reset the lookup-name value. This was not required in EE10, so why isn't the ejb-link sufficient to establish the jndi binding in the client env?

We can certainly use this as a workaround for now.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants