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

rmi tests failed due to "RMIDSelectorProvider: failed to bind to port NNNN due to "Address already in use", at AA:BB:CC.MMMMM"

    Details

    • Type: Bug
    • Status: Open
    • Priority: P4
    • Resolution: Unresolved
    • Affects Version/s: 15
    • Fix Version/s: tbd
    • Component/s: core-libs
    • Subcomponent:
    • CPU:
      x86_64
    • OS:
      os_x_10.15

      Description

      The following test failed in the JDK15 CI:

      java/rmi/activation/Activatable/restartService/RestartService.java

      Here's a snippet from the log file:

      ----------System.out:(676/69171)----------

      Regression test for bug 4095165

      RMIDSelectorProvider.inheritedChannel
      RmidSelectorProvider-listening-On:49848
      RMIDSelectorProvider.inheritedChannel
      RMIDSelectorProvider: failed to bind to port 49848 due to "Address already in use", at 17:57:28.935264
      RMIDSelectorProvider: failed to bind to port 49848 due to "Address already in use", at 17:57:30.082196
      RMIDSelectorProvider: failed to bind to port 49848 due to "Address already in use", at 17:57:31.223354

      <snip>

      RMIDSelectorProvider: failed to bind to port 49848 due to "Address already in use", at 18:10:14.823717
      RMIDSelectorProvider: failed to bind to port 49848 due to "Address already in use", at 18:10:15.973752
      RMIDSelectorProvider: failed to bind to port 49848 due to "Address already in use", at 18:10:17.123682
      RMIDSelectorProvider: fail to bind to port 49848 after trying for 768 seconds, exiting rmid process, at 18:10:17.123788
      ----------System.err:(57/8852)----------
      RMID: Starting rmid on port 0, at 17:57:26.423540
      JAVAVM: command = [/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/macosx-x64.jdk/jdk-15.jdk/Contents/Home/bin/java, -Xmx512m, -XX:MaxRAMPercentage=4, -ea, -esa, -Djava.security.policy=/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/src.full/open/test/jdk/java/rmi/activation/Activatable/restartService/rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/src.full/open/test/jdk/java/rmi/activation/Activatable/restartService, -Dtest.classes=/Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/activation/Activatable/restartService/RestartService.d, -Djava.rmi.server.logLevel=v, -Dsun.rmi.activation.execTimeout=60000, -Dsun.rmi.transport.tcp.handshakeTimeout=5000, -classpath, /Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/activation/Activatable/restartService/RestartService.d:/Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/testlibrary, --add-exports=java.base/sun.nio.ch=ALL-UNNAMED, -Djava.nio.channels.spi.SelectorProvider=RMIDSelectorProvider, -Dtest.java.rmi.testlibrary.RMIDSelectorProvider.port=0, -Dtest.java.rmi.testlibrary.RMIDSelectorProvider.timeout=0, -Dsun.rmi.server.activation.disableErrRedirect=true, sun.rmi.server.Activation, -log, /Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/scratch/0/./log, -C-Dtest.src=/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/src.full/open/test/jdk/java/rmi/activation/Activatable/restartService, -C-Dtest.classes=/Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/activation/Activatable/restartService/RestartService.d, -C-Xmx512m, -C-XX:MaxRAMPercentage=4, -C-ea, -C-esa, -C-Djava.rmi.server.useCodebaseOnly=false]
      RMID: Started rmid on port 49848, at 17:57:26.619805
      Thu May 07 17:57:26 GMT 2020
      rmid startup with inherited channel: sun.nio.ch.ServerSocketChannelImpl[localhost/127.0.0.1:49848]
      RMID: looking up activation system, at 17:57:26.725847
      RMID: Started successfully after 249ms, at 17:57:26.869765
      Creating descriptors
      Registering descriptors
      RMID: shutdown()
      RMID: ActivationSystem.shutdown()
      activation daemon shut down
      RMID: Shutdown successful after 1831ms.
      RMID: Starting rmid on port 49848, at 17:57:28.776410
      JAVAVM: command = [/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/macosx-x64.jdk/jdk-15.jdk/Contents/Home/bin/java, -Xmx512m, -XX:MaxRAMPercentage=4, -ea, -esa, -Djava.security.policy=/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/src.full/open/test/jdk/java/rmi/activation/Activatable/restartService/rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/src.full/open/test/jdk/java/rmi/activation/Activatable/restartService, -Dtest.classes=/Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/activation/Activatable/restartService/RestartService.d, -Djava.rmi.server.logLevel=v, -Dsun.rmi.activation.execTimeout=60000, -Dsun.rmi.transport.tcp.handshakeTimeout=5000, -classpath, /Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/activation/Activatable/restartService/RestartService.d:/Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/testlibrary, --add-exports=java.base/sun.nio.ch=ALL-UNNAMED, -Djava.nio.channels.spi.SelectorProvider=RMIDSelectorProvider, -Dtest.java.rmi.testlibrary.RMIDSelectorProvider.port=49848, -Dtest.java.rmi.testlibrary.RMIDSelectorProvider.timeout=768000, -Dsun.rmi.server.activation.disableErrRedirect=true, sun.rmi.server.Activation, -log, /Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/scratch/0/./log, -C-Dtest.src=/Volumes/Mesos/mesos/work_dir/jib-master/install/jdk-15+23-1050/src.full/open/test/jdk/java/rmi/activation/Activatable/restartService, -C-Dtest.classes=/Volumes/Mesos/mesos/work_dir/slaves/7c9ff9d4-a2d5-4d00-bd35-bdbc40f771b4-S1215/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2baf56f3-dd5f-4edb-92cc-07b72de19e3f/runs/4e5420a4-65fc-4956-93ab-f094d3ad2701/testoutput/test-support/jtreg_open_test_jdk_tier3/classes/5/java/rmi/activation/Activatable/restartService/RestartService.d, -C-Xmx512m, -C-XX:MaxRAMPercentage=4, -C-ea, -C-esa, -C-Djava.rmi.server.useCodebaseOnly=false]
      RMID: Started rmid on port 49848, at 18:10:17.137006
      TEST FAILED: Rmid process exited with status 1 after 111ms.
      TEST FAILED:
      Test failed with: TEST FAILED: Rmid process exited with status 1 after 111ms.
      TestFailedException: TEST FAILED: Rmid process exited with status 1 after 111ms.
      at TestLibrary.bomb(TestLibrary.java:121)
      at TestLibrary.bomb(TestLibrary.java:124)
      at RMID.start(RMID.java:366)
      at RMID.restart(RMID.java:409)
      at RestartService.main(RestartService.java:184)
      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)
      RMID: shutdown()
      RMID: lookupSystem() returned null after 2ms.
      RMID: Destroying RMID process.
      RMID: Destroy successful after 2ms.
      RMID: Removing rmid's old log file.
      TEST_LIBRARY: unexporting object...
      TestFailedException: TEST FAILED: ; nested exception is:
      TestFailedException: TEST FAILED: Rmid process exited with status 1 after 111ms.
      TestFailedException: TEST FAILED: Rmid process exited with status 1 after 111ms.
      at TestLibrary.bomb(TestLibrary.java:121)
      at TestLibrary.bomb(TestLibrary.java:124)
      at RMID.start(RMID.java:366)
      at RMID.restart(RMID.java:409)
      at RestartService.main(RestartService.java:184)
      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)

      JavaTest Message: Test threw exception: TestFailedException: TEST FAILED: ; nested exception is:
      TestFailedException: TEST FAILED: Rmid process exited with status 1 after 111ms.
      JavaTest Message: shutting down test

      STATUS:Failed.`main' threw exception: TestFailedException: TEST FAILED: ; nested exception is: TestFailedException: TEST FAILED: Rmid process exited with status 1 after 111ms.
      ----------rerun:(43/7044)*----------


      There are other unresolved bugs for this test, but those failure
      modes do not match this one.

        Attachments

          Activity

            People

            • Assignee:
              smarks Stuart Marks
              Reporter:
              dcubed Daniel Daugherty
            • Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

              Dates

              • Created:
                Updated: