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

java_util_logging_CheckZombieLockTest Unexpected lock files found #9186

Open
pshipton opened this issue Apr 8, 2020 · 74 comments
Open

java_util_logging_CheckZombieLockTest Unexpected lock files found #9186

pshipton opened this issue Apr 8, 2020 · 74 comments

Comments

@pshipton
Copy link
Member

pshipton commented Apr 8, 2020

https://ci.eclipse.org/openj9/job/Test_openjdk11_j9_sanity.openjdk_x86-64_windows_Nightly/3
win2012r2-x86-2
java/util/logging/CheckZombieLockTest.java.CheckZombieLockTest (from java_util_logging_CheckZombieLockTest.java)

WARNING: unable to delete/cleanup writable test directory: C:\\temp\\writable-lockfile-dir\\log.log
WARNING: unable to delete/cleanup writable test directory: C:\\temp\\writable-lockfile-dir\\log.log.lck
WARNING: unable to delete/cleanup writable test directory: C:\\temp\\writable-lockfile-dir
WARNING: unable to delete/cleanup writable test directory: C:\\temp\\writable-lockfile-dir\\log.log
WARNING: unable to delete/cleanup writable test directory: C:\\temp\\writable-lockfile-dir\\log.log.lck
WARNING: unable to delete/cleanup writable test directory: C:\\temp\\writable-lockfile-dir
java.lang.RuntimeException: Unexpected lock files found: []
	C:\\temp\\writable-lockfile-dir\\log.log.lck should not have been reused
	at CheckZombieLockTest.testFileHandlerReuse(CheckZombieLockTest.java:257)
	at CheckZombieLockTest.runTests(CheckZombieLockTest.java:105)
	at CheckZombieLockTest.main(CheckZombieLockTest.java:78)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
	at java.base/java.lang.Thread.run(Thread.java:834)

JavaTest Message: Test threw exception: java.lang.RuntimeException: Unexpected lock files found: []
	C:\\temp\\writable-lockfile-dir\\log.log.lck should not have been reused
JavaTest Message: shutting down test

STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Unexpected lock files found: [] C:\\temp\\writable-lockfile-dir\\log.log.lck should not have been reused
@pshipton
Copy link
Member Author

pshipton commented Apr 8, 2020

3x grinder on the same machine https://ci.eclipse.org/openj9/job/Grinder/761/ - passed

@lumpfish
Copy link
Contributor

@M-Davies
Copy link

@JasonFengJ9
Copy link
Member

Occurred in a JDK16 internal build Test_openjdknext_j9_sanity.openjdk_ppc64_aix_Nightly/232:

22:28:13  openjdk version "16-internal" 2021-03-16
22:28:13  OpenJDK Runtime Environment (build 16-internal+0-adhoc.jenkins.BuildJDKnextppc64aixNightly)
22:28:13  Eclipse OpenJ9 VM (build master-c2d4f396055, JRE 16 AIX ppc64-64-Bit Compressed References 20200922_334 (JIT enabled, AOT enabled)
22:28:13  OpenJ9   - c2d4f396055
22:28:13  OMR      - 4c2d9a1f1c2
22:28:13  JCL      - ed102a5ef9d based on jdk-16+16)

23:21:18  --------------------------------------------------
23:21:18  TEST: java/util/logging/CheckZombieLockTest.java
23:21:18  TEST JDK: /home/jenkins/workspace/Test_openjdknext_j9_sanity.openjdk_ppc64_aix_Nightly/openjdkbinary/j2sdk-image
23:21:18  

23:21:18  STDERR:
23:21:18  WARNING: unable to delete/cleanup writable test directory: /tmp/writable-lockfile-dir
23:21:18  java.lang.RuntimeException: Unexpected lock files found: []
23:21:18  	/tmp/writable-lockfile-dir/log.log.lck should not have been reused
23:21:18  	at CheckZombieLockTest.testFileHandlerReuse(CheckZombieLockTest.java:257)
23:21:18  	at CheckZombieLockTest.runTests(CheckZombieLockTest.java:105)
23:21:18  	at CheckZombieLockTest.main(CheckZombieLockTest.java:78)
23:21:18  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
23:21:18  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
23:21:18  	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
23:21:18  	at java.base/java.lang.reflect.Method.invoke(Method.java:564)
23:21:18  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
23:21:18  	at java.base/java.lang.Thread.run(Thread.java:845)

@pshipton pshipton added jdk16 and removed jdk16 labels Sep 24, 2020
@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@JasonFengJ9
Copy link
Member

Also seen in an internal JDK11 AIX mixed build Test_openjdk11_j9_sanity.openjdk_ppc64_aix_mixed/1.

@pshipton
Copy link
Member Author

pshipton commented Feb 4, 2021

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

pshipton commented Mar 8, 2021

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

pshipton commented Mar 2, 2022

@pshipton
Copy link
Member Author

pshipton commented Mar 4, 2022

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

@JasonFengJ9
Copy link
Member

Internal build(paix825)

java version "17.0.5" 2022-10-18
IBM Semeru Runtime Certified Edition 17.0.5+6 (build 17.0.5+6)
Eclipse OpenJ9 VM 17.0.5+6 (build master-784820387, JRE 17 AIX ppc64-64-Bit Compressed References 20221018_228 (JIT enabled, AOT enabled)
OpenJ9   - 784820387
OMR      - 24b511df5
JCL      - 4c6463ea1 based on jdk-17.0.5+6)

[2022-09-25T03:08:19.959Z] variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode150
[2022-09-25T03:08:19.959Z] JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:+UseCompressedOops 

[2022-09-25T03:26:38.733Z] TEST: java/util/logging/CheckZombieLockTest.java

[2022-09-25T03:26:38.741Z] STDERR:
[2022-09-25T03:26:38.741Z] WARNING: unable to delete/cleanup writable test directory: /tmp/writable-lockfile-dir
[2022-09-25T03:26:38.741Z] java.lang.RuntimeException: Unexpected lock files found: []
[2022-09-25T03:26:38.741Z] 	/tmp/writable-lockfile-dir/log.log.lck should not have been reused
[2022-09-25T03:26:38.741Z] 	at CheckZombieLockTest.testFileHandlerReuse(CheckZombieLockTest.java:257)
[2022-09-25T03:26:38.741Z] 	at CheckZombieLockTest.runTests(CheckZombieLockTest.java:105)
[2022-09-25T03:26:38.741Z] 	at CheckZombieLockTest.main(CheckZombieLockTest.java:78)
[2022-09-25T03:26:38.741Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2022-09-25T03:26:38.741Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
[2022-09-25T03:26:38.741Z] 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2022-09-25T03:26:38.741Z] 	at java.base/java.lang.reflect.Method.invoke(Method.java:568)
[2022-09-25T03:26:38.741Z] 	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
[2022-09-25T03:26:38.741Z] 	at java.base/java.lang.Thread.run(Thread.java:857)

[2022-09-25T03:56:05.368Z] Test results: passed: 897; failed: 1
[2022-09-25T03:56:34.193Z] Report written to /home/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_ppc64_aix_testList_0/aqa-tests/TKG/output_16640711942664/jdk_util_0/report/html/report.html
[2022-09-25T03:56:34.193Z] Results written to /home/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_ppc64_aix_testList_0/aqa-tests/TKG/output_16640711942664/jdk_util_0/work
[2022-09-25T03:56:34.193Z] Error: Some tests failed or other problems occurred.
[2022-09-25T03:56:34.193Z] 
[2022-09-25T03:56:34.193Z] jdk_util_0_FAILED

@pshipton
Copy link
Member Author

pshipton commented Nov 3, 2022

@JasonFengJ9
Copy link
Member

JDK17 0.36.0 build

openjdk version "17.0.6" 2023-01-17
IBM Semeru Runtime Open Edition 17.0.6.0-m1 (build 17.0.6+4)
Eclipse OpenJ9 VM 17.0.6.0-m1 (build v0.36.0-release-f98e55973, JRE 17 AIX ppc64-64-Bit Compressed References 20230117_324 (JIT enabled, AOT enabled)
OpenJ9   - f98e55973
OMR      - acdbafc4a
JCL      - 792b340c15c based on jdk-17.0.6+4)
[2022-12-01T09:30:25.092Z] TEST: java/util/logging/CheckZombieLockTest.java

[2022-12-01T09:30:25.095Z] STDERR:
[2022-12-01T09:30:25.095Z] WARNING: unable to delete/cleanup writable test directory: /tmp/writable-lockfile-dir
[2022-12-01T09:30:25.095Z] java.lang.RuntimeException: Unexpected lock files found: []
[2022-12-01T09:30:25.095Z] 	/tmp/writable-lockfile-dir/log.log.lck should not have been reused
[2022-12-01T09:30:25.095Z] 	at CheckZombieLockTest.testFileHandlerReuse(CheckZombieLockTest.java:257)
[2022-12-01T09:30:25.095Z] 	at CheckZombieLockTest.runTests(CheckZombieLockTest.java:105)
[2022-12-01T09:30:25.095Z] 	at CheckZombieLockTest.main(CheckZombieLockTest.java:78)
[2022-12-01T09:30:25.095Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2022-12-01T09:30:25.095Z] 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
[2022-12-01T09:30:25.095Z] 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2022-12-01T09:30:25.095Z] 	at java.base/java.lang.reflect.Method.invoke(Method.java:568)
[2022-12-01T09:30:25.095Z] 	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
[2022-12-01T09:30:25.095Z] 	at java.base/java.lang.Thread.run(Thread.java:857)
[2022-12-01T09:30:25.095Z] 
[2022-12-01T09:30:25.095Z] JavaTest Message: Test threw exception: java.lang.RuntimeException: Unexpected lock files found: []
[2022-12-01T09:30:25.095Z] 	/tmp/writable-lockfile-dir/log.log.lck should not have been reused
[2022-12-01T09:30:25.095Z] JavaTest Message: shutting down test
[2022-12-01T09:30:25.095Z] 
[2022-12-01T09:30:25.095Z] STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Unexpected lock files found: [] /tmp/writable-lockfile-dir/log.log.lck should not have been reused

@pshipton
Copy link
Member Author

@pshipton
Copy link
Member Author

pshipton commented Feb 7, 2023

@pshipton
Copy link
Member Author

@JasonFengJ9
Copy link
Member

JDK8 ppc64_aix(paix910)

[2023-11-17T09:44:28.221Z] variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode650
[2023-11-17T09:44:28.221Z] JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops 

[2023-11-17T09:55:25.464Z] TEST: java/util/logging/CheckZombieLockTest.java

[2023-11-17T09:55:25.468Z] STDERR:
[2023-11-17T09:55:25.468Z] WARNING: unable to delete/cleanup writable test directory: /tmp/writable-lockfile-dir
[2023-11-17T09:55:25.468Z] java.lang.RuntimeException: Unexpected lock files found: []
[2023-11-17T09:55:25.468Z] 	/tmp/writable-lockfile-dir/log.log.lck should not have been reused
[2023-11-17T09:55:25.468Z] 	at CheckZombieLockTest.testFileHandlerReuse(CheckZombieLockTest.java:257)
[2023-11-17T09:55:25.469Z] 	at CheckZombieLockTest.runTests(CheckZombieLockTest.java:105)
[2023-11-17T09:55:25.469Z] 	at CheckZombieLockTest.main(CheckZombieLockTest.java:78)
[2023-11-17T09:55:25.469Z] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2023-11-17T09:55:25.469Z] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2023-11-17T09:55:25.469Z] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2023-11-17T09:55:25.469Z] 	at java.lang.reflect.Method.invoke(Method.java:498)
[2023-11-17T09:55:25.469Z] 	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
[2023-11-17T09:55:25.469Z] 	at java.lang.Thread.run(Thread.java:826)
[2023-11-17T09:55:25.469Z] 
[2023-11-17T09:55:25.469Z] JavaTest Message: Test threw exception: java.lang.RuntimeException: Unexpected lock files found: []
[2023-11-17T09:55:25.469Z] 	/tmp/writable-lockfile-dir/log.log.lck should not have been reused

[2023-11-17T10:08:28.486Z] jdk_util_1_FAILED

@pshipton
Copy link
Member Author

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

No branches or pull requests

7 participants