Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-8172451

tools/pack200/Pack200Test.java timed out

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Unresolved
    • Icon: P4 P4
    • tbd
    • 9
    • tools

      This has been observed to be failing intermittent (rare).

      ----------messages:(123/6930)----------
      command: main -Xmx1280m -Xshare:off Pack200Test
      reason: User specified action: run main/othervm/timeout=1200 -Xmx1280m -Xshare:off Pack200Test
      Mode: othervm [/othervm specified]
      Timeout information:
      Running jstack on process 56445
      2017-01-06 00:31:37
      Full thread dump Java HotSpot(TM) 64-Bit Server VM (9-ea+151 mixed mode):

      "Attach Listener" #15 daemon prio=9 os_prio=64 tid=0x000000000365f800 nid=0x17 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "process reaper" #14 daemon prio=10 os_prio=64 tid=0x0000000002307800 nid=0x16 runnable [0xffff80ffbf68c000]
         java.lang.Thread.State: RUNNABLE
      at java.lang.ProcessHandleImpl.waitForProcessExit0(java.base@9-ea/Native Method)
      at java.lang.ProcessHandleImpl.lambda$completion$2(java.base@9-ea/ProcessHandleImpl.java:133)
      at java.lang.ProcessHandleImpl$$Lambda$57/1849857049.run(java.base@9-ea/Unknown Source)
      at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@9-ea/ThreadPoolExecutor.java:1161)
      at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:635)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:844)

      "MainThread" #11 prio=5 os_prio=64 tid=0x00000000008cc800 nid=0x14 runnable [0xffff80ffa21fd000]
         java.lang.Thread.State: RUNNABLE
      at java.io.FileInputStream.readBytes(java.base@9-ea/Native Method)
      at java.io.FileInputStream.read(java.base@9-ea/FileInputStream.java:256)
      at java.lang.ProcessImpl$DeferredCloseInputStream.read(java.base@9-ea/ProcessImpl.java:805)
      at java.io.BufferedInputStream.read1(java.base@9-ea/BufferedInputStream.java:284)
      at java.io.BufferedInputStream.read(java.base@9-ea/BufferedInputStream.java:345)
      - locked <0x00000000d7be2288> (a java.io.BufferedInputStream)
      at sun.nio.cs.StreamDecoder.readBytes(java.base@9-ea/StreamDecoder.java:284)
      at sun.nio.cs.StreamDecoder.implRead(java.base@9-ea/StreamDecoder.java:326)
      at sun.nio.cs.StreamDecoder.read(java.base@9-ea/StreamDecoder.java:178)
      - locked <0x00000000d7be46c8> (a java.io.InputStreamReader)
      at java.io.InputStreamReader.read(java.base@9-ea/InputStreamReader.java:185)
      at java.io.BufferedReader.fill(java.base@9-ea/BufferedReader.java:161)
      at java.io.BufferedReader.readLine(java.base@9-ea/BufferedReader.java:325)
      - locked <0x00000000d7be46c8> (a java.io.InputStreamReader)
      at java.io.BufferedReader.readLine(java.base@9-ea/BufferedReader.java:390)
      at Utils.runExec(Utils.java:528)
      at Utils.runExec(Utils.java:495)
      at Utils.doCompareBitWise(Utils.java:173)
      at Pack200Test.doPackUnpack(Pack200Test.java:116)
      at Pack200Test.main(Pack200Test.java:138)
      at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
      at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
      at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
      at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:538)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:110)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:844)

      "Service Thread" #9 daemon prio=9 os_prio=64 tid=0x000000000088b000 nid=0x12 runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Common-Cleaner" #8 daemon prio=8 os_prio=64 tid=0x0000000000723000 nid=0x11 in Object.wait() [0xffff80ffb2fde000]
         java.lang.Thread.State: TIMED_WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:151)
      - waiting to re-lock in wait() <0x00000000b0141140> (a java.lang.ref.ReferenceQueue$Lock)
      at jdk.internal.ref.CleanerImpl.run(java.base@9-ea/CleanerImpl.java:148)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:844)
      at jdk.internal.misc.InnocuousThread.run(java.base@9-ea/InnocuousThread.java:122)

      "Sweeper thread" #7 daemon prio=9 os_prio=64 tid=0x0000000000687000 nid=0x10 runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread1" #6 daemon prio=9 os_prio=64 tid=0x0000000000684800 nid=0xf waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread0" #5 daemon prio=9 os_prio=64 tid=0x0000000000683000 nid=0xe waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "Signal Dispatcher" #4 daemon prio=9 os_prio=64 tid=0x0000000000681000 nid=0xd runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Finalizer" #3 daemon prio=8 os_prio=64 tid=0x0000000000660800 nid=0xc in Object.wait() [0xffff80ffbdbfa000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:151)
      - waiting to re-lock in wait() <0x00000000b0115ce0> (a java.lang.ref.ReferenceQueue$Lock)
      at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:172)
      at java.lang.ref.Finalizer$FinalizerThread.run(java.base@9-ea/Finalizer.java:216)

      "Reference Handler" #2 daemon prio=10 os_prio=64 tid=0x000000000065a800 nid=0xb waiting on condition [0xffff80ffbdcfb000]
         java.lang.Thread.State: RUNNABLE
      at java.lang.ref.Reference.waitForReferencePendingList(java.base@9-ea/Native Method)
      at java.lang.ref.Reference.processPendingReferences(java.base@9-ea/Reference.java:163)
      at java.lang.ref.Reference.access$000(java.base@9-ea/Reference.java:44)
      at java.lang.ref.Reference$ReferenceHandler.run(java.base@9-ea/Reference.java:138)

      "main" #1 prio=5 os_prio=64 tid=0x000000000042f000 nid=0x2 in Object.wait() [0xffff80ffbf1fe000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <0x00000000b00f0550> (a java.lang.Thread)
      at java.lang.Thread.join(java.base@9-ea/Thread.java:1353)
      - waiting to re-lock in wait() <0x00000000b00f0550> (a java.lang.Thread)
      at java.lang.Thread.join(java.base@9-ea/Thread.java:1427)
      at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:74)

      "VM Thread" os_prio=64 tid=0x000000000064f000 nid=0xa runnable

      "GC Thread#0" os_prio=64 tid=0x0000000000445800 nid=0x3 runnable

      "GC Thread#1" os_prio=64 tid=0x0000000000446800 nid=0x4 runnable

      "G1 Main Marker" os_prio=64 tid=0x0000000000485000 nid=0x8 runnable

      "G1 Marker#0" os_prio=64 tid=0x0000000000486800 nid=0x9 runnable

      "G1 Refine#0" os_prio=64 tid=0x000000000044a000 nid=0x6 runnable

      "G1 Refine#1" os_prio=64 tid=0x0000000000448800 nid=0x5 runnable

      "G1 Young RemSet Sampling" os_prio=64 tid=0x000000000044b800 nid=0x7 runnable

      "VM Periodic Task Thread" os_prio=64 tid=0x000000000088f000 nid=0x13 waiting on condition

      JNI global references: 691

      --- Timeout information end.
      elapsed time (seconds): 4804.643
      ----------configuration:(0/0)----------
      ----------System.out:(106/6423)----------
      [rt.jar, /export/home/aginfra/sandbox/testbase/ws/jdk/test/tools/pack200/pack200-verifier/data/golden.jar]
      Packing [rt.jar]
      Done Packing [rt.jar]
      Start leak check
        Unpacking using java unpacker
        Testing...java unpacker
      --listing /export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/.---
      d xclasses
      - java-rt.jar
      - javac2132042835819268493.tmp
      - rt.jar.pack
      - rt.jar
      /export/home/aginfra/CommonData/jdk/bin/javac -d xclasses --add-exports=jdk.jdeps/com.sun.tools.classfile=ALL-UNNAMED @/export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/./javac2132042835819268493.tmp
      Note: /export/home/aginfra/sandbox/testbase/ws/jdk/test/tools/pack200/pack200-verifier/src/xmlkit/XMLKit.java uses or overrides a deprecated API.
      Note: Recompile with -Xlint:deprecation for details.
      Note: Some input files use unchecked or unsafe operations.
      Note: Recompile with -Xlint:unchecked for details.
      --listing /export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/.---
      d xclasses
      - java-rt.jar
      - javac2132042835819268493.tmp
      - rt.jar.pack
      - rt.jar
      /export/home/aginfra/CommonData/jdk/bin/jar cvfe pack200-verifier.jar sun.tools.pack.verify.Main -C xclasses .
      added manifest
      adding: sun/(in = 0) (out= 0)(stored 0%)
      adding: sun/tools/(in = 0) (out= 0)(stored 0%)
      adding: sun/tools/pack/(in = 0) (out= 0)(stored 0%)
      adding: sun/tools/pack/verify/(in = 0) (out= 0)(stored 0%)
      adding: sun/tools/pack/verify/JarFileCompare.class(in = 5311) (out= 2752)(deflated 48%)
      adding: sun/tools/pack/verify/VerifyTreeSet.class(in = 636) (out= 373)(deflated 41%)
      adding: sun/tools/pack/verify/Main.class(in = 4131) (out= 2226)(deflated 46%)
      adding: sun/tools/pack/verify/ClassCompare.class(in = 4647) (out= 2248)(deflated 51%)
      adding: sun/tools/pack/verify/Globals.class(in = 7711) (out= 3862)(deflated 49%)
      adding: xmlkit/(in = 0) (out= 0)(stored 0%)
      adding: xmlkit/TokenList.class(in = 7420) (out= 3614)(deflated 51%)
      adding: xmlkit/XMLKit$13.class(in = 1111) (out= 615)(deflated 44%)
      adding: xmlkit/XMLKit$5.class(in = 541) (out= 336)(deflated 37%)
      adding: xmlkit/XMLKit$Element$1ESet.class(in = 1034) (out= 538)(deflated 47%)
      adding: xmlkit/XMLKit$9.class(in = 893) (out= 524)(deflated 41%)
      adding: xmlkit/XMLKit$ElementFilter.class(in = 709) (out= 405)(deflated 42%)
      adding: xmlkit/XMLKit$Element$1AView.class(in = 1701) (out= 785)(deflated 53%)
      adding: xmlkit/AttributeVisitor$1.class(in = 2037) (out= 999)(deflated 50%)
      adding: xmlkit/XMLKit$4.class(in = 1213) (out= 628)(deflated 48%)
      adding: xmlkit/XMLKit$12.class(in = 990) (out= 552)(deflated 44%)
      adding: xmlkit/XMLKit$Builder.class(in = 5450) (out= 2897)(deflated 46%)
      adding: xmlkit/XMLKit$8.class(in = 896) (out= 528)(deflated 41%)
      adding: xmlkit/XMLKit$Element$1Entry.class(in = 1861) (out= 967)(deflated 48%)
      adding: xmlkit/XMLKit$11.class(in = 1003) (out= 531)(deflated 47%)
      adding: xmlkit/XMLKit$7.class(in = 1129) (out= 577)(deflated 48%)
      adding: xmlkit/AnnotationsElementVisitor.class(in = 4497) (out= 1491)(deflated 66%)
      adding: xmlkit/XMLKit$Printer.class(in = 3220) (out= 1905)(deflated 40%)
      adding: xmlkit/XMLKit$Element$ANItr.class(in = 1826) (out= 982)(deflated 46%)
      adding: xmlkit/StackMapVisitor.class(in = 7325) (out= 2439)(deflated 66%)
      adding: xmlkit/XMLKit$Element$1.class(in = 760) (out= 432)(deflated 43%)
      adding: xmlkit/XMLKit$6.class(in = 1085) (out= 533)(deflated 50%)
      adding: xmlkit/XMLKit$10.class(in = 903) (out= 532)(deflated 41%)
      adding: xmlkit/XMLKit.class(in = 19160) (out= 8843)(deflated 53%)
      adding: xmlkit/InstructionVisitor.class(in = 5407) (out= 2057)(deflated 61%)
      adding: xmlkit/ConstantPoolVisitor.class(in = 14754) (out= 4420)(deflated 70%)
      adding: xmlkit/XMLKit$Outputter.class(in = 2201) (out= 1199)(deflated 45%)
      adding: xmlkit/XMLKit$ContentComparator.class(in = 1115) (out= 635)(deflated 43%)
      adding: xmlkit/XMLKit$Element$LView.class(in = 1902) (out= 836)(deflated 56%)
      adding: xmlkit/XMLKit$Element$ElemW.class(in = 1987) (out= 1043)(deflated 47%)
      adding: xmlkit/XMLKit$1.class(in = 1300) (out= 641)(deflated 50%)
      adding: xmlkit/XMLKit$Element$1EIter.class(in = 1228) (out= 636)(deflated 48%)
      adding: xmlkit/XMLKit$Filter.class(in = 224) (out= 158)(deflated 29%)
      adding: xmlkit/AttributeVisitor.class(in = 30903) (out= 9925)(deflated 67%)
      adding: xmlkit/CommandLineParser.class(in = 5738) (out= 3099)(deflated 45%)
      adding: xmlkit/ClassReader.class(in = 16099) (out= 7700)(deflated 52%)
      adding: xmlkit/XMLKit$1MFilt.class(in = 2674) (out= 1371)(deflated 48%)
      adding: xmlkit/XMLKit$Element$Itr.class(in = 2161) (out= 1106)(deflated 48%)
      adding: xmlkit/XMLKit$AttrFilter.class(in = 1274) (out= 667)(deflated 47%)
      adding: xmlkit/XMLKit$Special.class(in = 1961) (out= 973)(deflated 50%)
      adding: xmlkit/XMLKit$Element.class(in = 33756) (out= 15392)(deflated 54%)
      adding: xmlkit/XMLKit$3.class(in = 1268) (out= 610)(deflated 51%)
      adding: xmlkit/XMLKit$14.class(in = 755) (out= 460)(deflated 39%)
      adding: xmlkit/XMLKit$2.class(in = 1348) (out= 667)(deflated 50%)
      --listing /export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/.---
      - pack200-verifier.jar
      d xclasses
      - java-rt.jar
      - javac2132042835819268493.tmp
      - rt.jar.pack
      - rt.jar
      /export/home/aginfra/CommonData/jdk/bin/java --add-exports=jdk.jdeps/com.sun.tools.classfile=ALL-UNNAMED -cp pack200-verifier.jar sun.tools.pack.verify.Main /export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/rt.jar /export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/java-rt.jar -O
      LogFile:./verifier.log
      Reference JAR:/export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/rt.jar
      Compared JAR:/export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/java-rt.jar
      PASS
        Unpacking using native unpacker
        Testing...native unpacker
      --listing /export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/.---
      - pack200-verifier.jar
      - verifier.log
      d xclasses
      - java-rt.jar
      - javac2132042835819268493.tmp
      - rt.jar.pack
      - native-rt.jar
      - rt.jar
      /export/home/aginfra/CommonData/jdk/bin/java -cp pack200-verifier.jar sun.tools.pack.verify.Main java-rt.jar native-rt.jar -O -b
      LogFile:./verifier.log
      Reference JAR:/export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/java-rt.jar
      Compared JAR:/export/home/aginfra/sandbox/gresults/testoutput/core_tools/JTwork/scratch/native-rt.jar
      Timeout signalled after 4,800 seconds

            Unassigned Unassigned
            xiaofeya Xiaofeng Yang
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Created:
              Updated: