search

eclipse-openj9 / openj9

Eclipse OpenJ9: A Java Virtual Machine for OpenJDK that's optimized for small footprint, fast start-up, and high throughput. Builds on Eclipse OMR (https://github.com/eclipse/omr) and combines with the Extensions for OpenJDK for OpenJ9 repo.
Other
3.28k stars 721 forks source link

cmdLineTester_verbosetest failed : Required condition was not found: [Output match: Loaded jit/test/vich/Allocation] #13065

Open JasonFengJ9 opened 3 years ago

JasonFengJ9 commented 3 years ago

Failure link

https://openj9-jenkins.osuosl.org/job/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal/5/tapResults/

07:50:11  openjdk version "1.8.0_302-internal"
07:50:11  OpenJDK Runtime Environment (build 1.8.0_302-internal-_2021_06_24_09_40-b00)
07:50:11  Eclipse OpenJ9 VM (build master-e476163, JRE 1.8.0 Linux amd64-64-Bit Compressed References 20210624_5 (JIT enabled, AOT enabled)
07:50:11  OpenJ9   - e476163
07:50:11  OMR      - 021248f
07:50:11  JCL      - fc9162b based on jdk8u302-b06)

to rebuild the failed tests in =https://openj9-jenkins.osuosl.org/job/Grinder, use the following links: 10:40:15 https://openj9-jenkins.osuosl.org/job/Grinder/parambuild/?JDK_VERSION=8&JDK_IMPL=openj9&JDK_VENDOR=eclipse&BUILD_LIST=functional&PLATFORM=x86-64_linux_mixed&TARGET=cmdLineTester_verbosetest_0 10:40:15 https://openj9-jenkins.osuosl.org/job/Grinder/parambuild/?JDK_VERSION=8&JDK_IMPL=openj9&JDK_VENDOR=eclipse&BUILD_LIST=functional&PLATFORM=x86-64_linux_mixed&TARGET=cmdLineTester_verbosetest_2 10:40:15 https://openj9-jenkins.osuosl.org/job/Grinder/parambuild/?JDK_VERSION=8&JDK_IMPL=openj9&JDK_VENDOR=eclipse&BUILD_LIST=functional&PLATFORM=x86-64_linux_mixed&TARGET=cmdLineTester_verbosetest_5

Optional info

Failure output (captured from console output)

===============================================
Running test cmdLineTester_verbosetest_1 ...
===============================================
cmdLineTester_verbosetest_1 Start Time: Thu Jun 24 09:42:40 2021 Epoch Time (ms): 1624538560972
variation: Mode501
JVM_OPTIONS: -Xshareclasses:name=cmdLineTester_verbosetest_1 -Xscmx400M -Xscmaxaot256m  -Xjit -Xgcpolicy:balanced -Xnocompressedrefs 

Test handling of other arguments
Testing: -verbose:dynload,sizes,stack,debug
Test start time: 2021/06/24 09:44:49 Atlantic Standard Time
Running command: "/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:name=cmdLineTester_verbosetest_1 -Xscmx400M -Xscmaxaot256m  -Xjit -Xgcpolicy:balanced -Xnocompressedrefs  -Xdump -verbose:dynload,sizes,stack,debug -cp "/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../../jvmtest/TestConfig/resources:/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../TKG/lib/testng.jar:/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../TKG/lib/jcommander.jar:/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../../jvmtest/functional/JIT_Test/jitt.jar" org.testng.TestNG -d "/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../TKG/output_16245354073677/cmdLineTester_verbosetest_1_ITER_1" "/home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../../jvmtest/functional/JIT_Test/testng.xml" -testnames AllocationTest -groups level.extended
Time spent starting: 2 milliseconds
Time spent executing: 2411 milliseconds
Test result: FAILED
Output from test:
 [OUT] [IncludeExcludeTestAnnotationTransformer] [INFO] EXCLUDE_FILE environment variable: /home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../TestConfig/resources/excludes/latest_exclude_8.txt
 [OUT] [IncludeExcludeTestAnnotationTransformer] [INFO] Processing exclude file: /home/jenkins/workspace/Test_openjdk8_j9_extended.functional_x86-64_linux_aot_Personal_testList_1/aqa-tests/TKG/../TestConfig/resources/excludes/latest_exclude_8.txt
 [OUT] ...
 [OUT] ... TestNG 6.14.2 by Cédric Beust (cedric@beust.com)
 [OUT] ...
 [OUT] 
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (0) = 4
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (100) = 76
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (200) = 112
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (300) = 153
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (400) = 188
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (500) = 225
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (600) = 229
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (700) = 75
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (800) = 75
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (900) = 91
 [OUT] [Allocation] [INFO] 100000 x ObjectAllocation.allocArrays (1000) = 93
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjects() = 48
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjectWithOneSlot() = 49
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjectWithTwoSlots() = 41
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjectWithTenSlots() = 65
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjectWithOneIntSlot() = 41
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjectWithTwoIntSlots() = 59
 [OUT] [Allocation] [INFO] 1000000 ObjectAllocation.allocObjectWithTenIntSlots() = 47
 [OUT] PASSED: testAllocation
 [OUT] 
 [OUT] ===============================================
 [OUT]     AllocationTest
 [OUT]     Tests run: 1, Failures: 0, Skips: 0
 [OUT] ===============================================
 [OUT] 
 [OUT] 
 [OUT] ===============================================
 [OUT] JIT_Test suite
 [OUT] Total tests run: 1, Failures: 0, Skips: 0
 [OUT] ===============================================
 [OUT] 
 [ERR]   -Xmca32K        RAM class segment increment
 [ERR]   -Xmco128K       ROM class segment increment
 [ERR]   -Xmcrs0K        compressed references metadata initial size
 [ERR]   -Xmns8M         initial new space size
 [ERR]   -Xmnx1504M      maximum new space size
 [ERR]   -Xms8M          initial memory size
 [ERR]   -Xmos8M         initial old space size
 [ERR]   -Xmox6024M      maximum old space size
 [ERR]   -Xmx6024M       memory maximum
 [ERR]   -Xlp:objectheap:pagesize=4K     large page size
 [ERR]                   available large page sizes:
 [ERR]                   4K
 [ERR]   -Xlp:codecache:pagesize=4K  large page size for JIT code cache
 [ERR]                   available large page sizes for JIT code cache:
 [ERR]                   4K
 [ERR]   -Xmso256K       operating system thread stack size
 [ERR]   -Xiss2K         java thread stack initial size
 [ERR]   -Xssi16K        java thread stack increment
 [ERR]   -Xss1M          java thread stack maximum size
 [ERR]   -XX:SharedCacheHardLimit=400M shared class cache size
 [ERR]   -Xscmx419429792 shared class cache soft max size
 [ERR]   -Xscdmx32736K   reserved shared class cache space for class debug attributes
 [ERR]   -Xscminaot0K    min reserved shared class cache space for AOT
 [ERR]   -Xscmaxaot256M  max allowed shared class cache space for AOT
 [ERR]   -Xscminjitdata0K min reserved shared class cache space for JIT data
 [ERR]   -Xscmaxjitdata376508K max allowed shared class cache space for JIT data
 [ERR] <Loaded org/testng/TestNG>
 [ERR] <  Class size 39714; ROM size 35240; debug size 0>
 [ERR] <  Read time 0 usec; Load time 614 usec; Translate time 1129 usec>
 [ERR] JVMVERB000I Verbose stack: "Attach API initializer" used 2440/0 bytes on Java/C stacks
 [ERR] <Loaded com/beust/jcommander/ParameterException>
 [ERR] <  Class size 677; ROM size 408; debug size 0>
 [ERR] <  Read time 0 usec; Load time 11 usec; Translate time 74 usec>
 [ERR] <Loaded sun/reflect/GeneratedMethodAccessor1>
 [ERR] <  Class size 775; ROM size 992; debug size 0>
 [ERR] <  Read time 0 usec; Load time 11 usec; Translate time 199 usec>
 [ERR] <Loaded sun/reflect/GeneratedMethodAccessor2>
 [ERR] <  Class size 777; ROM size 992; debug size 0>
 [ERR] <  Read time 0 usec; Load time 12 usec; Translate time 86 usec>
 [ERR] <Loaded com/sun/proxy/$Proxy0>
 [ERR] <  Class size 2193; ROM size 1528; debug size 0>
 [ERR] <  Read time 0 usec; Load time 25 usec; Translate time 74 usec>
 [ERR] <Loaded com/sun/proxy/$Proxy1>
 [ERR] <  Class size 4343; ROM size 3968; debug size 0>
 [ERR] <  Read time 0 usec; Load time 49 usec; Translate time 118 usec>
 [ERR] <Loaded sun/reflect/GeneratedMethodAccessor3>
 [ERR] <  Class size 787; ROM size 1008; debug size 0>
 [ERR] <  Read time 0 usec; Load time 12 usec; Translate time 123 usec>
 [ERR] <Loaded sun/reflect/GeneratedConstructorAccessor1>
 [ERR] <  Class size 1359; ROM size 1040; debug size 0>
 [ERR] <  Read time 0 usec; Load time 16 usec; Translate time 85 usec>
 [ERR] <Loaded jdk/crypto/jniprovider/NativeCrypto$$Lambda$1/0x0000000000000000>
 [ERR] <  Class size 429; ROM size 584; debug size 0>
 [ERR] <  Read time 0 usec; Load time 10 usec; Translate time 51 usec>
 [ERR] <Loaded jit/test/finalizer/TestObject>
 [ERR] <  Class size 3085; ROM size 2232; debug size 0>
 [ERR] <  Read time 1 usec; Load time 32 usec; Translate time 120 usec>
 [ERR] <Loaded com/sun/proxy/$Proxy2>
 [ERR] <  Class size 5684; ROM size 5352; debug size 0>
 [ERR] <  Read time 0 usec; Load time 70 usec; Translate time 160 usec>
 [ERR] JVMVERB000I Verbose stack: "Attach API wait loop" used 424/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "Attach API teardown" used 1624/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "main" used 5080/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-005" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-001" used 328/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-000" used 448/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-002" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-006" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-003" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Compilation Thread-004" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT Diagnostic Compilation Thread-007" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "IProfiler" used 320/0 bytes on Java/C stacks
 [ERR] JVMVERB000I Verbose stack: "JIT-SamplerThread" used 320/0 bytes on Java/C stacks
>> Required condition was not found: [Output match: Loaded jit/test/vich/Allocation]
>> Required condition was found: [Output match: RAM class segment increment]
>> Required condition was found: [Output match: JVMVERB000I Verbose stack]
>> Required condition was found: [Output match: ]
>> Required condition was found: [Output match: ]
>> Success condition was found: [Output match: 100000 x ObjectAllocation.allocArrays]
pshipton commented 3 years ago

@dsouzai fyi