JDK-8265294 : tools/jpackage/share/AppImagePackageTest.java failed with "Exit code: 134"
  • Type: Bug
  • Component: tools
  • Sub-Component: jpackage
  • Affected Version: 17
  • Priority: P4
  • Status: Closed
  • Resolution: Duplicate
  • OS: os_x_10.15
  • CPU: x86_64
  • Submitted: 2021-04-15
  • Updated: 2021-05-14
  • Resolved: 2021-05-14
Related Reports
Duplicate :  
JDK-8249395 - (macos) jpackage tests timeout on MacPro5_1 systems
Description
The following test failed in the JDK17 CI:

tools/jpackage/share/AppImagePackageTest.java

Here's a snippet from the log file:

[07:52:11.199] Succeeded in building Mac PKG Package package
[07:52:11.220] TRACE: exec: Done. Exit code: 0
[07:52:11.221] TRACE: assertEquals(0): Check command [/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-17+19-1508/macosx-x64.jdk/jdk-17.jdk/Contents/Home/bin/jpackage --dest ./test/output --name AppImagePackageTest --type pkg --app-image ./test/appimage/AppImagePackageTest.app --verbose](10) exited with 0 code
[07:52:11.222] TRACE: assertTrue(): Check [./test/output/AppImagePackageTest-1.0.pkg] path exists
[07:52:11.222] TRACE: assertTrue(): Check [./test/output/AppImagePackageTest-1.0.pkg] is a file
[07:52:11.224] TRACE: exec: Execute [pkgutil --expand ./test/output/AppImagePackageTest-1.0.pkg ./test/unpacked-pkg/data](4); discard I/O...
[07:52:11.636] TRACE: exec: Done. Exit code: 0
[07:52:11.636] TRACE: assertEquals(0): Check command [pkgutil --expand ./test/output/AppImagePackageTest-1.0.pkg ./test/unpacked-pkg/data](4) exited with 0 code
[07:52:11.707] TRACE: exec: Execute [tar -C ./test/unpacked-pkg/unpacked/Applications -xvf ./test/unpacked-pkg/data/AppImagePackageTest-app.pkg/Payload](5); discard I/O...
[07:52:12.871] TRACE: exec: Done. Exit code: 0
[07:52:12.872] TRACE: assertEquals(0): Check command [tar -C ./test/unpacked-pkg/unpacked/Applications -xvf ./test/unpacked-pkg/data/AppImagePackageTest-app.pkg/Payload](5) exited with 0 code
[07:52:12.872] TRACE: Verify unpacked: [/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-17+19-1508/macosx-x64.jdk/jdk-17.jdk/Contents/Home/bin/jpackage --dest ./test/output --name AppImagePackageTest --type pkg --app-image ./test/appimage/AppImagePackageTest.app jpt-unpacked-folder ./test/unpacked-pkg/unpacked](11)
[07:52:12.895] TRACE: assertStringListEquals(): Check there is only one file with [.jpackage.xml] name in the package
[07:52:12.897] TRACE: assertStringListEquals(1, ./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/app/.jpackage.xml)
[07:52:12.900] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/runtime] path exists
[07:52:12.901] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/runtime] is a directory
[07:52:12.907] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/MacOS/AppImagePackageTest] path exists
[07:52:12.907] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/MacOS/AppImagePackageTest] is a file
[07:52:12.907] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/MacOS/AppImagePackageTest] file is executable
[07:52:12.914] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/app/AppImagePackageTest.cfg] path exists
[07:52:12.915] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/app/AppImagePackageTest.cfg] is a file
[07:52:12.918] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/runtime/Contents/MacOS/libjli.dylib] path exists
[07:52:12.918] TRACE: assertTrue(): Check [./test/unpacked-pkg/unpacked/Applications/AppImagePackageTest.app/Contents/runtime/Contents/MacOS/libjli.dylib] is a file
[07:52:12.919] TRACE: exec: Execute [/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-17+19-1508/macosx-x64.jdk/jdk-17.jdk/Contents/Home/bin/jpackage --dest ./test/output --name AppImagePackageTest --type dmg --app-image ./test/appimage/AppImagePackageTest.app --verbose](10); inherit I/O...
Timeout refired 2160 times
[08:27:50.437] TRACE: exec: Done. Exit code: 134
[08:27:50.437] ERROR: Expected [0]. Actual [134]: Check command [/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-17+19-1508/macosx-x64.jdk/jdk-17.jdk/Contents/Home/bin/jpackage --dest ./test/output --name AppImagePackageTest --type dmg --app-image ./test/appimage/AppImagePackageTest.app --verbose](10) exited with 0 code
[08:27:50.438] [  FAILED  ] AppImagePackageTest.test; checks=19
Comments
Based on call stack for this test it was waiting for "hdiutil create" to complete. Exactly same call stack as for JDK-8249395. I will close it as duplicate of JDK-8249395, since JDK-8249395 tries to address exactly same issue, but for different test. "main" #1 prio=5 os_prio=31 cpu=274.74ms elapsed=2134.25s allocated=7306K defined_classes=835 tid=0x00007f9a59811c00 nid=0x1703 runnable [0x000070000c19b000] java.lang.Thread.State: RUNNABLE at java.io.FileInputStream.readBytes(java.base@17-ea/Native Method) at java.io.FileInputStream.read(java.base@17-ea/FileInputStream.java:271) at java.io.BufferedInputStream.read1(java.base@17-ea/BufferedInputStream.java:282) at java.io.BufferedInputStream.read(java.base@17-ea/BufferedInputStream.java:343) - locked <0x0000000600002058> (a java.lang.ProcessImpl$ProcessPipeInputStream) at sun.nio.cs.StreamDecoder.readBytes(java.base@17-ea/StreamDecoder.java:270) at sun.nio.cs.StreamDecoder.implRead(java.base@17-ea/StreamDecoder.java:313) at sun.nio.cs.StreamDecoder.read(java.base@17-ea/StreamDecoder.java:188) - locked <0x0000000600006190> (a java.io.InputStreamReader) at java.io.InputStreamReader.read(java.base@17-ea/InputStreamReader.java:169) at java.io.BufferedReader.fill(java.base@17-ea/BufferedReader.java:162) at java.io.BufferedReader.readLine(java.base@17-ea/BufferedReader.java:329) - locked <0x0000000600006190> (a java.io.InputStreamReader) at java.io.BufferedReader.readLine(java.base@17-ea/BufferedReader.java:396) at java.io.BufferedReader$1.hasNext(java.base@17-ea/BufferedReader.java:571) at java.util.Iterator.forEachRemaining(java.base@17-ea/Iterator.java:132) at java.util.Spliterators$IteratorSpliterator.forEachRemaining(java.base@17-ea/Spliterators.java:1801) at java.util.stream.AbstractPipeline.copyInto(java.base@17-ea/AbstractPipeline.java:484) at java.util.stream.AbstractPipeline.wrapAndCopyInto(java.base@17-ea/AbstractPipeline.java:474) at java.util.stream.AbstractPipeline.evaluate(java.base@17-ea/AbstractPipeline.java:550) at java.util.stream.AbstractPipeline.evaluateToArrayNode(java.base@17-ea/AbstractPipeline.java:260) at java.util.stream.ReferencePipeline.toArray(java.base@17-ea/ReferencePipeline.java:616) at java.util.stream.ReferencePipeline.toArray(java.base@17-ea/ReferencePipeline.java:622) at java.util.stream.ReferencePipeline.toList(java.base@17-ea/ReferencePipeline.java:627) at jdk.jpackage.internal.Executor.execute(jdk.jpackage@17-ea/Executor.java:151) at jdk.jpackage.internal.Executor.executeExpectSuccess(jdk.jpackage@17-ea/Executor.java:88) at jdk.jpackage.internal.IOUtils.exec(jdk.jpackage@17-ea/IOUtils.java:205) at jdk.jpackage.internal.IOUtils.exec(jdk.jpackage@17-ea/IOUtils.java:166) at jdk.jpackage.internal.MacDmgBundler.buildDMG(jdk.jpackage@17-ea/MacDmgBundler.java:328) at jdk.jpackage.internal.MacDmgBundler.bundle(jdk.jpackage@17-ea/MacDmgBundler.java:88) at jdk.jpackage.internal.MacDmgBundler.execute(jdk.jpackage@17-ea/MacDmgBundler.java:567) at jdk.jpackage.internal.Arguments.generateBundle(jdk.jpackage@17-ea/Arguments.java:676) at jdk.jpackage.internal.Arguments.processArguments(jdk.jpackage@17-ea/Arguments.java:550) at jdk.jpackage.main.Main.execute(jdk.jpackage@17-ea/Main.java:91) at jdk.jpackage.main.Main.main(jdk.jpackage@17-ea/Main.java:52) "process reaper" #14 daemon prio=10 os_prio=31 cpu=61.10ms elapsed=2133.97s allocated=2520B defined_classes=0 tid=0x00007f9a10086000 nid=0x9f03 waiting for monitor entry [0x000070000d705000] java.lang.Thread.State: BLOCKED (on object monitor) at java.lang.ProcessImpl$ProcessPipeInputStream.processExited(java.base@17-ea/ProcessImpl.java:592) - waiting to lock <0x0000000600002058> (a java.lang.ProcessImpl$ProcessPipeInputStream) at java.lang.ProcessImpl.lambda$initStreams$1(java.base@17-ea/ProcessImpl.java:370) at java.lang.ProcessImpl$$Lambda$215/0x0000000800ccd1c0.apply(java.base@17-ea/Unknown Source) at java.util.concurrent.CompletableFuture.uniHandle(java.base@17-ea/CompletableFuture.java:934) at java.util.concurrent.CompletableFuture$UniHandle.tryFire(java.base@17-ea/CompletableFuture.java:911) at java.util.concurrent.CompletableFuture.postComplete(java.base@17-ea/CompletableFuture.java:510) at java.util.concurrent.CompletableFuture.complete(java.base@17-ea/CompletableFuture.java:2147) at java.lang.ProcessHandleImpl$1.run(java.base@17-ea/ProcessHandleImpl.java:171) at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@17-ea/ThreadPoolExecutor.java:1135) at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@17-ea/ThreadPoolExecutor.java:635) at java.lang.Thread.run(java.base@17-ea/Thread.java:831)
14-05-2021
I've been running this test - and all test - over and over and have yet to see this failure. Have there been any other sightings of this failure ?
03-05-2021