adoptium / aqa-tests

Home of test infrastructure for Adoptium builds
https://adoptium.net/aqavit
Apache License 2.0
132 stars 312 forks source link

jdk_net_0, jdk_net_1 failures in AIX #3094

Open aixtools opened 2 years ago

aixtools commented 2 years ago

Describe the bug The (weekly) run of https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_ppc64_aix/ is not finishing green. Examination of the last week shows in: https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_ppc64_aix/34/ one build artifact and three Generated items: https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_0/, https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/, and https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_2/.

The first artifact finishes green,

To Reproduce Steps to reproduce the behavior (or Grinder rerun link):

Expected behavior A clear and concise description of what you expected to happen.

Screenshots the second fail https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/10/

06:00:18.093  DISPLAY=:0 \
06:00:18.093  HOME=/home/jenkins \
06:00:18.093  LANG=en_US \
06:00:18.093  PATH=/bin:/usr/bin:/usr/sbin \
06:00:18.093  TZ=CUT0 \
06:00:18.093      /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/openjdkbinary/j2sdk-image/bin/java \
06:00:18.093          -Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops -Djava.awt.headless=true' \
06:00:18.093          -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops -J-Djava.awt.headless=true' \
06:00:18.093          -Dtest.compiler.opts= \
06:00:18.093          -Dtest.java.opts= \
06:00:18.093          -Dtest.jdk=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/openjdkbinary/j2sdk-image \
06:00:18.093          -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/openjdkbinary/j2sdk-image \
06:00:18.093          -Dtest.timeout.factor=8.0 \
06:00:18.093          -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/openjdkbinary/openjdk-test-image/jdk/jtreg/native \
06:00:18.093          -Dtest.root=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk \
06:00:18.093          -Dtest.name=java/net/MulticastSocket/Promiscuous.java \
06:00:18.093          -Dtest.file=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/MulticastSocket/Promiscuous.java \
06:00:18.093          -Dtest.src=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/MulticastSocket \
06:00:18.093          -Dtest.src.path=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/MulticastSocket:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib \
06:00:18.093          -Dtest.classes=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/java/net/MulticastSocket/Promiscuous.d \
06:00:18.093          -Dtest.class.path=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/java/net/MulticastSocket/Promiscuous.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/test/lib \
06:00:18.093          -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/java/net/MulticastSocket/Promiscuous.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/MulticastSocket:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/test/lib \
06:00:18.093          -classpath /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/java/net/MulticastSocket/Promiscuous.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/MulticastSocket:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_16344628239249/jdk_net_1/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar \
06:00:18.093          Promiscuous
06:00:18.093  STDOUT:
06:00:18.093  Using port: 51774
06:00:18.093  Sending datagram to: /224.1.1.120/51774
06:00:18.093  STDERR:
06:00:18.093  java.lang.RuntimeException: 0.0.0.0/0.0.0.0:51774: Expected message not received, Receive timed out
06:00:18.093    at Promiscuous.receive(Promiscuous.java:77)
06:00:18.093    at Promiscuous.test(Promiscuous.java:138)
06:00:18.093    at Promiscuous.main(Promiscuous.java:192)
06:00:18.093    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
06:00:18.093    at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
06:00:18.093    at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
06:00:18.093    at java.base/java.lang.reflect.Method.invoke(Method.java:566)
06:00:18.093    at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:298)
06:00:18.093    at java.base/java.lang.Thread.run(Thread.java:866)
06:00:18.093  
06:00:18.093  JavaTest Message: Test threw exception: java.lang.RuntimeException
06:00:18.093  JavaTest Message: shutting down test
06:00:18.093  
06:00:18.093  
06:00:18.093  TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 0.0.0.0/0.0.0.0:51774: Expected message not received, Receive timed out
aixtools commented 2 years ago

Getting different error (details below) - so my beginners question: where, rather how, do I recognize the test being run - in order to add some additional debug info to understand what is there, or not, to figure out why the test is failing.

Below is a different error then before.

00:08:41.440  rerun:
00:08:41.440  cd /home/jenkins/workspace/Grinder/aqa-tests/TKG/output_16349846498284/jdk_net_1/work/scratch && \
00:08:41.440  DISPLAY=:0 \
00:08:41.440  HOME=/home/jenkins \
00:08:41.440  LANG=en_US \
00:08:41.440  PATH=/bin:/usr/bin:/usr/sbin \
00:08:41.441  TZ=CUT0 \
00:08:41.441      /home/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/bin/javac \
00:08:41.441          -J-ea \
00:08:41.441          -J-esa \
00:08:41.441          -J-Xmx512m \
00:08:41.441          -J-XX:-UseCompressedOops \
00:08:41.441          -J-Djava.awt.headless=true \
00:08:41.441          -J-Djava.library.path=/home/jenkins/workspace/Grinder/openjdkbinary/openjdk-test-image/jdk/jtreg/native \
00:08:41.441          -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops -Djava.awt.headless=true' \
00:08:41.441          -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops -J-Djava.awt.headless=true' \
00:08:41.441          -J-Dtest.compiler.opts= \
00:08:41.441          -J-Dtest.java.opts= \
00:08:41.441          -J-Dtest.jdk=/home/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image \
00:08:41.441          -J-Dcompile.jdk=/home/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image \
00:08:41.441          -J-Dtest.timeout.factor=8.0 \
00:08:41.441          -J-Dtest.nativepath=/home/jenkins/workspace/Grinder/openjdkbinary/openjdk-test-image/jdk/jtreg/native \
00:08:41.441          -J-Dtest.root=/home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk \
00:08:41.441          -J-Dtest.name=com/sun/net/httpserver/InputNotRead.java \
00:08:41.441          -J-Dtest.file=/home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver/InputNotRead.java \
00:08:41.441          -J-Dtest.src=/home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver \
00:08:41.441          -J-Dtest.src.path=/home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver \
00:08:41.441          -J-Dtest.classes=/home/jenkins/workspace/Grinder/aqa-tests/TKG/output_16349846498284/jdk_net_1/work/classes/com/sun/net/httpserver/InputNotRead.d \
00:08:41.441          -J-Dtest.class.path=/home/jenkins/workspace/Grinder/aqa-tests/TKG/output_16349846498284/jdk_net_1/work/classes/com/sun/net/httpserver/InputNotRead.d \
00:08:41.441          -J-Dtest.class.path.prefix=/home/jenkins/workspace/Grinder/aqa-tests/TKG/output_16349846498284/jdk_net_1/work/classes/com/sun/net/httpserver/InputNotRead.d:/home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver \
00:08:41.441          -J-Dtest.modules='jdk.httpserver java.logging' \
00:08:41.441          --add-modules jdk.httpserver,java.logging \
00:08:41.441          -d /home/jenkins/workspace/Grinder/aqa-tests/TKG/output_16349846498284/jdk_net_1/work/classes/com/sun/net/httpserver/InputNotRead.d \
00:08:41.441          -sourcepath /home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver \
00:08:41.441          -classpath /home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver:/home/jenkins/workspace/Grinder/aqa-tests/TKG/output_16349846498284/jdk_net_1/work/classes/com/sun/net/httpserver/InputNotRead.d:/home/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/testng.jar:/home/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/jcommander.jar /home/jenkins/workspace/Grinder/aqa-tests/openjdk/openjdk-jdk/test/jdk/com/sun/net/httpserver/InputNotRead.java
00:08:41.441  
00:08:41.441  ACTION: testng -- Failed. Execution failed: `main' threw exception: java.lang.AssertionError: State is not RESPONSE (REQUEST)
00:08:41.441  REASON: User specified action: run testng/othervm InputNotRead 
00:08:41.441  TIME:   1.263 seconds
00:08:41.441  messages:
00:08:41.441  command: testng InputNotRead
00:08:41.441  reason: User specified action: run testng/othervm InputNotRead 
00:08:41.441  Mode: othervm [/othervm specified]
00:08:41.441  Additional options from @modules: --add-modules jdk.httpserver,java.logging
00:08:41.441  elapsed time (seconds): 1.263
00:08:41.441  configuration:
00:08:41.441  Boot Layer
00:08:41.441    add modules: jdk.httpserver java.logging
00:08:41.441  
00:08:41.441  STDOUT:
00:08:41.441  [TestNG] Running:
00:08:41.441    com/sun/net/httpserver/InputNotRead.java
00:08:41.441  
00:08:41.441  testCloseOutputStream()
00:08:41.441  Server started at port 44269
00:08:41.441  Client connected by socket: Socket[addr=localhost/127.0.0.1,port=44269,localport=44270]
00:08:41.441  Client wrote request to socket: Socket[addr=localhost/127.0.0.1,port=44269,localport=44270]
00:08:41.441  Client start reading from server:
00:08:41.441  "HTTP/1.1 200 OK"
00:08:41.441  "Date: Sat, 23 Oct 2021 10:27:30 GMT"
00:08:41.441  "Content-length: 17"
00:08:41.441  Client finished reading from server
00:08:41.441  STDERR:
00:08:41.441  Oct 23, 2021 10:27:30 AM sun.net.httpserver.ServerImpl <init>
00:08:41.441  FINE: HttpServer created http localhost/127.0.0.1:0
00:08:41.441  Oct 23, 2021 10:27:30 AM sun.net.httpserver.ServerImpl createContext
00:08:41.441  FINE: context created: /context
00:08:41.441  Oct 23, 2021 10:27:30 AM sun.net.httpserver.ServerImpl$Exchange run
00:08:41.441  FINE: Exchange request line: GET /context/ HTTP/1.1
00:08:41.441  Handling request: /context/
00:08:41.441  Oct 23, 2021 10:27:30 AM sun.net.httpserver.ServerImpl logReply
00:08:41.441  FINE: GET /context/ HTTP/1.1 [200  OK] ()
00:08:41.441  java.lang.AssertionError: State is not RESPONSE (REQUEST)
00:08:41.441    at jdk.httpserver/sun.net.httpserver.ServerImpl.responseCompleted(ServerImpl.java:814)
00:08:41.441    at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handleEvent(ServerImpl.java:297)
00:08:41.441    at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:356)
00:08:41.441    at java.base/java.lang.Thread.run(Thread.java:861)
00:08:41.441  STATUS:Failed.`main' threw exception: java.lang.AssertionError: State is not RESPONSE (REQUEST)