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

SA core file tests sometimes time out on OSX with "java.io.IOException: App waiting timeout"

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: P4
    • Resolution: Fixed
    • Affects Version/s: 16
    • Fix Version/s: 16
    • Component/s: hotspot
    • Subcomponent:
      svc
    • Resolved In Build:
      b09
    • CPU:
      x86_64
    • OS:
      os_x

      Description

      The following test failed in the JDK16 CI:

      serviceability/sa/ClhsdbFindPC.java#id3
      serviceability/sa/ClhsdbFindPC.java#id1

      Both tests report a SIGSEGV in Java_jdk_test_lib_apps_LingeredApp_crash
      but I think that crash is intentionally caused by the test.

      Here's a snippet from the ClhsdbFindPC.java#id3 log file:

      #section:main
      ----------messages:(4/189)----------
      command: main ClhsdbFindPC false true
      reason: User specified action: run main/othervm/timeout=480 ClhsdbFindPC false true
      Mode: othervm [/othervm specified]
      elapsed time (seconds): 445.85
      ----------configuration:(0/0)----------
      ----------System.out:(9/1828)----------
      Starting the ClhsdbFindPC test
      Command line: ['/scratch/mesos/jib-master/install/jdk-16+6-168/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java' '-XX:MaxRAMPercentage=12' '-Djava.io.tmpdir=/scratch/mesos/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S992/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/1ca523ef-9cef-4c75-97f6-121eaacfa189/runs/130452c2-8592-4adc-9789-a0fa066e52a0/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/tmp' '-Xint' '-XX:+CreateCoredumpOnCrash' '-Djava.library.path=/scratch/mesos/jib-master/install/jdk-16+6-168/macosx-x64-debug.test/hotspot/jtreg/native' '-cp' '/scratch/mesos/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S992/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/1ca523ef-9cef-4c75-97f6-121eaacfa189/runs/130452c2-8592-4adc-9789-a0fa066e52a0/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/0/serviceability/sa/ClhsdbFindPC_id3.d:/scratch/mesos/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S992/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/1ca523ef-9cef-4c75-97f6-121eaacfa189/runs/130452c2-8592-4adc-9789-a0fa066e52a0/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/0/test/lib' 'LingeredAppWithTrivialMain' '3bf7de6b-8edf-411d-8bf7-7014944a9bd0.lck' 'forceCrash']
      [2020-07-09T08:13:08.986673Z] Gathering output for process 13944
      [2020-07-09T08:13:09.053042Z] Waiting for completion for process 13944
      [2020-07-09T08:13:09.053225Z] Waiting for completion finished for process 13944
      Output and diagnostic info for process 13944 was saved into 'pid-13944-output.log'
      [2020-07-09T08:13:09.247708Z] Waiting for completion for process 13944
      [2020-07-09T08:13:09.247843Z] Waiting for completion finished for process 13944
      Run test with ulimit -c: unlimited
      ----------System.err:(21/1339)----------
      java.lang.RuntimeException: Test ERROR java.io.IOException: App waiting timeout
      at ClhsdbFindPC.testFindPC(ClhsdbFindPC.java:155)
      at ClhsdbFindPC.main(ClhsdbFindPC.java:167)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.base/java.lang.reflect.Method.invoke(Method.java:564)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
      at java.base/java.lang.Thread.run(Thread.java:832)
      Caused by: java.io.IOException: App waiting timeout
      at jdk.test.lib.apps.LingeredApp.waitAppReady(LingeredApp.java:264)
      at jdk.test.lib.apps.LingeredApp.waitAppReady(LingeredApp.java:294)
      at jdk.test.lib.apps.LingeredApp.startAppExactJvmOpts(LingeredApp.java:416)
      at jdk.test.lib.apps.LingeredApp.startApp(LingeredApp.java:431)
      at ClhsdbFindPC.testFindPC(ClhsdbFindPC.java:90)
      ... 7 more

      JavaTest Message: Test threw exception: java.lang.RuntimeException: Test ERROR java.io.IOException: App waiting timeout
      JavaTest Message: shutting down test

      STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Test ERROR java.io.IOException: App waiting timeout
      ----------rerun:(33/6540)*----------


      Here's a snippet from the ClhsdbFindPC.java#id1 log file:

      #section:main
      ----------messages:(4/188)----------
      command: main ClhsdbFindPC true true
      reason: User specified action: run main/othervm/timeout=480 ClhsdbFindPC true true
      Mode: othervm [/othervm specified]
      elapsed time (seconds): 457.343
      ----------configuration:(0/0)----------
      ----------System.out:(9/1829)----------
      Starting the ClhsdbFindPC test
      Command line: ['/scratch/mesos/jib-master/install/jdk-16+6-168/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java' '-XX:MaxRAMPercentage=12' '-Djava.io.tmpdir=/scratch/mesos/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S992/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/1ca523ef-9cef-4c75-97f6-121eaacfa189/runs/130452c2-8592-4adc-9789-a0fa066e52a0/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/tmp' '-Xcomp' '-XX:+CreateCoredumpOnCrash' '-Djava.library.path=/scratch/mesos/jib-master/install/jdk-16+6-168/macosx-x64-debug.test/hotspot/jtreg/native' '-cp' '/scratch/mesos/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S992/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/1ca523ef-9cef-4c75-97f6-121eaacfa189/runs/130452c2-8592-4adc-9789-a0fa066e52a0/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/ClhsdbFindPC_id1.d:/scratch/mesos/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S992/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/1ca523ef-9cef-4c75-97f6-121eaacfa189/runs/130452c2-8592-4adc-9789-a0fa066e52a0/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib' 'LingeredAppWithTrivialMain' '87856dbf-33fd-454d-b89e-01435a05e80c.lck' 'forceCrash']
      [2020-07-09T08:12:57.448369Z] Gathering output for process 13928
      [2020-07-09T08:12:57.580371Z] Waiting for completion for process 13928
      [2020-07-09T08:12:57.580672Z] Waiting for completion finished for process 13928
      Output and diagnostic info for process 13928 was saved into 'pid-13928-output.log'
      [2020-07-09T08:12:57.657282Z] Waiting for completion for process 13928
      [2020-07-09T08:12:57.657499Z] Waiting for completion finished for process 13928
      Run test with ulimit -c: unlimited
      ----------System.err:(21/1339)----------
      java.lang.RuntimeException: Test ERROR java.io.IOException: App waiting timeout
      at ClhsdbFindPC.testFindPC(ClhsdbFindPC.java:155)
      at ClhsdbFindPC.main(ClhsdbFindPC.java:167)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.base/java.lang.reflect.Method.invoke(Method.java:564)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
      at java.base/java.lang.Thread.run(Thread.java:832)
      Caused by: java.io.IOException: App waiting timeout
      at jdk.test.lib.apps.LingeredApp.waitAppReady(LingeredApp.java:264)
      at jdk.test.lib.apps.LingeredApp.waitAppReady(LingeredApp.java:294)
      at jdk.test.lib.apps.LingeredApp.startAppExactJvmOpts(LingeredApp.java:416)
      at jdk.test.lib.apps.LingeredApp.startApp(LingeredApp.java:431)
      at ClhsdbFindPC.testFindPC(ClhsdbFindPC.java:88)
      ... 7 more

      JavaTest Message: Test threw exception: java.lang.RuntimeException: Test ERROR java.io.IOException: App waiting timeout
      JavaTest Message: shutting down test

      STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Test ERROR java.io.IOException: App waiting timeout
      ----------rerun:(33/6541)*----------

      A curious observation: both tests specify /timeout=480
      and both tests timeout before 480 seconds. That's weird
      and that timeout value should have been multiplied by
      the timeoutFactor which is usually 4.

        Attachments

          Activity

            People

            • Assignee:
              cjplummer Chris Plummer
              Reporter:
              dcubed Daniel Daugherty
            • Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: