JDK-8211850 : test/jdk/javax/naming/module/RunBasic.java fails intermittently
  • Type: Bug
  • Component: core-libs
  • Sub-Component: javax.naming
  • Affected Version: 12
  • Priority: P3
  • Status: Resolved
  • Resolution: Delivered
  • Submitted: 2018-10-08
  • Updated: 2018-10-11
  • Resolved: 2018-10-11
Related Reports
Relates :  
JDK-8211921 - AssertionError in MethodHandles$Lookup.defineClass
Sub Tasks
JDK-8211863 :  
Problem list test/jdk/javax/naming/module/RunBasic.java - Resolved
JDK-8211920 :  
Close server socket and cleanups in test/jdk/javax/naming/module/RunBasic.java - Resolved
JDK-8211922 :  
Remove test/jdk/javax/naming/module/RunBasic.java from the ProblemList - Resolved
Description
----------System.out:(160/8192)----------
Preparing the 'person' module...
Preparing the 'fruit' module...
Preparing the 'hello' module...
Preparing the 'foo' module...
Preparing the 'authz' module...
Preparing the 'ldapv4' module...
Preparing the 'test' module...
Running with the 'java.desktop' module...
Command line: [/scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/linux-x64.jdk/jdk-12/bin/java -XX:MaxRAMPercentage=8 -ea -esa -Xmx512m -Dtest.src=/scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/src.full/open/test/jdk/javax/naming/module -p mods -m test/test.StoreObject ldap://localhost/dc=ie,dc=oracle,dc=com]
StoreObject: connecting to ldap://localhost:49041/dc=ie,dc=oracle,dc=com
LDAPServer: Loading LDAP cache from: /scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/src.full/open/test/jdk/javax/naming/module/src/test/test/StoreObject.ldap
    adding LDAP BindRequest with message ID 1 to the cache
    adding LDAP BindResponse with message ID 1 to the cache
    adding LDAP AddRequest with message ID 2 to the cache
    adding LDAP AddResponse with message ID 2 to the cache
    adding LDAP AddRequest with message ID 3 to the cache
    adding LDAP AddResponse with message ID 3 to the cache
    adding LDAP SearchRequest with message ID 4 to the cache
    adding LDAP SearchResultEntry with message ID 4 to the cache
    adding LDAP SearchResultDone with message ID 4 to the cache
    adding LDAP SearchRequest with message ID 5 to the cache
    adding LDAP SearchResultEntry with message ID 5 to the cache
    adding LDAP SearchResultDone with message ID 5 to the cache
    adding LDAP DeleteRequest with message ID 6 to the cache
    adding LDAP DeleteResponse with message ID 6 to the cache
    adding LDAP DeleteRequest with message ID 7 to the cache
    adding LDAP DeleteResponse with message ID 7 to the cache
    adding LDAP UnbindRequest with message ID 8 to the cache
LDAPServer: listening on port 49041

LDAPServer: received LDAP BindRequest  [message ID 1]

LDAPServer: Sent LDAP BindResponse  [message ID 1]

LDAPServer: received LDAP AddRequest  [message ID 2]

LDAPServer: Sent LDAP AddResponse  [message ID 2]
StoreObject: created entry 'cn=myevent'

LDAPServer: received LDAP AddRequest  [message ID 3]

LDAPServer: Sent LDAP AddResponse  [message ID 3]
StoreObject: created entry 'cn=myevent2'

LDAPServer: received LDAP SearchRequest  [message ID 4]

LDAPServer: Sent LDAP SearchResultEntry  [message ID 4]

LDAPServer: Sent LDAP SearchResultDone  [message ID 4]
StoreObject: retrieved object: java.awt.event.ActionEvent[unknown type,cmd=Hello1,when=0,modifiers=] on null

LDAPServer: received LDAP SearchRequest  [message ID 5]

LDAPServer: Sent LDAP SearchResultEntry  [message ID 5]

LDAPServer: Sent LDAP SearchResultDone  [message ID 5]
StoreObject: retrieved object: java.awt.event.ActionEvent[unknown type,cmd=Hello2,when=0,modifiers=] on null

LDAPServer: received LDAP DeleteRequest  [message ID 6]

LDAPServer: Sent LDAP DeleteResponse  [message ID 6]
StoreObject: removed entry 'cn=myevent'

LDAPServer: received LDAP DeleteRequest  [message ID 7]

LDAPServer: Sent LDAP DeleteResponse  [message ID 7]
StoreObject: removed entry 'cn=myevent2'

LDAPServer: received LDAP UnbindRequest  [message ID 8]

[LDAP server exited normally]

Running with the 'person' module...
Command line: [/scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/linux-x64.jdk/jdk-12/bin/java -XX:MaxRAMPercentage=8 -ea -esa -Xmx512m -Dtest.src=/scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/src.full/open/test/jdk/javax/naming/module -p mods -m test/test.StorePerson ldap://localhost/dc=ie,dc=oracle,dc=com]
StorePerson: connecting to ldap://localhost:48340/dc=ie,dc=oracle,dc=com
LDAPServer: Loading LDAP cache from: /scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/src.full/open/test/jdk/javax/naming/module/src/test/test/StorePerson.ldap
    adding LDAP BindRequest with message ID 1 to the cache
    adding LDAP BindResponse with message ID 1 to the cache
    adding LDAP AddRequest with message ID 2 to the cache
    adding LDAP AddResponse with message ID 2 to the cache
    adding LDAP AddRequest with message ID 3 to the cache
    adding LDAP AddResponse with message ID 3 to the cache
    adding LDAP SearchRequest with message ID 4 to the cache
    adding LDAP SearchResultEntry with message ID 4 to the cache
    adding LDAP SearchResultDone with message ID 4 to the cache
    adding LDAP SearchRequest with message ID 5 to the cache
    adding LDAP SearchResultEntry with message ID 5 to the cache
    adding LDAP SearchResultDone with message ID 5 to the cache
    adding LDAP DeleteRequest with message ID 6 to the cache
    adding LDAP DeleteResponse with message ID 6 to the cache
    adding LDAP DeleteRequest with message ID 7 to the cache
    adding LDAP DeleteResponse with message ID 7 to the cache
    adding LDAP UnbindRequest with message ID 8 to the cache
LDAPServer: listening on port 48340

LDAPServer: received LDAP BindRequest  [message ID 1]

LDAPServer: Sent LDAP BindResponse  [message ID 1]

LDAPServer: received LDAP AddRequest  [message ID 2]

LDAPServer: Sent LDAP AddResponse  [message ID 2]
StorePerson: created entry 'cn=John Smith'

LDAPServer: received LDAP AddRequest  [message ID 3]

LDAPServer: Sent LDAP AddResponse  [message ID 3]
StorePerson: created entry 'cn=Jill Smyth'

LDAPServer: received LDAP SearchRequest  [message ID 4]

LDAPServer: Sent LDAP SearchResultEntry  [message ID 4]

LDAPServer: Sent LDAP SearchResultDone  [message ID 4]
StorePerson: retrieved object: org.example.person.Person@606d8acf
StorePerson: retrieved person matches original

LDAPServer: received LDAP SearchRequest  [message ID 5]

LDAPServer: Sent LDAP SearchResultEntry  [message ID 5]

LDAPServer: Sent LDAP SearchResultDone  [message ID 5]
StorePerson: retrieved object: org.example.person.Person@782830e
StorePerson: retrieved person matches original

LDAPServer: received LDAP DeleteRequest  [message ID 6]

LDAPServer: Sent LDAP DeleteResponse  [message ID 6]
StorePerson: removed entry 'cn=John Smith'

LDAPServer: received LDAP DeleteRequest  [message ID 7]

LDAPServer: Sent LDAP DeleteResponse  [message ID 7]
StorePerson: removed entry 'cn=Jill Smyth'

LDAPServer: received LDAP UnbindRequest  [message ID 8]

[LDAP server exited normally]

Running with the 'fruit' module...
Command line: [/scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/linux-x64.jdk/jdk-12/bin/java -XX:MaxRAMPercentage=8 -ea -esa -Xmx512m -Dtest.src=/scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/src.full/open/test/jdk/javax/naming/module -p mods -m test/test.StoreFruit ldap://localhost/dc=ie,dc=oracle,dc=com]
StoreFruit: connecting to ldap://localhost:28392/dc=ie,dc=oracle,dc=com
LDAPServer: Loading LDAP cache from: /scratch/opt/mach5/mesos/work_dir/jib-master/install/2018-10-08-1108435.chris.hegarty.jdk/src.full/open/test/jdk/javax/naming/module/src/test/test/StoreFruit.ldap
    adding LDAP BindRequest with message ID 1 to the cache
    adding LDAP BindResponse with message ID 1 to the cache
    adding LDAP AddRequest with message ID 2 to the cache
    adding LDAP AddResponse with message ID 2 to the cache
    adding LDAP AddRequest with message ID 3 to the cache
    adding LDAP AddResponse with message ID 3 to the cache
    adding LDAP SearchRequest with message ID 4 to the cache
    adding LDAP SearchResultEntry with message ID 4 to the cache
    adding LDAP SearchResultDone with message ID 4 to the cache
    adding LDAP SearchRequest with message ID 5 to the cache
    adding LDAP SearchResultEntry with message ID 5 to the cache
    adding LDAP SearchResultDone with message ID 5 to the cache
    adding LDAP DeleteRequest with message ID 6 to the cache
    adding LDAP DeleteResponse with message ID 6 to the cache
    adding LDAP DeleteRequest with message ID 7 to the cache
    adding LDAP DeleteResponse with message ID 7 to the cache
    adding 
result: Error. Agent error: java.lang.Exception: Agent 1 timed out with a timeout of 1200 seconds; check console log for any additional details


test result: Error. Agent error: java.lang.Exception: Agent 1 timed out with a timeout of 1200 seconds; check console log for any additional details
Comments
All subtasks are complete, and the test is running and passing successfully.
11-10-2018
I pushed changes to the test, JDK-8211920, that closed the server socket when the client-side completes. The underlying cause, assertion in MH's, can not be observed. JDK-8211921 will track this product bug. This JIRA issue will remain open until such time that the test can be removed from the ProblemList.
09-10-2018